[pciback] BUG: spinlock wrong CPU on CPU#2, xenwatch/40 lock: ffff88001dd9d310, .magic: dead4ead, .owner: xenwatch/40, .owner_cpu: 0

[pciback] BUG: spinlock wrong CPU on CPU#2, xenwatch/40 lock: ffff88001dd9d310, .magic: dead4ead, .owner: xenwatch/40, .owner_cpu: 0

[Sander Eikelenboom]

Hi Konrad,

I saw you have made a branch for upstream pciback in your tree ?
I have one request, would it be possible to allow a wildcard (*) for the function .. so xen-pciback.hide=(04:00.*) would work just as it does in the domU .cfg ?

Apart from that i sometimes get this when starting a domain with pci devices passed through:

Nov 10 09:48:04 localhost kernel: [  893.055513] BUG: spinlock wrong CPU on CPU#2, xenwatch/40
Nov 10 09:48:04 localhost kernel: [  893.056268]  lock: ffff88001dd9d310, .magic: dead4ead, .owner: xenwatch/40, .owner_cpu: 0

Xen-unstable: Thu Nov 04 16:42:34 2010 +0000 22364:0d5f09e3728c
Dom0 kernel:  2.6.32.25 pvops commit 6c72eadd151f6f1b904989532a83988af0ea661a
DomU kernel: 2.6.37-rc1 commit 151f52f09c5728ecfdd0c289da1a4b30bb416f2c

--
Sander


