Today I found strange bug in kernel. My system was very slow and I found dd and klogd are taking 100% of my cpu.
I take a look in /var/log/kern.log and saw those errors:
Oct 20 05:40:35 michallo-laptop kernel: [21300.953136] CE: hpet increasing min_delta_ns to 33750 nsec
Oct 20 06:02:06 michallo-laptop kernel: [22591.473112] CE: hpet increasing min_delta_ns to 50624 nsec
Oct 20 07:00:34 michallo-laptop kernel: [26099.438256] BUG: scheduling while atomic: swapper/0/0x00000100
Oct 20 07:00:34 michallo-laptop kernel: [26099.438265] Modules linked in: af_packet binfmt_misc sco bridge stp rfcomm bnep l2cap ipv6 ppdev acpi_cpufreq cpufreq_ondemand cpufreq_userspace cpufreq_stats freq_
table cpufreq_powersave cpufreq_conservative pci_slot sbs sbshc container iptable_filter ip_tables x_tables sbp2 parport_pc lp parport joydev arc4 ecb crypto_blkcipher dcdbas psmouse serio_raw pcspkr iwlagn
btusb bluetooth iwlcore snd_hda_intel evdev rfkill snd_pcm_oss led_class snd_mixer_oss mac80211 snd_pcm sdhci_pci sdhci cfg80211 uvcvideo mmc_core compat_ioctl32 videodev ricoh_mmc v4l1_compat iTCO_wdt snd_s
eq_dummy iTCO_vendor_support nvidia(P) snd_seq_oss i2c_core video output snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device snd battery wmi button soundcore ac shpchp pci_hotplug sn
d_page_alloc intel_agp agpgart ext3 jbd mbcache usb_storage sr_mod cdrom usbhid hid sd_mod crc_t10dif sg ata_piix ata_generic libusual ahci pata_acpi ohci1394 slI imt/so9lp2_4t_k8_+l+0er/cshp_uh 45667d4 /69
Oct 20 07:00:34 michallo-laptop kernel: >7e>19at3 r<lel tng b .0<,1exf0b11e x] 341>t 2.8ohoc2_ern
Oct 20 07:00:34 michallo-laptop kernel: fxx3s6[e3lepi_nre.:s<cas95mwi]101c10n[/9r9[>pr1Tratd0]0<: t05[_20.[n00/>>5.>e20eendete2 300 sec>p2t>01aes]x0x02a8 snodcl>P<c4 ca21 a0ex0<e4]<769 p_
Oct 20 07:00:34 michallo-laptop kernel: qaas _0>0]0re
Oct 20 07:00:34 michallo-laptop kernel: >l4d4__i]0o1x14 b=ro_erta[G0S:6:d<m4<8 2 9 >]0a000
Oct 20 07:00:34 michallo-laptop kernel: .ttcvo ->E1D[9+14a?et[74 4>4u02xd3kus rskbI]60f=0< 1xa90[447<00t 40=]ulrctictlD:09<f1740mxa<1[sr>_+b6=44cbimts4P65R>0-vc>tpdhe22>9
Oct 20 07:00:34 michallo-laptop kernel: ad9=4bmbl_qisW 954x]4/9<kbpe 03x40o2x=0ecsp_q_de[7cC0c= 02u 2c0be20+cc>.il sipri_io447R 4e4 6nb4i5]4 a7fs4xgaocv ewr d2
Oct 20 07:00:34 michallo-laptop kernel: b[+[r1eetzceed50<?l>mtausrwmeehg47 0 r<>1eetzceed57<?l>mtausrwmvn :i0 l2 ea]e5cr604x20?00>mtuesrl_eehg< :f6cr[0 efzceed57<?l>mtausrrl_c n02 u=:8a9e5cr6.4520?00>mtues
rl_eehc47 05r4[0
Oct 20 07:00:34 michallo-laptop kernel: cceed50<?l>mtausrw_eehg47 05u4>1eet.c30x20?00>mtuesrl_eehg< 05u4>1eetzceed50<?l>mtausrrlvn :i02 l2 ea]e5cae1_520?00>mtuesrl_eehg47:06u>[0
Oct 20 07:00:34 michallo-laptop kernel: cc9e<50<?l>mtausrrl_c 00G0.=0
Oct 20 07:00:34 michallo-laptop kernel: 1 etzceed57<?l>mtausrrl_c n0. [=:ea]e5cr6.4520?00>mtuesrl_eehg47 06u>[0
Oct 20 07:00:34 michallo-laptop kernel: cc9 <570?0>mdturr1ecl:0ED2l<>i>1eetzce <520?i>4mtusersrnsh
Oct 20 07:00:34 michallo-laptop kernel: 740GC:C20 R04l.u05e4=2 uh1r r<068k[>a>]019] e e]
Oct 20 07:00:34 michallo-laptop kernel: [eo55ftdc0cz.4a ccf609slot ert_pc rrwlsd1lm_rie_vencorestl sd2:n g68>ir<::00074D:20 0R0 f2 4[l6 < =4ce>m:->601e_>a>]019] e e]
Oct 20 07:00:34 michallo-laptop kernel: [eo55ftdc0c0.4ra crf60930.1
Oct 20 07:00:34 michallo-laptop kernel: 04_0<dx5>] c260270d8>] <4? acpi09ei0l0>[26094mic: swdt_miscaufreq_ueslot sert_pc rrwlsd1lm_rie_vencornestl dh2:n g68>ir<::00074DG2000R
Oct 20 07:00:34 michallo-laptop kernel: f2 4[l6 < =4ce>m:-46
Oct 20 07:00:34 michallo-laptop kernel: [e[>a>]019] e e]
Oct 20 07:00:34 michallo-laptop kernel: [eo55ftdc0c0.4ra crf6e9e301
Oct 20 07:00:34 michallo-laptop kernel: e440<dx5>] c260270d8>] <4? acpi_0ei0l0>[26094mic: swt_miscaufreq_ueslot sert_pc rrwlsd1lm_rie_vencornestl sh2:n g
Oct 20 07:00:34 michallo-laptop kernel: 8>ir<::00074D: 0 0R0 f2]4[l6 < =4ue1m -46
Oct 20 07:00:34 michallo-laptop kernel: [e[>a>]019] e e]
Oct 20 07:00:34 michallo-laptop kernel: [eo55ftdc0c0.4ra crf6e0e30.
...
...
ct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c036edb3>] rest_init+0x53/0x60
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] =======================
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] bad: scheduling f8/0x100
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c0137682>] __do_softirq+0x92/0x120
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c013776d>] do_softirq+0x5d/0x60
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c01378e5>] irq_exit+0x55/0x90
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c0106c1a>] do_IRQ+0x4a/0x80
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c0105003>] common_interrupt+0x23/0x30
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c01700d8>] ? __audit_mq_getsetattr+0x68/0xb0
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<f885a800>] ? acpi_idle_enter_bm+0x268/0x2b7 [processor]
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c02dbf6b>] cpuidle_idle_call+0x7b/0xd0
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c010288d>] cpu_idle+0x7d/0x140
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c036edb3>] rest_init+0x53/0x60
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] =======================
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] BUG: scheduling while atomic: swapper/0/0x00000100
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] Modules linked in: af_packet binfmt_misc sco bridge stp rfcomm bnep l2cap ipv6 ppdev acpi_cpufreq cpufreq_ondemand cpufreq_userspace cpufreq_stats freq_
table cpufreq_powersave cpufreq_conservative pci_slot sbs sbshc container iptable_filter ip_tables x_tables sbp2 parport_pc lp parport joydev arc4 ecb crypto_blkcipher dcdbas psmouse serio_raw pcspkr iwlagn
btusb bluetooth iwlcore snd_hda_intel evdev rfkill snd_pcm_oss led_class snd_mixer_oss mac80211 snd_pcm sdhci_pci sdhci cfg80211 uvcvideo mmc_core compat_ioctl32 videodev ricoh_mmc v4l1_compat iTCO_wdt snd_s
eq_dummy iTCO_vendor_support nvidia(P) snd_seq_oss i2c_core video output snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device snd battery wmi button soundcore ac shpchp pci_hotplug sn
d_page_alloc intel_agp agpgart ext3 jbd mbcache usb_storage sr_mod cdrom usbhid hid sd_mod crc_t10dif sg ata_piix ata_generic libusual ahci pata_acpi ohci1394 sky2 libata scsi_mod dock ieee1394 uhci_hcd ehci
Oct 20 07:00:34 michallo-laptop kernel: hcd usbcore thermal processor fan fbcon tileblit font bitblit softcursor fuse
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015]
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] Pid: 0, comm: swapper Tainted: P W (2.6.27-7-generic #1)
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] EIP: 0060:[<f885a800>] EFLAGS: 00000246 CPU: 0
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] EIP is at acpi_idle_enter_bm+0x268/0x2b7 [processor]
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] EAX: c0510760 EBX: 0000daf7 ECX: 00000000 EDX: 01cfe000
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] ESI: 00000000 EDI: 009cbbdf EBP: c04a7f90 ESP: c04a7f74
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] CR0: 8005003b CR2: b59b3000 CR3: 00515000 CR4: 00000690
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] DR0: 00000000 DR1: 00000000 DR2: 00000000 DR3: 00000000
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] DR6: ffff0ff0 DR7: 00000400
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c02dbf6b>] cpuidle_idle_call+0x7b/0xd0
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c010288d>] cpu_idle+0x7d/0x140
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c036edb3>] rest_init+0x53/0x60
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] =======================
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] bad: scheduling from the idle thread!
...
Today I found strange bug in kernel. My system was very slow and I found dd and klogd are taking 100% of my cpu.
I take a look in /var/log/kern.log and saw those errors:
Oct 20 05:40:35 michallo-laptop kernel: [21300.953136] CE: hpet increasing min_delta_ns to 33750 nsec 0/0x00000100 conservative pci_slot sbs sbshc container iptable_filter ip_tables x_tables sbp2 parport_pc lp parport joydev arc4 ecb crypto_blkcipher dcdbas psmouse serio_raw pcspkr iwlagn 4t_k8_+ l+0er/cshp_ uh 45667d4 /69 e3lepi_ nre.:s< cas95mwi] 101c10n[ /9r9[>pr1Tratd0 ]0<: t05[_20. [n00/>> 5.>e20eendete2 300 sec>p2t> 01aes]x0x02a8 snodcl>P<c4 ca21 a0ex0<e4]<769 p_ G0S:6:d< m4<8 2 9 >]0a000 09<f1740mxa< 1[sr>_+ b6=44cbimts4P65 R>0-vc> tpdhe22> 9 0ecsp_q_ de[7cC0c= 02u 2c0be20+cc>.il sipri_io447R 4e4 6nb4i5]4 a7fs4xgaocv ewr d2 0<?l>mtausrwmee hg47 0 r<>1eetzceed57< ?l>mtausrwmvn :i0 l2 ea]e5cr604x20? 00>mtuesrl_ eehg< :f6cr[0 efzceed57< ?l>mtausrrl_ c n02 u=:8a9e5cr6. 4520?00> mtues ?l>mtausrw_ eehg47 05u4>1eet. c30x20? 00>mtuesrl_ eehg< 05u4>1eetzceed5 0<?l>mtausrrlvn :i02 l2 ea]e5cae1_ 520?00> mtuesrl_ eehg47: 06u>[0 ?l>mtausrrl_ c 00G0.=0 ?l>mtausrrl_ c n0. [=:ea]e5cr6. 4520?00> mtuesrl_ eehg47 06u>[0 mdturr1ecl: 0ED2l<> i>1eetzce <520?i>4mtusersrnsh rie_vencorestl sd2:n g68>ir<::00074D:20 0R0 f2 4[l6 < =4ce>m: ->601e_ >a>]019] e e] [26094mic: swdt_miscaufreq _ueslot sert_pc rrwlsd1lm_ rie_vencornestl dh2:n g68>ir< ::00074DG2000R [26094mic: swt_miscaufreq_ ueslot sert_pc rrwlsd1lm_ rie_vencornestl sh2:n g ======= ======= == 0x92/0x120 0x5d/0x60 interrupt+ 0x23/0x30 mq_getsetattr+ 0x68/0xb0 enter_bm+ 0x268/0x2b7 [processor] idle_call+ 0x7b/0xd0 ======= ======= == 0/0x00000100 conservative pci_slot sbs sbshc container iptable_filter ip_tables x_tables sbp2 parport_pc lp parport joydev arc4 ecb crypto_blkcipher dcdbas psmouse serio_raw pcspkr iwlagn enter_bm+ 0x268/0x2b7 [processor] idle_call+ 0x7b/0xd0 ======= ======= ==
Oct 20 06:02:06 michallo-laptop kernel: [22591.473112] CE: hpet increasing min_delta_ns to 50624 nsec
Oct 20 07:00:34 michallo-laptop kernel: [26099.438256] BUG: scheduling while atomic: swapper/
Oct 20 07:00:34 michallo-laptop kernel: [26099.438265] Modules linked in: af_packet binfmt_misc sco bridge stp rfcomm bnep l2cap ipv6 ppdev acpi_cpufreq cpufreq_ondemand cpufreq_userspace cpufreq_stats freq_
table cpufreq_powersave cpufreq_
btusb bluetooth iwlcore snd_hda_intel evdev rfkill snd_pcm_oss led_class snd_mixer_oss mac80211 snd_pcm sdhci_pci sdhci cfg80211 uvcvideo mmc_core compat_ioctl32 videodev ricoh_mmc v4l1_compat iTCO_wdt snd_s
eq_dummy iTCO_vendor_support nvidia(P) snd_seq_oss i2c_core video output snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device snd battery wmi button soundcore ac shpchp pci_hotplug sn
d_page_alloc intel_agp agpgart ext3 jbd mbcache usb_storage sr_mod cdrom usbhid hid sd_mod crc_t10dif sg ata_piix ata_generic libusual ahci pata_acpi ohci1394 slI imt/so9lp2_
Oct 20 07:00:34 michallo-laptop kernel: >7e>19at3 r<lel tng b .0<,1exf0b11e x] 341>t 2.8ohoc2_ern
Oct 20 07:00:34 michallo-laptop kernel: fxx3s6[
Oct 20 07:00:34 michallo-laptop kernel: qaas _0>0]0re
Oct 20 07:00:34 michallo-laptop kernel: >l4d4__i]0o1x14 b=ro_erta[
Oct 20 07:00:34 michallo-laptop kernel: .ttcvo ->E1D[9+14a?et[74 4>4u02xd3kus rskbI]60f=0< 1xa90[447<00t 40=]ulrctictlD:
Oct 20 07:00:34 michallo-laptop kernel: ad9=4bmbl_qisW 954x]4/9<kbpe 03x40o2x=
Oct 20 07:00:34 michallo-laptop kernel: b[+[r1eetzceed5
rl_eehc47 05r4[0
Oct 20 07:00:34 michallo-laptop kernel: cceed50<
Oct 20 07:00:34 michallo-laptop kernel: cc9e<50<
Oct 20 07:00:34 michallo-laptop kernel: 1 etzceed57<
Oct 20 07:00:34 michallo-laptop kernel: cc9 <570?0>
Oct 20 07:00:34 michallo-laptop kernel: 740GC:C20 R04l.u05e4=2 uh1r r<068k[>a>]019] e e]
Oct 20 07:00:34 michallo-laptop kernel: [eo55ftdc0cz.4a ccf609slot ert_pc rrwlsd1lm_
Oct 20 07:00:34 michallo-laptop kernel: [eo55ftdc0c0.4ra crf60930.1
Oct 20 07:00:34 michallo-laptop kernel: 04_0<dx5>] c260270d8>] <4? acpi09ei0l0>
Oct 20 07:00:34 michallo-laptop kernel: f2 4[l6 < =4ce>m:-46
Oct 20 07:00:34 michallo-laptop kernel: [e[>a>]019] e e]
Oct 20 07:00:34 michallo-laptop kernel: [eo55ftdc0c0.4ra crf6e9e301
Oct 20 07:00:34 michallo-laptop kernel: e440<dx5>] c260270d8>] <4? acpi_0ei0l0>
Oct 20 07:00:34 michallo-laptop kernel: 8>ir<::00074D: 0 0R0 f2]4[l6 < =4ue1m -46
Oct 20 07:00:34 michallo-laptop kernel: [e[>a>]019] e e]
Oct 20 07:00:34 michallo-laptop kernel: [eo55ftdc0c0.4ra crf6e0e30.
...
...
ct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c036edb3>] rest_init+0x53/0x60
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] =======
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] bad: scheduling f8/0x100
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c0137682>] __do_softirq+
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c013776d>] do_softirq+
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c01378e5>] irq_exit+0x55/0x90
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c0106c1a>] do_IRQ+0x4a/0x80
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c0105003>] common_
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c01700d8>] ? __audit_
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<f885a800>] ? acpi_idle_
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c02dbf6b>] cpuidle_
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c010288d>] cpu_idle+0x7d/0x140
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c036edb3>] rest_init+0x53/0x60
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] =======
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] BUG: scheduling while atomic: swapper/
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] Modules linked in: af_packet binfmt_misc sco bridge stp rfcomm bnep l2cap ipv6 ppdev acpi_cpufreq cpufreq_ondemand cpufreq_userspace cpufreq_stats freq_
table cpufreq_powersave cpufreq_
btusb bluetooth iwlcore snd_hda_intel evdev rfkill snd_pcm_oss led_class snd_mixer_oss mac80211 snd_pcm sdhci_pci sdhci cfg80211 uvcvideo mmc_core compat_ioctl32 videodev ricoh_mmc v4l1_compat iTCO_wdt snd_s
eq_dummy iTCO_vendor_support nvidia(P) snd_seq_oss i2c_core video output snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device snd battery wmi button soundcore ac shpchp pci_hotplug sn
d_page_alloc intel_agp agpgart ext3 jbd mbcache usb_storage sr_mod cdrom usbhid hid sd_mod crc_t10dif sg ata_piix ata_generic libusual ahci pata_acpi ohci1394 sky2 libata scsi_mod dock ieee1394 uhci_hcd ehci
Oct 20 07:00:34 michallo-laptop kernel: hcd usbcore thermal processor fan fbcon tileblit font bitblit softcursor fuse
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015]
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] Pid: 0, comm: swapper Tainted: P W (2.6.27-7-generic #1)
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] EIP: 0060:[<f885a800>] EFLAGS: 00000246 CPU: 0
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] EIP is at acpi_idle_
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] EAX: c0510760 EBX: 0000daf7 ECX: 00000000 EDX: 01cfe000
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] ESI: 00000000 EDI: 009cbbdf EBP: c04a7f90 ESP: c04a7f74
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] CR0: 8005003b CR2: b59b3000 CR3: 00515000 CR4: 00000690
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] DR0: 00000000 DR1: 00000000 DR2: 00000000 DR3: 00000000
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] DR6: ffff0ff0 DR7: 00000400
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c02dbf6b>] cpuidle_
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c010288d>] cpu_idle+0x7d/0x140
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] [<c036edb3>] rest_init+0x53/0x60
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] =======
Oct 20 07:00:34 michallo-laptop kernel: [26099.444015] bad: scheduling from the idle thread!
...
my kern.log very fast grew up to 2048MB.