Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1761976AbXK2KT7 (ORCPT ); Thu, 29 Nov 2007 05:19:59 -0500 Received: (majordomo@vger.kernel.org) by vger.kernel.org id S1755773AbXK2KTv (ORCPT ); Thu, 29 Nov 2007 05:19:51 -0500 Received: from fxip-0047f.externet.hu ([88.209.222.127]:55317 "EHLO dorka.pomaz.szeredi.hu" rhost-flags-OK-OK-OK-FAIL) by vger.kernel.org with ESMTP id S1755159AbXK2KTu (ORCPT ); Thu, 29 Nov 2007 05:19:50 -0500 To: jdike@addtoit.com CC: mingo@elte.hu, user-mode-linux-devel@lists.sourceforge.net, linux-kernel@vger.kernel.org In-reply-to: (message from Miklos Szeredi on Wed, 28 Nov 2007 19:46:01 +0100) Subject: scheduling anomaly on uml (was: -rt doesn't compile for UML) References: <20071128150637.GA5518@c2.user-mode-linux.org> <20071128182935.GA8048@c2.user-mode-linux.org> Message-Id: From: Miklos Szeredi Date: Thu, 29 Nov 2007 11:19:40 +0100 Sender: linux-kernel-owner@vger.kernel.org X-Mailing-List: linux-kernel@vger.kernel.org Content-Length: 22033 Lines: 348 I can't say I'm understading these traces very well, but here's a snippet that looks a bit strange. I'm running 'while true; do date; done' in parallel with the dd. For some time it is doing 100% CPU as expected, then it goes into a second or so of mosty idle (afaics), and then returns to the normal pattern again. Any thoughts? date-7119 0.... 15636591us!: schedule (0 0) bash-502 0.... 15643908us!: schedule (0 0) bash-502 0.... 15646250us!: schedule (0 0) date-7120 0.... 15647023us!: schedule (0 0) dd-6444 0.... 15654505us+: schedule (0 0) bash-502 0.... 15654602us!: schedule (0 0) bash-502 0.... 15656809us!: schedule (0 0) date-7121 0.... 15657585us!: schedule (0 0) dd-6444 0.... 15664962us+: schedule (0 0) bash-502 0.... 15665056us!: schedule (0 0) bash-502 0.... 15667246us!: schedule (0 0) date-7122 0.... 15668015us!: schedule (0 0) dd-6444 0.... 15675447us+: schedule (0 0) bash-502 0.... 15675539us!: schedule (0 0) bash-502 0.... 15677726us!: schedule (0 0) date-7123 0.... 15678496us!: schedule (0 0) bash-502 0.... 15685867us!: schedule (0 0) bash-502 0.... 15688117us!: schedule (0 0) date-7124 0.... 15688896us!: schedule (0 0) bash-502 0.... 15696317us!: schedule (0 0) bash-502 0.... 15698604us!: schedule (0 0) date-7125 0.... 15699374us!: schedule (0 0) bash-502 0.... 15706800us!: schedule (0 0) bash-502 0.... 15709065us!: schedule (0 0) date-7126 0.... 15709838us!: schedule (0 0) bash-502 0.... 15717247us!: schedule (0 0) bash-502 0.... 15719626us!: schedule (0 0) date-7127 0.... 15720405us+: schedule (0 0) date-7127 0.Ns. 15720466us+: __trace_start_sched_wakeup (115 -1) ksoftirq-3 0.... 15720471us+: schedule (0 -5) date-7127 0.... 15720481us!: schedule (-5 0) dd-6444 0.... 15723423us!: schedule (0 0) dd-6444 0.... 15727689us!: __trace_start_sched_wakeup (115 -1) kswapd0-33 0.... 15736528us+: schedule (0 -5) dd-6444 0.... 15736537us!: schedule (-5 0) date-7127 0.... 15740399us!: schedule (0 0) bash-502 0.... 15745240us!: schedule (0 0) bash-502 0.... 15747667us!: schedule (0 0) date-7128 0.... 15748462us!: schedule (0 0) date-7128 0.... 15749685us!: __trace_start_sched_wakeup (115 -1) kswapd0-33 0.... 15755989us+: schedule (0 -5) bash-502 0.... 15756000us!: schedule (-5 0) bash-502 0.Ns. 15756427us+: __trace_start_sched_wakeup (115 -1) kblockd/-14 0.... 15756436us+: schedule (0 -5) bash-502 0.... 15756480us!: schedule (-5 0) bash-502 0.... 15758372us!: schedule (0 0) date-7129 0.... 15759148us!: schedule (0 0) bash-502 0.... 15766505us!: schedule (0 0) bash-502 0.... 15768896us!: schedule (0 0) date-7130 0.... 15769677us!: schedule (0 0) date-7130 0..h. 15773773us!: __trace_start_sched_wakeup (115 -1) kswapd0-33 0.... 15777168us+: schedule (0 -5) dd-6444 0.... 15777180us+: schedule (-5 0) bash-502 0.... 15777274us!: schedule (0 0) bash-502 0.... 15779535us!: schedule (0 0) date-7131 0.... 15780307us!: schedule (0 0) bash-502 0.... 15787778us!: schedule (0 0) bash-502 0.... 15790057us!: schedule (0 0) date-7132 0.... 15790829us!: schedule (0 0) bash-502 0.... 15798234us!: schedule (0 0) bash-502 0.... 15800525us!: schedule (0 0) date-7133 0.... 15801298us!: schedule (0 0) -0 0.... 15802726us!: schedule (0 20) -0 0.Nh. 15854372us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 15854375us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 15854378us+: __trace_start_sched_wakeup (115 -1) dd-6444 0.... 15854392us+: schedule <-0> (20 0) kswapd0-33 0.... 15854400us+: schedule (0 -5) date-7133 0.... 15854407us+: schedule (-5 0) -0 0.... 15854412us!: schedule (0 20) -0 0.Nh. 15904812us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 15904815us+: __trace_start_sched_wakeup (115 -1) -0 0.Nh. 15904818us+: __trace_start_sched_wakeup (120 -1) kswapd0-33 0.... 15904833us+: schedule <-0> (20 -5) dd-6444 0.... 15904843us+: schedule (-5 0) date-7133 0.... 15904852us+: schedule (0 0) -0 0.... 15904858us!: schedule (0 20) -0 0.Nh. 15945062us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 15945065us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 15945067us+: __trace_start_sched_wakeup (115 -1) dd-6444 0.... 15945083us+: schedule <-0> (20 0) kswapd0-33 0.... 15945091us+: schedule (0 -5) date-7133 0.... 15945099us+: schedule (-5 0) -0 0.... 15945106us!: schedule (0 20) -0 0.Nh. 15975282us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 15975285us+: __trace_start_sched_wakeup (115 -1) -0 0.Nh. 15975289us+: __trace_start_sched_wakeup (120 -1) kswapd0-33 0.... 15975307us+: schedule <-0> (20 -5) dd-6444 0.... 15975319us+: schedule (-5 0) date-7133 0.... 15975329us+: schedule (0 0) -0 0.... 15975337us!: schedule (0 20) -0 0.Nh. 16025774us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16025777us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16025781us+: __trace_start_sched_wakeup (115 -1) dd-6444 0.... 16025801us+: schedule <-0> (20 0) kswapd0-33 0.... 16025812us+: schedule (0 -5) date-7133 0.... 16025844us+: schedule (-5 0) -0 0.... 16025852us!: schedule (0 20) -0 0.Nh. 16035765us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16035768us+: __trace_start_sched_wakeup (115 -1) -0 0.Nh. 16035772us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16035780us+: __trace_start_sched_wakeup (115 -1) kswapd0-33 0.... 16035802us+: schedule <-0> (20 -5) dd-6444 0.... 16035814us+: schedule (-5 0) kjournal-68 0.... 16035824us!: schedule (0 -5) date-7133 0.... 16036451us+: schedule (-5 0) -0 0.... 16036459us!: schedule (0 20) -0 0.Ns. 16044503us+: __trace_start_sched_wakeup (115 -1) events/0-4 0.... 16044512us+: schedule <-0> (20 -5) -0 0.... 16044564us!: schedule (-5 20) -0 0.Nh. 16076072us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16076075us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16076078us+: __trace_start_sched_wakeup (115 -1) dd-6444 0.... 16076104us+: schedule <-0> (20 0) kswapd0-33 0.... 16076114us+: schedule (0 -5) date-7133 0.... 16076124us+: schedule (-5 0) -0 0.... 16076132us!: schedule (0 20) -0 0.Nh. 16237235us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16237239us+: __trace_start_sched_wakeup (115 -1) -0 0.Nh. 16237242us+: __trace_start_sched_wakeup (120 -1) kswapd0-33 0.... 16237266us+: schedule <-0> (20 -5) dd-6444 0.... 16237280us+: schedule (-5 0) date-7133 0.... 16237291us+: schedule (0 0) -0 0.... 16237299us!: schedule (0 20) -0 0.Nh. 16338000us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16338003us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16338007us+: __trace_start_sched_wakeup (115 -1) dd-6444 0.... 16338034us+: schedule <-0> (20 0) kswapd0-33 0.... 16338045us+: schedule (0 -5) date-7133 0.... 16338056us+: schedule (-5 0) -0 0.... 16338063us!: schedule (0 20) -0 0.Nh. 16347973us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16347976us+: __trace_start_sched_wakeup (115 -1) -0 0.Nh. 16347979us+: __trace_start_sched_wakeup (120 -1) kswapd0-33 0.... 16348002us+: schedule <-0> (20 -5) dd-6444 0.... 16348014us+: schedule (-5 0) date-7133 0.... 16348024us+: schedule (0 0) -0 0.... 16348032us!: schedule (0 20) -0 0.Nh. 16438814us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16438818us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16438821us+: __trace_start_sched_wakeup (115 -1) dd-6444 0.... 16438843us+: schedule <-0> (20 0) kswapd0-33 0.... 16438855us+: schedule (0 -5) date-7133 0.... 16438865us+: schedule (-5 0) -0 0.... 16438873us!: schedule (0 20) -0 0.Nh. 16519442us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16519445us+: __trace_start_sched_wakeup (115 -1) -0 0.Nh. 16519448us+: __trace_start_sched_wakeup (120 -1) kswapd0-33 0.... 16519466us+: schedule <-0> (20 -5) dd-6444 0.... 16519478us+: schedule (-5 0) date-7133 0.... 16519488us+: schedule (0 0) -0 0.... 16519496us!: schedule (0 20) -0 0.Nh. 16579941us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16579944us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16579947us+: __trace_start_sched_wakeup (115 -1) dd-6444 0.... 16579968us+: schedule <-0> (20 0) kswapd0-33 0.... 16579978us+: schedule (0 -5) date-7133 0.... 16579988us+: schedule (-5 0) -0 0.... 16579996us!: schedule (0 20) -0 0.Nh. 16589889us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16589892us+: __trace_start_sched_wakeup (115 -1) -0 0.Nh. 16589895us+: __trace_start_sched_wakeup (120 -1) kswapd0-33 0.... 16589913us+: schedule <-0> (20 -5) dd-6444 0.... 16589924us+: schedule (-5 0) date-7133 0.... 16589935us+: schedule (0 0) -0 0.... 16589942us!: schedule (0 20) -0 0.Nh. 16610104us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16610107us+: __trace_start_sched_wakeup (120 -1) -0 0.Nh. 16610110us+: __trace_start_sched_wakeup (115 -1) dd-6444 0.... 16610130us+: schedule <-0> (20 0) kswapd0-33 0.... 16610140us!: schedule (0 -5) date-7133 0.... 16610487us!: schedule (-5 0) bash-502 0.... 16622148us!: schedule (0 0) bash-502 0.... 16626384us!: schedule (0 0) date-7134 0.... 16627783us!: schedule (0 0) bash-502 0.... 16641348us!: schedule (0 0) bash-502 0.... 16645627us!: schedule (0 0) date-7135 0.... 16647022us!: schedule (0 0) bash-502 0.... 16658816us!: schedule (0 0) date-7136 0..h. 16660498us!: __trace_start_sched_wakeup (115 -1) kswapd0-33 0.... 16661220us+: schedule (0 -5) dd-6444 0.... 16661230us+: schedule (-5 0) bash-502 0.... 16661238us!: schedule (0 0) date-7136 0.... 16662012us!: schedule (0 0) bash-502 0.... 16669457us!: schedule (0 0) bash-502 0.... 16671791us!: schedule (0 0) date-7137 0.... 16672512us!: schedule (0 0) bash-502 0.... 16679839us!: schedule (0 0) bash-502 0.... 16682313us!: schedule (0 0) date-7138 0.... 16683096us!: schedule (0 0) bash-502 0.... 16691274us!: schedule (0 0) bash-502 0.... 16694598us!: schedule (0 0) date-7139 0.... 16695374us!: schedule (0 0) bash-502 0.... 16702798us!: schedule (0 0) bash-502 0.... 16705100us!: schedule (0 0) date-7140 0.... 16705881us!: schedule (0 0) dd-6444 0.... 16713383us+: schedule (0 0) bash-502 0.... 16713486us!: schedule (0 0) bash-502 0.... 16715698us!: schedule (0 0) date-7141 0.... 16716479us!: schedule (0 0) bash-502 0.... 16723865us!: schedule (0 0) bash-502 0.... 16726163us!: schedule (0 0) date-7142 0.... 16726929us!: schedule (0 0) bash-502 0.... 16734434us!: schedule (0 0) bash-502 0.... 16736736us!: schedule (0 0) date-7143 0.... 16737509us!: schedule (0 0) bash-502 0.... 16744911us!: schedule (0 0) bash-502 0.... 16747186us!: schedule (0 0) date-7144 0.... 16747955us!: schedule (0 0) bash-502 0.... 16755349us!: schedule (0 0) bash-502 0.... 16757672us!: schedule (0 0) date-7145 0.... 16758447us!: schedule (0 0) dd-6444 0.... 16765879us+: schedule (0 0) bash-502 0.... 16765982us!: schedule (0 0) bash-502 0.... 16768170us!: schedule (0 0) date-7146 0.... 16768954us!: schedule (0 0) bash-502 0.... 16776368us!: schedule (0 0) bash-502 0.... 16778667us!: schedule (0 0) date-7147 0.... 16779439us!: schedule (0 0) bash-502 0.... 16786847us!: schedule (0 0) bash-502 0.... 16789129us!: schedule (0 0) date-7148 0.... 16789906us!: schedule (0 0) bash-502 0.... 16797281us!: schedule (0 0) bash-502 0.... 16799726us!: schedule (0 0) date-7149 0.... 16800504us!: schedule (0 0) bash-502 0.... 16807922us!: schedule (0 0) bash-502 0.... 16810202us!: schedule (0 0) date-7150 0.... 16810969us!: schedule (0 0) bash-502 0.... 16818372us!: schedule (0 0) bash-502 0.... 16820666us!: schedule (0 0) date-7151 0.... 16821440us!: schedule (0 0) dd-6444 0.... 16829023us+: schedule (0 0) bash-502 0.... 16829125us!: schedule (0 0) bash-502 0.... 16831302us!: schedule (0 0) date-7152 0.... 16832074us!: schedule (0 0) bash-502 0.... 16839389us!: schedule (0 0) bash-502 0.... 16841702us!: schedule (0 0) bash-502 0.N.. 16842322us+: __trace_start_sched_wakeup (115 -1) kswapd0-33 0.... 16842352us!: schedule (0 -5) bash-502 0.... 16842568us!: schedule (-5 0) date-7153 0.... 16842745us!: schedule (0 0) bash-502 0.... 16850087us!: schedule (0 0) bash-502 0.... 16852366us!: schedule (0 0) date-7154 0.... 16853151us!: schedule (0 0) bash-502 0.... 16860617us!: schedule (0 0) bash-502 0.... 16862940us!: schedule (0 0) date-7155 0.... 16863715us!: schedule (0 0) bash-502 0.... 16871092us!: schedule (0 0) date-7156 0..h. 16872536us!: __trace_start_sched_wakeup (115 -1) kswapd0-33 0.... 16873519us+: schedule (0 -5) dd-6444 0.... 16873527us+: schedule (-5 0) bash-502 0.... 16873535us!: schedule (0 0) date-7156 0.... 16874310us!: schedule (0 0) bash-502 0.... 16881743us!: schedule (0 0) bash-502 0.... 16884041us!: schedule (0 0) date-7157 0.... 16884824us!: schedule (0 0) bash-502 0.... 16892154us!: schedule (0 0) bash-502 0.... 16894569us!: schedule (0 0) date-7158 0.... 16895343us!: schedule (0 0) bash-502 0.... 16902783us!: schedule (0 0) bash-502 0.... 16905369us!: schedule (0 0) date-7159 0.... 16906191us!: schedule (0 0) bash-502 0.... 16913569us!: schedule (0 0) bash-502 0.... 16915927us!: schedule (0 0) date-7160 0.... 16916760us!: schedule (0 0) bash-502 0.... 16924096us!: schedule (0 0) bash-502 0.... 16926448us!: schedule (0 0) date-7161 0.... 16927257us!: schedule (0 0) bash-502 0.... 16934587us!: schedule (0 0) bash-502 0.... 16936905us!: schedule (0 0) date-7162 0.... 16937720us!: schedule (0 0) bash-502 0.... 16945142us!: schedule (0 0) bash-502 0.... 16947452us!: schedule (0 0) date-7163 0.... 16948262us!: schedule (0 0) bash-502 0.... 16955611us!: schedule (0 0) dd-6444 0.... 16956304us!: schedule (0 0) dd-6444 0.... 16958669us!: __trace_start_sched_wakeup (115 -1) kswapd0-33 0.... 16958806us+: schedule (0 -5) bash-502 0.... 16958900us!: schedule (-5 0) bash-502 0.... 16960857us!: schedule (0 0) date-7164 0.... 16961712us!: schedule (0 0) date-7164 0.Ns. 16968470us+: __trace_start_sched_wakeup (115 -1) ksoftirq-3 0.... 16968479us+: schedule (0 -5) date-7164 0.... 16968578us!: schedule (-5 0) bash-502 0.... 16969310us!: schedule (0 0) bash-502 0.... 16971659us!: schedule (0 0) date-7165 0.... 16972487us!: schedule (0 0) bash-502 0.... 16979969us!: schedule (0 0) bash-502 0.... 16982280us!: schedule (0 0) date-7166 0.... 16983107us!: schedule (0 0) bash-502 0.... 16990504us!: schedule (0 0) bash-502 0.... 16992802us!: schedule (0 0) date-7167 0.... 16993618us!: schedule (0 0) bash-502 0.... 17001059us!: schedule (0 0) bash-502 0.... 17003350us!: schedule (0 0) date-7168 0.... 17004162us!: schedule (0 0) date-7168 0..h. 17006553us!: __trace_start_sched_wakeup (115 -1) kswapd0-33 0.... 17011682us+: schedule (0 -5) bash-502 0.... 17011692us!: schedule (-5 0) bash-502 0.... 17014005us!: schedule (0 0) date-7169 0.... 17014814us!: schedule (0 0) bash-502 0.... 17022217us!: schedule (0 0) bash-502 0.... 17024519us!: schedule (0 0) date-7170 0.... 17025334us!: schedule (0 0) bash-502 0.... 17032788us!: schedule (0 0) bash-502 0.... 17035069us!: schedule (0 0) date-7171 0.... 17035878us!: schedule (0 0) bash-502 0.... 17043372us!: schedule (0 0) bash-502 0.... 17045678us!: schedule (0 0) date-7172 0.... 17046486us!: schedule (0 0) bash-502 0.... 17053866us!: schedule (0 0) bash-502 0.... 17056168us!: schedule (0 0) date-7173 0.... 17056999us!: schedule (0 0) bash-502 0.... 17064367us!: schedule (0 0) bash-502 0.... 17066673us!: schedule (0 0) date-7174 0.... 17067523us!: schedule (0 0) bash-502 0.... 17074961us!: schedule (0 0) bash-502 0.... 17077271us!: schedule (0 0) date-7175 0.... 17078082us!: schedule (0 0) bash-502 0.... 17085411us!: schedule (0 0) bash-502 0.... 17087738us!: schedule (0 0) date-7176 0.... 17088561us!: schedule (0 0) bash-502 0.... 17095920us!: schedule (0 0) bash-502 0.... 17098194us!: schedule (0 0) date-7177 0.... 17098958us!: schedule (0 0) bash-502 0.... 17106376us!: schedule (0 0) bash-502 0.... 17108848us!: schedule (0 0) date-7178 0.... 17109639us!: schedule (0 0) bash-502 0.... 17117073us!: schedule (0 0) bash-502 0.... 17119410us!: schedule (0 0) date-7179 0.... 17120214us!: schedule (0 0) bash-502 0.... 17127600us!: schedule (0 0) - To unsubscribe from this list: send the line "unsubscribe linux-kernel" in the body of a message to majordomo@vger.kernel.org More majordomo info at http://vger.kernel.org/majordomo-info.html Please read the FAQ at http://www.tux.org/lkml/