先日のエントリで、kernel build中(というより終了後)にNMI関連のカーネルログを吐き出してて動作が数分かたまるという現象が発生した件について。最初、久々に取り付けたSSD(X25-V)が壊れてしまってたかと思ったけど全然そんなことはなく、CONCURRENCY_LEVELを上げたことによって発生していたことが分かった。今までkernelなんて出来ればいいやくらいにしか思ってなかったから、CONCURRENCY_LEVELをいじって作業することなんてなかったなあそういえば。
SSDを取り外してやってみても発生したものの、CONCURRENCY_LEVELを無指定なら発生せず。CONCURRENCY_LEVELは8でも4でも発生して2では出なかった。この違いは何かな。何か大切なor初歩的なkernelオプションの指定が漏れてるだけだったり、な予感が。
Sep 17 23:29:33 mary kernel: [ 1616.681497] INFO: rcu_sched_state detected stalls on CPUs/tasks: { 7} (detected by 6, t=67358 jiffies)
Sep 17 23:29:33 mary kernel: [ 1616.681821] sending NMI to all CPUs:
Sep 17 23:29:33 mary kernel: [ 1616.681884] NMI backtrace for cpu 0
Sep 17 23:29:33 mary kernel: [ 1616.681886] CPU 0
Sep 17 23:29:33 mary kernel: [ 1616.681887] Modules linked in: ipv6 ext2 mbcache sbp2 ieee1394 loop snd_hda_codec_nvhdmi snd_hda_codec_realtek snd_hda_intel snd_hda_codec parport_pc parport video output snd_pcm snd_timer snd i2c_i801 i2c_core soundcore tpm_tis tpm snd_page_alloc tpm_bios button processor pcspkr evdev xfs exportfs sd_mod ata_piix ata_generic libata scsi_mod ide_pci_generic e1000e ide_core ehci_hcd thermal fan thermal_sys [last unloaded: scsi_wait_scan]
Sep 17 23:29:33 mary kernel: [ 1616.681908]
Sep 17 23:29:33 mary kernel: [ 1616.681910] Pid: 0, comm: swapper Not tainted 2.6.35.4 #1 DH55TC/
Sep 17 23:29:33 mary kernel: [ 1616.681911] RIP: 0010:[<ffffffff81009fdc>] [<ffffffff81009fdc>] mwait_idle_with_hints+0xaa/0xb2
Sep 17 23:29:33 mary kernel: [ 1616.681916] RSP: 0018:ffffffff813c9e88 EFLAGS: 00000046
Sep 17 23:29:33 mary kernel: [ 1616.681918] RAX: 0000000000000020 RBX: 11d1dc774d79458d RCX: 0000000000000001
Sep 17 23:29:33 mary kernel: [ 1616.681919] RDX: 0000000000000000 RSI: ffffffff813c9fd8 RDI: 0000000000000020
Sep 17 23:29:33 mary kernel: [ 1616.681921] RBP: 0000000000000003 R08: ffffffffa01d745c R09: ffff88023f26300c
Sep 17 23:29:33 mary kernel: [ 1616.681923] R10: 0000000100000000 R11: ffffffff813c9e74 R12: 0000000000000020
Sep 17 23:29:33 mary kernel: [ 1616.681925] R13: 0000000000000001 R14: ffffffff814c87e0 R15: 0000000001592df8
Sep 17 23:29:33 mary kernel: [ 1616.681927] FS: 0000000000000000(0000) GS:ffff880001800000(0000) knlGS:0000000000000000
Sep 17 23:29:33 mary kernel: [ 1616.681930] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Sep 17 23:29:33 mary kernel: [ 1616.681932] CR2: 00007f75164a7000 CR3: 000000000141b000 CR4: 00000000000006f0
Sep 17 23:29:33 mary kernel: [ 1616.681934] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Sep 17 23:29:33 mary kernel: [ 1616.681936] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Sep 17 23:29:33 mary kernel: [ 1616.681938] Process swapper (pid: 0, threadinfo ffffffff813c8000, task ffffffff81423020)
Sep 17 23:29:33 mary kernel: [ 1616.681940] Stack:
Sep 17 23:29:33 mary kernel: [ 1616.682044] ffff88023f263020 11d1dc774d79458d ffff88023f263500 ffff88023f263020
Sep 17 23:29:33 mary kernel: [ 1616.682047] <0> ffff88023f263000 ffffffffa01d73b5 0000000000000000 00000000000001eb
Sep 17 23:29:33 mary kernel: [ 1616.682050] <0> 0000000000000000 ffff88023f263150 ffff88023f263020 6db6db6db6db6db7
Sep 17 23:29:33 mary kernel: [ 1616.682054] Call Trace:
Sep 17 23:29:33 mary kernel: [ 1616.682161] [<ffffffffa01d73b5>] ? acpi_idle_enter_bm+0x204/0x2e2 [processor]
Sep 17 23:29:33 mary kernel: [ 1616.682166] [<ffffffff812225e5>] ? cpuidle_idle_call+0x95/0xf4
Sep 17 23:29:33 mary kernel: [ 1616.682169] [<ffffffff81001db1>] ? cpu_idle+0x59/0x91
Sep 17 23:29:33 mary kernel: [ 1616.682173] [<ffffffff81491140>] ? early_idt_handler+0x0/0x71
Sep 17 23:29:33 mary kernel: [ 1616.682176] [<ffffffff81491d05>] ? start_kernel+0x3a0/0x3ac
Sep 17 23:29:33 mary kernel: [ 1616.682180] [<ffffffff814a92be>] ? __reserve_early+0xa4/0xba
Sep 17 23:29:33 mary kernel: [ 1616.682183] [<ffffffff814913a2>] ? x86_64_start_kernel+0xf9/0x106
Sep 17 23:29:33 mary kernel: [ 1616.682185] Code: 65 48 8b 34 25 48 b5 00 00 48 89 ca 48 8d 86 38 e0 ff ff 0f 01 c8 0f ae f0 f6 86 38 e0 ff ff 08 75 09 4c 89 e0 4c 89 e9 0f 01 c9 <59> 5b 5d 41 5c 41 5d c3 53 65 48 8b 04 25 48 b5 00 00 f6 80 38
Sep 17 23:29:33 mary kernel: [ 1616.688199] Call Trace:
Sep 17 23:29:33 mary kernel: [ 1616.688202] [<ffffffffa01d73b5>] ? acpi_idle_enter_bm+0x204/0x2e2 [processor]
Sep 17 23:29:33 mary kernel: [ 1616.688206] [<ffffffff812225e5>] ? cpuidle_idle_call+0x95/0xf4
Sep 17 23:29:33 mary kernel: [ 1616.688208] [<ffffffff81001db1>] ? cpu_idle+0x59/0x91
Sep 17 23:29:33 mary kernel: [ 1616.688211] [<ffffffff81491140>] ? early_idt_handler+0x0/0x71
Sep 17 23:29:33 mary kernel: [ 1616.688214] [<ffffffff81491d05>] ? start_kernel+0x3a0/0x3ac
Sep 17 23:29:33 mary kernel: [ 1616.688216] [<ffffffff814a92be>] ? __reserve_early+0xa4/0xba
Sep 17 23:29:33 mary kernel: [ 1616.688219] [<ffffffff814913a2>] ? x86_64_start_kernel+0xf9/0x106
Sep 17 23:29:33 mary kernel: [ 1616.688222] Pid: 0, comm: swapper Not tainted 2.6.35.4 #1
