Re: PROBLEM: Can't boot a (HZ = 1000) kernel using an AMD Phenom-II processor

[Mark Hounschell <dmarkh@cfl.rr.com>]

Mark Hounschell wrote:
> Mark Hounschell wrote:
>> Borislav Petkov wrote:
>>> On Mon, Jan 26, 2009 at 01:26:58PM -0500, Mark Hounschell wrote:
>>>
>>> [.. ]
>>>
>>>> If there is anything else I can try, I'm at your disposal.
>>> Ok, I just boot-tested a 1000 HZ kernel here and I can't reproduce
>>> your problem. Can you please send the failing .config. And also where
>>> exactly does your kernel hang during the boot process, can you capture
>>> any output and/or enable SysRq to dump backtrace on all cores (SysRq-T)
>>> if possible. Finally, what does 'cat /proc/timer_list' on the working
>>> kernel say?
>>>
>>> Thanks.
>>>
>>
>> You test booted using a Phenom-II?
>>
>> The config file is attached. The timer_list while running the 250 Hz 2.6.26.8
>> kernel output is below. When it happens I have no keyboard at all. SysRq is not
>> functional at this time. Sorry.
>>
>> Sorry it took so long. I was out of town today.
>>
>> Timer List Version: v0.3
>> HRTIMER_MAX_CLOCK_BASES: 2
>> now at 675544989236 nsecs
>>
>> cpu: 0
>>  clock 0:
>>   .index:      0
>>   .resolution: 1 nsecs
>>   .get_time:   ktime_get_real
>>   .offset:     1233101890018929078 nsecs
>> active timers:
>>  clock 1:
>>   .index:      1
>>   .resolution: 1 nsecs
>>   .get_time:   ktime_get
>>   .offset:     0 nsecs
>> active timers:
>>  #0: <f31ffec0>, tick_sched_timer, S:01, tick_setup_sched_timer, swapper/0
>>  # expires at 675548000000 nsecs [in 3010764 nsecs]
>>   .expires_next   : 675548000000 nsecs
>>   .hres_active    : 1
>>   .nr_events      : 170138
>>   .nohz_mode      : 0
>>   .idle_tick      : 0 nsecs
>>   .tick_stopped   : 0
>>   .idle_jiffies   : 0
>>   .idle_calls     : 0
>>   .idle_sleeps    : 0
>>   .idle_entrytime : 0 nsecs
>>   .idle_waketime  : 0 nsecs
>>   .idle_exittime  : 0 nsecs
>>   .idle_sleeptime : 0 nsecs
>>   .last_jiffies   : 0
>>   .next_jiffies   : 0
>>   .idle_expires   : 0 nsecs
>> jiffies: 93886
>>
>> cpu: 1
>>  clock 0:
>>   .index:      0
>>   .resolution: 1 nsecs
>>   .get_time:   ktime_get_real
>>   .offset:     1233101890018929078 nsecs
>> active timers:
>>  clock 1:
>>   .index:      1
>>   .resolution: 1 nsecs
>>   .get_time:   ktime_get
>>   .offset:     0 nsecs
>> active timers:
>>  #0: <f31ffec0>, tick_sched_timer, S:01, tick_setup_sched_timer, swapper/0
>>  # expires at 675548500000 nsecs [in 3510764 nsecs]
>>  #1: <f31ffec0>, it_real_fn, S:01, do_setitimer, ntpd/4446
>>  # expires at 676081032589 nsecs [in 536043353 nsecs]
>>  #2: <f31ffec0>, hrtimer_wakeup, S:01, futex_wait, nscd/4456
>>  # expires at 683136927993 nsecs [in 7591938757 nsecs]
>>  #3: <f31ffec0>, hrtimer_wakeup, S:01, do_nanosleep, cron/4539
>>  # expires at 711791265959 nsecs [in 36246276723 nsecs]
>>  #4: <f31ffec0>, it_real_fn, S:01, do_setitimer, qmgr/4549
>>  # expires at 1001716635269 nsecs [in 326171646033 nsecs]
>>  #5: <f31ffec0>, it_real_fn, S:01, do_setitimer, master/4517
>>  # expires at 1001716661669 nsecs [in 326171672433 nsecs]
>>  #6: <f31ffec0>, hrtimer_wakeup, S:01, futex_wait, nscd/4459
>>  # expires at 28868136987269 nsecs [in 28192591998033 nsecs]
>>   .expires_next   : 675548500000 nsecs
>>   .hres_active    : 1
>>   .nr_events      : 169510
>>   .nohz_mode      : 0
>>   .idle_tick      : 0 nsecs
>>   .tick_stopped   : 0
>>   .idle_jiffies   : 0
>>   .idle_calls     : 0
>>   .idle_sleeps    : 0
>>   .idle_entrytime : 0 nsecs
>>   .idle_waketime  : 0 nsecs
>>   .idle_exittime  : 0 nsecs
>>   .idle_sleeptime : 0 nsecs
>>   .last_jiffies   : 0
>>   .next_jiffies   : 0
>>   .idle_expires   : 0 nsecs
>> jiffies: 93886
>>
>> cpu: 2
>>  clock 0:
>>   .index:      0
>>   .resolution: 1 nsecs
>>   .get_time:   ktime_get_real
>>   .offset:     1233101890018929078 nsecs
>> active timers:
>>  clock 1:
>>   .index:      1
>>   .resolution: 1 nsecs
>>   .get_time:   ktime_get
>>   .offset:     0 nsecs
>> active timers:
>>  #0: <f31ffec0>, tick_sched_timer, S:01, tick_setup_sched_timer, swapper/0
>>  # expires at 675549000000 nsecs [in 4010764 nsecs]
>>  #1: <f31ffec0>, it_real_fn, S:01, do_setitimer, Xorg/3325
>>  # expires at 675563416492 nsecs [in 18427256 nsecs]
>>  #2: <f31ffec0>, hrtimer_wakeup, S:01, do_nanosleep, kwrapper/4982
>>  # expires at 676109815468 nsecs [in 564826232 nsecs]
>>  #3: <f31ffec0>, hrtimer_wakeup, S:01, futex_wait, nscd/4458
>>  # expires at 683435589386 nsecs [in 7890600150 nsecs]
>>  #4: <f31ffec0>, it_real_fn, S:01, do_setitimer, pickup/4548
>>  # expires at 1001716659224 nsecs [in 326171669988 nsecs]
>>   .expires_next   : 675549000000 nsecs
>>   .hres_active    : 1
>>   .nr_events      : 172253
>>   .nohz_mode      : 0
>>   .idle_tick      : 0 nsecs
>>   .tick_stopped   : 0
>>   .idle_jiffies   : 0
>>   .idle_calls     : 0
>>   .idle_sleeps    : 0
>>   .idle_entrytime : 0 nsecs
>>   .idle_waketime  : 0 nsecs
>>   .idle_exittime  : 0 nsecs
>>   .idle_sleeptime : 0 nsecs
>>   .last_jiffies   : 0
>>   .next_jiffies   : 0
>>   .idle_expires   : 0 nsecs
>> jiffies: 93886
>>
>> cpu: 3
>>  clock 0:
>>   .index:      0
>>   .resolution: 1 nsecs
>>   .get_time:   ktime_get_real
>>   .offset:     1233101890018929078 nsecs
>> active timers:
>>  clock 1:
>>   .index:      1
>>   .resolution: 1 nsecs
>>   .get_time:   ktime_get
>>   .offset:     0 nsecs
>> active timers:
>>  #0: <f31ffec0>, tick_sched_timer, S:01, tick_setup_sched_timer, swapper/0
>>  # expires at 675545500000 nsecs [in 510764 nsecs]
>>  #1: <f31ffec0>, sched_rt_period_timer, S:01, enqueue_task_rt, swapper/1
>>  # expires at 676000000000 nsecs [in 455010764 nsecs]
>>  #2: <f31ffec0>, hrtimer_wakeup, S:01, futex_wait, thunderbird-bin/5123
>>  # expires at 689314480389 nsecs [in 13769491153 nsecs]
>>  #3: <f31ffec0>, hrtimer_wakeup, S:01, futex_wait, thunderbird-bin/5209
>>  # expires at 689361744389 nsecs [in 13816755153 nsecs]
>>  #4: <f31ffec0>, hrtimer_wakeup, S:01, futex_wait, nscd/4457
>>  # expires at 3668136917342 nsecs [in 2992591928106 nsecs]
>>   .expires_next   : 675545500000 nsecs
>>   .hres_active    : 1
>>   .nr_events      : 171267
>>   .nohz_mode      : 0
>>   .idle_tick      : 0 nsecs
>>   .tick_stopped   : 0
>>   .idle_jiffies   : 0
>>   .idle_calls     : 0
>>   .idle_sleeps    : 0
>>   .idle_entrytime : 0 nsecs
>>   .idle_waketime  : 0 nsecs
>>   .idle_exittime  : 0 nsecs
>>   .idle_sleeptime : 0 nsecs
>>   .last_jiffies   : 0
>>   .next_jiffies   : 0
>>   .idle_expires   : 0 nsecs
>> jiffies: 93886
>>
>>
>> Tick Device: mode:     1
>> Clock Event Device: hpet
>>  max_delta_ns:   2147483647
>>  min_delta_ns:   5000
>>  mult:           61496114
>>  shift:          32
>>  mode:           3
>>  next_event:     9223372036854775807 nsecs
>>  set_next_event: hpet_legacy_next_event
>>  set_mode:       hpet_legacy_set_mode
>>  event_handler:  tick_handle_oneshot_broadcast
>> tick_broadcast_mask: 00000000
>> tick_broadcast_oneshot_mask: 00000000
>>
>>
>> Tick Device: mode:     1
>> Clock Event Device: lapic
>>  max_delta_ns:   671091850
>>  min_delta_ns:   1200
>>  mult:           53686828
>>  shift:          32
>>  mode:           3
>>  next_event:     675548000000 nsecs
>>  set_next_event: lapic_next_event
>>  set_mode:       lapic_timer_setup
>>  event_handler:  hrtimer_interrupt
>>
>> Tick Device: mode:     1
>> Clock Event Device: lapic
>>  max_delta_ns:   671091850
>>  min_delta_ns:   1200
>>  mult:           53686828
>>  shift:          32
>>  mode:           3
>>  next_event:     675548500000 nsecs
>>  set_next_event: lapic_next_event
>>  set_mode:       lapic_timer_setup
>>  event_handler:  hrtimer_interrupt
>>
>> Tick Device: mode:     1
>> Clock Event Device: lapic
>>  max_delta_ns:   671091850
>>  min_delta_ns:   1200
>>  mult:           53686828
>>  shift:          32
>>  mode:           3
>>  next_event:     675549000000 nsecs
>>  set_next_event: lapic_next_event
>>  set_mode:       lapic_timer_setup
>>  event_handler:  hrtimer_interrupt
>>
>> Tick Device: mode:     1
>> Clock Event Device: lapic
>>  max_delta_ns:   671091850
>>  min_delta_ns:   1200
>>  mult:           53686828
>>  shift:          32
>>  mode:           3
>>  next_event:     675545500000 nsecs
>>  set_next_event: lapic_next_event
>>  set_mode:       lapic_timer_setup
>>  event_handler:  hrtimer_interrupt
>>
>>
>>
>> Regards
>> Mark
>>
> 
> Oh I Forgot. The last thing I see on the screen is.
> 
> Checking if this processor honours the WP bit even in hypervisor mode... OK.
> SLUB: Genslabs=12, HWalign=64, Order=0,3, MinObjects=0, CPUs=4, Nodes=1
> 
> Mark
> --

So were you able to reproduce this with my .config. Has anyone booted a Hz=1000
kernel on an AMD Phenom-II? Is there something wrong with my .config? Is there
anything else I can do?

Thanks
Mark