Bug 16701

Summary: Hyper-V (hv) staging drivers fail with 'scheduling while atomic' bug
Product: Drivers Reporter: Artem Grachev (linux.kernel)
Component: StagingAssignee: Hank Janssen (hjanssen)
Status: RESOLVED CODE_FIX    
Severity: high CC: greg, hjanssen, natanael.copa
Priority: P1    
Hardware: All   
OS: Linux   
Kernel Version: 2.6.35.2 Subsystem:
Regression: No Bisected commit-id:
Attachments: /var/log/syslog
/boot/grub/grub.cfg
/var/log/messages

Description Artem Grachev 2010-08-21 08:33:06 UTC 
Created attachment 27581 [details]
/var/log/syslog

Failing kernel version: 2.6.35.2 (didn't test earlier)
Tested with Hyper-V (hv) drivers from 2.6.35.2 and 2.6.36-rc1 linux kernels.
Distribution: Debian (squeeze/sid)
Hardware Environment: 
  Microsoft Windows Server 2008 R2 Hyper-V virtual machine.
Software Environment: Debian (squeeze/sid).
Problem Description:
  Drivers fail showing 'BUG: scheduling while atomic: ...'.
  Also, in about a minute after linux booting finishes, network interface fails.

Steps to reproduce:
  Install Debian Lenny or Testing inside Windows Server R2 (see [1]; 
  add additional network adapter (non Legacy) in Virtual Machine settings).
  Boot Linux Kernel 2.6.35 with Hyper-V (hv) drivers included in it.
  Then boot virtual machine using this kernel.

[1] http://wiki.debian.org/WindowsServerHyperV
Comment 1 Hank Janssen 2010-08-26 15:56:56 UTC 
Thanks for reporting this bug. I was hoping if you could give me some more information. If you do not load the hv_netvsc driver, do you still see the scheduling while atomic problem?  My main development machine is a OpenSuse machine and I run 2.6.35 as my current kernel without any problems with all the drivers loaded.

Can you give me more information about your Hyper-V configuration? How many legacy and virtual ethernet ports do you have added. What other virtual devices have you defined (SCSI/IDE)

And send me the configuration of your boot loader.

I have submitted 5 additional patches a few weeks ago that are not in 2.6.35 yet. Did you apply these patches by any chance.

I will try to spin up a debian lenny and put the 2.6.35 kernel on it.
Comment 2 Artem Grachev 2010-09-03 05:34:29 UTC 
Created attachment 28902 [details]
/boot/grub/grub.cfg

You can find my boot loader configuration in attached grub.cfg. In general, it's default grub2 configuration, which was generated by update-grub in debian.

I tried to load Linux with hyper-v drivers excluded from initrd and when boot finishes inserted hv_vmbus.ko using insmod. After that I had "BUG: scheduling while atomic: ...". There were no other hyper-v modules activated.

I tried last hv drivers, that were available in 2.6.35.2 and 2.6.36-rc1.
Comment 3 Natanael Copa 2010-12-10 12:37:43 UTC 
Created attachment 39702 [details]
/var/log/messages

I have same issue with hv_vmbus.ko and vanilla kernel 2.6.36.2. I have the following setup in hyper-v:

* memory: 512 MB
* processor: 2 virtual processors
* IDE controller 0: 1 harddrive for booting
* IDE controlelr 1: DVD drive none
* SCSI controller: nothing
* Legacy network adapter: 1, connected to guet network #1.
(I had issues with hv_netvsc in 2.6.35.y kernel so i moved to legacy NIC)

I built kernel from git 2.6.36.y and enabled some debugging. I had to blacklist all hv_* drivers and the attatched log is what happens when i modprobe hv_netvsc.

I make the Alpine Linux and are willing to follow this up, do necessary tests and provide all necessary info to fix this. I would be interested in backporting the fix to 2.6.35.y too since its broken there too. Infact, the "Save" feature is broken in 2.6.32.y.

Thanks!
Comment 4 Natanael Copa 2010-12-10 12:45:03 UTC 
I rebooted and did modprobe hv_vmbus. I got a cleaner output from dmesg:


[   88.040688] hv_vmbus: module is from the staging directory, the quality is unknown, you have been warned.
[   88.042744] VMBUS_DRV: Vmbus initializing.... current log level 0x1f1f0006 (1f1f,6)
[   88.042751] VMBUS: +++++++ HV Driver version = 3.1 +++++++
[   88.042753] VMBUS: +++++++ Vmbus supported version = 13 +++++++
[   88.042754] VMBUS: +++++++ Vmbus using SINT 2 +++++++
[   88.042757] VMBUS: Windows hypervisor detected! Retrieving more info...
[   88.042761] VMBUS: Vendor ID: Microsoft Hv
[   88.042763] VMBUS: Interface ID: Hv#1
[   88.042766] VMBUS: OS Build:7600-6.1-20-0.20742
[   88.042827] VMBUS: Hypercall page VA=e1236000, PA=0x1d41e000
[   88.044669] VMBUS_DRV: irq 0x5 vector 0x35
[   88.044730] VMBUS: SynIC version: 1
[   88.044819] VMBUS: SynIC version: 1
[   88.046252] VMBUS: Vmbus connected!!
[   88.046382] VMBUS_DRV: generating uevent - VMBUS_DEVICE_CLASS_GUID={c5295816-f63a-4d5f-8d1a4daf999ca185}
[   88.047105] VMBUS: Channel offer notification - child relid 1 monitor id 0 allocated 1, type {32412632-86cb-44a2-9b5c50d1417354f5} instance {00000000-0000-8899-0000000000000000}
[   88.047215] VMBUS_DRV: generating uevent - VMBUS_DEVICE_CLASS_GUID={32412632-86cb-44a2-9b5c50d1417354f5}
[   88.047228] VMBUS_DRV: child device (dc3cf6a0) registered
[   88.047233] VMBUS: Channel offer notification - child relid 2 monitor id 255 allocated 0, type {cfa8b69e-5b4a-4cc0-b98b8ba1a1f3f95a} instance {58f75a6d-d949-4320-99e1a2a2576d581c}
[   88.047593] VMBUS_DRV: generating uevent - VMBUS_DEVICE_CLASS_GUID={cfa8b69e-5b4a-4cc0-b98b8ba1a1f3f95a}
[   88.047603] VMBUS_DRV: child device (dc3cfea0) registered
[   88.047608] VMBUS: Channel offer notification - child relid 4 monitor id 255 allocated 0, type {57164f39-9115-4e78-ab55382f3bd5422d} instance {fd149e91-82e0-4a7d-afa62a4166cbd7c0}
[   88.047766] VMBUS_DRV: generating uevent - VMBUS_DEVICE_CLASS_GUID={57164f39-9115-4e78-ab55382f3bd5422d}
[   88.047773] VMBUS_DRV: child device (dc3ce8a0) registered
[   88.047837] VMBUS: Channel offer notification - child relid 6 monitor id 255 allocated 0, type {0e0b6031-5213-4934-818b38d90ced39db} instance {b6650ff7-33bc-4840-8048e0676786f393}
[   88.047888] VMBUS_DRV: generating uevent - VMBUS_DEVICE_CLASS_GUID={0e0b6031-5213-4934-818b38d90ced39db}
[   88.047896] VMBUS_DRV: child device (dc3ce0a0) registered
[   88.048250] VMBUS: Channel offer notification - child relid 7 monitor id 255 allocated 0, type {9527e630-d0ae-497b-adcee80ab0175caf} instance {2dd1ce17-079e-403c-b352a1921ee207ee}
[   88.048359] VMBUS_DRV: generating uevent - VMBUS_DEVICE_CLASS_GUID={9527e630-d0ae-497b-adcee80ab0175caf}
[   88.048366] VMBUS_DRV: child device (dc3ccca0) registered
[   88.048446] BUG: scheduling while atomic: swapper/0/0x10000100
[   88.048450] Modules linked in: hv_vmbus(C+) ipv6 af_packet serio_raw psmouse i2c_piix4 i2c_core tulip evdev rtc_cmos rtc_core rtc_lib thermal button processor sr_mod cdrom pata_acpi ata_generic ata_piix libata floppy cramfs loop ext3 mbcache jbd sd_mod scsi_mod
[   88.048531] Modules linked in: hv_vmbus(C+) ipv6 af_packet serio_raw psmouse i2c_piix4 i2c_core tulip evdev rtc_cmos rtc_core rtc_lib thermal button processor sr_mod cdrom pata_acpi ata_generic ata_piix libata floppy cramfs loop ext3 mbcache jbd sd_mod scsi_mod
[   88.048548] 
[   88.048556] Pid: 0, comm: swapper Tainted: G         C  2.6.36.2+ #1 Virtual Machine/Virtual Machine
[   88.048558] EIP: 0060:[<c1045cf3>] EFLAGS: 00000246 CPU: 0
[   88.048574] EIP is at tick_nohz_restart_sched_tick+0x140/0x14c
[   88.048576] EAX: 801893ab EBX: 8018865f ECX: 801893ab EDX: 00000014
[   88.048578] ESI: 00000014 EDI: c1803f54 EBP: c1283fb8 ESP: c1283fa4
[   88.048580]  DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[   88.048582] Process swapper (pid: 0, ti=c1282000 task=c12956e0 task.ti=c1282000)
[   88.052900] Stack:
[   88.058669]  00000000 00000060 c12ae574 00000000 c1284000 c1283fc8 c1001c78 c12dc068
[   88.058680] <0> 00020000 c1283fd0 c11cbc38 c1283fe0 c12b0738 c12dc068 1ffef000 c1283ff8
[   88.058685] <0> c12b00c9 1f12b000 00000000 00040800 00020000 014db003 00000000
[   88.058693] Call Trace:
[   88.059955]  [<c1001c78>] ? cpu_idle+0x5c/0x63
[   88.059967]  [<c11cbc38>] ? rest_init+0x58/0x5a
[   88.059973]  [<c12b0738>] ? start_kernel+0x2ae/0x2b3
[   88.059976]  [<c12b00c9>] ? i386_start_kernel+0xc9/0xd0
[   88.059977] Code: 74 13 eb 25 8b 47 0c 31 c9 8b 57 10 e8 e0 f5 ff ff 85 c0 74 14 89 d8 89 f2 e8 6d f7 ff ff e8 0d b1 ff ff 89 c3 89 d6 eb 90 fb 90 <8d> 74 26 00 8d 65 f4 5b 5e 5f 5d c3 55 89 e5 57 bf 54 3f 30 c1 
[   88.175428] Call Trace:
[   88.175458]  [<c1001c78>] cpu_idle+0x5c/0x63
[   88.175471]  [<c11cbc38>] rest_init+0x58/0x5a
[   88.175477]  [<c12b0738>] start_kernel+0x2ae/0x2b3
[   88.175480]  [<c12b00c9>] i386_start_kernel+0xc9/0xd0
[   88.176597] VMBUS: Channel offer notification - child relid 9 monitor id 1 allocated 1, type {ba6163d9-04a1-4d29-b60572e2ffb1dc7f} instance {50b4d48a-7ded-4a13-aaaae81b81f811cf}
[   88.176899] VMBUS_DRV: generating uevent - VMBUS_DEVICE_CLASS_GUID={ba6163d9-04a1-4d29-b60572e2ffb1dc7f}
[   88.176916] VMBUS_DRV: child device (dd24e6a0) registered
[   88.177178] VMBUS: channel <dc3cee00> open success!!
[   88.177180] VMBUS: Heartbeat channel functionality initialized
[   88.177247] VMBUS: channel <dc3ce200> open success!!
[   88.177249] VMBUS: Shutdown channel functionality initialized
[   88.177323] VMBUS: channel <dc3cd600> open success!!
[   88.177324] VMBUS: Timesync channel functionality initialized
Comment 5 Natanael Copa 2010-12-10 13:48:22 UTC 
with modprobe hv_vmbus vmbus_loglevel=0xffff000f

[ 2034.791175] hv_vmbus: module is from the staging directory, the quality is unknown, you have been warned.
[ 2034.793712] VMBUS_DRV: Vmbus initializing.... current log level 0xffff000f (ffff,f)
[ 2034.793724] VMBUS: +++++++ HV Driver version = 3.1 +++++++
[ 2034.793726] VMBUS: +++++++ Vmbus supported version = 13 +++++++
[ 2034.793727] VMBUS: +++++++ Vmbus using SINT 2 +++++++
[ 2034.793730] VMBUS: VmbusInitialize() sizeof(VMBUS_CHANNEL_PACKET_PAGE_BUFFER)=280, sizeof(VMBUS_CHANNEL_PACKET_MULITPAGE_BUFFER)=288
[ 2034.793736] VMBUS: Windows hypervisor detected! Retrieving more info...
[ 2034.793740] VMBUS: Vendor ID: Microsoft Hv
[ 2034.793742] VMBUS: Interface ID: Hv#1
[ 2034.793745] VMBUS: OS Build:7600-6.1-20-0.20742
[ 2034.794380] VMBUS: Hypercall page VA=e1236000, PA=0x1eec5000
[ 2034.795719] VMBUS_DRV: irq 0x5 vector 0x35
[ 2034.795787] VMBUS: SynIC version: 1
[ 2034.795816] VMBUS: HvSynicInit() HV_X64_MSR_SIMP msr set to: 1ee86001
[ 2034.795884] VMBUS: HvSynicInit() HV_X64_MSR_SIEFP msr set to: 1ece5001
[ 2034.795943] VMBUS: HvSynicInit() HV_X64_MSR_SINT1 msr set to: 20035
[ 2034.796001] VMBUS: SynIC version: 1
[ 2034.796045] VMBUS: HvSynicInit() HV_X64_MSR_SIMP msr set to: 1edd7001
[ 2034.796099] VMBUS: HvSynicInit() HV_X64_MSR_SIEFP msr set to: 1edd6001
[ 2034.796162] VMBUS: HvSynicInit() HV_X64_MSR_SINT1 msr set to: 20035
[ 2034.798540] VMBUS: VmbusConnect() Vmbus connection - interrupt pfn 1ef76000, monitor1 pfn 1efd0000,, monitor2 pfn 1efd1000
[ 2034.798543] VMBUS: VmbusConnect() Sending channel initiate msg...
[ 2034.798548] VMBUS: HvDoHypercall() Hypercall <control 5c input cc83b600 output (null)>
[ 2034.798559] VMBUS: HvDoHypercall() Hypercall <return 0>
[ 2034.799750] VMBUS: VmbusOnISR() received msg type 1 size 16
[ 2034.799889] VMBUS: VmbusOnChannelMessage() message type 15 size 16
[ 2034.800472] VMBUS: Vmbus connected!!
[ 2034.800587] VMBUS_DRV: generating uevent - VMBUS_DEVICE_CLASS_GUID={c5295816-f63a-4d5f-8d1a4daf999ca185}
[ 2034.801241] VMBUS: HvDoHypercall() Hypercall <control 5c input cc838c00 output (null)>
[ 2034.801252] VMBUS: HvDoHypercall() Hypercall <return 0>
[ 2034.801645] VMBUS: VmbusOnISR() received msg type 1 size 196
[ 2034.801658] VMBUS: VmbusOnISR() received msg type 1 size 196
[ 2034.801665] BUG: scheduling while atomic: swapper/0/0x10000100
[ 2034.801670] Modules linked in: hv_vmbus(C+) ipv6 af_packet serio_raw psmouse i2c_piix4 i2c_core tulip evdev rtc_cmos rtc_core rtc_lib thermal button processor sr_mod cdrom pata_
acpi ata_generic ata_piix libata floppy cramfs loop ext3 mbcache jbd sd_mod scsi_mod
[ 2034.801762] Modules linked in: hv_vmbus(C+) ipv6 af_packet serio_raw psmouse i2c_piix4 i2c_core tulip evdev rtc_cmos rtc_core rtc_lib thermal button processor sr_mod cdrom pata_
acpi ata_generic ata_piix libata floppy cramfs loop ext3 mbcache jbd sd_mod scsi_mod
[ 2034.801777] 
[ 2034.801786] Pid: 0, comm: swapper Tainted: G         C  2.6.36.2+ #1 Virtual Machine/Virtual Machine
[ 2034.801789] EIP: 0060:[<c101852b>] EFLAGS: 00000246 CPU: 0
[ 2034.801804] EIP is at native_safe_halt+0x5/0x7
[ 2034.801806] EAX: 00000000 EBX: c12ae574 ECX: c1802da0 EDX: 00000000
[ 2034.801808] ESI: 00000000 EDI: c1284000 EBP: c1283fb0 ESP: c1283fb0
[ 2034.801810]  DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[ 2034.801814] Process swapper (pid: 0, ti=c1282000 task=c12956e0 task.ti=c1282000)
[ 2034.804559] Stack:
[ 2034.805266]  c1283fb8 c1008776 c1283fc8 c1001c65 c12dc068 00020000 c1283fd0 c11cbc38
[ 2034.805276] <0> c1283fe0 c12b0738 c12dc068 1ffef000 c1283ff8 c12b00c9 1f12b000 00000000
[ 2034.805282] <0> 00040800 00020000 014db003 00000000
[ 2034.805287] Call Trace:
[ 2034.805928]  [<c1008776>] ? default_idle+0x31/0x51
[ 2034.805932]  [<c1001c65>] ? cpu_idle+0x49/0x63
[ 2034.805947]  [<c11cbc38>] ? rest_init+0x58/0x5a
[ 2034.805954]  [<c12b0738>] ? start_kernel+0x2ae/0x2b3
[ 2034.805958]  [<c12b00c9>] ? i386_start_kernel+0xc9/0xd0
[ 2034.805960] Code: 89 e5 57 e8 dc ff ff ff 5f 5d c3 90 55 89 e5 9c 58 5d c3 55 89 e5 50 9d 5d c3 55 89 e5 fa 5d c3 55 89 e5 fb 5d c3 55 89 e5 fb f4 <5d> c3 55 89 e5 f4 5d c3 55 8
9 e5 0f 06 5d c3 55 89 e5 0f 20 c0 
[ 2034.822948] Call Trace:
[ 2034.822953]  [<c1008776>] default_idle+0x31/0x51
[ 2034.822957]  [<c1001c65>] cpu_idle+0x49/0x63
[ 2034.822961]  [<c11cbc38>] rest_init+0x58/0x5a
[ 2034.822964]  [<c12b0738>] start_kernel+0x2ae/0x2b3
[ 2034.822968]  [<c12b00c9>] i386_start_kernel+0xc9/0xd0
[ 2034.822973] VMBUS: VmbusOnChannelMessage() message type 1 size 196
[ 2034.822980] VMBUS: Channel offer notification - child relid 1 monitor id 0 allocated 1, type {32412632-86cb-44a2-9b5c50d1417354f5} instance {00000000-0000-8899-0000000000000000}

[ 2034.823094] VMBUS: VmbusOnISR() received msg type 1 size 196
[ 2034.823099] BUG: scheduling while atomic: swapper/0/0x10000100
[ 2034.823108] Modules linked in: hv_vmbus(C+) ipv6 af_packet serio_raw psmouse i2c_piix4 i2c_core tulip evdev rtc_cmos rtc_core rtc_lib thermal button processor sr_mod cdrom pata_
acpi ata_generic ata_piix libata floppy cramfs loop ext3 mbcache jbd sd_mod scsi_mod
[ 2034.823124] Modules linked in: hv_vmbus(C+) ipv6 af_packet serio_raw psmouse i2c_piix4 i2c_core tulip evdev rtc_cmos rtc_core rtc_lib thermal button processor sr_mod cdrom pata_
acpi ata_generic ata_piix libata floppy cramfs loop ext3 mbcache jbd sd_mod scsi_mod
[ 2034.823189] 
[ 2034.823191] Pid: 0, comm: swapper Tainted: G         C  2.6.36.2+ #1 Virtual Machine/Virtual Machine
[ 2034.823193] EIP: 0060:[<c101852b>] EFLAGS: 00000246 CPU: 0
[ 2034.823196] EIP is at native_safe_halt+0x5/0x7
[ 2034.823198] EAX: 00000000 EBX: c12ae574 ECX: c1802da0 EDX: 00000000
[ 2034.823200] ESI: 00000000 EDI: c1284000 EBP: c1283fb0 ESP: c1283fb0
[ 2034.823203]  DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[ 2034.823207] Process swapper (pid: 0, ti=c1282000 task=c12956e0 task.ti=c1282000)
[ 2034.824623] Stack:
[ 2034.825213]  c1283fb8 c1008776 c1283fc8 c1001c65 c12dc068 00020000 c1283fd0 c11cbc38
[ 2034.825219] <0> c1283fe0 c12b0738 c12dc068 1ffef000 c1283ff8 c12b00c9 1f12b000 00000000
[ 2034.825225] <0> 00040800 00020000 014db003 00000000
[ 2034.825230] Call Trace:
[ 2034.825820]  [<c1008776>] ? default_idle+0x31/0x51
[ 2034.825824]  [<c1001c65>] ? cpu_idle+0x49/0x63
[ 2034.825828]  [<c11cbc38>] ? rest_init+0x58/0x5a
[ 2034.825831]  [<c12b0738>] ? start_kernel+0x2ae/0x2b3
[ 2034.825835]  [<c12b00c9>] ? i386_start_kernel+0xc9/0xd0
[ 2034.825837] Code: 89 e5 57 e8 dc ff ff ff 5f 5d c3 90 55 89 e5 9c 58 5d c3 55 89 e5 50 9d 5d c3 55 89 e5 fa 5d c3 55 89 e5 fb 5d c3 55 89 e5 fb f4 <5d> c3 55 89 e5 f4 5d c3 55 8
9 e5 0f 06 5d c3 55 89 e5 0f 20 c0 
[ 2034.848342] Call Trace:
[ 2034.848371]  [<c1008776>] default_idle+0x31/0x51
[ 2034.848375]  [<c1001c65>] cpu_idle+0x49/0x63
[ 2034.848389]  [<c11cbc38>] rest_init+0x58/0x5a
[ 2034.848395]  [<c12b0738>] start_kernel+0x2ae/0x2b3
[ 2034.848399]  [<c12b00c9>] i386_start_kernel+0xc9/0xd0
[ 2034.848493] VMBUS: VmbusChannelOnOffer() channel object allocated - cc838c00
[ 2034.848521] VMBUS_DRV: vmbus_child_device_create() child device (cc8380a0) allocated - type {32412632-86cb-44a2-9b5c50d1417354f5},id {00000000-0000-8899-0000000000000000}
[ 2034.848524] VMBUS: VmbusChannelProcessOffer() child device object allocated - cc838034
[ 2034.848526] VMBUS_DRV: vmbus_child_device_register() child device (cc838000) registering
[ 2034.848636] VMBUS_DRV: generating uevent - VMBUS_DEVICE_CLASS_GUID={32412632-86cb-44a2-9b5c50d1417354f5}
[ 2034.848756] VMBUS: VmbusOnChannelMessage() message type 1 size 196
[ 2034.848761] VMBUS: Channel offer notification - child relid 2 monitor id 255 allocated 0, type {cfa8b69e-5b4a-4cc0-b98b8ba1a1f3f95a} instance {58f75a6d-d949-4320-99e1a2a2576d581
c}
[ 2034.848840] VMBUS: VmbusChannelOnOffer() channel object allocated - cc839000
[ 2034.848846] VMBUS_DRV: vmbus_child_device_create() child device (cc83aea0) allocated - type {cfa8b69e-5b4a-4cc0-b98b8ba1a1f3f95a},id {58f75a6d-d949-4320-99e1a2a2576d581c}
[ 2034.848849] VMBUS: VmbusChannelProcessOffer() child device object allocated - cc83ae34
[ 2034.848851] VMBUS_DRV: vmbus_child_device_register() child device (cc83ae00) registering
[ 2034.849199] VMBUS_DRV: generating uevent - VMBUS_DEVICE_CLASS_GUID={cfa8b69e-5b4a-4cc0-b98b8ba1a1f3f95a}
[ 2034.849238] VMBUS: VmbusOnISR() received msg type 1 size 196
[ 2034.849252] BUG: scheduling while atomic: swapper/0/0x10000100
[ 2034.849254] Modules linked in: hv_vmbus(C+) ipv6 af_packet serio_raw psmouse i2c_piix4 i2c_core tulip evdev rtc_cmos rtc_core rtc_lib thermal button processor sr_mod cdrom pata_
acpi ata_generic ata_piix libata floppy cramfs loop ext3 mbcache jbd sd_mod scsi_mod
[ 2034.849327] Modules linked in: hv_vmbus(C+) ipv6 af_packet serio_raw psmouse i2c_piix4 i2c_core tulip evdev rtc_cmos rtc_core rtc_lib thermal button processor sr_mod cdrom pata_
acpi ata_generic ata_piix libata floppy cramfs loop ext3 mbcache jbd sd_mod scsi_mod
[ 2034.849341] 
[ 2034.849349] Pid: 0, comm: swapper Tainted: G         C  2.6.36.2+ #1 Virtual Machine/Virtual Machine
[ 2034.849352] EIP: 0060:[<c101852b>] EFLAGS: 00000246 CPU: 0
[ 2034.849357] EIP is at native_safe_halt+0x5/0x7
[ 2034.849359] EAX: 00000000 EBX: c12ae574 ECX: c1802da0 EDX: 00000000
[ 2034.849361] ESI: 00000000 EDI: c1284000 EBP: c1283fb0 ESP: c1283fb0
[ 2034.849363]  DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[ 2034.849365] Process swapper (pid: 0, ti=c1282000 task=c12956e0 task.ti=c1282000)
[ 2034.851038] Stack:
[ 2034.851664]  c1283fb8 c1008776 c1283fc8 c1001c65 c12dc068 00020000 c1283fd0 c11cbc38
[ 2034.851671] <0> c1283fe0 c12b0738 c12dc068 1ffef000 c1283ff8 c12b00c9 1f12b000 00000000
[ 2034.851677] <0> 00040800 00020000 014db003 00000000
[ 2034.851681] Call Trace:
[ 2034.852293]  [<c1008776>] ? default_idle+0x31/0x51
[ 2034.852297]  [<c1001c65>] ? cpu_idle+0x49/0x63
[ 2034.852301]  [<c11cbc38>] ? rest_init+0x58/0x5a
[ 2034.852305]  [<c12b0738>] ? start_kernel+0x2ae/0x2b3
[ 2034.852308]  [<c12b00c9>] ? i386_start_kernel+0xc9/0xd0
[ 2034.852310] Code: 89 e5 57 e8 dc ff ff ff 5f 5d c3 90 55 89 e5 9c 58 5d c3 55 89 e5 50 9d 5d c3 55 89 e5 fa 5d c3 55 89 e5 fb 5d c3 55 89 e5 fb f4 <5d> c3 55 89 e5 f4 5d c3 55 8
9 e5 0f 06 5d c3 55 89 e5 0f 20 c0 
[ 2034.869196] Call Trace:
[ 2034.869200]  [<c1008776>] default_idle+0x31/0x51
[ 2034.869202]  [<c1001c65>] cpu_idle+0x49/0x63
[ 2034.869206]  [<c11cbc38>] rest_init+0x58/0x5a
[ 2034.869209]  [<c12b0738>] start_kernel+0x2ae/0x2b3
[ 2034.869211]  [<c12b00c9>] i386_start_kernel+0xc9/0xd0
[ 2034.869219] VMBUS_DRV: child device (cc8380a0) registered
[ 2034.869224] VMBUS_DRV: child device (cc83aea0) registered
[ 2034.869226] VMBUS: VmbusOnChannelMessage() message type 1 size 196
[ 2034.869228] VMBUS: VmbusChannelOnOffer() Ignoring channel offer notification for child relid 3
[ 2034.869249] VMBUS: VmbusOnISR() received msg type 1 size 196
[ 2034.869253] BUG: scheduling while atomic: swapper/0/0x10000100
[ 2034.869254] Modules linked in: hv_vmbus(C+) ipv6 af_packet serio_raw psmouse i2c_piix4 i2c_core tulip evdev rtc_cmos rtc_core rtc_lib thermal button processor sr_mod cdrom pata_
acpi ata_generic ata_piix libata floppy cramfs loop ext3 mbcache jbd sd_mod scsi_mod
[ 2034.869271] Modules linked in: hv_vmbus(C+) ipv6 af_packet serio_raw psmouse i2c_piix4 i2c_core tulip evdev rtc_cmos rtc_core rtc_lib thermal button processor sr_mod cdrom pata_
acpi ata_generic ata_piix libata floppy cramfs loop ext3 mbcache jbd sd_mod scsi_mod
[ 2034.869286] 
[ 2034.869288] Pid: 0, comm: swapper Tainted: G         C  2.6.36.2+ #1 Virtual Machine/Virtual Machine
[ 2034.869291] EIP: 0060:[<c101852b>] EFLAGS: 00000246 CPU: 0
[ 2034.869294] EIP is at native_safe_halt+0x5/0x7
[ 2034.869295] EAX: 00000000 EBX: c12ae574 ECX: c1802da0 EDX: 00000000
[ 2034.869298] ESI: 00000000 EDI: c1284000 EBP: c1283fb0 ESP: c1283fb0
[ 2034.869300]  DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[ 2034.869302] Process swapper (pid: 0, ti=c1282000 task=c12956e0 task.ti=c1282000)
[ 2034.870277] Stack:
[ 2034.870874]  c1283fb8 c1008776 c1283fc8 c1001c65 c12dc068 00020000 c1283fd0 c11cbc38
[ 2034.870879] <0> c1283fe0 c12b0738 c12dc068 1ffef000 c1283ff8 c12b00c9 1f12b000 00000000
[ 2034.870884] <0> 00040800 00020000 014db003 00000000
[ 2034.870911] Call Trace:
[ 2034.871684]  [<c1008776>] ? default_idle+0x31/0x51
[ 2034.871687]  [<c1001c65>] ? cpu_idle+0x49/0x63
[ 2034.871690]  [<c11cbc38>] ? rest_init+0x58/0x5a
[ 2034.871693]  [<c12b0738>] ? start_kernel+0x2ae/0x2b3
[ 2034.871696]  [<c12b00c9>] ? i386_start_kernel+0xc9/0xd0
[ 2034.871697] Code: 89 e5 57 e8 dc ff ff ff 5f 5d c3 90 55 89 e5 9c 58 5d c3 55 89 e5 50 9d 5d c3 55 89 e5 fa 5d c3 55 89 e5 fb 5d c3 55 89 e5 fb f4 <5d> c3 55 89 e5 f4 5d c3 55 8
9 e5 0f 06 5d c3 55 89 e5 0f 20 c0 
[ 2034.899718] Call Trace:
[ 2034.899750]  [<c1008776>] default_idle+0x31/0x51
[ 2034.899754]  [<c1001c65>] cpu_idle+0x49/0x63
[ 2034.899768]  [<c11cbc38>] rest_init+0x58/0x5a
[ 2034.899775]  [<c12b0738>] start_kernel+0x2ae/0x2b3
[ 2034.899779]  [<c12b00c9>] i386_start_kernel+0xc9/0xd0
[ 2034.899974] VMBUS: VmbusOnChannelMessage() message type 1 size 196
[ 2034.899979] VMBUS: Channel offer notification - child relid 4 monitor id 255 allocated 0, type {57164f39-9115-4e78-ab55382f3bd5422d} instance {fd149e91-82e0-4a7d-afa62a4166cbd7c
0}
[ 2034.913193] VMBUS: VmbusChannelOnOffer() channel object allocated - cc839200
[ 2034.913204] VMBUS_DRV: vmbus_child_device_create() child device (cc8388a0) allocated - type {57164f39-9115-4e78-ab55382f3bd5422d},id {fd149e91-82e0-4a7d-afa62a4166cbd7c0}
[ 2034.913207] VMBUS: VmbusChannelProcessOffer() child device object allocated - cc838834
[ 2034.913209] VMBUS_DRV: vmbus_child_device_register() child device (cc838800) registering
[ 2034.913315] VMBUS_DRV: generating uevent - VMBUS_DEVICE_CLASS_GUID={57164f39-9115-4e78-ab55382f3bd5422d}
[ 2034.913784] VMBUS_DRV: child device (cc8388a0) registered
[ 2034.913804] VMBUS: VmbusChannelOpen() Establishing ring buffer's gpadl for channel cc839200...
[ 2034.913807] VMBUS: DumpGpadlHeader() gpadl header - relid 4, range count 1, range buflen 40
[ 2034.913810] VMBUS: DumpGpadlHeader() gpadl range 0 - len 16384 offset 0 page count 4
[ 2034.913812] VMBUS: DumpGpadlHeader() 0) pfn 62984
[ 2034.913813] VMBUS: DumpGpadlHeader() 1) pfn 62985
[ 2034.913815] VMBUS: DumpGpadlHeader() 2) pfn 62986
[ 2034.913816] VMBUS: DumpGpadlHeader() 3) pfn 62987
[ 2034.913818] VMBUS: VmbusChannelEstablishGpadl() buffer cf608000, size 16384 msg cnt 1
[ 2034.913820] VMBUS: VmbusChannelEstablishGpadl() Sending GPADL Header - len 60
[ 2034.913827] VMBUS: HvDoHypercall() Hypercall <control 5c input cc83a000 output (null)>
[ 2034.913838] VMBUS: HvDoHypercall() Hypercall <return 0>
Comment 6 Greg Kroah-Hartman 2010-12-16 20:36:08 UTC 
Now fixed in the linux-next tree and will be backported to older kernels when it hits Linus's tree.