rendered paste body[20110502T00:26:49.073Z|debug|almdudler.oob.rename-it.nl|10885 inet-RPC|VM import R:b83e41510a86|stream_vdi] Verified checksum on Ref:2/00684192: 3ad796d1829f5a4faf47cc5ac86854ba9f3dd34d = 3ad796d1829f5a4faf47cc5ac86854ba9f3dd34d
[20110502T00:26:49.073Z|debug|almdudler.oob.rename-it.nl|10885 inet-RPC|VM import R:b83e41510a86|stream_vdi] suffix=00684193 last_suffix=00684192
[20110502T00:26:49.380Z|debug|almdudler.oob.rename-it.nl|10885 inet-RPC|VM import R:b83e41510a86|stream_vdi] Verified checksum on Ref:2/00684193: 92862e48c096f980b2488735059c28dbbeb1887c = 92862e48c096f980b2488735059c28dbbeb1887c
[20110502T00:26:49.381Z|debug|almdudler.oob.rename-it.nl|10885 inet-RPC|VM import R:b83e41510a86|stream_vdi] suffix=00684194 last_suffix=00684193
[20110502T00:26:50.236Z|debug|almdudler.oob.rename-it.nl|10885 inet-RPC|VM import R:b83e41510a86|stream_vdi] Verified checksum on Ref:2/00684194: 2f8773411648543abff171f982644eb08d3608cd = 2f8773411648543abff171f982644eb08d3608cd
[20110502T00:26:50.236Z|debug|almdudler.oob.rename-it.nl|10885 inet-RPC|VM import R:b83e41510a86|stream_vdi] suffix=00684195 last_suffix=00684194
[20110502T00:26:50.372Z|debug|almdudler.oob.rename-it.nl|10885 inet-RPC|VM import R:b83e41510a86|xapi] Raised at db_cache_types.ml:75.27-76 -> db_cache_types.ml:118.2-40 -> pervasiveext.ml:22.2-9
[20110502T00:26:50.372Z|debug|almdudler.oob.rename-it.nl|10885 inet-RPC|VM import R:b83e41510a86|xapi] Raised at pervasiveext.ml:26.22-25 -> stream_vdi.ml:84.4-64 -> stream_vdi.ml:271.5-69 -> stream_vdi.ml:285.1-20 -> pervasiveext.ml:22.2-9
[20110502T00:26:50.908Z|debug|almdudler.oob.rename-it.nl|10885 inet-RPC|VM import R:b83e41510a86|xapi] Raised at pervasiveext.ml:26.22-25 -> pervasiveext.ml:22.2-9
[20110502T00:26:50.909Z|debug|almdudler.oob.rename-it.nl|10885 inet-RPC|VM import R:b83e41510a86|xapi] Raised at db_cache_types.ml:75.27-76 -> db_cache_types.ml:118.2-40 -> pervasiveext.ml:22.2-9
[20110502T00:26:50.916Z|debug|almdudler.oob.rename-it.nl|17356 unix-RPC||dummytaskhelper] task dispatch:session.slave_login D:e68aa999eabe created by task R:b83e41510a86
[20110502T00:26:50.932Z| info|almdudler.oob.rename-it.nl|17356 unix-RPC|session.slave_login D:bc1aa8f68d0a|xapi] Session.create trackid=0014dedaa1d1ebc2b251a8fec76745d9 pool=true uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20110502T00:26:50.943Z|debug|almdudler.oob.rename-it.nl|17357 unix-RPC||dummytaskhelper] task dispatch:session.get_uuid D:99cc63e73604 created by task D:bc1aa8f68d0a
[20110502T00:26:50.964Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC||dummytaskhelper] task dispatch:VBD.unplug D:3fbfc810ae20 created by task R:b83e41510a86
[20110502T00:26:50.971Z| info|almdudler.oob.rename-it.nl|17358 unix-RPC|dispatch:VBD.unplug D:3fbfc810ae20|taskhelper] task VBD.unplug R:1b6a61d8a660 (uuid:3b241ae7-a292-ee22-04f0-984db6b12003) created (trackid=0014dedaa1d1ebc2b251a8fec76745d9) by task R:b83e41510a86
[20110502T00:26:50.975Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|audit] VBD.unplug: VBD = '9bffec07-b649-33ad-2596-8785a2687bcf'
[20110502T00:26:50.979Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xapi] Raised at db_cache_types.ml:75.27-76 -> db_cache_types.ml:118.2-40 -> pervasiveext.ml:22.2-9
[20110502T00:26:51.012Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xapi] Raised at db_cache_types.ml:75.27-76 -> db_cache_types.ml:118.2-40 -> pervasiveext.ml:22.2-9
[20110502T00:26:51.013Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|locking_helpers] Acquired lock on VM OpaqueRef:3d1b377f-518a-4ac6-c506-23b34a3b10c6 with token 5
[20110502T00:26:51.014Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xapi] Attempting to dynamically detach VBD from domid 0
[20110502T00:26:51.051Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xenops] Device.Vbd.clean_shutdown frontend (domid=0 | kind=vbd | devid=51712); backend (domid=0 | kind=vbd | devid=51712)
[20110502T00:26:51.051Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xenops] Device.Vbd.request_shutdown frontend (domid=0 | kind=vbd | devid=51712); backend (domid=0 | kind=vbd | devid=51712) normal
[20110502T00:26:51.086Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xenops] xenstore-write /local/domain/0/backend/vbd/0/51712/online = 0
[20110502T00:26:51.153Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xenops] xenstore-write /local/domain/0/backend/vbd/0/51712/shutdown-request = normal
[20110502T00:26:51.188Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xenops] watch: watching xenstore paths: [ /local/domain/0/backend/vbd/0/51712/shutdown-done; /local/domain/0/error/device/vbd/51712/error ] with timeout 1200.000000 seconds
[20110502T00:26:51.296Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xenops] Device.Vbd.shutdown_common: shutdown-done appeared
[20110502T00:26:51.296Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xenops] Device.rm_device_state frontend (domid=0 | kind=vbd | devid=51712); backend (domid=0 | kind=vbd | devid=51712)
[20110502T00:26:51.296Z|debug|almdudler.oob.rename-it.nl|185 xal_listen||event] VM (domid: 0) device_event = device shutdown {vbd,51712}
[20110502T00:26:51.297Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xenops] xenstore-rm /local/domain/0/device/vbd/51712
[20110502T00:26:51.312Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xenops] xenstore-rm /local/domain/0/backend/vbd/0/51712
[20110502T00:26:51.314Z|debug|almdudler.oob.rename-it.nl|185 xal_listen|VM (domid: 0) device_event = device shutdown {vbd,51712} D:e2168ff3e2ce|event] Adding Resync.vbd to queue
[20110502T00:26:51.314Z|debug|almdudler.oob.rename-it.nl|185 xal_listen|VM (domid: 0) device_event = device shutdown {vbd,51712} D:e2168ff3e2ce|locking_helpers] push(per-VM queue, DevShutdownDone(vbd, 51712) domid: 0); queue = [ DevShutdownDone(vbd, 51712) domid: 0 ](1)
[20110502T00:26:51.314Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xenops] xenstore-rm /local/domain/0/error/backend/vbd/0
[20110502T00:26:51.314Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xenops] xenstore-rm /local/domain/0/error/device/vbd/51712
[20110502T00:26:51.315Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xenops] Device.Vbd.release frontend (domid=0 | kind=vbd | devid=51712); backend (domid=0 | kind=vbd | devid=51712)
[20110502T00:26:51.315Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xenops] xenstore-rm /local/domain/0/backend/vbd/0/51712
[20110502T00:26:51.315Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|hotplug] Hotplug.release: frontend (domid=0 | kind=vbd | devid=51712); backend (domid=0 | kind=vbd | devid=51712)
[20110502T00:26:51.316Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|hotplug] Hotplug.wait_for_unplug: frontend (domid=0 | kind=vbd | devid=51712); backend (domid=0 | kind=vbd | devid=51712)
[20110502T00:26:51.316Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xenops] watch: watching xenstore paths: [ /xapi/0/hotplug/vbd/51712/hotplug ] with timeout 1200.000000 seconds
[20110502T00:26:53.598Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|hotplug] Synchronised ok with hotplug script: frontend (domid=0 | kind=vbd | devid=51712); backend (domid=0 | kind=vbd | devid=51712)
[20110502T00:26:53.615Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|hotplug] Hotplug.wait_for_frontend_unplug: frontend (domid=0 | kind=vbd | devid=51712); backend (domid=0 | kind=vbd | devid=51712)
[20110502T00:26:53.615Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xenops] watch: watching xenstore paths: [ /xapi/0/frontend/vbd/51712/hotplug ] with timeout 1200.000000 seconds
[20110502T00:26:53.638Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|hotplug] Synchronised ok with frontend hotplug script: frontend (domid=0 | kind=vbd | devid=51712); backend (domid=0 | kind=vbd | devid=51712)
[20110502T00:26:53.638Z|debug|almdudler.oob.rename-it.nl|17358 unix-RPC|VBD.unplug R:1b6a61d8a660|xapi] vbd_unplug: setting currently_attached to false