[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

[Xen-devel] NMI on domU start with PCI passthrough, xen/master dom0, xen 3.5


  • To: xen-devel@xxxxxxxxxxxxxxxxxxx
  • From: Bruce Edge <bruce.edge@xxxxxxxxx>
  • Date: Tue, 13 Oct 2009 08:57:08 -0700
  • Delivery-date: Tue, 13 Oct 2009 08:57:46 -0700
  • Domainkey-signature: a=rsa-sha1; c=nofws; d=gmail.com; s=gamma; h=mime-version:date:message-id:subject:from:to:content-type :content-transfer-encoding; b=B1+PKhX6wnuPl9h8j3VH/OsJpe/E46EXi+D+DTjdnAFY0zGn6LztADM035Gu8BIpNt Mol4d/3TNpqSuf6IUlZ4HAAusQ5eS3m822s07qP5dcGgSKbq1dt9j86vLHIvn0DpQJeK hNHdnIIAmLsCfysQbWuizrcRr70k2rixUUItc=
  • List-id: Xen developer discussion <xen-devel.lists.xensource.com>

I managed to get a xen/master & xen 3.5 unstable to NMI by attempting
to pass through some PCIe devices. Details below.
This is a dual CPU nehalem X58 chipset system.
I will attempt to replicate with one PCI passthrough instead of 8.
Please let me know what other information is needed.

Thanks in advance.

-Bruce

(XEN) memory.c:126:d0 Could not allocate order=9 extent: id=2
memflags=0 (1 of 4)
(XEN) memory.c:126:d0 Could not allocate order=9 extent: id=2
memflags=0 (0 of 4)
(XEN) memory.c:126:d0 Could not allocate order=9 extent: id=2
memflags=0 (0 of 4)
(XEN) memory.c:126:d0 Could not allocate order=9 extent: id=2
memflags=0 (0 of 4)
(XEN) memory.c:126:d0 Could not allocate order=9 extent: id=2
memflags=0 (0 of 4)
(XEN) memory.c:126:d0 Could not allocate order=9 extent: id=2
memflags=0 (0 of 3)
(XEN) [VT-D]iommu.c:1289:d0 domain_context_unmap:PCIe: bdf = 7:0.0
(XEN) [VT-D]iommu.c:1175:d0 domain_context_mapping:PCIe: bdf = 7:0.0
(XEN)
(XEN)
(XEN) NMI - MEMORY ERROR
(XEN) ----[ Xen-3.5-unstable  x86_64  debug=y  Not tainted ]----
(XEN) CPU:    0
(XEN) RIP:    e008:[<ffff82c480137d96>] hvm_do_IRQ_dpci+0x5e/0xab
(XEN) RFLAGS: 0000000000000002   CONTEXT: hypervisor
(XEN) rax: 0000000000000001   rbx: ffff83031fe7c000   rcx: 0000000000000001
(XEN) rdx: 0000000000000082   rsi: 000000000000011f   rdi: ffff83031ff82138
(XEN) rbp: ffff82c4802e7b88   rsp: ffff82c4802e7b68   r8:  ffff82c4802e7f28
(XEN) r9:  ffff82c4802e7ee4   r10: 0000000000affb00   r11: 000000000000ffff
(XEN) r12: 000000000000011f   r13: 0000000000000000   r14: ffff83031fe64f70
(XEN) r15: 0000000000000000   cr0: 000000008005003b   cr4: 00000000000026f0
(XEN) cr3: 00000002f6b08000   cr2: ffff8802df8c66c0
(XEN) ds: 0000   es: 0000   fs: 0000   gs: 0000   ss: 0000   cs: e008
(XEN) Xen stack trace from rsp=ffff82c4802e7b68:
(XEN)    ffff82c4802e7b88 ffff83031fe7c000 000000000000011f 0000000000000000
(XEN)    ffff82c4802e7c38 ffff82c480155cdc ffff82c4802e7bc8 ffff83031fe7c000
(XEN)    0000000000000015 0000000000000000 0000000000000041 ffff83031ff82134
(XEN)    00000041802e7c68 ffff82c4802e7c68 00000000802e7c18 0000000000000000
(XEN)    ffff83031ff82100 0000000000000104 ffff82c4802e7c98 ffff82c4802e7c98
(XEN)    9800000000000002 0000000000000004 0000000000000000 00000000803211f8
(XEN)    0000000080170000 ffff8300d652c000 00007d3b7fd18397 ffff82c48014f626
(XEN)    ffff8300d652c000 0000000080170000 00000000803211f8 0000000000000000
(XEN)    ffff82c4802e7cf8 0000000000000004 000000000000ffff 0000000000affb00
(XEN)    ffff82c4802e7ee4 ffff82c4802e7f28 0000000000000000 0000000000000001
(XEN)    0000000000000cfc 0000000000000286 ffff82c48022d9a8 000000b200000000
(XEN)    ffff82c48011da3d 000000000000e008 0000000000000286 ffff82c4802e7cf8
(XEN)    0000000000000000 0000000000000286 ffff82c4802e7d28 ffff82c480166729
(XEN)    0000000000000004 0000000000000cfc 0000000000000004 00000000803211f8
(XEN)    ffff82c4802e7d68 ffff82c48016e36a 0000000000000cfc 0000000000000cfc
(XEN)    ffff82c4802e7f28 ffffffff813f4954 0000000000000008 ffff82c4802e7e88
(XEN)    ffff82c4802e7f18 ffff82c480171c26 ffff83000000f800 0000000100007ff0
(XEN)    ffff82c4802e7dd8 ffff82c48015b2e0 0000000000000001 ffff83031fe7c000
(XEN)    000000020001fd05 ffff82c4802e7eb8 ffff82c4802e7df8 ffff82f6003fa0a0
(XEN)    ffff82c4802e7dd8 0000000000000000 ffff82c4802e7e38 ffff82c48015f3c2
(XEN) Xen call trace:
(XEN)    [<ffff82c480137d96>] hvm_do_IRQ_dpci+0x5e/0xab
(XEN)    [<ffff82c480155cdc>] do_IRQ+0x38a/0x690
(XEN)    [<ffff82c48014f626>] common_interrupt+0x26/0x30
(XEN)    [<ffff82c48011da3d>] _spin_unlock_irqrestore+0x22/0x24
(XEN)    [<ffff82c480166729>] pci_conf_write+0x9d/0xb2
(XEN)    [<ffff82c48016e36a>] guest_io_write+0x14d/0x19f
(XEN)    [<ffff82c480171c26>] do_general_protection+0x1e01/0x2ef3
(XEN)
(XEN)
(XEN) ****************************************
(XEN) Panic on CPU 0:
(XEN) FATAL TRAP: vector = 2 (nmi)
(XEN) [error_code=0000] , IN INTERRUPT CONTEXT
(XEN) ****************************************
(XEN)
(XEN) Reboot in five seconds...

Xen cmdline

(XEN) Xen version 3.5-unstable (bedge@xxxxxxxxxxx) (gcc version 4.3.3
(Ubuntu 4.3.3-5ubuntu4) ) Fri Oct  9 16:02:02 PDT 2009
(XEN) Latest ChangeSet: Thu Oct 08 09:24:32 2009 +0100 20296:1d7221667204
(XEN) Command line: com1=115200,8n1 console=com1 loglvl=all
loglvl_guest=all dom0_max_vcpus=1 dom0_vcpus_pin=true
iommu=1,passthrough

dom0 cndline

[    0.000000] Linux version 2.6.31.1 (root@kaan) (gcc version 4.3.3
(Ubuntu 4.3.3-5ubuntu4) ) #1 SMP Sat Oct 10 12:43:06 PDT 2009
[    0.000000] Command line:
root=UUID=e0a8abaf-ebb4-4a46-a968-36764653b87f ro earlyprintk=xen
irqpoll console=hvc0 loglevel=10 debug acpi=force

Devices to passthrough:

07:00.0 Fibre Channel: PMC-Sierra Inc. Device 8032 (rev 05)
07:00.1 Fibre Channel: PMC-Sierra Inc. Device 8032 (rev 05)
07:00.2 Fibre Channel: PMC-Sierra Inc. Device 8032 (rev 05)
07:00.3 Fibre Channel: PMC-Sierra Inc. Device 8032 (rev 05)
14:00.0 Fibre Channel: PMC-Sierra Inc. Device 8032 (rev 05)
14:00.1 Fibre Channel: PMC-Sierra Inc. Device 8032 (rev 05)
17:00.0 Fibre Channel: PMC-Sierra Inc. Device 8032 (rev 05)
17:00.1 Fibre Channel: PMC-Sierra Inc. Device 8032 (rev 05)

lspci -vv -s 17:00.0:

17:00.0 Fibre Channel: PMC-Sierra Inc. Device 8032 (rev 05)
        Subsystem: Atto Technology Device 003b
        Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop-
ParErr+ Stepping- SERR- FastB2B- DisINTx-
        Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort-
<TAbort- <MAbort- >SERR- <PERR- INTx-
        Latency: 0, Cache Line Size: 64 bytes
        Interrupt: pin A routed to IRQ 7
        Region 0: Memory at fbf00000 (64-bit, non-prefetchable) [size=1M]
        Region 2: I/O ports at 8000 [size=256]
        Region 3: I/O ports at 8400 [size=256]
        Region 4: Memory at fbef0000 (64-bit, non-prefetchable) [size=32K]
        [virtual] Expansion ROM at e4300000 [disabled] [size=1M]
        Capabilities: [50] Power Management version 3
                Flags: PMEClk- DSI- D1- D2- AuxCurrent=0mA
PME(D0-,D1-,D2-,D3hot-,D3cold-)
                Status: D0 PME-Enable- DSel=0 DScale=0 PME-
        Capabilities: [60] Message Signalled Interrupts: Mask- 64bit+
Queue=0/1 Enable-
                Address: 0000000000000000  Data: 0000
        Capabilities: [70] Express (v2) Endpoint, MSI 01
                DevCap: MaxPayload 4096 bytes, PhantFunc 0, Latency
L0s <4us, L1 unlimited
                        ExtTag- AttnBtn- AttnInd- PwrInd- RBE+ FLReset-
                DevCtl: Report errors: Correctable- Non-Fatal+ Fatal+
Unsupported-
                        RlxdOrd+ ExtTag- PhantFunc- AuxPwr- NoSnoop-
                        MaxPayload 256 bytes, MaxReadReq 4096 bytes
                DevSta: CorrErr+ UncorrErr- FatalErr- UnsuppReq+
AuxPwr- TransPend-
                LnkCap: Port #0, Speed 5GT/s, Width x8, ASPM L0s L1,
Latency L0 <2us, L1 <4us
                        ClockPM- Suprise- LLActRep- BwNot-
                LnkCtl: ASPM Disabled; RCB 64 bytes Disabled- Retrain- CommClk-
                        ExtSynch- ClockPM- AutWidDis- BWInt- AutBWInt-
                LnkSta: Speed 2.5GT/s, Width x4, TrErr- Train-
SlotClk+ DLActive- BWMgmt- ABWMgmt-
        Capabilities: [b0] MSI-X: Enable- Mask- TabSize=9
                Vector table: BAR=4 offset=00004100
                PBA: BAR=4 offset=00004000
        Capabilities: [100] Advanced Error Reporting <?>


Prior to starting domU:

 #> xm pci-list-assignable-devices

0000:17:00.0
0000:17:00.1
0000:14:00.0
0000:14:00.1
0000:07:00.0
0000:07:00.1
0000:07:00.2
0000:07:00.3

I was tailing /var/log/xen/*.log at the time and here's what was
flushed out before the NMI:

==> xend.log <==
[2009-10-13 08:31:16 2903] INFO (XendDomainInfo:1961) Domain has
shutdown: name=dpm id=1 reason=poweroff.
[2009-10-13 08:31:16 2903] DEBUG (XendDomainInfo:2844)
XendDomainInfo.destroy: domid=1
[2009-10-13 08:31:17 2903] DEBUG (XendDomainInfo:2279) Destroying device model
[2009-10-13 08:31:17 2903] INFO (image:586) dpm device model terminated
[2009-10-13 08:31:17 2903] DEBUG (XendDomainInfo:2286) Releasing devices
[2009-10-13 08:31:17 2903] DEBUG (XendDomainInfo:2292) Removing vif/0
[2009-10-13 08:31:17 2903] DEBUG (XendDomainInfo:1185)
XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
[2009-10-13 08:31:17 2903] DEBUG (XendDomainInfo:2292) Removing console/0
[2009-10-13 08:31:17 2903] DEBUG (XendDomainInfo:1185)
XendDomainInfo.destroyDevice: deviceClass = console, device =
console/0
[2009-10-13 08:31:18 2903] DEBUG (XendDomainInfo:2292) Removing vbd/51712
[2009-10-13 08:31:18 2903] DEBUG (XendDomainInfo:1185)
XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/51712
[2009-10-13 08:31:18 2903] DEBUG (XendDomainInfo:2292) Removing vbd/51728
[2009-10-13 08:31:18 2903] DEBUG (XendDomainInfo:1185)
XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/51728
[2009-10-13 08:31:18 2903] DEBUG (XendDomainInfo:2292) Removing vfb/0
[2009-10-13 08:31:18 2903] DEBUG (XendDomainInfo:1185)
XendDomainInfo.destroyDevice: deviceClass = vfb, device = vfb/0
[2009-10-13 08:32:03 2903] DEBUG (XendDomainInfo:99)
XendDomainInfo.create(['vm', ['name', 'dpm'], ['memory', 4096],
['on_poweroff', 'destroy'], ['on_reboot', 'restart'], ['on_crash',
'restart'], ['vcpus', 1], ['on_xend_start', 'ignore']
, ['on_xend_stop', 'ignore'], ['bootloader', '/usr/bin/pygrub'],
['bootloader_args', ''], ['image', ['hvm', ['kernel',
'/usr/lib64/xen/boot/hvmloader'], ['videoram', 4], ['device_model',
'/usr/lib64/xen/bin/qemu-dm'], ['pae', 1], ['vcpus
', 1], ['boot', 'c'], ['fda', ''], ['fdb', ''], ['timer_mode', 1],
['localtime', 0], ['serial', 'pty'], ['stdvga', 0], ['isa', 0],
['nographic', 0], ['soundhw', ''], ['vnc', 1], ['vncunused', 1],
['vncconsole', 1], ['vnclisten', '0.0.0.0
'], ['sdl', 0], ['xauthority', '/root/.Xauthority'],
['rtc_timeoffset', 0], ['monitor', 0], ['acpi', 1], ['apic', 1],
['usb', 0], ['usbdevice', 'tablet'], ['keymap', ''], ['pci',
[['0x0000', '0x07', '0x00', '0x0', '0x100', [], '07:00.0']
, ['0x0000', '0x07', '0x00', '0x1', '0x100', [], '07:00.1'],
['0x0000', '0x07', '0x00', '0x2', '0x100', [], '07:00.2'], ['0x0000',
'0x07', '0x00', '0x3', '0x100', [], '07:00.3'], ['0x0000', '0x14',
'0x00', '0x0', '0x100', [], '14:00.0'],
 ['0x0000', '0x14', '0x00', '0x1', '0x100', [], '14:00.1'], ['0x0000',
'0x17', '0x00', '0x0', '0x100', [], '17:00.0'], ['0x0000', '0x17',
'0x00', '0x1', '0x100', [], '17:00.1']]], ['hpet', 0],
['guest_os_type', 'default'], ['hap', 0], ['
cpuid', []], ['cpuid_check', []], ['viridian', 0],
['pci_msitranslate', 1], ['vpt_align', 1], ['pci_power_mgmt', 0],
['xen_platform_pci', 1], ['vncpasswd', 'XXXXXXXX'], ['tsc_native',
0]]], ['s3_integrity', 1], ['device', ['vbd', ['uname
', 'phy:system/dpm_root_0'], ['dev', 'xvda:sda1'], ['mode', 'w']]],
['device', ['vbd', ['uname', 'phy:system/dpm_swap'], ['dev',
'xvdb:sda2'], ['mode', 'w']]], ['device', ['pci', ['dev', ['slot',
'0x00'], ['domain', '0x0000'], ['key', '0
7:00.0'], ['bus', '0x07'], ['vdevfn', '0x100'], ['func', '0x0']],
['dev', ['slot', '0x00'], ['domain', '0x0000'], ['key', '07:00.1'],
['bus', '0x07'], ['vdevfn', '0x100'], ['func', '0x1']], ['dev',
['slot', '0x00'], ['domain', '0x0000'],
 ['key', '07:00.2'], ['bus', '0x07'], ['vdevfn', '0x100'], ['func',
'0x2']], ['dev', ['slot', '0x00'], ['domain', '0x0000'], ['key',
'07:00.3'], ['bus', '0x07'], ['vdevfn', '0x100'], ['func', '0x3']],
['dev', ['slot', '0x00'], ['domain',
 '0x0000'], ['key', '14:00.0'], ['bus', '0x14'], ['vdevfn', '0x100'],
['func', '0x0']], ['dev', ['slot', '0x00'], ['domain', '0x0000'],
['key', '14:00.1'], ['bus', '0x14'], ['vdevfn', '0x100'], ['func',
'0x1']], ['dev', ['slot', '0x00'],
 ['domain', '0x0000'], ['key', '17:00.0'], ['bus', '0x17'], ['vdevfn',
'0x100'], ['func', '0x0']], ['dev', ['slot', '0x00'], ['domain',
'0x0000'], ['key', '17:00.1'], ['bus', '0x17'], ['vdevfn', '0x100'],
['func', '0x1']]]], ['device', [
'vif', ['bridge', 'eth0'], ['model', 'e1000'], ['mac', '00:16:3e:00:14:01']]]])
[2009-10-13 08:32:03 2903] DEBUG (XendDomainInfo:2369)
XendDomainInfo.constructDomain
[2009-10-13 08:32:03 2903] DEBUG (balloon:181) Balloon: 4234496 KiB
free; need 4096; done.
[2009-10-13 08:32:03 2903] DEBUG (XendDomain:454) Adding Domain: 2
[2009-10-13 08:32:03 2903] DEBUG (XendDomainInfo:2614)
XendDomainInfo.initDomain: 2 256
[2009-10-13 08:32:04 2903] DEBUG (image:338) No VNC passwd configured
for vfb access
[2009-10-13 08:32:04 2903] DEBUG (image:843) args: boot, val: c
[2009-10-13 08:32:04 2903] DEBUG (image:843) args: fda, val: None
[2009-10-13 08:32:04 2903] DEBUG (image:843) args: fdb, val: None
[2009-10-13 08:32:04 2903] DEBUG (image:843) args: soundhw, val: None
[2009-10-13 08:32:04 2903] DEBUG (image:843) args: localtime, val: 0
[2009-10-13 08:32:04 2903] DEBUG (image:843) args: serial, val: ['pty']
[2009-10-13 08:32:04 2903] DEBUG (image:843) args: std-vga, val: 0
[2009-10-13 08:32:04 2903] DEBUG (image:843) args: isa, val: 0
[2009-10-13 08:32:04 2903] DEBUG (image:843) args: acpi, val: 1
[2009-10-13 08:32:04 2903] DEBUG (image:843) args: usb, val: 0
[2009-10-13 08:32:04 2903] DEBUG (image:843) args: usbdevice, val: tablet
[2009-10-13 08:32:04 2903] DEBUG (image:843) args: gfx_passthru, val: None
[2009-10-13 08:32:04 2903] INFO (image:779) Need to create platform
device.[domid:2]
[2009-10-13 08:32:04 2903] DEBUG (XendDomainInfo:2641)
_initDomain:shadow_memory=0x0, memory_static_max=0x100000000,
memory_static_min=0x0.
[2009-10-13 08:32:04 2903] DEBUG (balloon:135) Balloon: tmem
relinquished -1 KiB of 15536 KiB requested.
[2009-10-13 08:32:04 2903] DEBUG (balloon:187) Balloon: 4233040 KiB
free; 0 to scrub; need 4248576; retries: 20.
[2009-10-13 08:32:04 2903] DEBUG (balloon:202) Balloon: setting dom0
target to 7986 MiB.
[2009-10-13 08:32:04 2903] DEBUG (XendDomainInfo:1369) Setting memory
target of domain Domain-0 (0) to 7986 MiB.
[2009-10-13 08:32:04 2903] DEBUG (balloon:181) Balloon: 4249424 KiB
free; need 4248576; done.
[2009-10-13 08:32:04 2903] INFO (image:181) buildDomain os=hvm dom=2 vcpus=1
[2009-10-13 08:32:04 2903] DEBUG (image:896) domid          = 2
[2009-10-13 08:32:04 2903] DEBUG (image:897) image          =
/usr/lib64/xen/boot/hvmloader
[2009-10-13 08:32:04 2903] DEBUG (image:898) store_evtchn   = 2
[2009-10-13 08:32:04 2903] DEBUG (image:899) memsize        = 4096
[2009-10-13 08:32:04 2903] DEBUG (image:900) target         = 4096
[2009-10-13 08:32:04 2903] DEBUG (image:901) vcpus          = 1
[2009-10-13 08:32:04 2903] DEBUG (image:902) acpi           = 1
[2009-10-13 08:32:04 2903] DEBUG (image:903) apic           = 1
[2009-10-13 08:32:04 2903] INFO (XendDomainInfo:2240) createDevice:
vfb : {'vncunused': 1, 'vnclisten': '0.0.0.0', 'vnc': '1', 'uuid':
'87ec5ba7-11fd-f2fc-7fe0-3011a9d9ef44', 'other_config': {'vncunused':
1, 'vnclisten': '0.0.0.0', 'vnc'
: '1'}}
[2009-10-13 08:32:04 2903] DEBUG (DevController:95) DevController:
writing {'state': '1', 'backend-id': '0', 'backend':
'/local/domain/0/backend/vfb/2/0'} to /local/domain/2/device/vfb/0.
[2009-10-13 08:32:04 2903] DEBUG (DevController:97) DevController:
writing {'vncunused': '1', 'domain': 'dpm', 'frontend':
'/local/domain/2/device/vfb/0', 'uuid':
'87ec5ba7-11fd-f2fc-7fe0-3011a9d9ef44', 'frontend-id': '2',
'vnclisten': '
0.0.0.0', 'state': '1', 'online': '1', 'vnc': '1'} to
/local/domain/0/backend/vfb/2/0.
[2009-10-13 08:32:04 2903] INFO (XendDomainInfo:2240) createDevice:
vbd : {'uuid': '02bfe483-9b60-af38-3ee5-08fd59f3adc9', 'bootable': 1,
'driver': 'paravirtualised', 'dev': 'xvda:sda1', 'uname':
'phy:system/dpm_root_0', 'mode': 'w'}
[2009-10-13 08:32:04 2903] DEBUG (DevController:95) DevController:
writing {'backend-id': '0', 'virtual-device': '51712', 'device-type':
'sda1', 'state': '1', 'backend':
'/local/domain/0/backend/vbd/2/51712'} to /local/domain/2/device/vb
d/51712.
[2009-10-13 08:32:04 2903] DEBUG (DevController:97) DevController:
writing {'domain': 'dpm', 'frontend':
'/local/domain/2/device/vbd/51712', 'uuid':
'02bfe483-9b60-af38-3ee5-08fd59f3adc9', 'bootable': '1', 'dev':
'xvda', 'state': '1', 'p
arams': 'system/dpm_root_0', 'mode': 'w', 'online': '1',
'frontend-id': '2', 'type': 'phy'} to
/local/domain/0/backend/vbd/2/51712.
[2009-10-13 08:32:05 2903] INFO (XendDomainInfo:2240) createDevice:
vbd : {'uuid': '4d6cf39a-8720-8672-de5a-5a5295baefc7', 'bootable': 0,
'driver': 'paravirtualised', 'dev': 'xvdb:sda2', 'uname':
'phy:system/dpm_swap', 'mode': 'w'}
[2009-10-13 08:32:05 2903] DEBUG (DevController:95) DevController:
writing {'backend-id': '0', 'virtual-device': '51728', 'device-type':
'sda2', 'state': '1', 'backend':
'/local/domain/0/backend/vbd/2/51728'} to /local/domain/2/device/vb
d/51728.
[2009-10-13 08:32:05 2903] DEBUG (DevController:97) DevController:
writing {'domain': 'dpm', 'frontend':
'/local/domain/2/device/vbd/51728', 'uuid':
'4d6cf39a-8720-8672-de5a-5a5295baefc7', 'bootable': '0', 'dev':
'xvdb', 'state': '1', 'p
arams': 'system/dpm_swap', 'mode': 'w', 'online': '1', 'frontend-id':
'2', 'type': 'phy'} to /local/domain/0/backend/vbd/2/51728.
[2009-10-13 08:32:05 2903] INFO (XendDomainInfo:2240) createDevice:
vif : {'bridge': 'eth0', 'model': 'e1000', 'mac': '00:16:3e:00:14:01',
'uuid': '2c93f5d3-5aa0-0802-d5d8-71926d329433'}
[2009-10-13 08:32:05 2903] DEBUG (DevController:95) DevController:
writing {'backend-id': '0', 'mac': '00:16:3e:00:14:01', 'handle': '0',
'state': '1', 'backend': '/local/domain/0/backend/vif/2/0'} to
/local/domain/2/device/vif/0.
[2009-10-13 08:32:05 2903] DEBUG (DevController:97) DevController:
writing {'bridge': 'eth0', 'domain': 'dpm', 'handle': '0', 'uuid':
'2c93f5d3-5aa0-0802-d5d8-71926d329433', 'script':
'/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:00:14
:01', 'frontend-id': '2', 'state': '1', 'online': '1', 'frontend':
'/local/domain/2/device/vif/0', 'model': 'e1000'} to
/local/domain/0/backend/vif/2/0.
[2009-10-13 08:32:05 2903] INFO (XendDomainInfo:2240) createDevice:
pci : {'devs': [{'slot': '0x00', 'domain': '0x0000', 'key': '07:00.0',
'bus': '0x07', 'vdevfn': '0x100', 'func': '0x0', 'uuid':
'5e3d06bc-c314-fd29-298d-2b658c8e397a'},
{'slot': '0x00', 'domain': '0x0000', 'key': '07:00.1', 'bus': '0x07',
'vdevfn': '0x100', 'func': '0x1', 'uuid':
'3b48c14f-4a39-e146-44cd-78d7a62a191a'}, {'slot': '0x00', 'domain':
'0x0000', 'key': '07:00.2', 'bus': '0x07', 'vdevfn': '0x1
00', 'func': '0x2', 'uuid': '020d04bd-516f-0a9b-a344-f1c306a28c18'},
{'slot': '0x00', 'domain': '0x0000', 'key': '07:00.3', 'bus': '0x07',
'vdevfn': '0x100', 'func': '0x3', 'uuid':
'b0fd8c7e-c2ee-993e-f14e-0881368d8795'}, {'slot': '0x00'
, 'domain': '0x0000', 'key': '14:00.0', 'bus': '0x14', 'vdevfn':
'0x100', 'func': '0x0', 'uuid':
'a76dc4b6-bf90-3812-8c6e-be9e888bf47b'}, {'slot': '0x00', 'domain':
'0x0000', 'key': '14:00.1', 'bus': '0x14', 'vdevfn': '0x100', 'func':
'0
x1', 'uuid': '912b3f64-57a3-aab4-252f-1f90950b8254'}, {'slot': '0x00',
'domain': '0x0000', 'key': '17:00.0', 'bus': '0x17', 'vdevfn':
'0x100', 'func': '0x0', 'uuid':
'69eb1d4e-82bc-82b6-e754-f08714adcc01'}, {'slot': '0x00', 'domain':
'0x
0000', 'key': '17:00.1', 'bus': '0x17', 'vdevfn': '0x100', 'func':
'0x1', 'uuid': '58675104-bdda-4239-9a25-9f4bf8578c02'}], 'uuid':
'4d92ccdb-ddd1-44e8-91b4-aaa22b6264d4'}


==> xend.log <==
[2009-10-13 08:32:06 2903] DEBUG (pciif:446) pci: register aer watch
/local/domain/0/backend/pci/2/0/aerState
[2009-10-13 08:32:06 2903] DEBUG (DevController:95) DevController:
writing {'state': '1', 'backend-id': '0', 'backend':
'/local/domain/0/backend/pci/2/0'} to /local/domain/2/device/pci/0.
[2009-10-13 08:32:06 2903] DEBUG (DevController:97) DevController:
writing {'key-3': '07:00.3', 'key-2': '07:00.2', 'key-1': '07:00.1',
'key-0': '07:00.0', 'key-7': '17:00.1', 'key-6': '17:00.0', 'key-5':
'14:00.1', 'key-4': '14:00.0', '
domain': 'dpm', 'frontend': '/local/domain/2/device/pci/0', 'uuid-4':
'a76dc4b6-bf90-3812-8c6e-be9e888bf47b', 'uuid-5':
'912b3f64-57a3-aab4-252f-1f90950b8254', 'uuid-6':
'69eb1d4e-82bc-82b6-e754-f08714adcc01', 'uuid-7': '58675104-bdda-42
39-9a25-9f4bf8578c02', 'uuid-0':
'5e3d06bc-c314-fd29-298d-2b658c8e397a', 'uuid-1':
'3b48c14f-4a39-e146-44cd-78d7a62a191a', 'uuid-2':
'020d04bd-516f-0a9b-a344-f1c306a28c18', 'uuid-3':
'b0fd8c7e-c2ee-993e-f14e-0881368d8795', 'uuid': '4d92c
cdb-ddd1-44e8-91b4-aaa22b6264d4', 'state': '1', 'online': '1',
'power_mgmt': '0', 'dev-3': '0000:07:00.3', 'dev-2': '0000:07:00.2',
'dev-1': '0000:07:00.1', 'dev-0': '0000:07:00.0', 'dev-7':
'0000:17:00.1', 'dev-6': '0000:17:00.0', 'dev-
5': '0000:14:00.1', 'dev-4': '0000:14:00.0', 'msitranslate': '1',
'vdevfn-4': '100', 'vdevfn-5': '100', 'vdevfn-6': '100', 'vdevfn-7':
'100', 'vdevfn-0': '100', 'vdevfn-1': '100', 'vdevfn-2': '100',
'vdevfn-3': '100', 'frontend-id': '2',
 'num_devs': '8'} to /local/domain/0/backend/pci/2/0.
[2009-10-13 08:32:06 2903] INFO (image:410) spawning device models:
/usr/lib64/xen/bin/qemu-dm ['/usr/lib64/xen/bin/qemu-dm', '-d', '2',
'-domain-name', 'dpm', '-videoram', '4', '-vnc', '0.0.0.0:0',
'-vncunused', '-vcpus', '1', '-boot',
'c', '-serial', 'pty', '-acpi', '-usbdevice', 'tablet', '-net',
'nic,vlan=1,macaddr=00:16:3e:00:14:01,model=e1000', '-net',
'tap,vlan=1,ifname=tap2.0,bridge=eth0', '-M', 'xenfv']
[2009-10-13 08:32:06 2903] INFO (image:459) device model pid: 14802
[2009-10-13 08:32:06 2903] DEBUG (XendDomainInfo:840)
XendDomainInfo.pci_device_configure: ['pci', ['dev', ['slot', '0x00'],
['domain', '0x0000'], ['key', '07:00.0'], ['bus', '0x07'], ['vdevfn',
'0x100'], ['func', '0x0'], ['uuid', '5e3d0
6bc-c314-fd29-298d-2b658c8e397a']], ['state', 'Initialising'],
['sub_state', 'Booting']]
[2009-10-13 08:32:06 2903] DEBUG (XendDomainInfo:743)
XendDomainInfo.hvm_pci_device_insert: {'devs': [{'slot': '0x00',
'domain': '0x0000', 'key': '07:00.0', 'bus': '0x07', 'vdevfn':
'0x100', 'func': '0x0', 'uuid': '5e3d06bc-c314-fd29-298
d-2b658c8e397a'}], 'states': ['Initialising']}
[2009-10-13 08:32:06 2903] DEBUG (XendDomainInfo:754)
XendDomainInfo.hvm_pci_device_insert_dev: {'slot': '0x00', 'domain':
'0x0000', 'key': '07:00.0', 'bus': '0x07', 'vdevfn': '0x100', 'func':
'0x0', 'uuid': '5e3d06bc-c314-fd29-298d-2b65
8c8e397a'}
[2009-10-13 08:32:06 2903] DEBUG (XendDomainInfo:763)
XendDomainInfo.hvm_pci_device_insert_dev: 0000:07:00.0@100
[2009-10-13 08:32:06 2903] INFO (image:561) waiting for sentinel_fifo
[2009-10-13 08:32:06 2903] DEBUG (image:479) signalDeviceModel:
orig_state is None, retrying
[2009-10-13 08:32:07 2903] WARNING (image:523) domain dpm: device
model failure: pid 14802: exited with nonzero status 1; see
/var/log/xen/qemu-dm-dpm.log
[2009-10-13 08:32:17 2903] ERROR (XendDomainInfo:2694)
XendDomainInfo.initDomain: exception occurred
Traceback (most recent call last):
  File "/usr/local/lib/python2.6/dist-packages/xen/xend/XendDomainInfo.py",
line 2686, in _initDomain
    self._createDevices()
  File "/usr/local/lib/python2.6/dist-packages/xen/xend/XendDomainInfo.py",
line 2273, in _createDevices
    self.pci_device_configure_boot()
  File "/usr/local/lib/python2.6/dist-packages/xen/xend/XendDomainInfo.py",
line 620, in pci_device_configure_boot
    self.pci_device_configure(dev_sxp)
  File "/usr/local/lib/python2.6/dist-packages/xen/xend/XendDomainInfo.py",
line 863, in pci_device_configure
    vdevfn = self.hvm_pci_device_insert(dev_config)
  File "/usr/local/lib/python2.6/dist-packages/xen/xend/XendDomainInfo.py",
line 750, in hvm_pci_device_insert
    return self.hvm_pci_device_insert_dev(new_dev)
  File "/usr/local/lib/python2.6/dist-packages/xen/xend/XendDomainInfo.py",
line 764, in hvm_pci_device_insert_dev
    self.image.signalDeviceModel('pci-ins', 'pci-inserted', bdf_str)
  File "/usr/local/lib/python2.6/dist-packages/xen/xend/image.py",
line 504, in signalDeviceModel
    raise VmError('Timed out waiting for device model action')
VmError: Timed out waiting for device model action
[2009-10-13 08:32:17 2903] ERROR (XendDomainInfo:467) VM start failed
Traceback (most recent call last):
  File "/usr/local/lib/python2.6/dist-packages/xen/xend/XendDomainInfo.py",
line 453, in start
    XendTask.log_progress(31, 60, self._initDomain)
  File "/usr/local/lib/python2.6/dist-packages/xen/xend/XendTask.py",
line 209, in log_progress
    retval = func(*args, **kwds)
  File "/usr/local/lib/python2.6/dist-packages/xen/xend/XendDomainInfo.py",
line 2697, in _initDomain
    raise exn
VmError: Timed out waiting for device model action
[2009-10-13 08:32:17 2903] DEBUG (XendDomainInfo:2844)
XendDomainInfo.destroy: domid=2

_______________________________________________
Xen-devel mailing list
Xen-devel@xxxxxxxxxxxxxxxxxxx
http://lists.xensource.com/xen-devel


 


Rackspace

Lists.xenproject.org is hosted with RackSpace, monitoring our
servers 24x7x365 and backed by RackSpace's Fanatical Support®.