Nov 10 09:47:59 localhost kernel: [  888.310761]   alloc irq_desc for 1691 on node 0
Nov 10 09:47:59 localhost kernel: [  888.310766]   alloc kstat_irqs on node 0
Nov 10 09:47:59 localhost logger: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/14/51713
Nov 10 09:47:59 localhost logger: /etc/xen/scripts/block: Writing backend/vbd/14/51713/node /dev/loop20 to xenstore.
Nov 10 09:47:59 localhost logger: /etc/xen/scripts/block: Writing backend/vbd/14/51713/physical-device 7:14 to xenstore.
Nov 10 09:47:59 localhost logger: /etc/xen/scripts/block: Writing backend/vbd/14/51713/hotplug-status connected to xenstore.
Nov 10 09:48:00 localhost logger: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/14/51714
Nov 10 09:48:00 localhost logger: /etc/xen/scripts/block: Writing backend/vbd/14/51714/node /dev/loop21 to xenstore.
Nov 10 09:48:00 localhost logger: /etc/xen/scripts/block: Writing backend/vbd/14/51714/physical-device 7:15 to xenstore.
Nov 10 09:48:00 localhost logger: /etc/xen/scripts/block: Writing backend/vbd/14/51714/hotplug-status connected to xenstore.
Nov 10 09:48:00 localhost logger: /etc/xen/scripts/block: add XENBUS_PATH=backend/vbd/14/51729
Nov 10 09:48:00 localhost logger: /etc/xen/scripts/vif-bridge: online type_if=vif XENBUS_PATH=backend/vif/14/0
Nov 10 09:48:01 localhost kernel: [  889.998853] device vif14.0 entered promiscuous mode
Nov 10 09:48:01 localhost kernel: [  890.023895] xen_bridge: port 11(vif14.0) entering learning state
Nov 10 09:48:01 localhost logger: /etc/xen/scripts/vif-bridge: Successful vif-bridge online for vif14.0, bridge xen_bridge.
Nov 10 09:48:01 localhost logger: /etc/xen/scripts/vif-bridge: Writing backend/vif/14/0/hotplug-status connected to xenstore.
Nov 10 09:48:01 localhost logger: /etc/xen/scripts/vif-bridge: online XENBUS_PATH=backend/vif/14/0
Nov 10 09:48:01 localhost /USR/SBIN/CRON[15623]: (root) CMD ( /bin/bash /etc/eigen_scripts/zabbix-1.8/scripts/zabbix_collect_statistics.sh)
Nov 10 09:48:01 localhost kernel: [  890.335943] xen_bridge: port 11(vif14.0) entering disabled state
Nov 10 09:48:01 localhost kernel: [  890.365172] xen_bridge: port 11(vif14.0) entering learning state
Nov 10 09:48:01 localhost logger: /etc/xen/scripts/block: Writing backend/vbd/14/51729/physical-device fc:8 to xenstore.
Nov 10 09:48:01 localhost logger: /etc/xen/scripts/block: Writing backend/vbd/14/51729/hotplug-status connected to xenstore.
Nov 10 09:48:01 localhost logger: /etc/xen/scripts/vif-bridge: Successful vif-bridge online for vif14.0, bridge xen_bridge.
Nov 10 09:48:01 localhost logger: /etc/xen/scripts/vif-bridge: Writing backend/vif/14/0/hotplug-status connected to xenstore.
Nov 10 09:48:03 localhost /USR/SBIN/CRON[15620]: (root) MAIL (mailed 198 bytes of output but got status 0x0001#012)
Nov 10 09:48:03 localhost kernel: [  892.789037] pciback pci-14-0: allocated pdev @ 0xffff8800251c2600
Nov 10 09:48:03 localhost kernel: [  892.809265] pciback pci-14-0: getting be setup
Nov 10 09:48:03 localhost kernel: [  892.813947] pciback pci-14-0: exporting dom 0 bus 4 slot 0 func 0
Nov 10 09:48:03 localhost kernel: [  892.821631] pciback 0000:04:00.0: registering for 14
Nov 10 09:48:03 localhost kernel: [  892.835730] pciback pci-14-0: exporting dom 0 bus 4 slot 0 func 1
Nov 10 09:48:03 localhost kernel: [  892.839632] pciback 0000:04:00.1: registering for 14
Nov 10 09:48:03 localhost kernel: [  892.853634] pciback pci-14-0: exporting dom 0 bus 4 slot 0 func 2
Nov 10 09:48:03 localhost kernel: [  892.860462] pciback 0000:04:00.2: registering for 14
Nov 10 09:48:03 localhost kernel: [  892.883340] pciback pci-14-0: exporting dom 0 bus 4 slot 0 func 3
Nov 10 09:48:03 localhost kernel: [  892.893487] pciback 0000:04:00.3: registering for 14
Nov 10 09:48:03 localhost kernel: [  892.903580] pciback pci-14-0: exporting dom 0 bus 4 slot 0 func 4
Nov 10 09:48:03 localhost kernel: [  892.910463] pciback 0000:04:00.4: registering for 14
Nov 10 09:48:03 localhost kernel: [  892.926832] pciback pci-14-0: exporting dom 0 bus 4 slot 0 func 5
Nov 10 09:48:03 localhost kernel: [  892.937014] pciback 0000:04:00.5: registering for 14
Nov 10 09:48:03 localhost kernel: [  892.943421] pciback pci-14-0: exporting dom 0 bus 4 slot 0 func 6
Nov 10 09:48:03 localhost kernel: [  892.950156] pciback 0000:04:00.6: registering for 14
Nov 10 09:48:03 localhost kernel: [  892.967932] pciback pci-14-0: exporting dom 0 bus 4 slot 0 func 7
Nov 10 09:48:04 localhost kernel: [  892.974821] pciback 0000:04:00.7: registering for 14
Nov 10 09:48:04 localhost kernel: [  892.984644] pciback pci-14-0: Publishing pci roots
Nov 10 09:48:04 localhost kernel: [  892.988186] pciback pci-14-0: writing root 0 at 0000:04
Nov 10 09:48:04 localhost kernel: [  893.002377] pciback pci-14-0: Publishing pci roots
Nov 10 09:48:04 localhost kernel: [  893.009333] pciback pci-14-0: Publishing pci roots
Nov 10 09:48:04 localhost kernel: [  893.013285] pciback pci-14-0: Publishing pci roots
Nov 10 09:48:04 localhost kernel: [  893.020255] pciback pci-14-0: Publishing pci roots
Nov 10 09:48:04 localhost kernel: [  893.031625] pciback pci-14-0: Publishing pci roots
Nov 10 09:48:04 localhost kernel: [  893.042788] pciback pci-14-0: Publishing pci roots
Nov 10 09:48:04 localhost kernel: [  893.047202] pciback pci-14-0: Publishing pci roots
Nov 10 09:48:04 localhost kernel: [  893.055513] BUG: spinlock wrong CPU on CPU#2, xenwatch/40
Nov 10 09:48:04 localhost kernel: [  893.056268]  lock: ffff88001dd9d310, .magic: dead4ead, .owner: xenwatch/40, .owner_cpu: 0
Nov 10 09:48:04 localhost kernel: [  893.056268] Pid: 40, comm: xenwatch Not tainted 2.6.32.25-xen-next-2.6.32.x-20101106 #8
Nov 10 09:48:04 localhost kernel: [  893.056268] Call Trace:
Nov 10 09:48:04 localhost kernel: [  893.056268]  [<ffffffff81332552>] spin_bug+0xa2/0xf0
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff81045e3f>] ? xen_restore_fl_direct_end+0x0/0x1
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff81332606>] _raw_spin_unlock+0x66/0xa0
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff8175799b>] _spin_unlock+0x2b/0x40
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff813b8663>] pciback_publish_pci_roots+0xc3/0xe0
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff813b5870>] ? pciback_publish_pci_root+0x0/0x220
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff813b5e4c>] pciback_be_watch+0x22c/0x2c0
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff813b0256>] ? show_current_kb+0x16/0x40
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff813b6663>] ? pciback_xenbus_probe+0x1c3/0x1f0
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff810c7406>] ? lock_release+0x126/0x260
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff813b666f>] pciback_xenbus_probe+0x1cf/0x1f0
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff813af163>] xenbus_dev_probe+0x93/0x190
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff81403e75>] driver_probe_device+0xb5/0x1d0
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff81404040>] ? __device_attach+0x0/0x60
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff81404093>] __device_attach+0x53/0x60
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff81403113>] bus_for_each_drv+0x73/0xa0
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff81404176>] device_attach+0x96/0xb0
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff81402ecd>] bus_probe_device+0x2d/0x50
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff814012ea>] device_add+0x3fa/0x630
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff8140153e>] device_register+0x1e/0x30
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff813aec50>] xenbus_probe_node+0x120/0x1a0
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff814034da>] ? bus_for_each_dev+0x8a/0xa0
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff813aee96>] xenbus_dev_changed+0x1c6/0x1d0
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff810c7406>] ? lock_release+0x126/0x260
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff813af45b>] backend_changed+0x1b/0x20
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff813ad345>] xenwatch_thread+0x55/0x160
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff810b0760>] ? autoremove_wake_function+0x0/0x40
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff813ad2f0>] ? xenwatch_thread+0x0/0x160
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff810b03c6>] kthread+0x96/0xb0
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff8104a6ca>] child_rip+0xa/0x20
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff8104a050>] ? restore_args+0x0/0x30
Nov 10 09:48:04 localhost kernel: [  893.148525]  [<ffffffff8104a6c0>] ? child_rip+0x0/0x20
Nov 10 09:48:04 localhost kernel: [  893.292564]   alloc irq_desc for 1690 on node 0
Nov 10 09:48:04 localhost kernel: [  893.292567]   alloc kstat_irqs on node 0
Nov 10 09:48:04 localhost kernel: [  893.294725] pciback pci-14-0: fe state changed 1
Nov 10 09:48:09 localhost kernel: [  898.579722] pciback pci-14-0: fe state changed 3
Nov 10 09:48:09 localhost kernel: [  898.589522] pciback pci-14-0: Reading frontend config
Nov 10 09:48:09 localhost kernel: [  898.589887] pciback pci-14-0: Attaching to frontend resources - gnt_ref=8 evtchn=8
Nov 10 09:48:09 localhost kernel: [  898.589960]   alloc irq_desc for 1689 on node 0
Nov 10 09:48:09 localhost kernel: [  898.589962]   alloc kstat_irqs on node 0
Nov 10 09:48:09 localhost kernel: [  898.590001] pciback pci-14-0: Attached!
Nov 10 09:48:09 localhost kernel: [  898.590002] pciback pci-14-0: Connecting...
Nov 10 09:48:09 localhost kernel: [  898.600591] pciback pci-14-0: Connected? 0
Nov 10 09:48:09 localhost kernel: [  898.625435] blkback: ring-ref 9, event-channel 9, protocol 1 (x86_64-abi)
Nov 10 09:48:09 localhost kernel: [  898.625514]   alloc irq_desc for 1688 on node 0
Nov 10 09:48:09 localhost kernel: [  898.625516]   alloc kstat_irqs on node 0
Nov 10 09:48:09 localhost kernel: [  898.852400] blkback: ring-ref 10, event-channel 10, protocol 1 (x86_64-abi)
Nov 10 09:48:09 localhost kernel: [  898.859514]   alloc irq_desc for 1687 on node 0
Nov 10 09:48:09 localhost kernel: [  898.866203]   alloc kstat_irqs on node 0
Nov 10 09:48:09 localhost kernel: [  898.922676] pciback 0000:04:00.1: set power state to 0
Nov 10 09:48:09 localhost kernel: [  898.929319] pciback 0000:04:00.1: enabling device (0000 -> 0002)
Nov 10 09:48:09 localhost kernel: [  898.935280] xen: registering gsi 40 triggering 0 polarity 1
Nov 10 09:48:09 localhost kernel: [  898.935280] xen_allocate_pirq: returning irq 40 for gsi 40
Nov 10 09:48:09 localhost kernel: [  898.935280] xen: --> irq=40
Nov 10 09:48:09 localhost kernel: [  898.935484] Already setup the GSI :40
Nov 10 09:48:09 localhost kernel: [  898.935486] pciback 0000:04:00.1: PCI INT A -> GSI 40 (level, low) -> IRQ 40
Nov 10 09:48:09 localhost kernel: [  898.935493] pciback 0000:04:00.1: pciback[0000:04:00.1]: #40 on  disable-> enable
Nov 10 09:48:10 localhost kernel: [  898.935833] pciback 0000:04:00.1: pciback[0000:04:00.1]: #40 on  enabled
Nov 10 09:48:10 localhost kernel: [  898.936193] pciback 0000:04:00.1: enabling bus mastering
Nov 10 09:48:10 localhost kernel: [  898.936202] pciback 0000:04:00.1: setting latency timer to 64
Nov 10 09:48:10 localhost kernel: [  898.944952] blkback: ring-ref 11, event-channel 11, protocol 1 (x86_64-abi)
Nov 10 09:48:10 localhost kernel: [  898.945024]   alloc irq_desc for 1686 on node 0
Nov 10 09:48:10 localhost kernel: [  898.945026]   alloc kstat_irqs on node 0
Nov 10 09:48:10 localhost kernel: [  898.957507] pciback 0000:04:00.1: enabling Mem-Wr-Inval
Nov 10 09:48:10 localhost kernel: [  898.963912] pciback 0000:04:00.3: set power state to 0
Nov 10 09:48:10 localhost kernel: [  898.964214] pciback 0000:04:00.3: enabling device (0000 -> 0002)
Nov 10 09:48:10 localhost kernel: [  898.964222] xen: registering gsi 41 triggering 0 polarity 1
Nov 10 09:48:10 localhost kernel: [  898.964224] xen_allocate_pirq: returning irq 41 for gsi 41
Nov 10 09:48:10 localhost kernel: [  898.964226] xen: --> irq=41
Nov 10 09:48:10 localhost kernel: [  898.964229] Already setup the GSI :41
Nov 10 09:48:10 localhost kernel: [  898.964231] pciback 0000:04:00.3: PCI INT B -> GSI 41 (level, low) -> IRQ 41
Nov 10 09:48:10 localhost kernel: [  898.964237] pciback 0000:04:00.3: pciback[0000:04:00.3]: #41 on  disable-> enable
Nov 10 09:48:10 localhost kernel: [  898.964275] pciback 0000:04:00.3: pciback[0000:04:00.3]: #41 on  enabled
Nov 10 09:48:10 localhost kernel: [  898.964502] pciback 0000:04:00.3: enabling bus mastering
Nov 10 09:48:10 localhost kernel: [  898.964510] pciback 0000:04:00.3: setting latency timer to 64
Nov 10 09:48:10 localhost kernel: [  898.985498] pciback 0000:04:00.3: enabling Mem-Wr-Inval
Nov 10 09:48:10 localhost kernel: [  898.991803] pciback 0000:04:00.5: set power state to 0
Nov 10 09:48:10 localhost kernel: [  898.991874] pciback 0000:04:00.5: enabling device (0000 -> 0002)
Nov 10 09:48:10 localhost kernel: [  898.991881] xen: registering gsi 42 triggering 0 polarity 1
Nov 10 09:48:10 localhost kernel: [  898.991884] xen_allocate_pirq: returning irq 42 for gsi 42
Nov 10 09:48:10 localhost kernel: [  898.991885] xen: --> irq=42
Nov 10 09:48:10 localhost kernel: [  898.991888] Already setup the GSI :42
Nov 10 09:48:10 localhost kernel: [  898.991890] pciback 0000:04:00.5: PCI INT C -> GSI 42 (level, low) -> IRQ 42
Nov 10 09:48:10 localhost kernel: [  898.991897] pciback 0000:04:00.5: pciback[0000:04:00.5]: #42 on  disable-> enable
Nov 10 09:48:10 localhost kernel: [  898.991934] pciback 0000:04:00.5: pciback[0000:04:00.5]: #42 on  enabled
Nov 10 09:48:10 localhost kernel: [  898.991998] pciback 0000:04:00.5: enabling bus mastering
Nov 10 09:48:10 localhost kernel: [  898.992006] pciback 0000:04:00.5: setting latency timer to 64
Nov 10 09:48:10 localhost kernel: [  899.012475] pciback 0000:04:00.5: enabling Mem-Wr-Inval
Nov 10 09:48:10 localhost kernel: [  899.014577] pciback pci-14-0: fe state changed 4
Nov 10 09:48:10 localhost kernel: [  899.018849] pciback 0000:04:00.7: set power state to 0
Nov 10 09:48:10 localhost kernel: [  899.018908] pciback 0000:04:00.7: enabling device (0000 -> 0002)
Nov 10 09:48:10 localhost kernel: [  899.018915] xen: registering gsi 43 triggering 0 polarity 1
Nov 10 09:48:10 localhost kernel: [  899.018917] xen_allocate_pirq: returning irq 43 for gsi 43
Nov 10 09:48:10 localhost kernel: [  899.018919] xen: --> irq=43
Nov 10 09:48:10 localhost kernel: [  899.018922] Already setup the GSI :43
Nov 10 09:48:10 localhost kernel: [  899.018924] pciback 0000:04:00.7: PCI INT D -> GSI 43 (level, low) -> IRQ 43
Nov 10 09:48:10 localhost kernel: [  899.018929] pciback 0000:04:00.7: pciback[0000:04:00.7]: #43 on  disable-> enable
Nov 10 09:48:10 localhost kernel: [  899.018966] pciback 0000:04:00.7: pciback[0000:04:00.7]: #43 on  enabled
Nov 10 09:48:10 localhost kernel: [  899.019028] pciback 0000:04:00.7: enabling bus mastering
Nov 10 09:48:10 localhost kernel: [  899.019036] pciback 0000:04:00.7: setting latency timer to 64
Nov 10 09:48:10 localhost kernel: [  899.039458] pciback 0000:04:00.7: enabling Mem-Wr-Inval
Nov 10 09:48:10 localhost kernel: [  899.095415] pciback 0000:04:00.0: set power state to 0
Nov 10 09:48:10 localhost kernel: [  899.095513] pciback 0000:04:00.0: enabling device (0000 -> 0002)
Nov 10 09:48:10 localhost kernel: [  899.095520] xen: registering gsi 40 triggering 0 polarity 1
Nov 10 09:48:10 localhost kernel: [  899.095522] xen_allocate_pirq: returning irq 40 for gsi 40
Nov 10 09:48:10 localhost kernel: [  899.095524] xen: --> irq=40
Nov 10 09:48:10 localhost kernel: [  899.095527] Already setup the GSI :40
Nov 10 09:48:10 localhost kernel: [  899.095529] pciback 0000:04:00.0: PCI INT A -> GSI 40 (level, low) -> IRQ 40
Nov 10 09:48:10 localhost kernel: [  899.095535] pciback 0000:04:00.0: pciback[0000:04:00.0]: #40 on  disable-> enable
Nov 10 09:48:10 localhost kernel: [  899.095552] pciback 0000:04:00.0: pciback[0000:04:00.0]: #40 on  enabled
Nov 10 09:48:10 localhost kernel: [  899.095779] pciback 0000:04:00.0: enabling bus mastering
Nov 10 09:48:10 localhost kernel: [  899.095787] pciback 0000:04:00.0: setting latency timer to 64
Nov 10 09:48:10 localhost kernel: [  899.129122]   alloc irq_desc for 1685 on node 0
Nov 10 09:48:10 localhost kernel: [  899.129126]   alloc kstat_irqs on node 0
Nov 10 09:48:10 localhost kernel: [  899.150960] pciback 0000:04:00.2: set power state to 0
Nov 10 09:48:10 localhost kernel: [  899.151018] pciback 0000:04:00.2: enabling device (0000 -> 0002)
Nov 10 09:48:10 localhost kernel: [  899.151025] xen: registering gsi 41 triggering 0 polarity 1
Nov 10 09:48:10 localhost kernel: [  899.151027] xen_allocate_pirq: returning irq 41 for gsi 41
Nov 10 09:48:10 localhost kernel: [  899.151029] xen: --> irq=41
Nov 10 09:48:10 localhost kernel: [  899.151031] Already setup the GSI :41
Nov 10 09:48:10 localhost kernel: [  899.151033] pciback 0000:04:00.2: PCI INT B -> GSI 41 (level, low) -> IRQ 41
Nov 10 09:48:10 localhost kernel: [  899.151039] pciback 0000:04:00.2: pciback[0000:04:00.2]: #41 on  disable-> enable
Nov 10 09:48:10 localhost kernel: [  899.151053] pciback 0000:04:00.2: pciback[0000:04:00.2]: #41 on  enabled
Nov 10 09:48:10 localhost kernel: [  899.151294] pciback 0000:04:00.2: enabling bus mastering
Nov 10 09:48:10 localhost kernel: [  899.151302] pciback 0000:04:00.2: setting latency timer to 64
Nov 10 09:48:10 localhost kernel: [  899.206902] pciback 0000:04:00.4: set power state to 0
Nov 10 09:48:10 localhost kernel: [  899.206958] pciback 0000:04:00.4: enabling device (0000 -> 0002)
Nov 10 09:48:10 localhost kernel: [  899.206964] xen: registering gsi 42 triggering 0 polarity 1
Nov 10 09:48:10 localhost kernel: [  899.206966] xen_allocate_pirq: returning irq 42 for gsi 42
Nov 10 09:48:10 localhost kernel: [  899.206968] xen: --> irq=42
Nov 10 09:48:10 localhost kernel: [  899.206970] Already setup the GSI :42
Nov 10 09:48:10 localhost kernel: [  899.206972] pciback 0000:04:00.4: PCI INT C -> GSI 42 (level, low) -> IRQ 42
Nov 10 09:48:10 localhost kernel: [  899.206977] pciback 0000:04:00.4: pciback[0000:04:00.4]: #42 on  disable-> enable
Nov 10 09:48:10 localhost kernel: [  899.206989] pciback 0000:04:00.4: pciback[0000:04:00.4]: #42 on  enabled
Nov 10 09:48:10 localhost kernel: [  899.207258] pciback 0000:04:00.4: enabling bus mastering
Nov 10 09:48:10 localhost kernel: [  899.207266] pciback 0000:04:00.4: setting latency timer to 64
Nov 10 09:48:10 localhost kernel: [  899.262744] pciback 0000:04:00.6: set power state to 0
Nov 10 09:48:10 localhost kernel: [  899.262823] pciback 0000:04:00.6: enabling device (0000 -> 0002)
Nov 10 09:48:10 localhost kernel: [  899.262829] xen: registering gsi 43 triggering 0 polarity 1
Nov 10 09:48:10 localhost kernel: [  899.262831] xen_allocate_pirq: returning irq 43 for gsi 43
Nov 10 09:48:10 localhost kernel: [  899.262832] xen: --> irq=43
Nov 10 09:48:10 localhost kernel: [  899.262835] Already setup the GSI :43
Nov 10 09:48:10 localhost kernel: [  899.262836] pciback 0000:04:00.6: PCI INT D -> GSI 43 (level, low) -> IRQ 43
Nov 10 09:48:10 localhost kernel: [  899.262842] pciback 0000:04:00.6: pciback[0000:04:00.6]: #43 on  disable-> enable
Nov 10 09:48:10 localhost kernel: [  899.262854] pciback 0000:04:00.6: pciback[0000:04:00.6]: #43 on  enabled
Nov 10 09:48:10 localhost kernel: [  899.262909] pciback 0000:04:00.6: enabling bus mastering
Nov 10 09:48:10 localhost kernel: [  899.262917] pciback 0000:04:00.6: setting latency timer to 64

