2007-05-02 21:42:48

by Michal Piotrowski

[permalink] [raw]
Subject: 2.6.21-git4 BUG: soft lockup detected on CPU#1!

Hi Thomas,

I hit this soft lockup

[ 5401.599275] BUG: soft lockup detected on CPU#1!
[ 5401.603809] [<c0105039>] show_trace_log_lvl+0x1a/0x2f
[ 5401.608980] [<c0105720>] show_trace+0x12/0x14
[ 5401.613456] [<c01057d2>] dump_stack+0x16/0x18
[ 5401.617934] [<c01518e8>] softlockup_tick+0xa7/0xb9
[ 5401.622841] [<c012a0b5>] run_local_timers+0x12/0x14
[ 5401.627829] [<c012a482>] update_process_times+0x3e/0x63
[ 5401.633164] [<c013a028>] tick_sched_timer+0x5d/0xa1
[ 5401.638160] [<c01366fb>] hrtimer_interrupt+0x127/0x1b4
[ 5401.643415] [<c0114c66>] smp_apic_timer_interrupt+0x72/0x84
[ 5401.649097] [<c0104b2b>] apic_timer_interrupt+0x33/0x38
[ 5401.654437] =======================
[ 5401.658055] Clocksource tsc unstable (delta = 9372000804 ns)
[ 5401.664061] Time: acpi_pm clocksource has been installed.

http://www.stardust.webpages.pl/files/tbf/bitis-gabonica/2.6.21-git4/git-config
http://www.stardust.webpages.pl/files/tbf/bitis-gabonica/2.6.21-git4/git-dmesg

Regards,
Michal

--
Michal K. K. Piotrowski
Kernel Monkeys
(http://kernel.wikidot.com/start)


2007-05-02 21:46:56

by Thomas Gleixner

[permalink] [raw]
Subject: Re: 2.6.21-git4 BUG: soft lockup detected on CPU#1!

On Wed, 2007-05-02 at 23:42 +0200, Michal Piotrowski wrote:
> Hi Thomas,
>
> I hit this soft lockup
>
> [ 5401.599275] BUG: soft lockup detected on CPU#1!
> [ 5401.603809] [<c0105039>] show_trace_log_lvl+0x1a/0x2f
> [ 5401.608980] [<c0105720>] show_trace+0x12/0x14
> [ 5401.613456] [<c01057d2>] dump_stack+0x16/0x18
> [ 5401.617934] [<c01518e8>] softlockup_tick+0xa7/0xb9
> [ 5401.622841] [<c012a0b5>] run_local_timers+0x12/0x14
> [ 5401.627829] [<c012a482>] update_process_times+0x3e/0x63
> [ 5401.633164] [<c013a028>] tick_sched_timer+0x5d/0xa1
> [ 5401.638160] [<c01366fb>] hrtimer_interrupt+0x127/0x1b4
> [ 5401.643415] [<c0114c66>] smp_apic_timer_interrupt+0x72/0x84
> [ 5401.649097] [<c0104b2b>] apic_timer_interrupt+0x33/0x38
> [ 5401.654437] =======================
> [ 5401.658055] Clocksource tsc unstable (delta = 9372000804 ns)
> [ 5401.664061] Time: acpi_pm clocksource has been installed.

Hmm. Looks like TSC going awry and the softlockup hits before the tsc
watchdog logic kicks in.

Was the system stable afterwards ?

tglx


2007-05-02 21:49:54

by Michal Piotrowski

[permalink] [raw]
Subject: Re: 2.6.21-git4 BUG: soft lockup detected on CPU#1!

On 02/05/07, Thomas Gleixner <[email protected]> wrote:
> On Wed, 2007-05-02 at 23:42 +0200, Michal Piotrowski wrote:
> > Hi Thomas,
> >
> > I hit this soft lockup
> >
> > [ 5401.599275] BUG: soft lockup detected on CPU#1!
> > [ 5401.603809] [<c0105039>] show_trace_log_lvl+0x1a/0x2f
> > [ 5401.608980] [<c0105720>] show_trace+0x12/0x14
> > [ 5401.613456] [<c01057d2>] dump_stack+0x16/0x18
> > [ 5401.617934] [<c01518e8>] softlockup_tick+0xa7/0xb9
> > [ 5401.622841] [<c012a0b5>] run_local_timers+0x12/0x14
> > [ 5401.627829] [<c012a482>] update_process_times+0x3e/0x63
> > [ 5401.633164] [<c013a028>] tick_sched_timer+0x5d/0xa1
> > [ 5401.638160] [<c01366fb>] hrtimer_interrupt+0x127/0x1b4
> > [ 5401.643415] [<c0114c66>] smp_apic_timer_interrupt+0x72/0x84
> > [ 5401.649097] [<c0104b2b>] apic_timer_interrupt+0x33/0x38
> > [ 5401.654437] =======================
> > [ 5401.658055] Clocksource tsc unstable (delta = 9372000804 ns)
> > [ 5401.664061] Time: acpi_pm clocksource has been installed.
>
> Hmm. Looks like TSC going awry and the softlockup hits before the tsc
> watchdog logic kicks in.
>
> Was the system stable afterwards ?

It works.

>
> tglx
>
>
>

Regards,
Michal

--
Michal K. K. Piotrowski
Kernel Monkeys
(http://kernel.wikidot.com/start)

2007-05-02 22:41:34

by Thomas Gleixner

[permalink] [raw]
Subject: Re: 2.6.21-git4 BUG: soft lockup detected on CPU#1!

On Wed, 2007-05-02 at 23:49 +0200, Michal Piotrowski wrote:
> On 02/05/07, Thomas Gleixner <[email protected]> wrote:
> > On Wed, 2007-05-02 at 23:42 +0200, Michal Piotrowski wrote:
> > > Hi Thomas,
> > >
> > > I hit this soft lockup
> > >
> > > [ 5401.599275] BUG: soft lockup detected on CPU#1!
> > > [ 5401.603809] [<c0105039>] show_trace_log_lvl+0x1a/0x2f
> > > [ 5401.608980] [<c0105720>] show_trace+0x12/0x14
> > > [ 5401.613456] [<c01057d2>] dump_stack+0x16/0x18
> > > [ 5401.617934] [<c01518e8>] softlockup_tick+0xa7/0xb9
> > > [ 5401.622841] [<c012a0b5>] run_local_timers+0x12/0x14
> > > [ 5401.627829] [<c012a482>] update_process_times+0x3e/0x63
> > > [ 5401.633164] [<c013a028>] tick_sched_timer+0x5d/0xa1
> > > [ 5401.638160] [<c01366fb>] hrtimer_interrupt+0x127/0x1b4
> > > [ 5401.643415] [<c0114c66>] smp_apic_timer_interrupt+0x72/0x84
> > > [ 5401.649097] [<c0104b2b>] apic_timer_interrupt+0x33/0x38
> > > [ 5401.654437] =======================
> > > [ 5401.658055] Clocksource tsc unstable (delta = 9372000804 ns)
> > > [ 5401.664061] Time: acpi_pm clocksource has been installed.
> >
> > Hmm. Looks like TSC going awry and the softlockup hits before the tsc
> > watchdog logic kicks in.
> >
> > Was the system stable afterwards ?
>
> It works.

Ok. So it's more annoyance than a problem.

Nevertheless, I keep it in my list of things to look at.

tglx