Bug 16701 - Hyper-V (hv) staging drivers fail with 'scheduling while atomic' bug
Summary: Hyper-V (hv) staging drivers fail with 'scheduling while atomic' bug
Status: RESOLVED CODE_FIX
Alias: None
Product: Drivers
Classification: Unclassified
Component: Staging (show other bugs)
Hardware: All Linux
: P1 high
Assignee: Hank Janssen
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2010-08-21 08:33 UTC by Artem Grachev
Modified: 2010-12-16 20:36 UTC (History)
3 users (show)

See Also:
Kernel Version: 2.6.35.2
Subsystem:
Regression: No
Bisected commit-id:


Attachments
/var/log/syslog (135.99 KB, text/plain)
2010-08-21 08:33 UTC, Artem Grachev
Details
/boot/grub/grub.cfg (4.64 KB, text/plain)
2010-09-03 05:34 UTC, Artem Grachev
Details
/var/log/messages (50.41 KB, application/octet-stream)
2010-12-10 12:37 UTC, Natanael Copa
Details

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.

Note You need to log in before you can comment on or make changes to this bug.