Re: [pciback] BUG: spinlock wrong CPU on CPU#2, xenwatch/40 lock: ffff88001dd9d310, .magic: dead4ead, .owner: xenwatch/40, .owner_cpu: 0

[Konrad Rzeszutek Wilk]

On Wed, Nov 10, 2010 at 10:11:10AM +0100, Sander Eikelenboom wrote:
> Hi Konrad,
> 
> I saw you have made a branch for upstream pciback in your tree ?

Wow, you are quick, I just made it on Monday. There is still some
extra work required as the 2.6.37-rc1 does not have the XenBus backend up-ported.

So that branch is not ready.

> I have one request, would it be possible to allow a wildcard (*) for the function .. so xen-pciback.hide=(04:00.*) would work just as it does in the domU .cfg ?

Good idea - will do it.
> 
> Apart from that i sometimes get this when starting a domain with pci devices passed through:
> 
> Nov 10 09:48:04 localhost kernel: [  893.055513] BUG: spinlock wrong CPU on CPU#2, xenwatch/40
> Nov 10 09:48:04 localhost kernel: [  893.056268]  lock: ffff88001dd9d310, .magic: dead4ead, .owner: xenwatch/40, .owner_cpu: 0

Yeah, that one is tied in the fact that the XenBus backend thread is taking
the spinlock and in pciback we call xenstore, which can go to sleep, and then
we can wake up on another CPU.

I need to talk to Ian about the XenBus thread to see how this can be made safe.