Tom Rotenberg
2009-Jul-26 14:46 UTC
[Xen-devel] Machine hangs when doing pass-through of all the USB devices on a Lenovo T400 - With xen-unstable
Hi,
Just to notify you all, i have tested the same scenario, on the latest
xen-unstable, and i got the same result - the machine hangs. It looks
like a major bug.
Can someone please help me with this?
Tom
---------- Forwarded message ----------
From: Tom Rotenberg <tom.rotenberg@gmail.com>
Date: Wed, Jul 22, 2009 at 12:17 PM
Subject: Machine hangs when doing pass-through of all the USB devices
on a Lenovo T400
To: Simon Horman <horms@verge.net.au>, xen-devel@lists.xensource.com
Hi,
First, i must say that i have seen this bug on Simon''s Xen 3.4
multi-function pass-through tree, but i think that this problem isn''t
specific for Xen 3.4, but a major problem which will happen on
xen-unstable too.
(I''m using Lenovo T400 for my tests)
What i did:
1. Used Simon''s Xen 3.4 multi-function pass-through tree
2. Did pass-through for all the USB devices in the system, using the command:
xm create /etc/xen/hvm pci=''00:1a.*''
pci=''00:1d.*''
3. After this, the guest Windows booted ok, but a little bit after
this, the whole machine got stuck (including dom0), and the errors in
the UART were:
[ 287.624198] ACPI: EC: missing write data confirmation, don''t expect
it any longer.
[ 293.748242] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
[ 293.748353] ata2.00: cmd a0/00:00:00:00:00/00:00:00:00:00/a0 tag 0
[ 293.748354] cdb 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 293.748354] res 40/00:03:00:00:00/00:00:00:00:00/a0 Emask
0x4 (timeout)
[ 293.748658] ata2.00: status: { DRDY }
which looks like the SATA in dom0 is dead...
I suspect that this is because of some problem in the
interrupts-sharing between the USB device which is in pass-through to
the HVM, and between the SATA in dom0 (they share the same interrupt).
I have also made a test, which i pass-through only the
''00:1a.*''
devices, and saw that doesn''t happen (probably, because the interrupt
isn''t shared). You can see in the logs that follow that interrupt 17
is shared between the AHCI (the HD), and one of the USB devices.
Could it be that there is problem when doing interrupt sharing between
PT device in a domU, and between a device in dom0? Will this happen
without the multi-function PT too?
Any ideas?
-----------
Logs:
----------
_______________________________________________
The interrupts of the machine (as reported by dom0):
_______________________________________________
t500-testing:~# cat /proc/interrupts
CPU0 CPU1
1: 8 0 Phys-irq-level i8042
3: 1 0 Phys-irq-level
5: 1 0 Phys-irq-level
7: 1 0 Phys-irq-level parport0
8: 0 0 Phys-irq-level rtc
9: 333 0 Phys-irq-level acpi
10: 1 0 Phys-irq-level
11: 1 0 Phys-irq-level
12: 2332 0 Phys-irq-level i8042
16: 2718 0 Phys-irq-level uhci_hcd:usb4, ahci, yenta
17: 93 0 Phys-irq-level uhci_hcd:usb5,
ohci1394, HDA Intel
18: 35 0 Phys-irq-level uhci_hcd:usb6, sdhci:slot0
19: 2 0 Phys-irq-level ehci_hcd:usb8
20: 249 0 Phys-irq-level uhci_hcd:usb1, peth0
21: 54 0 Phys-irq-level uhci_hcd:usb2
22: 0 0 Phys-irq-level uhci_hcd:usb3
23: 26 0 Phys-irq-level ehci_hcd:usb7
256: 13683 0 Dynamic-irq-level timer0
257: 1180 0 Dynamic-irq-level resched0
258: 27 0 Dynamic-irq-level callfunc0
259: 0 2027 Dynamic-irq-level resched1
260: 0 64 Dynamic-irq-level callfunc1
261: 0 13348 Dynamic-irq-level timer1
262: 77 0 Dynamic-irq-level xenbus
263: 0 0 Dynamic-irq-level console
NMI: 0 0 Non-maskable interrupts
RES: 1180 2027 Rescheduling interrupts
CAL: 27 64 function call interrupts
_______________________________________________
Full UART Log (from the relevant part):
_______________________________________________
[ 28.652292] Setting mem allocation to 1913836 kiB
[ 214.320871] uhci_hcd 0000:00:1a.0: remove, state 4
[ 214.320983] usb usb1: USB disconnect, address 1
[ 214.322560] uhci_hcd 0000:00:1a.0: USB bus 1 deregistered
[ 214.322694] ACPI: PCI interrupt for device 0000:00:1a.0 disabled
[ 214.608987] pciback 0000:00:1a.0: seizing device
[ 214.609184] ACPI: PCI Interrupt 0000:00:1a.0[A] -> GSI 20 (level,
low) -> IRQ 20
[ 214.609364] ACPI: PCI interrupt for device 0000:00:1a.0 disabled
[ 214.334168] uhci_hcd 0000:00:1a.1: remove, state 1
[ 214.334270] usb usb2: USB disconnect, address 1
[ 214.334362] usb 2-1: USB disconnect, address 3
[ 214.334803] uhci_hcd 0000:00:1a.1: USB bus 2 deregistered
[ 214.334971] ACPI: PCI interrupt for device 0000:00:1a.1 disabled
[ 214.621835] pciback 0000:00:1a.1: seizing device
[ 214.622056] ACPI: PCI Interrupt 0000:00:1a.1[B] -> GSI 21 (level,
low) -> IRQ 21
[ 214.622258] ACPI: PCI interrupt for device 0000:00:1a.1 disabled
[ 214.627337] uhci_hcd 0000:00:1a.2: remove, state 4
[ 214.627545] usb usb3: USB disconnect, address 1
[ 214.627932] uhci_hcd 0000:00:1a.2: USB bus 3 deregistered
[ 214.628152] ACPI: PCI interrupt for device 0000:00:1a.2 disabled
[ 214.635694] pciback 0000:00:1a.2: seizing device
[ 214.636000] ACPI: PCI Interrupt 0000:00:1a.2[C] -> GSI 22 (level,
low) -> IRQ 22
[ 214.636186] ACPI: PCI interrupt for device 0000:00:1a.2 disabled
[ 214.360823] ehci_hcd 0000:00:1a.7: remove, state 4
[ 214.360925] usb usb7: USB disconnect, address 1
[ 214.361017] usb 7-5: USB disconnect, address 3
[ 214.365340] ehci_hcd 0000:00:1a.7: USB bus 7 deregistered
[ 214.365506] ACPI: PCI interrupt for device 0000:00:1a.7 disabled
[ 214.371946] pciback 0000:00:1a.7: seizing device
[ 214.372390] ACPI: PCI Interrupt 0000:00:1a.7[D] -> GSI 23 (level,
low) -> IRQ 23
[ 214.372583] ACPI: PCI interrupt for device 0000:00:1a.7 disabled
[ 214.376078] uhci_hcd 0000:00:1d.0: remove, state 4
[ 214.376303] usb usb4: USB disconnect, address 1
[ 214.376742] uhci_hcd 0000:00:1d.0: USB bus 4 deregistered
[ 214.376938] ACPI: PCI interrupt for device 0000:00:1d.0 disabled
[ 214.383475] pciback 0000:00:1d.0: seizing device
[ 214.383786] ACPI: PCI Interrupt 0000:00:1d.0[A] -> GSI 16 (level,
low) -> IRQ 16
[ 214.383978] ACPI: PCI interrupt for device 0000:00:1d.0 disabled
[ 214.387654] uhci_hcd 0000:00:1d.1: remove, state 4
[ 214.387857] usb usb5: USB disconnect, address 1
[ 214.388220] uhci_hcd 0000:00:1d.1: USB bus 5 deregistered
[ 214.388417] ACPI: PCI interrupt for device 0000:00:1d.1 disabled
[ 214.394899] pciback 0000:00:1d.1: seizing device
[ 214.395212] ACPI: PCI Interrupt 0000:00:1d.1[B] -> GSI 17 (level,
low) -> IRQ 17
[ 214.395398] ACPI: PCI interrupt for device 0000:00:1d.1 disabled
[ 214.677786] uhci_hcd 0000:00:1d.2: remove, state 1
[ 214.677890] usb usb6: USB disconnect, address 1
[ 214.677982] usb 6-2: USB disconnect, address 2
[ 214.678444] uhci_hcd 0000:00:1d.2: USB bus 6 deregistered
[ 214.678574] ACPI: PCI interrupt for device 0000:00:1d.2 disabled
[ 214.406336] pciback 0000:00:1d.2: seizing device
[ 214.406537] ACPI: PCI Interrupt 0000:00:1d.2[C] -> GSI 18 (level,
low) -> IRQ 18
[ 214.406725] ACPI: PCI interrupt for device 0000:00:1d.2 disabled
[ 214.410481] ehci_hcd 0000:00:1d.7: remove, state 4
[ 214.410691] usb usb8: USB disconnect, address 1
[ 214.414959] ehci_hcd 0000:00:1d.7: USB bus 8 deregistered
[ 214.415200] ACPI: PCI interrupt for device 0000:00:1d.7 disabled
[ 214.421670] pciback 0000:00:1d.7: seizing device
[ 214.422214] ACPI: PCI Interrupt 0000:00:1d.7[D] -> GSI 19 (level,
low) -> IRQ 19
[ 214.422407] ACPI: PCI interrupt for device 0000:00:1d.7 disabled
[ 231.366540] Setting mem allocation to 973824 kiB
(XEN) memory.c:128:d0 Could not allocate order=9 extent: id=1
memflags=0 (2 of 4)
(XEN) memory.c:128:d0 Could not allocate order=9 extent: id=1
memflags=0 (0 of 3)
[ 232.398469] pciback: vpci: 0000:00:1a.7: assign to virtual slot 0
[ 232.398776] pciback: vpci: 0000:00:1a.2: assign to virtual slot 0 func 2
[ 232.398973] pciback: vpci: 0000:00:1a.1: assign to virtual slot 0 func 1
[ 232.399171] pciback: vpci: 0000:00:1a.0: assign to virtual slot 0 func 0
[ 232.399318] pciback: vpci: 0000:00:1d.7: assign to virtual slot 1
[ 232.399509] pciback: vpci: 0000:00:1d.2: assign to virtual slot 1 func 2
[ 232.399717] pciback: vpci: 0000:00:1d.1: assign to virtual slot 1 func 1
[ 232.399946] pciback: vpci: 0000:00:1d.0: assign to virtual slot 1 func 0
(XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1a.0
(XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1a.0
(XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 14 device = 1a intx = 0
(XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1a.7
(XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1a.7
(XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 17 device = 1a intx = 3
(XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1a.2
(XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1a.2
(XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 16 device = 1a intx = 2
(XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1a.1
(XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1a.1
(XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 15 device = 1a intx = 1
(XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1d.0
(XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1d.0
(XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 10 device = 1d intx = 0
(XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1d.7
(XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1d.7
(XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 13 device = 1d intx = 3
(XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1d.2
(XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1d.2
(XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 12 device = 1d intx = 2
(XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1d.1
(XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1d.1
(XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 11 device = 1d intx = 1
(XEN) HVM1: HVM Loader
(XEN) HVM1: Detected Xen v3.4.1-rc7
(XEN) HVM1: CPU speed is 2394 MHz
(XEN) irq.c:243: Dom1 PCI link 0 changed 0 -> 5
(XEN) HVM1: PCI-ISA link 0 routed to IRQ5
(XEN) irq.c:243: Dom1 PCI link 1 changed 0 -> 10
(XEN) HVM1: PCI-ISA link 1 routed to IRQ10
(XEN) irq.c:243: Dom1 PCI link 2 changed 0 -> 11
(XEN) HVM1: PCI-ISA link 2 routed to IRQ11
(XEN) irq.c:243: Dom1 PCI link 3 changed 0 -> 5
(XEN) HVM1: PCI-ISA link 3 routed to IRQ5
(XEN) HVM1: pci dev 01:3 INTA->IRQ10
(XEN) HVM1: pci dev 03:0 INTA->IRQ5
(XEN) HVM1: pci dev 02:0 bar 10 size 02000000: f0000008
(XEN) HVM1: pci dev 03:0 bar 14 size 01000000: f2000008
(XEN) HVM1: pci dev 02:0 bar 14 size 00001000: f3000000
(XEN) HVM1: pci dev 03:0 bar 10 size 00000100: 0000c001
(XEN) HVM1: pci dev 01:1 bar 20 size 00000010: 0000c101
(XEN) HVM1: Multiprocessor initialisation:
(XEN) HVM1: - CPU0 ... 36-bit phys ... fixed MTRRs ... var MTRRs
[2/8] ... done.
(XEN) HVM1: Writing SMBIOS tables ...
(XEN) HVM1: Loading ROMBIOS ...
(XEN) HVM1: 10940 bytes of ROMBIOS high-memory extensions:
(XEN) HVM1: Relocating to 0xfc000000-0xfc002abc ... done
(XEN) HVM1: Creating MP tables ...
(XEN) HVM1: Loading Cirrus VGABIOS ...
(XEN) HVM1: Loading ACPI ...
(XEN) HVM1: - Lo data: 000ea020-000ea04f
(XEN) HVM1: - Hi data: fc002c00-fc0121cf
(XEN) HVM1: vm86 TSS at fc012400
(XEN) HVM1: BIOS map:
(XEN) HVM1: c0000-c8fff: VGA BIOS
(XEN) HVM1: eb000-eb148: SMBIOS tables
(XEN) HVM1: f0000-fffff: Main BIOS
(XEN) HVM1: Invoking ROMBIOS ...
(XEN) HVM1: $Revision: 1.221 $ $Date: 2008/12/07 17:32:29 $
(XEN) stdvga.c:147:d1 entering stdvga and caching modes
(XEN) HVM1: VGABios $Id: vgabios.c,v 1.67 2008/01/27 09:44:12 vruppert Exp $
(XEN) HVM1: Bochs BIOS - build: 06/23/99
(XEN) HVM1: $Revision: 1.221 $ $Date: 2008/12/07 17:32:29 $
(XEN) HVM1: Options: apmbios pcibios eltorito PMM
(XEN) HVM1:
(XEN) HVM1: ata0-0: PCHS=12483/16/63 translation=lba LCHS=783/255/63
(XEN) HVM1: ata0 master: QEMU HARDDISK ATA-7 Hard-Disk (6144 MBytes)
(XEN) HVM1: IDE time out
(XEN) HVM1:
(XEN) HVM1:
(XEN) HVM1:
(XEN) HVM1: Press F12 for boot menu.
(XEN) HVM1:
(XEN) HVM1: Booting from Hard Disk...
(XEN) HVM1: Booting from 0000:7c00
(XEN) HVM1: int13_harddisk: function 15, unmapped device for ELDL=81
(XEN) HVM1: *** int 15h function AX=e980, BX=006e not yet supported!
(XEN) common.c:3623:d0 tracking VRAM f0000 - f0026
(XEN) irq.c:243: Dom1 PCI link 0 changed 5 -> 0
(XEN) irq.c:243: Dom1 PCI link 1 changed 10 -> 0
(XEN) irq.c:243: Dom1 PCI link 2 changed 11 -> 0
(XEN) irq.c:243: Dom1 PCI link 3 changed 5 -> 0
(XEN) domctl.c:835:d0 memory_map:add: gfn=fbfff mfn=80101 nr_mfns=1
(XEN) domctl.c:886:d0 ioport_map:add f_gport=ffe0 f_mport=1c00 np=20
(XEN) domctl.c:886:d0 ioport_map:add f_gport=ffc0 f_mport=1860 np=20
(XEN) domctl.c:886:d0 ioport_map:add f_gport=ffa0 f_mport=18e0 np=20
(XEN) domctl.c:886:d0 ioport_map:add f_gport=ff80 f_mport=1880 np=20
(XEN) domctl.c:886:d0 ioport_map:add f_gport=ff60 f_mport=18c0 np=20
(XEN) domctl.c:886:d0 ioport_map:add f_gport=ff40 f_mport=18a0 np=20
(XEN) domctl.c:835:d0 memory_map:add: gfn=fbffe mfn=80100 nr_mfns=1
[ 287.624198] ACPI: EC: missing write data confirmation, don''t expect
it any longer.
[ 293.748242] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
[ 293.748353] ata2.00: cmd a0/00:00:00:00:00/00:00:00:00:00/a0 tag 0
[ 293.748354] cdb 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 293.748354] res 40/00:03:00:00:00/00:00:00:00:00/a0 Emask
0x4 (timeout)
[ 293.748658] ata2.00: status: { DRDY }
[ 294.060173] ata2: soft resetting link
[ 294.224137] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[ 297.579229] ata1.00: exception Emask 0x0 SAct 0xffff SErr 0x0
action 0x2 frozen
[ 297.579344] ata1.00: cmd 61/10:00:f7:9e:ed/00:00:00:00:00/40 tag 0
ncq 8192 out
[ 297.579345] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 297.579570] ata1.00: status: { DRDY }
[ 297.579657] ata1.00: cmd 61/18:08:6f:bc:65/00:00:00:00:00/40 tag 1
ncq 12288 out
[ 297.579658] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 297.579849] ata1.00: status: { DRDY }
[ 297.579936] ata1.00: cmd 61/10:10:3f:1c:67/00:00:00:00:00/40 tag 2
ncq 8192 out
[ 297.579937] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 297.580127] ata1.00: status: { DRDY }
[ 297.580215] ata1.00: cmd 61/08:18:27:26:67/00:00:00:00:00/40 tag 3
ncq 4096 out
[ 297.580216] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 297.580406] ata1.00: status: { DRDY }
[ 297.580494] ata1.00: cmd 61/08:20:2f:26:67/00:00:00:00:00/40 tag 4
ncq 4096 out
[ 297.580494] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 297.580684] ata1.00: status: { DRDY }
[ 297.580795] ata1.00: cmd 61/20:28:6f:27:67/00:00:00:00:00/40 tag 5
ncq 16384 out
[ 297.580796] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 297.580999] ata1.00: status: { DRDY }
[ 297.581088] ata1.00: cmd 61/08:30:0f:33:67/00:00:00:00:00/40 tag 6
ncq 4096 out
[ 297.581089] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 297.581281] ata1.00: status: { DRDY }
[ 297.581369] ata1.00: cmd 61/10:38:1f:9f:ed/00:00:00:00:00/40 tag 7
ncq 8192 out
[ 297.581370] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 297.581563] ata1.00: status: { DRDY }
[ 297.581652] ata1.00: cmd 61/08:40:17:33:67/00:00:00:00:00/40 tag 8
ncq 4096 out
[ 297.581653] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 297.582447] ata1.00: status: { DRDY }
[ 297.582536] ata1.00: cmd 61/50:48:c7:35:67/00:00:00:00:00/40 tag 9
ncq 40960 out
[ 297.582537] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 297.582728] ata1.00: status: { DRDY }
[ 297.582817] ata1.00: cmd 61/10:50:b7:36:67/00:00:00:00:00/40 tag 10
ncq 8192 out
[ 297.582818] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 297.583009] ata1.00: status: { DRDY }
[ 297.583097] ata1.00: cmd 61/08:58:3f:3a:67/00:00:00:00:00/40 tag 11
ncq 4096 out
[ 297.583098] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 297.583293] ata1.00: status: { DRDY }
[ 297.583383] ata1.00: cmd 61/10:60:47:3a:67/00:00:00:00:00/40 tag 12
ncq 8192 out
[ 297.583384] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 297.583613] ata1.00: status: { DRDY }
[ 297.583701] ata1.00: cmd 61/10:68:87:3b:67/00:00:00:00:00/40 tag 13
ncq 8192 out
[ 297.583702] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 297.583894] ata1.00: status: { DRDY }
[ 297.583983] ata1.00: cmd 61/10:70:8f:3f:67/00:00:00:00:00/40 tag 14
ncq 8192 out
[ 297.583983] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 297.584174] ata1.00: status: { DRDY }
[ 297.584262] ata1.00: cmd 61/08:78:27:41:67/00:00:00:00:00/40 tag 15
ncq 4096 out
[ 297.584263] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 297.584458] ata1.00: status: { DRDY }
[ 297.266871] ata1: soft resetting link
[ 297.430863] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Tom
_______________________________________________
Xen-devel mailing list
Xen-devel@lists.xensource.com
http://lists.xensource.com/xen-devel
Cui, Dexuan
2009-Jul-27 07:03 UTC
RE: [Xen-devel] Machine hangs when doing pass-through of all the USB devices on a Lenovo T400 - With xen-unstable
Can you try the xen-debug-key ''d'' to dump what the cpus are doing?>From serial, press Ctrl+A 3 times (or 6 time if you''re using minicom) and after you see "Serial input -> Xen", press h, d.Thanks, -- Dexuan -----Original Message----- From: xen-devel-bounces@lists.xensource.com [mailto:xen-devel-bounces@lists.xensource.com] On Behalf Of Tom Rotenberg Sent: 2009?7?26? 22:46 To: xen-devel@lists.xensource.com Subject: [Xen-devel] Machine hangs when doing pass-through of all the USB devices on a Lenovo T400 - With xen-unstable Hi, Just to notify you all, i have tested the same scenario, on the latest xen-unstable, and i got the same result - the machine hangs. It looks like a major bug. Can someone please help me with this? Tom ---------- Forwarded message ---------- From: Tom Rotenberg <tom.rotenberg@gmail.com> Date: Wed, Jul 22, 2009 at 12:17 PM Subject: Machine hangs when doing pass-through of all the USB devices on a Lenovo T400 To: Simon Horman <horms@verge.net.au>, xen-devel@lists.xensource.com Hi, First, i must say that i have seen this bug on Simon''s Xen 3.4 multi-function pass-through tree, but i think that this problem isn''t specific for Xen 3.4, but a major problem which will happen on xen-unstable too. (I''m using Lenovo T400 for my tests) What i did: 1. Used Simon''s Xen 3.4 multi-function pass-through tree 2. Did pass-through for all the USB devices in the system, using the command: xm create /etc/xen/hvm pci=''00:1a.*'' pci=''00:1d.*'' 3. After this, the guest Windows booted ok, but a little bit after this, the whole machine got stuck (including dom0), and the errors in the UART were: [ 287.624198] ACPI: EC: missing write data confirmation, don''t expect it any longer. [ 293.748242] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen [ 293.748353] ata2.00: cmd a0/00:00:00:00:00/00:00:00:00:00/a0 tag 0 [ 293.748354] cdb 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 [ 293.748354] res 40/00:03:00:00:00/00:00:00:00:00/a0 Emask 0x4 (timeout) [ 293.748658] ata2.00: status: { DRDY } which looks like the SATA in dom0 is dead... I suspect that this is because of some problem in the interrupts-sharing between the USB device which is in pass-through to the HVM, and between the SATA in dom0 (they share the same interrupt). I have also made a test, which i pass-through only the ''00:1a.*'' devices, and saw that doesn''t happen (probably, because the interrupt isn''t shared). You can see in the logs that follow that interrupt 17 is shared between the AHCI (the HD), and one of the USB devices. Could it be that there is problem when doing interrupt sharing between PT device in a domU, and between a device in dom0? Will this happen without the multi-function PT too? Any ideas? ----------- Logs: ---------- _______________________________________________ The interrupts of the machine (as reported by dom0): _______________________________________________ t500-testing:~# cat /proc/interrupts CPU0 CPU1 1: 8 0 Phys-irq-level i8042 3: 1 0 Phys-irq-level 5: 1 0 Phys-irq-level 7: 1 0 Phys-irq-level parport0 8: 0 0 Phys-irq-level rtc 9: 333 0 Phys-irq-level acpi 10: 1 0 Phys-irq-level 11: 1 0 Phys-irq-level 12: 2332 0 Phys-irq-level i8042 16: 2718 0 Phys-irq-level uhci_hcd:usb4, ahci, yenta 17: 93 0 Phys-irq-level uhci_hcd:usb5, ohci1394, HDA Intel 18: 35 0 Phys-irq-level uhci_hcd:usb6, sdhci:slot0 19: 2 0 Phys-irq-level ehci_hcd:usb8 20: 249 0 Phys-irq-level uhci_hcd:usb1, peth0 21: 54 0 Phys-irq-level uhci_hcd:usb2 22: 0 0 Phys-irq-level uhci_hcd:usb3 23: 26 0 Phys-irq-level ehci_hcd:usb7 256: 13683 0 Dynamic-irq-level timer0 257: 1180 0 Dynamic-irq-level resched0 258: 27 0 Dynamic-irq-level callfunc0 259: 0 2027 Dynamic-irq-level resched1 260: 0 64 Dynamic-irq-level callfunc1 261: 0 13348 Dynamic-irq-level timer1 262: 77 0 Dynamic-irq-level xenbus 263: 0 0 Dynamic-irq-level console NMI: 0 0 Non-maskable interrupts RES: 1180 2027 Rescheduling interrupts CAL: 27 64 function call interrupts _______________________________________________ Full UART Log (from the relevant part): _______________________________________________ [ 28.652292] Setting mem allocation to 1913836 kiB [ 214.320871] uhci_hcd 0000:00:1a.0: remove, state 4 [ 214.320983] usb usb1: USB disconnect, address 1 [ 214.322560] uhci_hcd 0000:00:1a.0: USB bus 1 deregistered [ 214.322694] ACPI: PCI interrupt for device 0000:00:1a.0 disabled [ 214.608987] pciback 0000:00:1a.0: seizing device [ 214.609184] ACPI: PCI Interrupt 0000:00:1a.0[A] -> GSI 20 (level, low) -> IRQ 20 [ 214.609364] ACPI: PCI interrupt for device 0000:00:1a.0 disabled [ 214.334168] uhci_hcd 0000:00:1a.1: remove, state 1 [ 214.334270] usb usb2: USB disconnect, address 1 [ 214.334362] usb 2-1: USB disconnect, address 3 [ 214.334803] uhci_hcd 0000:00:1a.1: USB bus 2 deregistered [ 214.334971] ACPI: PCI interrupt for device 0000:00:1a.1 disabled [ 214.621835] pciback 0000:00:1a.1: seizing device [ 214.622056] ACPI: PCI Interrupt 0000:00:1a.1[B] -> GSI 21 (level, low) -> IRQ 21 [ 214.622258] ACPI: PCI interrupt for device 0000:00:1a.1 disabled [ 214.627337] uhci_hcd 0000:00:1a.2: remove, state 4 [ 214.627545] usb usb3: USB disconnect, address 1 [ 214.627932] uhci_hcd 0000:00:1a.2: USB bus 3 deregistered [ 214.628152] ACPI: PCI interrupt for device 0000:00:1a.2 disabled [ 214.635694] pciback 0000:00:1a.2: seizing device [ 214.636000] ACPI: PCI Interrupt 0000:00:1a.2[C] -> GSI 22 (level, low) -> IRQ 22 [ 214.636186] ACPI: PCI interrupt for device 0000:00:1a.2 disabled [ 214.360823] ehci_hcd 0000:00:1a.7: remove, state 4 [ 214.360925] usb usb7: USB disconnect, address 1 [ 214.361017] usb 7-5: USB disconnect, address 3 [ 214.365340] ehci_hcd 0000:00:1a.7: USB bus 7 deregistered [ 214.365506] ACPI: PCI interrupt for device 0000:00:1a.7 disabled [ 214.371946] pciback 0000:00:1a.7: seizing device [ 214.372390] ACPI: PCI Interrupt 0000:00:1a.7[D] -> GSI 23 (level, low) -> IRQ 23 [ 214.372583] ACPI: PCI interrupt for device 0000:00:1a.7 disabled [ 214.376078] uhci_hcd 0000:00:1d.0: remove, state 4 [ 214.376303] usb usb4: USB disconnect, address 1 [ 214.376742] uhci_hcd 0000:00:1d.0: USB bus 4 deregistered [ 214.376938] ACPI: PCI interrupt for device 0000:00:1d.0 disabled [ 214.383475] pciback 0000:00:1d.0: seizing device [ 214.383786] ACPI: PCI Interrupt 0000:00:1d.0[A] -> GSI 16 (level, low) -> IRQ 16 [ 214.383978] ACPI: PCI interrupt for device 0000:00:1d.0 disabled [ 214.387654] uhci_hcd 0000:00:1d.1: remove, state 4 [ 214.387857] usb usb5: USB disconnect, address 1 [ 214.388220] uhci_hcd 0000:00:1d.1: USB bus 5 deregistered [ 214.388417] ACPI: PCI interrupt for device 0000:00:1d.1 disabled [ 214.394899] pciback 0000:00:1d.1: seizing device [ 214.395212] ACPI: PCI Interrupt 0000:00:1d.1[B] -> GSI 17 (level, low) -> IRQ 17 [ 214.395398] ACPI: PCI interrupt for device 0000:00:1d.1 disabled [ 214.677786] uhci_hcd 0000:00:1d.2: remove, state 1 [ 214.677890] usb usb6: USB disconnect, address 1 [ 214.677982] usb 6-2: USB disconnect, address 2 [ 214.678444] uhci_hcd 0000:00:1d.2: USB bus 6 deregistered [ 214.678574] ACPI: PCI interrupt for device 0000:00:1d.2 disabled [ 214.406336] pciback 0000:00:1d.2: seizing device [ 214.406537] ACPI: PCI Interrupt 0000:00:1d.2[C] -> GSI 18 (level, low) -> IRQ 18 [ 214.406725] ACPI: PCI interrupt for device 0000:00:1d.2 disabled [ 214.410481] ehci_hcd 0000:00:1d.7: remove, state 4 [ 214.410691] usb usb8: USB disconnect, address 1 [ 214.414959] ehci_hcd 0000:00:1d.7: USB bus 8 deregistered [ 214.415200] ACPI: PCI interrupt for device 0000:00:1d.7 disabled [ 214.421670] pciback 0000:00:1d.7: seizing device [ 214.422214] ACPI: PCI Interrupt 0000:00:1d.7[D] -> GSI 19 (level, low) -> IRQ 19 [ 214.422407] ACPI: PCI interrupt for device 0000:00:1d.7 disabled [ 231.366540] Setting mem allocation to 973824 kiB (XEN) memory.c:128:d0 Could not allocate order=9 extent: id=1 memflags=0 (2 of 4) (XEN) memory.c:128:d0 Could not allocate order=9 extent: id=1 memflags=0 (0 of 3) [ 232.398469] pciback: vpci: 0000:00:1a.7: assign to virtual slot 0 [ 232.398776] pciback: vpci: 0000:00:1a.2: assign to virtual slot 0 func 2 [ 232.398973] pciback: vpci: 0000:00:1a.1: assign to virtual slot 0 func 1 [ 232.399171] pciback: vpci: 0000:00:1a.0: assign to virtual slot 0 func 0 [ 232.399318] pciback: vpci: 0000:00:1d.7: assign to virtual slot 1 [ 232.399509] pciback: vpci: 0000:00:1d.2: assign to virtual slot 1 func 2 [ 232.399717] pciback: vpci: 0000:00:1d.1: assign to virtual slot 1 func 1 [ 232.399946] pciback: vpci: 0000:00:1d.0: assign to virtual slot 1 func 0 (XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1a.0 (XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1a.0 (XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 14 device = 1a intx = 0 (XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1a.7 (XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1a.7 (XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 17 device = 1a intx = 3 (XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1a.2 (XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1a.2 (XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 16 device = 1a intx = 2 (XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1a.1 (XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1a.1 (XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 15 device = 1a intx = 1 (XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1d.0 (XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1d.0 (XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 10 device = 1d intx = 0 (XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1d.7 (XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1d.7 (XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 13 device = 1d intx = 3 (XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1d.2 (XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1d.2 (XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 12 device = 1d intx = 2 (XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1d.1 (XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1d.1 (XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 11 device = 1d intx = 1 (XEN) HVM1: HVM Loader (XEN) HVM1: Detected Xen v3.4.1-rc7 (XEN) HVM1: CPU speed is 2394 MHz (XEN) irq.c:243: Dom1 PCI link 0 changed 0 -> 5 (XEN) HVM1: PCI-ISA link 0 routed to IRQ5 (XEN) irq.c:243: Dom1 PCI link 1 changed 0 -> 10 (XEN) HVM1: PCI-ISA link 1 routed to IRQ10 (XEN) irq.c:243: Dom1 PCI link 2 changed 0 -> 11 (XEN) HVM1: PCI-ISA link 2 routed to IRQ11 (XEN) irq.c:243: Dom1 PCI link 3 changed 0 -> 5 (XEN) HVM1: PCI-ISA link 3 routed to IRQ5 (XEN) HVM1: pci dev 01:3 INTA->IRQ10 (XEN) HVM1: pci dev 03:0 INTA->IRQ5 (XEN) HVM1: pci dev 02:0 bar 10 size 02000000: f0000008 (XEN) HVM1: pci dev 03:0 bar 14 size 01000000: f2000008 (XEN) HVM1: pci dev 02:0 bar 14 size 00001000: f3000000 (XEN) HVM1: pci dev 03:0 bar 10 size 00000100: 0000c001 (XEN) HVM1: pci dev 01:1 bar 20 size 00000010: 0000c101 (XEN) HVM1: Multiprocessor initialisation: (XEN) HVM1: - CPU0 ... 36-bit phys ... fixed MTRRs ... var MTRRs [2/8] ... done. (XEN) HVM1: Writing SMBIOS tables ... (XEN) HVM1: Loading ROMBIOS ... (XEN) HVM1: 10940 bytes of ROMBIOS high-memory extensions: (XEN) HVM1: Relocating to 0xfc000000-0xfc002abc ... done (XEN) HVM1: Creating MP tables ... (XEN) HVM1: Loading Cirrus VGABIOS ... (XEN) HVM1: Loading ACPI ... (XEN) HVM1: - Lo data: 000ea020-000ea04f (XEN) HVM1: - Hi data: fc002c00-fc0121cf (XEN) HVM1: vm86 TSS at fc012400 (XEN) HVM1: BIOS map: (XEN) HVM1: c0000-c8fff: VGA BIOS (XEN) HVM1: eb000-eb148: SMBIOS tables (XEN) HVM1: f0000-fffff: Main BIOS (XEN) HVM1: Invoking ROMBIOS ... (XEN) HVM1: $Revision: 1.221 $ $Date: 2008/12/07 17:32:29 $ (XEN) stdvga.c:147:d1 entering stdvga and caching modes (XEN) HVM1: VGABios $Id: vgabios.c,v 1.67 2008/01/27 09:44:12 vruppert Exp $ (XEN) HVM1: Bochs BIOS - build: 06/23/99 (XEN) HVM1: $Revision: 1.221 $ $Date: 2008/12/07 17:32:29 $ (XEN) HVM1: Options: apmbios pcibios eltorito PMM (XEN) HVM1: (XEN) HVM1: ata0-0: PCHS=12483/16/63 translation=lba LCHS=783/255/63 (XEN) HVM1: ata0 master: QEMU HARDDISK ATA-7 Hard-Disk (6144 MBytes) (XEN) HVM1: IDE time out (XEN) HVM1: (XEN) HVM1: (XEN) HVM1: (XEN) HVM1: Press F12 for boot menu. (XEN) HVM1: (XEN) HVM1: Booting from Hard Disk... (XEN) HVM1: Booting from 0000:7c00 (XEN) HVM1: int13_harddisk: function 15, unmapped device for ELDL=81 (XEN) HVM1: *** int 15h function AX=e980, BX=006e not yet supported! (XEN) common.c:3623:d0 tracking VRAM f0000 - f0026 (XEN) irq.c:243: Dom1 PCI link 0 changed 5 -> 0 (XEN) irq.c:243: Dom1 PCI link 1 changed 10 -> 0 (XEN) irq.c:243: Dom1 PCI link 2 changed 11 -> 0 (XEN) irq.c:243: Dom1 PCI link 3 changed 5 -> 0 (XEN) domctl.c:835:d0 memory_map:add: gfn=fbfff mfn=80101 nr_mfns=1 (XEN) domctl.c:886:d0 ioport_map:add f_gport=ffe0 f_mport=1c00 np=20 (XEN) domctl.c:886:d0 ioport_map:add f_gport=ffc0 f_mport=1860 np=20 (XEN) domctl.c:886:d0 ioport_map:add f_gport=ffa0 f_mport=18e0 np=20 (XEN) domctl.c:886:d0 ioport_map:add f_gport=ff80 f_mport=1880 np=20 (XEN) domctl.c:886:d0 ioport_map:add f_gport=ff60 f_mport=18c0 np=20 (XEN) domctl.c:886:d0 ioport_map:add f_gport=ff40 f_mport=18a0 np=20 (XEN) domctl.c:835:d0 memory_map:add: gfn=fbffe mfn=80100 nr_mfns=1 [ 287.624198] ACPI: EC: missing write data confirmation, don''t expect it any longer. [ 293.748242] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen [ 293.748353] ata2.00: cmd a0/00:00:00:00:00/00:00:00:00:00/a0 tag 0 [ 293.748354] cdb 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 [ 293.748354] res 40/00:03:00:00:00/00:00:00:00:00/a0 Emask 0x4 (timeout) [ 293.748658] ata2.00: status: { DRDY } [ 294.060173] ata2: soft resetting link [ 294.224137] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 297.579229] ata1.00: exception Emask 0x0 SAct 0xffff SErr 0x0 action 0x2 frozen [ 297.579344] ata1.00: cmd 61/10:00:f7:9e:ed/00:00:00:00:00/40 tag 0 ncq 8192 out [ 297.579345] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 297.579570] ata1.00: status: { DRDY } [ 297.579657] ata1.00: cmd 61/18:08:6f:bc:65/00:00:00:00:00/40 tag 1 ncq 12288 out [ 297.579658] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 297.579849] ata1.00: status: { DRDY } [ 297.579936] ata1.00: cmd 61/10:10:3f:1c:67/00:00:00:00:00/40 tag 2 ncq 8192 out [ 297.579937] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 297.580127] ata1.00: status: { DRDY } [ 297.580215] ata1.00: cmd 61/08:18:27:26:67/00:00:00:00:00/40 tag 3 ncq 4096 out [ 297.580216] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 297.580406] ata1.00: status: { DRDY } [ 297.580494] ata1.00: cmd 61/08:20:2f:26:67/00:00:00:00:00/40 tag 4 ncq 4096 out [ 297.580494] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 297.580684] ata1.00: status: { DRDY } [ 297.580795] ata1.00: cmd 61/20:28:6f:27:67/00:00:00:00:00/40 tag 5 ncq 16384 out [ 297.580796] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 297.580999] ata1.00: status: { DRDY } [ 297.581088] ata1.00: cmd 61/08:30:0f:33:67/00:00:00:00:00/40 tag 6 ncq 4096 out [ 297.581089] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 297.581281] ata1.00: status: { DRDY } [ 297.581369] ata1.00: cmd 61/10:38:1f:9f:ed/00:00:00:00:00/40 tag 7 ncq 8192 out [ 297.581370] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 297.581563] ata1.00: status: { DRDY } [ 297.581652] ata1.00: cmd 61/08:40:17:33:67/00:00:00:00:00/40 tag 8 ncq 4096 out [ 297.581653] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 297.582447] ata1.00: status: { DRDY } [ 297.582536] ata1.00: cmd 61/50:48:c7:35:67/00:00:00:00:00/40 tag 9 ncq 40960 out [ 297.582537] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 297.582728] ata1.00: status: { DRDY } [ 297.582817] ata1.00: cmd 61/10:50:b7:36:67/00:00:00:00:00/40 tag 10 ncq 8192 out [ 297.582818] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 297.583009] ata1.00: status: { DRDY } [ 297.583097] ata1.00: cmd 61/08:58:3f:3a:67/00:00:00:00:00/40 tag 11 ncq 4096 out [ 297.583098] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 297.583293] ata1.00: status: { DRDY } [ 297.583383] ata1.00: cmd 61/10:60:47:3a:67/00:00:00:00:00/40 tag 12 ncq 8192 out [ 297.583384] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 297.583613] ata1.00: status: { DRDY } [ 297.583701] ata1.00: cmd 61/10:68:87:3b:67/00:00:00:00:00/40 tag 13 ncq 8192 out [ 297.583702] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 297.583894] ata1.00: status: { DRDY } [ 297.583983] ata1.00: cmd 61/10:70:8f:3f:67/00:00:00:00:00/40 tag 14 ncq 8192 out [ 297.583983] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 297.584174] ata1.00: status: { DRDY } [ 297.584262] ata1.00: cmd 61/08:78:27:41:67/00:00:00:00:00/40 tag 15 ncq 4096 out [ 297.584263] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 297.584458] ata1.00: status: { DRDY } [ 297.266871] ata1: soft resetting link [ 297.430863] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Tom _______________________________________________ Xen-devel mailing list Xen-devel@lists.xensource.com http://lists.xensource.com/xen-devel _______________________________________________ Xen-devel mailing list Xen-devel@lists.xensource.com http://lists.xensource.com/xen-devel
Tom Rotenberg
2009-Jul-27 08:50 UTC
Re: [Xen-devel] Machine hangs when doing pass-through of all the USB devices on a Lenovo T400 - With xen-unstable
Hi,
I did it, and it looks like both cpu''s are idle:
...
(XEN) *** Dumping CPU0 guest state: ***
(XEN) No guest context (CPU is idle).
...
(XEN) *** Dumping CPU1 guest state: ***
(XEN) No guest context (CPU is idle).
...
What can cause it?
Here is the full log of the result:
(XEN) [VT-D]iommu.c:1278:d0 domain_context_unmap:PCI: bdf = 0:1d.0
(XEN) [VT-D]iommu.c:1164:d0 domain_context_mapping:PCI: bdf = 0:1d.0
(XEN) [VT-D]iommu.c:1278:d0 domain_context_unmap:PCI: bdf = 0:1a.0
(XEN) [VT-D]iommu.c:1164:d0 domain_context_mapping:PCI: bdf = 0:1a.0
[ 886.252440] pciback: vpci: 0000:00:1a.7: assign to virtual slot 0
[ 886.252736] pciback: vpci: 0000:00:1a.2: assign to virtual slot 0 func 2
[ 886.252915] pciback: vpci: 0000:00:1a.1: assign to virtual slot 0 func 1
[ 886.253071] pciback: vpci: 0000:00:1a.0: assign to virtual slot 0 func 0
[ 886.253336] pciback: vpci: 0000:00:1d.7: assign to virtual slot 1
[ 886.253503] pciback: vpci: 0000:00:1d.2: assign to virtual slot 1 func 2
[ 886.253658] pciback: vpci: 0000:00:1d.1: assign to virtual slot 1 func 1
[ 886.253812] pciback: vpci: 0000:00:1d.0: assign to virtual slot 1 func 0
(XEN) [VT-D]iommu.c:1278:d0 domain_context_unmap:PCI: bdf = 0:1a.0
(XEN) [VT-D]iommu.c:1164:d0 domain_context_mapping:PCI: bdf = 0:1a.0
(XEN) [VT-D]io.c:277:d0 VT-d irq bind: m_irq = 14 device = 4 intx = 0
(XEN) [VT-D]iommu.c:1278:d0 domain_context_unmap:PCI: bdf = 0:1a.7
(XEN) [VT-D]iommu.c:1164:d0 domain_context_mapping:PCI: bdf = 0:1a.7
(XEN) [VT-D]io.c:277:d0 VT-d irq bind: m_irq = 17 device = 4 intx = 3
(XEN) [VT-D]iommu.c:1278:d0 domain_context_unmap:PCI: bdf = 0:1a.2
(XEN) [VT-D]iommu.c:1164:d0 domain_context_mapping:PCI: bdf = 0:1a.2
(XEN) [VT-D]io.c:277:d0 VT-d irq bind: m_irq = 16 device = 4 intx = 2
(XEN) [VT-D]iommu.c:1278:d0 domain_context_unmap:PCI: bdf = 0:1a.1
(XEN) [VT-D]iommu.c:1164:d0 domain_context_mapping:PCI: bdf = 0:1a.1
(XEN) [VT-D]io.c:277:d0 VT-d irq bind: m_irq = 15 device = 4 intx = 1
(XEN) [VT-D]iommu.c:1278:d0 domain_context_unmap:PCI: bdf = 0:1d.0
(XEN) [VT-D]iommu.c:1164:d0 domain_context_mapping:PCI: bdf = 0:1d.0
(XEN) [VT-D]io.c:277:d0 VT-d irq bind: m_irq = 10 device = 5 intx = 0
(XEN) [VT-D]iommu.c:1278:d0 domain_context_unmap:PCI: bdf = 0:1d.7
(XEN) [VT-D]iommu.c:1164:d0 domain_context_mapping:PCI: bdf = 0:1d.7
(XEN) [VT-D]io.c:277:d0 VT-d irq bind: m_irq = 13 device = 5 intx = 3
(XEN) [VT-D]iommu.c:1278:d0 domain_context_unmap:PCI: bdf = 0:1d.2
(XEN) [VT-D]iommu.c:1164:d0 domain_context_mapping:PCI: bdf = 0:1d.2
(XEN) [VT-D]io.c:277:d0 VT-d irq bind: m_irq = 12 device = 5 intx = 2
(XEN) [VT-D]iommu.c:1278:d0 domain_context_unmap:PCI: bdf = 0:1d.1
(XEN) [VT-D]iommu.c:1164:d0 domain_context_mapping:PCI: bdf = 0:1d.1
(XEN) [VT-D]io.c:277:d0 VT-d irq bind: m_irq = 11 device = 5 intx = 1
(XEN) HVM3: HVM Loader
(XEN) HVM3: Detected Xen v3.5-unstable
(XEN) HVM3: CPU speed is 2394 MHz
(XEN) irq.c:243: Dom3 PCI link 0 changed 0 -> 5
(XEN) HVM3: PCI-ISA link 0 routed to IRQ5
(XEN) irq.c:243: Dom3 PCI link 1 changed 0 -> 10
(XEN) HVM3: PCI-ISA link 1 routed to IRQ10
(XEN) irq.c:243: Dom3 PCI link 2 changed 0 -> 11
(XEN) HVM3: PCI-ISA link 2 routed to IRQ11
(XEN) irq.c:243: Dom3 PCI link 3 changed 0 -> 5
(XEN) HVM3: PCI-ISA link 3 routed to IRQ5
(XEN) HVM3: pci dev 01:3 INTA->IRQ10
(XEN) HVM3: pci dev 03:0 INTA->IRQ5
(XEN) HVM3: pci dev 04:0 INTA->IRQ5
(XEN) HVM3: pci dev 04:1 INTA->IRQ5
(XEN) HVM3: pci dev 04:2 INTA->IRQ5
(XEN) HVM3: pci dev 04:7 INTA->IRQ5
(XEN) HVM3: pci dev 05:0 INTA->IRQ10
(XEN) HVM3: pci dev 05:1 INTA->IRQ10
(XEN) HVM3: pci dev 05:2 INTA->IRQ10
(XEN) HVM3: pci dev 05:7 INTA->IRQ10
(XEN) HVM3: pci dev 02:0 bar 10 size 02000000: f0000008
(XEN) HVM3: pci dev 03:0 bar 14 size 01000000: f2000008
(XEN) HVM3: pci dev 02:0 bar 14 size 00001000: f3000000
(XEN) HVM3: pci dev 04:7 bar 10 size 00001000: f3001000
(XEN) domctl.c:836:d0 memory_map:add: gfn=f3001 mfn=80100 nr_mfns=1
(XEN) HVM3: pci dev 05:7 bar 10 size 00001000: f3002000
(XEN) domctl.c:836:d0 memory_map:add: gfn=f3002 mfn=80101 nr_mfns=1
(XEN) HVM3: pci dev 03:0 bar 10 size 00000100: 0000c001
(XEN) HVM3: pci dev 04:0 bar 20 size 00000020: 0000c101
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c100 f_mport=1860 np=20
(XEN) HVM3: pci dev 04:1 bar 20 size 00000020: 0000c121
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c120 f_mport=1880 np=20
(XEN) HVM3: pci dev 04:2 bar 20 size 00000020: 0000c141
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c140 f_mport=18a0 np=20
(XEN) HVM3: pci dev 05:0 bar 20 size 00000020: 0000c161
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c160 f_mport=18c0 np=20
(XEN) HVM3: pci dev 05:1 bar 20 size 00000020: 0000c181
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c180 f_mport=18e0 np=20
(XEN) HVM3: pci dev 05:2 bar 20 size 00000020: 0000c1a1
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c1a0 f_mport=1c00 np=20
(XEN) HVM3: pci dev 01:1 bar 20 size 00000010: 0000c1c1
(XEN) HVM3: Multiprocessor initialisation:
(XEN) HVM3: - CPU0 ... 36-bit phys ... fixed MTRRs ... var MTRRs
[2/8] ... done.
(XEN) HVM3: Testing HVM environment:
(XEN) HVM3: - REP INSB across page boundaries ... passed
(XEN) HVM3: - GS base MSRs and SWAPGS ... passed
(XEN) HVM3: Passed 2 of 2 tests
(XEN) HVM3: Writing SMBIOS tables ...
(XEN) HVM3: Loading ROMBIOS ...
(XEN) HVM3: 9788 bytes of ROMBIOS high-memory extensions:
(XEN) HVM3: Relocating to 0xfc000000-0xfc00263c ... done
(XEN) HVM3: Creating MP tables ...
(XEN) HVM3: Loading Cirrus VGABIOS ...
(XEN) HVM3: Loading ACPI ...
(XEN) HVM3: - Lo data: 000ea020-000ea04f
(XEN) HVM3: - Hi data: fc002800-fc011dcf
(XEN) HVM3: vm86 TSS at fc012000
(XEN) HVM3: BIOS map:
(XEN) HVM3: c0000-c8fff: VGA BIOS
(XEN) HVM3: eb000-eb14e: SMBIOS tables
(XEN) HVM3: f0000-fffff: Main BIOS
(XEN) HVM3: Invoking ROMBIOS ...
(XEN) HVM3: $Revision: 1.221 $ $Date: 2008/12/07 17:32:29 $
(XEN) stdvga.c:147:d3 entering stdvga and caching modes
(XEN) HVM3: VGABios $Id: vgabios.c,v 1.67 2008/01/27 09:44:12 vruppert Exp $
(XEN) HVM3: Bochs BIOS - build: 06/23/99
(XEN) HVM3: $Revision: 1.221 $ $Date: 2008/12/07 17:32:29 $
(XEN) HVM3: Options: apmbios pcibios eltorito PMM
(XEN) HVM3:
(XEN) HVM3: ata0-0: PCHS=12483/16/63 translation=lba LCHS=783/255/63
(XEN) HVM3: ata0 master: QEMU HARDDISK ATA-7 Hard-Disk (6144 MBytes)
(XEN) HVM3: IDE time out
(XEN) HVM3:
(XEN) HVM3:
(XEN) HVM3:
(XEN) HVM3: Press F12 for boot menu.
(XEN) HVM3:
(XEN) HVM3: Booting from Hard Disk...
(XEN) HVM3: Booting from 0000:7c00
(XEN) HVM3: int13_harddisk: function 15, unmapped device for ELDL=81
(XEN) HVM3: *** int 15h function AX=e980, BX=006e not yet supported!
(XEN) common.c:3626:d0 tracking VRAM f0000 - f0026
(XEN) mce.c:693:d3 MCE: rdmsr MCG_CAP lo 806 hi 0
(XEN) mce.c:714:d3 MCE: rd MC0_CTL lo ffffffff hi ffffffff
(XEN) mce.c:693:d3 MCE: rdmsr MCG_CAP lo 806 hi 0
(XEN) irq.c:243: Dom3 PCI link 0 changed 5 -> 0
(XEN) irq.c:243: Dom3 PCI link 1 changed 10 -> 0
(XEN) irq.c:243: Dom3 PCI link 2 changed 11 -> 0
(XEN) irq.c:243: Dom3 PCI link 3 changed 5 -> 0
(XEN) domctl.c:911:d0 ioport_map:remove f_gport=c100 f_mport=1860 np=20
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c100 f_mport=1860 np=20
(XEN) domctl.c:911:d0 ioport_map:remove f_gport=c120 f_mport=1880 np=20
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c120 f_mport=1880 np=20
(XEN) domctl.c:911:d0 ioport_map:remove f_gport=c140 f_mport=18a0 np=20
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c140 f_mport=18a0 np=20
(XEN) domctl.c:846:d0 memory_map:remove: gfn=f3001 mfn=80100 nr_mfns=1
(XEN) domctl.c:836:d0 memory_map:add: gfn=f3001 mfn=80100 nr_mfns=1
(XEN) domctl.c:911:d0 ioport_map:remove f_gport=c160 f_mport=18c0 np=20
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c160 f_mport=18c0 np=20
(XEN) domctl.c:911:d0 ioport_map:remove f_gport=c180 f_mport=18e0 np=20
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c180 f_mport=18e0 np=20
(XEN) domctl.c:911:d0 ioport_map:remove f_gport=c1a0 f_mport=1c00 np=20
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c1a0 f_mport=1c00 np=20
(XEN) domctl.c:846:d0 memory_map:remove: gfn=f3002 mfn=80101 nr_mfns=1
(XEN) domctl.c:836:d0 memory_map:add: gfn=f3002 mfn=80101 nr_mfns=1
(XEN) domctl.c:911:d0 ioport_map:remove f_gport=c1a0 f_mport=1c00 np=20
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c1a0 f_mport=1c00 np=20
(XEN) domctl.c:911:d0 ioport_map:remove f_gport=c180 f_mport=18e0 np=20
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c180 f_mport=18e0 np=20
(XEN) domctl.c:911:d0 ioport_map:remove f_gport=c100 f_mport=1860 np=20
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c100 f_mport=1860 np=20
(XEN) domctl.c:911:d0 ioport_map:remove f_gport=c160 f_mport=18c0 np=20
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c160 f_mport=18c0 np=20
(XEN) domctl.c:911:d0 ioport_map:remove f_gport=c120 f_mport=1880 np=20
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c120 f_mport=1880 np=20
(XEN) domctl.c:911:d0 ioport_map:remove f_gport=c140 f_mport=18a0 np=20
(XEN) domctl.c:887:d0 ioport_map:add f_gport=c140 f_mport=18a0 np=20
(XEN) stdvga.c:151:d3 leaving stdvga
(XEN) common.c:3602:d0 stopping tracking VRAM f0000 - f0026
(XEN) common.c:3626:d0 tracking VRAM f0000 - f0160
[ 916.760770] ACPI: EC: missing write data confirmation, don''t expect
it any longer.
(XEN) *** Serial input -> Xen (type ''CTRL-a'' three times to
switch
input to DOM0)
(XEN) ''d'' pressed -> dumping registers
(XEN)
(XEN) *** Dumping CPU0 host state: ***
(XEN) ----[ Xen-3.5-unstable x86_64 debug=y Not tainted ]----
(XEN) CPU: 0
(XEN) RIP: e008:[<ffff828c8010ed21>] __dump_execstate+0x9/0x78
(XEN) RFLAGS: 0000000000010296 CONTEXT: hypervisor
(XEN) rax: 0000000000000000 rbx: 0000000000000064 rcx: 0000000000000000
(XEN) rdx: 0000000000000000 rsi: 000000000000000a rdi: 0000000000000000
(XEN) rbp: ffff828c8026fc58 rsp: ffff828c8026fc48 r8: 0000000000000004
(XEN) r9: ffff828c801ee420 r10: 00000000fffffffc r11: 0000000000000001
(XEN) r12: ffff828c8026fe38 r13: 0000000000000064 r14: 0000000000000286
(XEN) r15: ffff828c8026fe38 cr0: 000000008005003b cr4: 00000000000026f0
(XEN) cr3: 000000007cde0000 cr2: ffff880033dd6ae0
(XEN) ds: 0000 es: 0000 fs: 0000 gs: 0000 ss: e010 cs: e008
(XEN) Xen stack trace from rsp=ffff828c8026fc48:
(XEN) 0000000000000064 0000000000000064 ffff828c8026fc98 ffff828c8010eded
(XEN) ffff828c8026fc88 0000000000000064 ffff828c8026fe38 0000000000000064
(XEN) 0000000000000286 ffff828c8026fe38 ffff828c8026fcb8 ffff828c8010ef8f
(XEN) ffff828c8026fe38 ffff828c8021df68 ffff828c8026fcc8 ffff828c80130f0e
(XEN) ffff828c8026fcf8 ffff828c80130fe8 ffff828c8026fcf8 ffff828c8021e040
(XEN) ffff828c80130f52 ffff828c8021e0b8 ffff828c8026fd48 ffff828c8013222c
(XEN) ffff828c8026fd18 ffff828c80131571 64ff828c8026fd48 0000000000000061
(XEN) ffff828c8028c9a0 ffff828c8021e040 ffff828c8026fe38 ffff828c8026ff28
(XEN) ffff828c8026fd78 ffff828c80131916 ffff828c8029d380 ffff828c8026fe38
(XEN) 0000000000000000 ffff828c80286d00 ffff828c8026fe28 ffff828c801523dc
(XEN) 0000000000000000 ffff828c8026fde8 00000000000000f1 ffff828c8026ff28
(XEN) ffff828c8026fdc8 ffff828c80247080 ffff828c8029d3a4 000000f180247080
(XEN) ffff828c8028c9c8 0000000080654e38 ffff828c8026fe08 ffff83007c70e000
(XEN) 0000000000000012 0000000000000000 0000000000000046 ffff828c8026ff28
(XEN) ffff828c80224fa0 ffff828c8026ff28 ffff828c80247600 ffff828c802252b8
(XEN) 00007d737fd901a7 ffff828c8014b846 ffff828c802252b8 ffff828c80247600
(XEN) ffff828c8026ff28 ffff828c80224fa0 ffff828c8026fee0 ffff828c8026ff28
(XEN) 000000f496a709de ffff830039a12838 ffff83007e6b2ab0 0000000000000000
(XEN) 0000000000000000 0000000000000000 ffff828c80286d00 0000000000000000
(XEN) ffff83007e4f2000 000000f100000000 ffff828c801455ce 000000000000e008
(XEN) Xen call trace:
(XEN) [<ffff828c8010ed21>] __dump_execstate+0x9/0x78
(XEN) [<ffff828c8010eded>] dump_registers+0x5d/0x144
(XEN) [<ffff828c8010ef8f>] handle_keypress+0x76/0xa7
(XEN) [<ffff828c80130f0e>] __serial_rx+0x16/0x5a
(XEN) [<ffff828c80130fe8>] serial_rx+0x96/0xa1
(XEN) [<ffff828c8013222c>] serial_rx_interrupt+0xb3/0xc9
(XEN) [<ffff828c80131916>] ns16550_interrupt+0x47/0x61
(XEN) [<ffff828c801523dc>] do_IRQ+0x492/0x513
(XEN) [<ffff828c8014b846>] common_interrupt+0x26/0x30
(XEN) [<ffff828c801455ce>] default_idle+0x2f/0x34
(XEN) [<ffff828c801477f0>] idle_loop+0x8e/0x98
(XEN)
(XEN) *** Dumping CPU0 guest state: ***
(XEN) No guest context (CPU is idle).
(XEN)
(XEN) *** Dumping CPU1 host state: ***
(XEN) ----[ Xen-3.5-unstable x86_64 debug=y Not tainted ]----
(XEN) CPU: 1
(XEN) RIP: e008:[<ffff828c8010ed21>] __dump_execstate+0x9/0x78
(XEN) RFLAGS: 0000000000010092 CONTEXT: hypervisor
(XEN) rax: 0000000000000080 rbx: ffff83007e4e7f28 rcx: ffff828c8010ed18
(XEN) rdx: ffff828c80286d00 rsi: ffff828c80248620 rdi: 0000000000000000
(XEN) rbp: ffff83007e4e7e08 rsp: ffff83007e4e7df8 r8: 0000000000000001
(XEN) r9: 0000000000000002 r10: ffff83007ac70418 r11: 000000d70c55e44f
(XEN) r12: ffff828c80224fa0 r13: ffff83007e4e7f28 r14: ffff828c80247600
(XEN) r15: ffff828c802252b8 cr0: 000000008005003b cr4: 00000000000026f0
(XEN) cr3: 000000007e674000 cr2: 0000000000b47e88
(XEN) ds: 0000 es: 0000 fs: 0000 gs: 0000 ss: 0000 cs: e008
(XEN) Xen stack trace from rsp=ffff83007e4e7df8:
(XEN) ffff828c80248100 ffff83007e4e7f28 ffff83007e4e7e18 ffff828c80163104
(XEN) ffff83007e4e7e28 ffff828c8016317d 00007cff81b181a7 ffff828c8014c900
(XEN) ffff828c802252b8 ffff828c80247600 ffff83007e4e7f28 ffff828c80224fa0
(XEN) ffff83007e4e7ee0 ffff83007e4e7f28 000000d70c55e44f ffff83007ac70418
(XEN) 0000000000000002 0000000000000001 0000000000000080 0000000000000001
(XEN) ffff828c80286d00 ffff828c80248620 0000000000001000 000000fb00000000
(XEN) ffff828c801455ce 000000000000e008 0000000000000246 ffff83007e4e7ee0
(XEN) 0000000000000000 ffff83007e4e7f20 ffff828c801477f0 ffff83007cf34000
(XEN) ffff83007e6b8000 0000000000000001 ffff83007cf34000 000000d667038df0
(XEN) ffff828c80248080 ffff83007e4e7d98 0000000000000000 0000000000000000
(XEN) 0000000000000000 0000000000000000 0000000080550234 000000000000c1c0
(XEN) 0000000000000000 0000000000000000 0000000000000000 0000000000000000
(XEN) 0000000000000000 0000000000000001 000000000000c1c0 00000000806eaaac
(XEN) 00000000865a94e0 0000beef0000beef 00000000806eaab5 0000beef0000beef
(XEN) 0000000000000246 000000008055021c 000000000000beef 555555555555beef
(XEN) 555555555555beef 555555555555beef 555555555555beef 5555555500000001
(XEN) ffff83007e6b8000
(XEN) Xen call trace:
(XEN) [<ffff828c8010ed21>] __dump_execstate+0x9/0x78
(XEN) [<ffff828c8063104>] __smp_call_function_interrupt+0x66/0xac
(XEN) [<ffff828c8016317d>] smp_call_function_interrupt+0x33/0x35
(XEN) [<ffff828c8014c900>] call_function_interrupt+0x30/0x40
(XEN) [<ffff828c801455ce>] default_idle+0x2f/0x34
(XEN) [<ffff828c801477f0>] idle_loop+0x8e/0x98
(XEN)
(XEN) *** Dumping CPU1 guest state: ***
(XEN) No guest context (CPU is idle).
(XEN)
(XEN) ''d'' pressed -> dumping registers
(XEN)
(XEN) *** Dumping CPU0 host state: ***
(XEN) ----[ Xen-3.5-unstable x86_64 debug=y Not tainted ]----
(XEN) CPU: 0
(XEN) RIP: e008:[<ffff828c8010ed21>] __dump_execstate+0x9/0x78
(XEN) RFLAGS: 0000000000010296 CONTEXT: hypervisor
(XEN) rax: 0000000000000000 rbx: 0000000000000064 rcx: 0000000000000000
(XEN) rdx: 0000000000000000 rsi: 000000000000000a rdi: 0000000000000000
(XEN) rbp: ffff828c8026fc58 rsp: ffff828c8026fc48 r8: 0000000000000004
(XEN) r9: ffff828c801ee420 r10: 00000000fffffffc r11: 0000000000000001
(XEN) r12: ffff828c8026fe38 r13: 0000000000000064 r14: 0000000000000286
(XEN) r15: ffff828c8026fe38 cr0: 000000008005003b cr4: 00000000000026f0
(XEN) cr3: 000000003995e000 cr2: ffff880033dd6ae0
(XEN) ds: 002b es: 002b fs: 0000 gs: 0000 ss: e010 cs: e008
(XEN) Xen stack trace from rsp=ffff828c8026fc48:
(XEN) 0000000000000064 0000000000000064 ffff828c8026fc98 ffff828c8010eded
(XEN) ffff828c8026fc88 0000000000000064 ffff828c8026fe38 0000000000000064
(XEN) 0000000000000286 ffff828c8026fe38 ffff828c8026fcb8 ffff828c8010ef8f
(XEN) ffff828c8026fe38 ffff828c8021df68 ffff828c8026fcc8 ffff828c80130f0e
(XEN) ffff828c8026fcf8 ffff828c80130fe8 ffff828c8026fcf8 ffff828c8021e040
(XEN) ffff828c80130f52 ffff828c8021e0b8 ffff828c8026fd48 ffff828c8013222c
(XEN) ffff828c8026fd18 ffff828c80131571 64ff828c8026fd48 0000000000000061
(XEN) ffff828c8028c9a0 ffff828c8021e040 ffff828c8026fe38 ffff828c8026ff28
(XEN) ffff828c8026fd78 ffff828c80131916 ffff828c8029d380 ffff828c8026fe38
(XEN) 0000000000000000 ffff828c80286d00 ffff828c8026fe28 ffff828c801523dc
(XEN) 0000000000000016 ffff828c8026fde8 00000000000000f1 ffff828c8026ff28
(XEN) ffff830077a2b690 ffff828c8026fdc0 ffff828c8029d3a4 000000f18026fe20
(XEN) ffff828c8028c9c8 0000000000400000 ffff828c8026fe08 0000000000000001
(XEN) ffff83007e4f2000 ffff830077a2b698 ffff828c80247100 ffff828c8026ff28
(XEN) ffff828c80224fa0 ffff828c8026ff28 ffff828c80247600 ffff828c802252b8
(XEN) 00007d737fd901a7 ffff828c8014b846 ffff828c802252b8 ffff828c80247600
(XEN) ffff828c8026ff28 ffff828c80224fa0 ffff828c8026fee0 ffff828c8026ff28
(XEN) 000000da1d12f48e ffff830039a12cb8 0000000000000006 0000000000000003
(XEN) 0000000000000000 0000000000000001 ffff828c80286d00 ffff828c80247620
(XEN) 0000000000000000 000000f100000000 ffff828c801455ce 000000000000e008
(XEN) Xen call trace:
(XEN) [<ffff828c8010ed21>] __dump_execstate+0x9/0x78
(XEN) [<ffff828c8010eded>] dump_registers+0x5d/0x144
(XEN) [<ffff828c8010ef8f>] handle_keypress+0x76/0xa7
(XEN) [<ffff828c80130f0e>] __serial_rx+0x16/0x5a
(XEN) [<ffff828c80130fe8>] serial_rx+0x96/0xa1
(XEN) [<ffff828c8013222c>] serial_rx_interrupt+0xb3/0xc9
(XEN) [<ffff828c80131916>] ns16550_interrupt+0x47/0x61
(XEN) [<ffff828c801523dc>] do_IRQ+0x492/0x513
(XEN) [<ffff828c8014b846>] common_interrupt+0x26/0x30
(XEN) [<ffff828c801455ce>] default_idle+0x2f/0x34
(XEN) [<ffff828c801477f0>] idle_loop+0x8e/0x98
(XEN)
(XEN) *** Dumping CPU0 guest state: ***
(XEN) No guest context (CPU is idle).
(XEN)
(XEN) *** Dumping CPU1 host state: ***
(XEN) ----[ Xen-3.5-unstable x86_64 debug=y Not tainted ]----
(XEN) CPU: 1
(XEN) RIP: e008:[<ffff828c8010ed21>] __dump_execstate+0x9/0x78
(XEN) RFLAGS: 0000000000010092 CONTEXT: hypervisor
(XEN) rax: 0000000000000080 rbx: ffff83007e4e7f28 rcx: ffff828c8010ed18
(XEN) rdx: ffff828c80286d00 rsi: 0000000000000000 rdi: 0000000000000000
(XEN) rbp: ffff83007e4e7e08 rsp: ffff83007e4e7df8 r8: 0000000000000000
(XEN) r9: ffff83007e6ba010 r10: ffff83007ac70780 r11: 0000000000000246
(XEN) r12: ffff828c80224fa0 r13: ffff83007e4e7f28 r14: ffff828c80247600
(XEN) r15: ffff828c802252b8 cr0: 000000008005003b cr4: 00000000000026f0
(XEN) cr3: 000000007cde0000 cr2: 0000000000b47e88
(XEN) ds: 0000 es: 0000 fs: 0000 gs: 0000 ss: e010 cs: e008
(XEN) Xen stack trace from rsp=ffff83007e4e7df8:
(XEN) 0000000000000006 ffff83007e4e7f28 ffff83007e4e7e18 ffff828c80163104
(XEN) ffff83007e4e7e28 ffff828c8016317d 00007cff81b181a7 ffff828c8014c900
(XEN) ffff828c802252b8 ffff828c80247600 ffff83007e4e7f28 ffff828c80224fa0
(XEN) ffff83007e4e7ee0 ffff83007e4e7f28 0000000000000246 ffff83007ac70780
(XEN) ffff83007e6ba010 0000000000000000 0000000000000080 0000000000000000
(XEN) ffff828c80286d00 0000000000000000 0000000000001000 000000fb00000000
(XEN) ffff828c801455ce 000000000000e008 0000000000000246 ffff83007e4e7ee0
(XEN) 000000000000e010 ffff83007e4e7f20 ffff828c801477f0 ffff83007ad88000
(XEN) ffff83007e6b8000 0000000000000001 ffff83007ad88000 000000da2db61563
(XEN) ffff828c80248080 ffff83007e4e7d98 0000000000000000 0000000000000000
(XEN) 0000000000000000 0000000000000000 0000000000000000 0000000000000000
(XEN) 0000000000000246 0000000000000000 0000000100026d89 000000001de24f2c
(XEN) 0000000000000000 ffffffff802083aa 0000000000000001 00000000deadbeef
(XEN) 00000000deadbeef 0000010000000000 ffffffff802083aa 000000000000e033
(XEN) 0000000000000246 ffffffff80611f50 000000000000e02b 555555555555beef
(XEN) 555555555555beef 555555555555beef 555555555555beef 5555555500000001
(XEN) ffff83007e6b8000
(XEN) Xen call trace:
(XEN) [<ffff828c8010ed21>] __dump_execstate+0x9/0x78
(XEN) [<ffff828c80163104>] __smp_call_function_interrupt+0x66/0xac
(XEN) [<ffff828c8016317d>] smp_call_function_interrupt+0x33/0x35
(XEN) [<ffff828c8014c900>] call_function_interrupt+0x30/0x40
(XEN) [<ffff828c801455ce>] default_idle+0x2f/0x34
(XEN) [<ffff828c801477f0>] idle_loop+0x8e/0x98
(XEN)
(XEN) *** Dumping CPU1 guest state: ***
(XEN) No guest context (CPU is idle).
(XEN)
[ 937.930695] ata1.00: exception Emask 0x0 SAct 0x1 SErr 0x0 action 0x2 frozen
[ 937.930800] ata1.00: cmd 60/08:00:8f:b4:3f/00:00:00:00:00/40 tag 0
ncq 4096 in
[ 937.930801] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
0x4 (timeout)
[ 937.931021] ata1.00: status: { DRDY }
[ 938.242629] ata1: soft resetting link
[ 938.254624] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
[ 938.254725] ata2.00: cmd a0/00:00:00:00:00/00:00:00:00:00/a0 tag 0
[ 938.254726] cdb 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 938.254726] res 40/00:03:00:00:00/00:00:00:00:00/a0 Emask
0x4 (timeout)
[ 938.255029] ata2.00: status: { DRDY }
[ 938.406611] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[ 938.566577] ata2: soft resetting link
[ 938.730550] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
(XEN) ''d'' pressed -> dumping registers
(XEN)
(XEN) *** Dumping CPU0 host state: ***
(XEN) ----[ Xen-3.5-unstable x86_64 debug=y Not tainted ]----
(XEN) CPU: 0
(XEN) RIP: e008:[<ffff828c8010ed21>] __dump_execstate+0x9/0x78
(XEN) RFLAGS: 0000000000010296 CONTEXT: hypervisor
(XEN) rax: 0000000000000000 rbx: 0000000000000064 rcx: 0000000000000000
(XEN) rdx: 0000000000000000 rsi: 000000000000000a rdi: 0000000000000000
(XEN) rbp: ffff828c8026fc58 rsp: ffff828c8026fc48 r8: 0000000000000004
(XEN) r9: ffff828c801ee420 r10: 00000000fffffffc r11: 0000000000000001
(XEN) r12: ffff828c8026fe38 r13: 0000000000000064 r14: 0000000000000286
(XEN) r15: ffff828c8026fe38 cr0: 000000008005003b cr4: 00000000000026f0
(XEN) cr3: 000000003995e000 cr2: ffff880033dd6ae0
(XEN) ds: 002b es: 002b fs: 0000 gs: 0000 ss: e010 cs: e008
(XEN) Xen stack trace from rsp=ffff828c8026fc48:
(XEN) 0000000000000064 0000000000000064 ffff828c8026fc98 ffff828c8010eded
(XEN) ffff828c8026fc88 0000000000000064 ffff828c8026fe38 0000000000000064
(XEN) 0000000000000286 ffff828c8026fe38 ffff828c8026fcb8 ffff828c8010ef8f
(XEN) ffff828c8026fe38 ffff828c8021df68 ffff828c8026fcc8 ffff828c80130f0e
(XEN) ffff828c8026fcf8 ffff828c80130fe8 ffff828c8026fcf8 ffff828c8021e040
(XEN) ffff828c80130f52 ffff828c8021e0b8 ffff828c8026fd48 ffff828c8013222c
(XEN) ffff828c8026fd18 ffff828c80131571 64ff828c8026fd48 0000000000000061
(XEN) ffff828c8028c9a0 ffff828c8021e040 ffff828c8026fe38 ffff828c8026ff28
(XEN) ffff828c8026fd78 ffff828c80131916 ffff828c8029d380 ffff828c8026fe38
(XEN) 0000000000000000 ffff828c80286d00 ffff828c8026fe28 ffff828c801523dc
(XEN) 0000000000000016 ffff828c8026fde8 00000000000000f1 ffff828c8026ff28
(XEN) ffff830077a2b690 ffff828c8026fdc0 ffff828c8029d3a4 000000f18026fe20
(XEN) ffff828c8028c9c8 0000000000400000 ffff828c8026fe08 ffc0000000000001
(XEN) ffff83007e4f2000 ffff830077a2b698 ffff828c80247100 ffff828c8026ff28
(XEN) ffff828c80224fa0 ffff828c8026ff28 ffff828c80247600 ffff828c802252b8
(XEN) 00007d737fd901a7 ffff828c8014b846 ffff828c802252b8 ffff828c80247600
(XEN) ffff828c8026ff28 ffff828c80224fa0 ffff828c8026fee0 ffff828c8026ff28
(XEN) 000000dc89a17563 ffff830039a12cb8 0000000000000006 0000000000000003
(XEN) 0000000000000000 0000000000000001 ffff828c80286d00 ffff828c80247620
(XEN) 0000000000000000 000000f100000000 ffff828c801455ce 000000000000e008
(XEN) Xen call trace:
(XEN) [<ffff828c8010ed21>] __dump_execstate+0x9/0x78
(XEN) [<ffff828c8010eded>] dump_registers+0x5d/0x144
(XEN) [<ffff828c8010ef8f>] handle_keypress+0x76/0xa7
(XEN) [<ffff828c80130f0e>] __serial_rx+0x16/0x5a
(XEN) [<ffff828c80130fe8>] serial_rx+0x96/0xa1
(XEN) [<ffff828c8013222c>] serial_rx_interrupt+0xb3/0xc9
(XEN) [<ffff828c80131916>] ns16550_interrupt+0x47/0x61
(XEN) [<ffff828c801523dc>] do_IRQ+0x492/0x513
(XEN) [<ffff828c8014b846>] common_interrupt+0x26/0x30
(XEN) [<ffff828c801455ce>] default_idle+0x2f/0x34
(XEN) [<ffff828c801477f0>] idle_loop+0x8e/0x98
(XEN)
(XEN) *** Dumping CPU0 guest state: ***
(XEN) No guest context (CPU is idle).
(XEN)
(XEN) *** Dumping CPU1 host state: ***
(XEN) ----[ Xen-3.5-unstable x86_64 debug=y Not tainted ]----
(XEN) CPU: 1
(XEN) RIP: e008:[<ffff828c8010ed21>] __dump_execstate+0x9/0x78
(XEN) RFLAGS: 0000000000010092 CONTEXT: hypervisor
(XEN) rax: 0000000000000080 rbx: ffff83007e4e7f28 rcx: ffff828c8010ed18
(XEN) rdx: ffff828c80286d00 rsi: 0000000000000000 rdi: 0000000000000000
(XEN) rbp: ffff83007e4e7e08 rsp: ffff83007e4e7df8 r8: 0000000000000000
(XEN) r9: ffff83007e6ba010 r10: ffff83007ac70780 r11: 0000000000000246
(XEN) r12: ffff828c80224fa0 r13: ffff83007e4e7f28 r14: ffff828c80247600
(XEN) r15: ffff828c802252b8 cr0: 000000008005003b cr4: 00000000000026f0
(XEN) cr3: 000000007cde0000 cr2: 0000000000b47e88
(XEN) ds: 0000 es: 0000 fs: 0000 gs: 0000 ss: e010 cs: e008
(XEN) Xen stack trace from rsp=ffff83007e4e7df8:
(XEN) 0000000000000006 ffff83007e4e7f28 ffff83007e4e7e18 ffff828c80163104
(XEN) ffff83007e4e7e28 ffff828c8016317d 00007cff81b181a7 ffff828c8014c900
(XEN) ffff828c802252b8 ffff828c80247600 ffff83007e4e7f28 ffff828c80224fa0
(XEN) ffff83007e4e7ee0 ffff83007e4e7f28 0000000000000246 ffff83007ac70780
(XEN) ffff83007e6ba010 0000000000000000 0000000000000080 0000000000000000
(XEN) ffff828c80286d00 0000000000000000 0000000000001000 000000fb00000000
(XEN) ffff828c801455ce 000000000000e008 0000000000000246 ffff83007e4e7ee0
(XEN) 000000000000e010 ffff83007e4e7f20 ffff828c801477f0 ffff83007ad88000
(XEN) ffff83007e6b8000 0000000000000001 ffff83007ad88000 000000dc9999770a
(XEN) ffff828c80248080 ffff83007e4e7d98 0000000000000000 0000000000000000
(XEN) 0000000000000000 0000000000000000 0000000000000000 0000000000000000
(XEN) 0000000000000246 0000000000000000 00000001000277b1 0000000035c2d825
(XEN) 0000000000000000 ffffffff802083aa 0000000000000001 00000000deadbeef
(XEN) 00000000deadbeef 0000010000000000 ffffffff802083aa 000000000000e033
(XEN) 0000000000000246 ffffffff80611f50 000000000000e02b 555555555555beef
(XEN) 555555555555beef 555555555555beef 555555555555beef 5555555500000001
(XEN) ffff83007e6b8000
(XEN) Xen call trace:
(XEN) [<ffff828c8010ed21>] __dump_execstate+0x9/0x78
(XEN) [<ffff828c80163104>] __smp_call_function_interrupt+0x66/0xac
(XEN) [<ffff828c8016317d>] smp_call_function_interrupt+0x33/0x35
(XEN) [<ffff828c8014c900>] call_function_interrupt+0x30/0x40
(XEN) [<ffff828c801455ce>] default_idle+0x2f/0x34
(XEN) [<ffff828c801477f0>] idle_loop+0x8e/0x98
(XEN)
(XEN) *** Dumping CPU1 guest state: ***
(XEN) No guest context (CPU is idle).
(XEN)
(XEN) ''h'' pressed -> showing installed handlers
(XEN) key ''%'' (ascii ''25'') => Trap to
xendbg
(XEN) key ''0'' (ascii ''30'') => dump Dom0
registers
(XEN) key ''C'' (ascii ''43'') => trigger a
crashdump
(XEN) key ''H'' (ascii ''48'') => dump heap
info
(XEN) key ''N'' (ascii ''4e'') => NMI
statistics
(XEN) key ''O'' (ascii ''4f'') => toggle
shadow audits
(XEN) key ''Q'' (ascii ''51'') => dump PCI
devices
(XEN) key ''R'' (ascii ''52'') => reboot
machine
(XEN) key ''S'' (ascii ''53'') => reset
shadow pagetables
(XEN) key ''V'' (ascii ''56'') => dump iommu
info
(XEN) key ''a'' (ascii ''61'') => dump timer
queues
(XEN) key ''c'' (ascii ''63'') => dump cx
structures
(XEN) key ''d'' (ascii ''64'') => dump
registers
(XEN) key ''e'' (ascii ''65'') => dump
evtchn info
(XEN) key ''h'' (ascii ''68'') => show this
message
(XEN) key ''i'' (ascii ''69'') => dump
interrupt bindings
(XEN) key ''m'' (ascii ''6d'') => memory
info
(XEN) key ''n'' (ascii ''6e'') => trigger an
NMI
(XEN) key ''q'' (ascii ''71'') => dump
domain (and guest debug) info
(XEN) key ''r'' (ascii ''72'') => dump run
queues
(XEN) key ''t'' (ascii ''74'') => display
multi-cpu clock info
(XEN) key ''u'' (ascii ''75'') => dump numa
info
(XEN) key ''v'' (ascii ''76'') => dump
Intel''s VMCS
(XEN) key ''z'' (ascii ''7a'') => print
ioapic info
[ 968.401686] ata1.00: qc timeout (cmd 0xec)
[ 968.401790] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
[ 968.401908] ata1.00: revalidation failed (errno=-5)
[ 968.401997] ata1: failed to recover some devices, retrying in 5 secs
[ 968.881603] ata2.00: qc timeout (cmd 0xa1)
[ 968.881704] ata2.00: failed to IDENTIFY (I/O error, err_mask=0x4)
[ 968.881824] ata2.00: revalidation failed (errno=-5)
[ 968.881913] ata2: failed to recover some devices, retrying in 5 secs
[ 973.404847] ata1: hard resetting link
[ 973.884768] ata2: hard resetting link
(XEN) ''d'' pressed -> dumping registers
(XEN)
(XEN) *** Dumping CPU0 host state: ***
(XEN) ----[ Xen-3.5-unstable x86_64 debug=y Not tainted ]----
(XEN) CPU: 0
(XEN) RIP: e008:[<ffff828c8010ed21>] __dump_execstate+0x9/0x78
(XEN) RFLAGS: 0000000000010296 CONTEXT: hypervisor
(XEN) rax: 0000000000000000 rbx: 0000000000000064 rcx: 0000000000000000
(XEN) rdx: 0000000000000000 rsi: 000000000000000a rdi: 0000000000000000
(XEN) rbp: ffff828c8026fc58 rsp: ffff828c8026fc48 r8: 0000000000000004
(XEN) r9: ffff828c801ee420 r10: 00000000fffffffc r11: 0000000000000001
(XEN) r12: ffff828c8026fe38 r13: 0000000000000064 r14: 0000000000000286
(XEN) r15: ffff828c8026fe38 cr0: 000000008005003b cr4: 00000000000026f0
(XEN) cr3: 000000003995e000 cr2: ffff880033dd6ae0
(XEN) ds: 002b es: 002b fs: 0000 gs: 0000 ss: e010 cs: e008
(XEN) Xen stack trace from rsp=ffff828c8026fc48:
(XEN) 0000000000000064 0000000000000064 ffff828c8026fc98 ffff828c8010eded
(XEN) ffff828c8026fc88 0000000000000064 ffff828c8026fe38 0000000000000064
(XEN) 0000000000000286 ffff828c8026fe38 ffff828c8026fcb8 ffff828c8010ef8f
(XEN) ffff828c8026fe38 ffff828c8021df68 ffff828c8026fcc8 ffff828c80130f0e
(XEN) ffff828c8026fcf8 ffff828c80130fe8 ffff828c8026fcf8 ffff828c8021e040
(XEN) ffff828c80130f52 ffff828c8021e0b8 ffff828c8026fd48 ffff828c8013222c
(XEN) ffff828c8026fd18 ffff828c80131571 64ff828c8026fd48 0000000000000061
(XEN) ffff828c8028c9a0 ffff828c8021e040 ffff828c8026fe38 ffff828c8026ff28
(XEN) ffff828c8026fd78 ffff828c80131916 ffff828c8029d380 ffff828c8026fe38
(XEN) 0000000000000000 ffff828c80286d00 ffff828c8026fe28 ffff828c801523dc
(XEN) 0000000000000000 ffff828c8026fde8 00000000000000f1 ffff828c8026ff28
(XEN) ffff828c80163050 ffff828c80247080 ffff828c8029d3a4 000000f180247080
(XEN) ffff828c8028c9c8 0000000000000286 ffff828c8026fe08 ffc0000000000001
(XEN) ffff83007e4f2000 ffff83007e6ba010 0000000000000046 ffff828c8026ff28
(XEN) ffff828c80224fa0 ffff828c8026ff28 ffff828c80247600 ffff828c802252b8
(XEN) 00007d737fd901a7 ffff828c8014b846 ffff828c802252b8 ffff828c80247600
(XEN) ffff828c8026ff28 ffff828c80224fa0 ffff828c8026fee0 ffff828c8026ff28
(XEN) 0000000000000246 ffff828c80286bc0 ffff83007e6b2ab0 0000000000000000
(XEN) 0000000000000000 0000000000000000 ffff828c80286d00 0000000000000000
(XEN) ffff83007e4f2000 000000f100000000 ffff828c801455ce 000000000000e008
(XEN) Xen call trace:
(XEN) [<ffff828c8010ed21>] __dump_execstate+0x9/0x78
(XEN) [<ffff828c8010eded>] dump_registers+0x5d/0x144
(XEN) [<ffff828c8010ef8f>] handle_keypress+0x76/0xa7
(XEN) [<ffff828c80130f0e>] __serial_rx+0x16/0x5a
(XEN) [<ffff828c80130fe8>] serial_rx+0x96/0xa1
(XEN) [<ffff828c8013222c>] serial_rx_interrupt+0xb3/0xc9
(XEN) [<ffff828c80131916>] ns16550_interrupt+0x47/0x61
(XEN) [<ffff828c801523dc>] do_IRQ+0x492/0x513
(XEN) [<ffff828c8014b846>] common_interrupt+0x26/0x30
(XEN) [<ffff828c801455ce>] default_idle+0x2f/0x34
(XEN) [<ffff828c801477f0>] idle_loop+0x8e/0x98
(XEN)
(XEN) *** Dumping CPU0 guest state: ***
(XEN) No guest context (CPU is idle).
(XEN)
(XEN) *** Dumping CPU1 host state: ***
(XEN) ----[ Xen-3.5-unstable x86_64 debug=y Not tainted ]----
(XEN) CPU: 1
(XEN) RIP: e008:[<ffff828c8010ed21>] __dump_execstate+0x9/0x78
(XEN) RFLAGS: 0000000000010092 CONTEXT: hypervisor
(XEN) rax: 0000000000000080 rbx: ffff83007e4e7f28 rcx: ffff828c8010ed18
(XEN) rdx: ffff828c80286d00 rsi: 0000000000000000 rdi: 0000000000000000
(XEN) rbp: ffff83007e4e7e08 rsp: ffff83007e4e7df8 r8: 0000000000000000
(XEN) r9: ffff83007e6ba010 r10: ffff83007ac70780 r11: 0000000000000246
(XEN) r12: ffff828c80224fa0 r13: ffff83007e4e7f28 r14: ffff828c80247600
(XEN) r15: ffff828c802252b8 cr0: 000000008005003b cr4: 00000000000026f0
(XEN) cr3: 000000007cde0000 cr2: ffff880072272ea0
(XEN) ds: 0000 es: 0000 fs: 0000 gs: 0000 ss: e010 cs: e008
(XEN) Xen stack trace from rsp=ffff83007e4e7df8:
(XEN) 0000000000000006 ffff83007e4e7f28 ffff83007e4e7e18 ffff828c80163104
(XEN) ffff83007e4e7e28 ffff828c8016317d 00007cff81b181a7 ffff828c8014c900
(XEN) ffff828c802252b8 ffff828c80247600 ffff83007e4e7f28 ffff828c80224fa0
(XEN) ffff83007e4e7ee0 ffff83007e4e7f28 0000000000000246 ffff83007ac70780
(XEN) ffff83007e6ba010 0000000000000000 0000000000000080 0000000000000000
(XEN) ffff828c80286d00 0000000000000000 0000000000001000 000000fb00000000
(XEN) ffff828c801455ce 000000000000e008 0000000000000246 ffff83007e4e7ee0
(XEN) 000000000000e010 ffff83007e4e7f20 ffff828c801477f0 ffff83007ad88000
(XEN) ffff83007e6b8000 0000000000000001 ffff83007ad88000 000000e45ba05199
(XEN) ffff828c80248080 ffff83007e4e7d98 0000000000000000 0000000000000000
(XEN) 0000000000000000 0000000000000000 0000000000000000 0000000000000000
(XEN) 0000000000000246 0000000000000000 000000010002983b 000000000d57d82d
(XEN) 0000000000000000 ffffffff802083aa 0000000000000001 00000000deadbeef
(XEN) 00000000deadbeef 0000010000000000 ffffffff802083aa 000000000000e033
(XEN) 0000000000000246 ffffffff80611f50 000000000000e02b 555555555555beef
(XEN) 555555555555beef 555555555555beef 555555555555beef 5555555500000001
(XEN) ffff83007e6b8000
(XEN) Xen call trace:
(XEN) [<ffff828c8010ed21>] __dump_execstate+0x9/0x78
(XEN) [<ffff828c80163104>] __smp_call_function_interrupt+0x66/0xac
(XEN) [<ffff828c8016317d>] smp_call_function_interrupt+0x33/0x35
(XEN) [<ffff828c8014c900>] call_function_interrupt+0x30/0x40
(XEN) [<ffff828c801455ce>] default_idle+0x2f/0x34
(XEN) [<ffff828c801477f0>] idle_loop+0x8e/0x98
(XEN)
(XEN) *** Dumping CPU1 guest state: ***
(XEN) No guest context (CPU is idle).
(XEN)
[ 978.923992] ata1: port is slow to respond, please be patient (Status 0x80)
[ 979.371915] ata2: port is slow to respond, please be patient (Status 0x80)
[ 983.459235] ata1: COMRESET failed (errno=-16)
[ 983.459333] ata1: hard resetting link
[ 983.907158] ata2: COMRESET failed (errno=-16)
[ 983.907256] ata2: hard resetting link
[ 983.935162] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[ 984.383089] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Tom
On Mon, Jul 27, 2009 at 10:03 AM, Cui, Dexuan<dexuan.cui@intel.com>
wrote:> Can you try the xen-debug-key ''d'' to dump what the cpus
are doing?
> From serial, press Ctrl+A 3 times (or 6 time if you''re using
minicom) and after you see "Serial input -> Xen", press h, d.
>
> Thanks,
> -- Dexuan
>
>
> -----Original Message-----
> From: xen-devel-bounces@lists.xensource.com
[mailto:xen-devel-bounces@lists.xensource.com] On Behalf Of Tom Rotenberg
> Sent: 2009?7?26? 22:46
> To: xen-devel@lists.xensource.com
> Subject: [Xen-devel] Machine hangs when doing pass-through of all the USB
devices on a Lenovo T400 - With xen-unstable
>
> Hi,
>
> Just to notify you all, i have tested the same scenario, on the latest
> xen-unstable, and i got the same result - the machine hangs. It looks
> like a major bug.
> Can someone please help me with this?
>
> Tom
>
>
> ---------- Forwarded message ----------
> From: Tom Rotenberg <tom.rotenberg@gmail.com>
> Date: Wed, Jul 22, 2009 at 12:17 PM
> Subject: Machine hangs when doing pass-through of all the USB devices
> on a Lenovo T400
> To: Simon Horman <horms@verge.net.au>, xen-devel@lists.xensource.com
>
>
> Hi,
>
> First, i must say that i have seen this bug on Simon''s Xen 3.4
> multi-function pass-through tree, but i think that this problem
isn''t
> specific for Xen 3.4, but a major problem which will happen on
> xen-unstable too.
>
> (I''m using Lenovo T400 for my tests)
>
> What i did:
> 1. Used Simon''s Xen 3.4 multi-function pass-through tree
> 2. Did pass-through for all the USB devices in the system, using the
command:
> xm create /etc/xen/hvm pci=''00:1a.*''
pci=''00:1d.*''
>
> 3. After this, the guest Windows booted ok, but a little bit after
> this, the whole machine got stuck (including dom0), and the errors in
> the UART were:
> [ 287.624198] ACPI: EC: missing write data confirmation, don''t
expect
> it any longer.
> [ 293.748242] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2
frozen
> [ 293.748353] ata2.00: cmd a0/00:00:00:00:00/00:00:00:00:00/a0 tag 0
> [ 293.748354] cdb 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
00
> [ 293.748354] res 40/00:03:00:00:00/00:00:00:00:00/a0 Emask
> 0x4 (timeout)
> [ 293.748658] ata2.00: status: { DRDY }
>
> which looks like the SATA in dom0 is dead...
>
> I suspect that this is because of some problem in the
> interrupts-sharing between the USB device which is in pass-through to
> the HVM, and between the SATA in dom0 (they share the same interrupt).
> I have also made a test, which i pass-through only the
''00:1a.*''
> devices, and saw that doesn''t happen (probably, because the
interrupt
> isn''t shared). You can see in the logs that follow that interrupt
17
> is shared between the AHCI (the HD), and one of the USB devices.
>
> Could it be that there is problem when doing interrupt sharing between
> PT device in a domU, and between a device in dom0? Will this happen
> without the multi-function PT too?
>
> Any ideas?
>
> -----------
> Logs:
> ----------
> _______________________________________________
> The interrupts of the machine (as reported by dom0):
> _______________________________________________
> t500-testing:~# cat /proc/interrupts
> CPU0 CPU1
> 1: 8 0 Phys-irq-level i8042
> 3: 1 0 Phys-irq-level
> 5: 1 0 Phys-irq-level
> 7: 1 0 Phys-irq-level parport0
> 8: 0 0 Phys-irq-level rtc
> 9: 333 0 Phys-irq-level acpi
> 10: 1 0 Phys-irq-level
> 11: 1 0 Phys-irq-level
> 12: 2332 0 Phys-irq-level i8042
> 16: 2718 0 Phys-irq-level uhci_hcd:usb4, ahci, yenta
> 17: 93 0 Phys-irq-level uhci_hcd:usb5,
> ohci1394, HDA Intel
> 18: 35 0 Phys-irq-level uhci_hcd:usb6, sdhci:slot0
> 19: 2 0 Phys-irq-level ehci_hcd:usb8
> 20: 249 0 Phys-irq-level uhci_hcd:usb1, peth0
> 21: 54 0 Phys-irq-level uhci_hcd:usb2
> 22: 0 0 Phys-irq-level uhci_hcd:usb3
> 23: 26 0 Phys-irq-level ehci_hcd:usb7
> 256: 13683 0 Dynamic-irq-level timer0
> 257: 1180 0 Dynamic-irq-level resched0
> 258: 27 0 Dynamic-irq-level callfunc0
> 259: 0 2027 Dynamic-irq-level resched1
> 260: 0 64 Dynamic-irq-level callfunc1
> 261: 0 13348 Dynamic-irq-level timer1
> 262: 77 0 Dynamic-irq-level xenbus
> 263: 0 0 Dynamic-irq-level console
> NMI: 0 0 Non-maskable interrupts
> RES: 1180 2027 Rescheduling interrupts
> CAL: 27 64 function call interrupts
>
> _______________________________________________
> Full UART Log (from the relevant part):
> _______________________________________________
> [ 28.652292] Setting mem allocation to 1913836 kiB
> [ 214.320871] uhci_hcd 0000:00:1a.0: remove, state 4
> [ 214.320983] usb usb1: USB disconnect, address 1
> [ 214.322560] uhci_hcd 0000:00:1a.0: USB bus 1 deregistered
> [ 214.322694] ACPI: PCI interrupt for device 0000:00:1a.0 disabled
> [ 214.608987] pciback 0000:00:1a.0: seizing device
> [ 214.609184] ACPI: PCI Interrupt 0000:00:1a.0[A] -> GSI 20 (level,
> low) -> IRQ 20
> [ 214.609364] ACPI: PCI interrupt for device 0000:00:1a.0 disabled
> [ 214.334168] uhci_hcd 0000:00:1a.1: remove, state 1
> [ 214.334270] usb usb2: USB disconnect, address 1
> [ 214.334362] usb 2-1: USB disconnect, address 3
> [ 214.334803] uhci_hcd 0000:00:1a.1: USB bus 2 deregistered
> [ 214.334971] ACPI: PCI interrupt for device 0000:00:1a.1 disabled
> [ 214.621835] pciback 0000:00:1a.1: seizing device
> [ 214.622056] ACPI: PCI Interrupt 0000:00:1a.1[B] -> GSI 21 (level,
> low) -> IRQ 21
> [ 214.622258] ACPI: PCI interrupt for device 0000:00:1a.1 disabled
> [ 214.627337] uhci_hcd 0000:00:1a.2: remove, state 4
> [ 214.627545] usb usb3: USB disconnect, address 1
> [ 214.627932] uhci_hcd 0000:00:1a.2: USB bus 3 deregistered
> [ 214.628152] ACPI: PCI interrupt for device 0000:00:1a.2 disabled
> [ 214.635694] pciback 0000:00:1a.2: seizing device
> [ 214.636000] ACPI: PCI Interrupt 0000:00:1a.2[C] -> GSI 22 (level,
> low) -> IRQ 22
> [ 214.636186] ACPI: PCI interrupt for device 0000:00:1a.2 disabled
> [ 214.360823] ehci_hcd 0000:00:1a.7: remove, state 4
> [ 214.360925] usb usb7: USB disconnect, address 1
> [ 214.361017] usb 7-5: USB disconnect, address 3
> [ 214.365340] ehci_hcd 0000:00:1a.7: USB bus 7 deregistered
> [ 214.365506] ACPI: PCI interrupt for device 0000:00:1a.7 disabled
> [ 214.371946] pciback 0000:00:1a.7: seizing device
> [ 214.372390] ACPI: PCI Interrupt 0000:00:1a.7[D] -> GSI 23 (level,
> low) -> IRQ 23
> [ 214.372583] ACPI: PCI interrupt for device 0000:00:1a.7 disabled
> [ 214.376078] uhci_hcd 0000:00:1d.0: remove, state 4
> [ 214.376303] usb usb4: USB disconnect, address 1
> [ 214.376742] uhci_hcd 0000:00:1d.0: USB bus 4 deregistered
> [ 214.376938] ACPI: PCI interrupt for device 0000:00:1d.0 disabled
> [ 214.383475] pciback 0000:00:1d.0: seizing device
> [ 214.383786] ACPI: PCI Interrupt 0000:00:1d.0[A] -> GSI 16 (level,
> low) -> IRQ 16
> [ 214.383978] ACPI: PCI interrupt for device 0000:00:1d.0 disabled
> [ 214.387654] uhci_hcd 0000:00:1d.1: remove, state 4
> [ 214.387857] usb usb5: USB disconnect, address 1
> [ 214.388220] uhci_hcd 0000:00:1d.1: USB bus 5 deregistered
> [ 214.388417] ACPI: PCI interrupt for device 0000:00:1d.1 disabled
> [ 214.394899] pciback 0000:00:1d.1: seizing device
> [ 214.395212] ACPI: PCI Interrupt 0000:00:1d.1[B] -> GSI 17 (level,
> low) -> IRQ 17
> [ 214.395398] ACPI: PCI interrupt for device 0000:00:1d.1 disabled
> [ 214.677786] uhci_hcd 0000:00:1d.2: remove, state 1
> [ 214.677890] usb usb6: USB disconnect, address 1
> [ 214.677982] usb 6-2: USB disconnect, address 2
> [ 214.678444] uhci_hcd 0000:00:1d.2: USB bus 6 deregistered
> [ 214.678574] ACPI: PCI interrupt for device 0000:00:1d.2 disabled
> [ 214.406336] pciback 0000:00:1d.2: seizing device
> [ 214.406537] ACPI: PCI Interrupt 0000:00:1d.2[C] -> GSI 18 (level,
> low) -> IRQ 18
> [ 214.406725] ACPI: PCI interrupt for device 0000:00:1d.2 disabled
> [ 214.410481] ehci_hcd 0000:00:1d.7: remove, state 4
> [ 214.410691] usb usb8: USB disconnect, address 1
> [ 214.414959] ehci_hcd 0000:00:1d.7: USB bus 8 deregistered
> [ 214.415200] ACPI: PCI interrupt for device 0000:00:1d.7 disabled
> [ 214.421670] pciback 0000:00:1d.7: seizing device
> [ 214.422214] ACPI: PCI Interrupt 0000:00:1d.7[D] -> GSI 19 (level,
> low) -> IRQ 19
> [ 214.422407] ACPI: PCI interrupt for device 0000:00:1d.7 disabled
> [ 231.366540] Setting mem allocation to 973824 kiB
> (XEN) memory.c:128:d0 Could not allocate order=9 extent: id=1
> memflags=0 (2 of 4)
> (XEN) memory.c:128:d0 Could not allocate order=9 extent: id=1
> memflags=0 (0 of 3)
> [ 232.398469] pciback: vpci: 0000:00:1a.7: assign to virtual slot 0
> [ 232.398776] pciback: vpci: 0000:00:1a.2: assign to virtual slot 0 func 2
> [ 232.398973] pciback: vpci: 0000:00:1a.1: assign to virtual slot 0 func 1
> [ 232.399171] pciback: vpci: 0000:00:1a.0: assign to virtual slot 0 func 0
> [ 232.399318] pciback: vpci: 0000:00:1d.7: assign to virtual slot 1
> [ 232.399509] pciback: vpci: 0000:00:1d.2: assign to virtual slot 1 func 2
> [ 232.399717] pciback: vpci: 0000:00:1d.1: assign to virtual slot 1 func 1
> [ 232.399946] pciback: vpci: 0000:00:1d.0: assign to virtual slot 1 func 0
> (XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1a.0
> (XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1a.0
> (XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 14 device = 1a intx = 0
> (XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1a.7
> (XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1a.7
> (XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 17 device = 1a intx = 3
> (XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1a.2
> (XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1a.2
> (XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 16 device = 1a intx = 2
> (XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1a.1
> (XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1a.1
> (XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 15 device = 1a intx = 1
> (XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1d.0
> (XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1d.0
> (XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 10 device = 1d intx = 0
> (XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1d.7
> (XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1d.7
> (XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 13 device = 1d intx = 3
> (XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1d.2
> (XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1d.2
> (XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 12 device = 1d intx = 2
> (XEN) [VT-D]iommu.c:1364:d0 domain_context_unmap:PCI: bdf = 0:1d.1
> (XEN) [VT-D]iommu.c:1252:d0 domain_context_mapping:PCI: bdf = 0:1d.1
> (XEN) [VT-D]io.c:251:d0 VT-d irq bind: m_irq = 11 device = 1d intx = 1
> (XEN) HVM1: HVM Loader
> (XEN) HVM1: Detected Xen v3.4.1-rc7
> (XEN) HVM1: CPU speed is 2394 MHz
> (XEN) irq.c:243: Dom1 PCI link 0 changed 0 -> 5
> (XEN) HVM1: PCI-ISA link 0 routed to IRQ5
> (XEN) irq.c:243: Dom1 PCI link 1 changed 0 -> 10
> (XEN) HVM1: PCI-ISA link 1 routed to IRQ10
> (XEN) irq.c:243: Dom1 PCI link 2 changed 0 -> 11
> (XEN) HVM1: PCI-ISA link 2 routed to IRQ11
> (XEN) irq.c:243: Dom1 PCI link 3 changed 0 -> 5
> (XEN) HVM1: PCI-ISA link 3 routed to IRQ5
> (XEN) HVM1: pci dev 01:3 INTA->IRQ10
> (XEN) HVM1: pci dev 03:0 INTA->IRQ5
> (XEN) HVM1: pci dev 02:0 bar 10 size 02000000: f0000008
> (XEN) HVM1: pci dev 03:0 bar 14 size 01000000: f2000008
> (XEN) HVM1: pci dev 02:0 bar 14 size 00001000: f3000000
> (XEN) HVM1: pci dev 03:0 bar 10 size 00000100: 0000c001
> (XEN) HVM1: pci dev 01:1 bar 20 size 00000010: 0000c101
> (XEN) HVM1: Multiprocessor initialisation:
> (XEN) HVM1: - CPU0 ... 36-bit phys ... fixed MTRRs ... var MTRRs
> [2/8] ... done.
> (XEN) HVM1: Writing SMBIOS tables ...
> (XEN) HVM1: Loading ROMBIOS ...
> (XEN) HVM1: 10940 bytes of ROMBIOS high-memory extensions:
> (XEN) HVM1: Relocating to 0xfc000000-0xfc002abc ... done
> (XEN) HVM1: Creating MP tables ...
> (XEN) HVM1: Loading Cirrus VGABIOS ...
> (XEN) HVM1: Loading ACPI ...
> (XEN) HVM1: - Lo data: 000ea020-000ea04f
> (XEN) HVM1: - Hi data: fc002c00-fc0121cf
> (XEN) HVM1: vm86 TSS at fc012400
> (XEN) HVM1: BIOS map:
> (XEN) HVM1: c0000-c8fff: VGA BIOS
> (XEN) HVM1: eb000-eb148: SMBIOS tables
> (XEN) HVM1: f0000-fffff: Main BIOS
> (XEN) HVM1: Invoking ROMBIOS ...
> (XEN) HVM1: $Revision: 1.221 $ $Date: 2008/12/07 17:32:29 $
> (XEN) stdvga.c:147:d1 entering stdvga and caching modes
> (XEN) HVM1: VGABios $Id: vgabios.c,v 1.67 2008/01/27 09:44:12 vruppert Exp
$
> (XEN) HVM1: Bochs BIOS - build: 06/23/99
> (XEN) HVM1: $Revision: 1.221 $ $Date: 2008/12/07 17:32:29 $
> (XEN) HVM1: Options: apmbios pcibios eltorito PMM
> (XEN) HVM1:
> (XEN) HVM1: ata0-0: PCHS=12483/16/63 translation=lba LCHS=783/255/63
> (XEN) HVM1: ata0 master: QEMU HARDDISK ATA-7 Hard-Disk (6144 MBytes)
> (XEN) HVM1: IDE time out
> (XEN) HVM1:
> (XEN) HVM1:
> (XEN) HVM1:
> (XEN) HVM1: Press F12 for boot menu.
> (XEN) HVM1:
> (XEN) HVM1: Booting from Hard Disk...
> (XEN) HVM1: Booting from 0000:7c00
> (XEN) HVM1: int13_harddisk: function 15, unmapped device for ELDL=81
> (XEN) HVM1: *** int 15h function AX=e980, BX=006e not yet supported!
> (XEN) common.c:3623:d0 tracking VRAM f0000 - f0026
> (XEN) irq.c:243: Dom1 PCI link 0 changed 5 -> 0
> (XEN) irq.c:243: Dom1 PCI link 1 changed 10 -> 0
> (XEN) irq.c:243: Dom1 PCI link 2 changed 11 -> 0
> (XEN) irq.c:243: Dom1 PCI link 3 changed 5 -> 0
> (XEN) domctl.c:835:d0 memory_map:add: gfn=fbfff mfn=80101 nr_mfns=1
> (XEN) domctl.c:886:d0 ioport_map:add f_gport=ffe0 f_mport=1c00 np=20
> (XEN) domctl.c:886:d0 ioport_map:add f_gport=ffc0 f_mport=1860 np=20
> (XEN) domctl.c:886:d0 ioport_map:add f_gport=ffa0 f_mport=18e0 np=20
> (XEN) domctl.c:886:d0 ioport_map:add f_gport=ff80 f_mport=1880 np=20
> (XEN) domctl.c:886:d0 ioport_map:add f_gport=ff60 f_mport=18c0 np=20
> (XEN) domctl.c:886:d0 ioport_map:add f_gport=ff40 f_mport=18a0 np=20
> (XEN) domctl.c:835:d0 memory_map:add: gfn=fbffe mfn=80100 nr_mfns=1
> [ 287.624198] ACPI: EC: missing write data confirmation, don''t
expect
> it any longer.
> [ 293.748242] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2
frozen
> [ 293.748353] ata2.00: cmd a0/00:00:00:00:00/00:00:00:00:00/a0 tag 0
> [ 293.748354] cdb 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
00
> [ 293.748354] res 40/00:03:00:00:00/00:00:00:00:00/a0 Emask
> 0x4 (timeout)
> [ 293.748658] ata2.00: status: { DRDY }
> [ 294.060173] ata2: soft resetting link
> [ 294.224137] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> [ 297.579229] ata1.00: exception Emask 0x0 SAct 0xffff SErr 0x0
> action 0x2 frozen
> [ 297.579344] ata1.00: cmd 61/10:00:f7:9e:ed/00:00:00:00:00/40 tag 0
> ncq 8192 out
> [ 297.579345] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 297.579570] ata1.00: status: { DRDY }
> [ 297.579657] ata1.00: cmd 61/18:08:6f:bc:65/00:00:00:00:00/40 tag 1
> ncq 12288 out
> [ 297.579658] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 297.579849] ata1.00: status: { DRDY }
> [ 297.579936] ata1.00: cmd 61/10:10:3f:1c:67/00:00:00:00:00/40 tag 2
> ncq 8192 out
> [ 297.579937] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 297.580127] ata1.00: status: { DRDY }
> [ 297.580215] ata1.00: cmd 61/08:18:27:26:67/00:00:00:00:00/40 tag 3
> ncq 4096 out
> [ 297.580216] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 297.580406] ata1.00: status: { DRDY }
> [ 297.580494] ata1.00: cmd 61/08:20:2f:26:67/00:00:00:00:00/40 tag 4
> ncq 4096 out
> [ 297.580494] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 297.580684] ata1.00: status: { DRDY }
> [ 297.580795] ata1.00: cmd 61/20:28:6f:27:67/00:00:00:00:00/40 tag 5
> ncq 16384 out
> [ 297.580796] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 297.580999] ata1.00: status: { DRDY }
> [ 297.581088] ata1.00: cmd 61/08:30:0f:33:67/00:00:00:00:00/40 tag 6
> ncq 4096 out
> [ 297.581089] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 297.581281] ata1.00: status: { DRDY }
> [ 297.581369] ata1.00: cmd 61/10:38:1f:9f:ed/00:00:00:00:00/40 tag 7
> ncq 8192 out
> [ 297.581370] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 297.581563] ata1.00: status: { DRDY }
> [ 297.581652] ata1.00: cmd 61/08:40:17:33:67/00:00:00:00:00/40 tag 8
> ncq 4096 out
> [ 297.581653] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 297.582447] ata1.00: status: { DRDY }
> [ 297.582536] ata1.00: cmd 61/50:48:c7:35:67/00:00:00:00:00/40 tag 9
> ncq 40960 out
> [ 297.582537] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 297.582728] ata1.00: status: { DRDY }
> [ 297.582817] ata1.00: cmd 61/10:50:b7:36:67/00:00:00:00:00/40 tag 10
> ncq 8192 out
> [ 297.582818] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 297.583009] ata1.00: status: { DRDY }
> [ 297.583097] ata1.00: cmd 61/08:58:3f:3a:67/00:00:00:00:00/40 tag 11
> ncq 4096 out
> [ 297.583098] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 297.583293] ata1.00: status: { DRDY }
> [ 297.583383] ata1.00: cmd 61/10:60:47:3a:67/00:00:00:00:00/40 tag 12
> ncq 8192 out
> [ 297.583384] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 297.583613] ata1.00: status: { DRDY }
> [ 297.583701] ata1.00: cmd 61/10:68:87:3b:67/00:00:00:00:00/40 tag 13
> ncq 8192 out
> [ 297.583702] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 297.583894] ata1.00: status: { DRDY }
> [ 297.583983] ata1.00: cmd 61/10:70:8f:3f:67/00:00:00:00:00/40 tag 14
> ncq 8192 out
> [ 297.583983] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 297.584174] ata1.00: status: { DRDY }
> [ 297.584262] ata1.00: cmd 61/08:78:27:41:67/00:00:00:00:00/40 tag 15
> ncq 4096 out
> [ 297.584263] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask
> 0x4 (timeout)
> [ 297.584458] ata1.00: status: { DRDY }
> [ 297.266871] ata1: soft resetting link
> [ 297.430863] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
>
> Tom
>
> _______________________________________________
> Xen-devel mailing list
> Xen-devel@lists.xensource.com
> http://lists.xensource.com/xen-devel
>
_______________________________________________
Xen-devel mailing list
Xen-devel@lists.xensource.com
http://lists.xensource.com/xen-devel
Simon Horman
2009-Jul-27 11:59 UTC
Re: [Xen-devel] Machine hangs when doing pass-through of all the USB devices on a Lenovo T400 - With xen-unstable
On Sun, Jul 26, 2009 at 05:46:22PM +0300, Tom Rotenberg wrote:> Hi, > > Just to notify you all, i have tested the same scenario, on the latest > xen-unstable, and i got the same result - the machine hangs. It looks > like a major bug. > Can someone please help me with this?Hi Tom, this is a bit of a stab in the dark, but could you try each of the following patches _separately_ to see if they make any difference? 1) Allow virtual functions to use the legacy interrupt pin of the corresponding physical device. This was already half-implemented, and I wonder if this missing piece is causing problems. Index: ioemu-remote/hw/pass-through.c ==================================================================--- ioemu-remote.orig/hw/pass-through.c 2009-07-27 21:42:12.000000000 +1000 +++ ioemu-remote/hw/pass-through.c 2009-07-27 21:47:34.000000000 +1000 @@ -2617,13 +2617,7 @@ static uint32_t pt_status_reg_init(struc static uint32_t pt_irqpin_reg_init(struct pt_dev *ptdev, struct pt_reg_info_tbl *reg, uint32_t real_offset) { - int reg_field = 0; - - /* set Interrupt Pin register to use INTA# if it has */ - if (ptdev->dev.config[real_offset]) - reg_field = 0x01; - - return reg_field; + return ptdev->dev.config[real_offset]; } /* initialize BAR */ 2) Always use INTA everywhere. This should revert things to they way they were before the multi-function changes. diff --git a/hw/pass-through.c b/hw/pass-through.c index f3d033b..cddfe5c 100644 --- a/hw/pass-through.c +++ b/hw/pass-through.c @@ -4329,21 +4329,7 @@ int pt_init(PCIBus *e_bus) */ static uint8_t pci_read_intx(struct pt_dev *ptdev) { - uint8_t r_val = pci_read_byte(ptdev->pci_dev, PCI_INTERRUPT_PIN); - - PT_LOG("intx=%i\n", r_val); - if (r_val < 1 || r_val > 4) - { - PT_LOG("Interrupt pin read from hardware is out of range: " - "value=%i, acceptable range is 1 - 4\n", r_val); - r_val = 0; - } - else - { - r_val -= 1; - } - - return r_val; + return 0; } /* _______________________________________________ Xen-devel mailing list Xen-devel@lists.xensource.com http://lists.xensource.com/xen-devel
Tom Rotenberg
2009-Jul-27 15:07 UTC
Re: [Xen-devel] Machine hangs when doing pass-through of all the USB devices on a Lenovo T400 - With xen-unstable
Simon, It looks like each one of your patches (when applied separately) solved the problem. However, it looks like the first patch caused it to work better (i didn''t run real benchmarks tough). Will u post a fix for this issue? Tom On Mon, Jul 27, 2009 at 2:59 PM, Simon Horman<horms@verge.net.au> wrote:> On Sun, Jul 26, 2009 at 05:46:22PM +0300, Tom Rotenberg wrote: >> Hi, >> >> Just to notify you all, i have tested the same scenario, on the latest >> xen-unstable, and i got the same result - the machine hangs. It looks >> like a major bug. >> Can someone please help me with this? > > Hi Tom, > > this is a bit of a stab in the dark, but could you try each > of the following patches _separately_ to see if they make any difference? > > 1) > > Allow virtual functions to use the legacy interrupt pin of the > corresponding physical device. This was already half-implemented, and I > wonder if this missing piece is causing problems. > > Index: ioemu-remote/hw/pass-through.c > ==================================================================> --- ioemu-remote.orig/hw/pass-through.c 2009-07-27 21:42:12.000000000 +1000 > +++ ioemu-remote/hw/pass-through.c 2009-07-27 21:47:34.000000000 +1000 > @@ -2617,13 +2617,7 @@ static uint32_t pt_status_reg_init(struc > static uint32_t pt_irqpin_reg_init(struct pt_dev *ptdev, > struct pt_reg_info_tbl *reg, uint32_t real_offset) > { > - int reg_field = 0; > - > - /* set Interrupt Pin register to use INTA# if it has */ > - if (ptdev->dev.config[real_offset]) > - reg_field = 0x01; > - > - return reg_field; > + return ptdev->dev.config[real_offset]; > } > > /* initialize BAR */ > > 2) Always use INTA everywhere. This should revert things to > they way they were before the multi-function changes. > > > diff --git a/hw/pass-through.c b/hw/pass-through.c > index f3d033b..cddfe5c 100644 > --- a/hw/pass-through.c > +++ b/hw/pass-through.c > @@ -4329,21 +4329,7 @@ int pt_init(PCIBus *e_bus) > */ > static uint8_t pci_read_intx(struct pt_dev *ptdev) > { > - uint8_t r_val = pci_read_byte(ptdev->pci_dev, PCI_INTERRUPT_PIN); > - > - PT_LOG("intx=%i\n", r_val); > - if (r_val < 1 || r_val > 4) > - { > - PT_LOG("Interrupt pin read from hardware is out of range: " > - "value=%i, acceptable range is 1 - 4\n", r_val); > - r_val = 0; > - } > - else > - { > - r_val -= 1; > - } > - > - return r_val; > + return 0; > } > > /* > >_______________________________________________ Xen-devel mailing list Xen-devel@lists.xensource.com http://lists.xensource.com/xen-devel
Simon Horman
2009-Jul-28 00:27 UTC
Re: [Xen-devel] Machine hangs when doing pass-through of all the USB devices on a Lenovo T400 - With xen-unstable
On Mon, Jul 27, 2009 at 06:07:58PM +0300, Tom Rotenberg wrote:> Simon, > > It looks like each one of your patches (when applied separately) > solved the problem. However, it looks like the first patch caused it > to work better (i didn''t run real benchmarks tough). > Will u post a fix for this issue?Hi Tom, As the first patch fixes the issue, I will post that. Its my preferred solution. Thanks for testing the patches. _______________________________________________ Xen-devel mailing list Xen-devel@lists.xensource.com http://lists.xensource.com/xen-devel