From: Roland Dreier <rdreier@cisco.com>
To: Ingo Molnar <mingo@elte.hu>
Cc: linux-kernel@vger.kernel.org
Subject: Re: Early hang with 2.6.21-rc4-rt1
Date: Tue, 27 Mar 2007 12:42:18 -0700 [thread overview]
Message-ID: <ada648mo4md.fsf@cisco.com> (raw)
In-Reply-To: <20070327192400.GB31695@elte.hu> (Ingo Molnar's message of "Tue, 27 Mar 2007 21:24:00 +0200")
Great, thanks ... I'm not sure which of the 2 patches fixed things,
but I now got the following trace. I've not really analyzed this, but
it definitely looks like tun_init() is doing something
fishy... ("149776 us maximum-latency"!!)
- R.
[ 272.392694] tun: Universal TUN/TAP device driver, 1.6
[ 272.397780] ( modprobe-1857 |#2): new 5088 us maximum-latency critical section.
[ 272.397782] => started at timestamp 176092523: <__spin_lock_irqsave+0x18/0x42>
[ 272.397790] => ended at timestamp 176097606: <release_console_sem+0x1ae/0x1fa>
[ 272.397795]
[ 272.397796] Call Trace:
[ 272.397801] [<ffffffff80255e74>] check_critical_timing+0x1e6/0x2f7
[ 272.397805] [<ffffffff8022be60>] release_console_sem+0x1ae/0x1fa
[ 272.397810] [<ffffffff8022be60>] release_console_sem+0x1ae/0x1fa
[ 272.397814] [<ffffffff80256323>] trace_hardirqs_on+0x9a/0xb9
[ 272.397819] [<ffffffff8022be60>] release_console_sem+0x1ae/0x1fa
[ 272.397824] [<ffffffff8022c4e3>] vprintk+0x2a4/0x301
[ 272.397828] [<ffffffff80255f76>] check_critical_timing+0x2e8/0x2f7
[ 272.397833] [<ffffffff8022c5ac>] printk+0x6c/0x6e
[ 272.397837] [<ffffffff80256323>] trace_hardirqs_on+0x9a/0xb9
[ 272.397841] [<ffffffff80429572>] __spin_unlock_irq+0x14/0x17
[ 272.397846] [<ffffffff80429572>] __spin_unlock_irq+0x14/0x17
[ 272.397849] [<ffffffff8042785c>] wait_for_completion+0xbd/0xc6
[ 272.397854] [<ffffffff802262f9>] default_wake_function+0x0/0x19
[ 272.397859] [<ffffffff8042847a>] mutex_unlock+0x9/0x15
[ 272.397863] [<ffffffff80238dec>] blocking_notifier_call_chain+0x10/0x4e
[ 272.397868] [<ffffffff8800a02a>] :tun:tun_init+0x2a/0x6b
[ 272.397873] [<ffffffff8024d597>] sys_init_module+0x15d6/0x1731
[ 272.397879] [<ffffffff80256323>] trace_hardirqs_on+0x9a/0xb9
[ 272.397885] [<ffffffff803d2b89>] register_netdevice+0x0/0x31e
[ 272.397891] [<ffffffff80209e0f>] system_call+0xef/0x15c
[ 272.397896]
[ 272.397900] => dump-end timestamp 176097734
[ 272.397902]
[ 272.547556] ( modprobe-1857 |#2): new 149776 us maximum-latency critical section.
[ 272.547559] => started at timestamp 176097899: <__spin_lock_irqsave+0x18/0x42>
[ 272.547563] => ended at timestamp 176247674: <release_console_sem+0x1ae/0x1fa>
[ 272.547566]
[ 272.547567] Call Trace:
[ 272.547570] [<ffffffff80255e74>] check_critical_timing+0x1e6/0x2f7
[ 272.547575] [<ffffffff8022be60>] release_console_sem+0x1ae/0x1fa
[ 272.547579] [<ffffffff8022be60>] release_console_sem+0x1ae/0x1fa
[ 272.547583] [<ffffffff80256323>] trace_hardirqs_on+0x9a/0xb9
[ 272.547588] [<ffffffff8022be60>] release_console_sem+0x1ae/0x1fa
[ 272.547593] [<ffffffff8022c4e3>] vprintk+0x2a4/0x301
[ 272.547597] [<ffffffff80255f76>] check_critical_timing+0x2e8/0x2f7
[ 272.547602] [<ffffffff8022c5ac>] printk+0x6c/0x6e
[ 272.547606] [<ffffffff80256323>] trace_hardirqs_on+0x9a/0xb9
[ 272.547610] [<ffffffff80429572>] __spin_unlock_irq+0x14/0x17
[ 272.547614] [<ffffffff80429572>] __spin_unlock_irq+0x14/0x17
[ 272.547618] [<ffffffff8042785c>] wait_for_completion+0xbd/0xc6
[ 272.547622] [<ffffffff802262f9>] default_wake_function+0x0/0x19
[ 272.547627] [<ffffffff8042847a>] mutex_unlock+0x9/0x15
[ 272.547630] [<ffffffff80238dec>] blocking_notifier_call_chain+0x10/0x4e
[ 272.547635] [<ffffffff8800a02a>] :tun:tun_init+0x2a/0x6b
[ 272.547639] [<ffffffff8024d597>] sys_init_module+0x15d6/0x1731
[ 272.547646] [<ffffffff80256323>] trace_hardirqs_on+0x9a/0xb9
[ 272.547650] [<ffffffff803d2b89>] register_netdevice+0x0/0x31e
[ 272.547656] [<ffffffff80209e0f>] system_call+0xef/0x15c
[ 272.547660]
[ 272.547663] => dump-end timestamp 176247784
[ 272.547664]
[ 272.697343] ( modprobe-1857 |#2): new 149919 us maximum-latency critical section.
[ 272.697345] => started at timestamp 176247830: <__spin_lock_irqsave+0x18/0x42>
[ 272.697349] => ended at timestamp 176397741: <release_console_sem+0x1ae/0x1fa>
[ 272.697353]
[ 272.697354] Call Trace:
[ 272.697357] [<ffffffff80255e74>] check_critical_timing+0x1e6/0x2f7
[ 272.697361] [<ffffffff8022be60>] release_console_sem+0x1ae/0x1fa
[ 272.697366] [<ffffffff8022be60>] release_console_sem+0x1ae/0x1fa
[ 272.697369] [<ffffffff80256323>] trace_hardirqs_on+0x9a/0xb9
[ 272.697374] [<ffffffff8022be60>] release_console_sem+0x1ae/0x1fa
[ 272.697379] [<ffffffff8022c4e3>] vprintk+0x2a4/0x301
[ 272.697383] [<ffffffff80255f76>] check_critical_timing+0x2e8/0x2f7
[ 272.697388] [<ffffffff8022c5ac>] printk+0x6c/0x6e
[ 272.697392] [<ffffffff80256323>] trace_hardirqs_on+0x9a/0xb9
[ 272.697396] [<ffffffff80429572>] __spin_unlock_irq+0x14/0x17
[ 272.697401] [<ffffffff80429572>] __spin_unlock_irq+0x14/0x17
[ 272.697404] [<ffffffff8042785c>] wait_for_completion+0xbd/0xc6
[ 272.697409] [<ffffffff802262f9>] default_wake_function+0x0/0x19
[ 272.697413] [<ffffffff8042847a>] mutex_unlock+0x9/0x15
[ 272.697416] [<ffffffff80238dec>] blocking_notifier_call_chain+0x10/0x4e
[ 272.697421] [<ffffffff8800a02a>] :tun:tun_init+0x2a/0x6b
[ 272.697425] [<ffffffff8024d597>] sys_init_module+0x15d6/0x1731
[ 272.697432] [<ffffffff80256323>] trace_hardirqs_on+0x9a/0xb9
[ 272.697436] [<ffffffff803d2b89>] register_netdevice+0x0/0x31e
[ 272.697442] [<ffffffff80209e0f>] system_call+0xef/0x15c
[ 272.697446]
[ 272.697450] => dump-end timestamp 176397858
[ 272.697451]
[ 272.847183] ( modprobe-1857 |#2): new 149953 us maximum-latency critical section.
[ 272.847186] => started at timestamp 176397924: <__spin_lock_irqsave+0x18/0x42>
[ 272.847190] => ended at timestamp 176547875: <release_console_sem+0x1ae/0x1fa>
[ 272.847193]
[ 272.847194] Call Trace:
[ 272.847197] [<ffffffff80255e74>] check_critical_timing+0x1e6/0x2f7
[ 272.847201] [<ffffffff8022be60>] release_console_sem+0x1ae/0x1fa
[ 272.847206] [<ffffffff8022be60>] release_console_sem+0x1ae/0x1fa
[ 272.847210] [<ffffffff80256323>] trace_hardirqs_on+0x9a/0xb9
[ 272.847215] [<ffffffff8022be60>] release_console_sem+0x1ae/0x1fa
[ 272.847219] [<ffffffff8022c4e3>] vprintk+0x2a4/0x301
[ 272.847224] [<ffffffff80255f76>] check_critical_timing+0x2e8/0x2f7
[ 272.847229] [<ffffffff8022c5ac>] printk+0x6c/0x6e
[ 272.847232] [<ffffffff80256323>] trace_hardirqs_on+0x9a/0xb9
[ 272.847237] [<ffffffff80429572>] __spin_unlock_irq+0x14/0x17
[ 272.847241] [<ffffffff80429572>] __spin_unlock_irq+0x14/0x17
[ 272.847245] [<ffffffff8042785c>] wait_for_completion+0xbd/0xc6
[ 272.847249] [<ffffffff802262f9>] default_wake_function+0x0/0x19
[ 272.847253] [<ffffffff8042847a>] mutex_unlock+0x9/0x15
[ 272.847257] [<ffffffff80238dec>] blocking_notifier_call_chain+0x10/0x4e
[ 272.847262] [<ffffffff8800a02a>] :tun:tun_init+0x2a/0x6b
[ 272.847266] [<ffffffff8024d597>] sys_init_module+0x15d6/0x1731
[ 272.847272] [<ffffffff80256323>] trace_hardirqs_on+0x9a/0xb9
[ 272.847277] [<ffffffff803d2b89>] register_netdevice+0x0/0x31e
[ 272.847282] [<ffffffff80209e0f>] system_call+0xef/0x15c
[ 272.847287]
[ 272.847289] => dump-end timestamp 176547985
[ 272.847291]
[ 272.997056] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
next prev parent reply other threads:[~2007-03-27 19:42 UTC|newest]
Thread overview: 14+ messages / expand[flat|nested] mbox.gz Atom feed top
2007-03-25 5:03 Early hang with 2.6.21-rc4-rt1 Roland Dreier
2007-03-25 5:29 ` Roland Dreier
2007-03-25 7:29 ` Ingo Molnar
2007-03-25 7:34 ` Ingo Molnar
2007-03-25 8:06 ` Ingo Molnar
2007-03-25 16:27 ` Roland Dreier
2007-03-25 7:31 ` Ingo Molnar
2007-03-25 14:35 ` Roland Dreier
2007-03-25 17:01 ` Roland Dreier
2007-03-27 19:22 ` Ingo Molnar
2007-03-27 19:24 ` Ingo Molnar
2007-03-27 19:42 ` Roland Dreier [this message]
2007-03-30 9:02 ` Ingo Molnar
2007-03-30 9:24 ` Ingo Molnar
Reply instructions:
You may reply publicly to this message via plain-text email
using any one of the following methods:
* Save the following mbox file, import it into your mail client,
and reply-to-all from there: mbox
Avoid top-posting and favor interleaved quoting:
https://en.wikipedia.org/wiki/Posting_style#Interleaved_style
* Reply using the --to, --cc, and --in-reply-to
switches of git-send-email(1):
git send-email \
--in-reply-to=ada648mo4md.fsf@cisco.com \
--to=rdreier@cisco.com \
--cc=linux-kernel@vger.kernel.org \
--cc=mingo@elte.hu \
/path/to/YOUR_REPLY
https://kernel.org/pub/software/scm/git/docs/git-send-email.html
* If your mail client supports setting the In-Reply-To header
via mailto: links, try the mailto: link
Be sure your reply has a Subject: header at the top and a blank line
before the message body.
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox