[20110720T20:33:21.666Z|debug|xen-blade13|330 unix-RPC||cli] xe vbd-plug uuid=92a3b3bc-8b48-b13d-a53c-891c10fba1b9 username=root password=null [20110720T20:33:21.671Z| info|xen-blade13|330 unix-RPC|session.login_with_password D:221719594bec|xapi] Session.create trackid=f8ef7f65f2687215f90c4585a94ee190 pool=false uname=root is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49 [20110720T20:33:21.676Z|debug|xen-blade13|331 unix-RPC||dummytaskhelper] task dispatch:session.get_uuid D:ebaee2bb634f created by task D:221719594bec [20110720T20:33:21.693Z|debug|xen-blade13|330 unix-RPC|VBD.plug R:2443e315259d|audit] VBD.plug: VBD = '92a3b3bc-8b48-b13d-a53c-891c10fba1b9' [20110720T20:33:21.698Z|debug|xen-blade13|330 unix-RPC|VBD.plug R:2443e315259d|locking_helpers] Acquired lock on VM OpaqueRef:14265b6a-41a3-c860-dde6-f3fe107fabba with token 2 [20110720T20:33:21.698Z|debug|xen-blade13|330 unix-RPC|VBD.plug R:2443e315259d|xapi] vbd_plug: attempting to attach vbd [20110720T20:33:21.698Z|debug|xen-blade13|330 unix-RPC|VBD.plug R:2443e315259d|xapi] Attempting to dynamically attach VBD to domid 0 [20110720T20:33:21.698Z|debug|xen-blade13|330 unix-RPC|VBD.plug R:2443e315259d|xapi] VBD device name 0 interpreted as Xen(0, 0) (hvm = false) [20110720T20:33:21.699Z|debug|xen-blade13|330 unix-RPC|VBD.plug R:2443e315259d|sm] SM ext sr_content_type sr=OpaqueRef:36c23e3c-898b-0d33-76f5-8703be1328b8 [20110720T20:33:21.705Z| info|xen-blade13|330 unix-RPC|sm_exec D:93877d100714|xapi] Session.create trackid=b2fc5b2df176db87203ee5309986ee4d pool=false uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49 [20110720T20:33:21.709Z|debug|xen-blade13|332 unix-RPC||dummytaskhelper] task dispatch:session.get_uuid D:adbff082194f created by task D:93877d100714 [20110720T20:33:21.824Z|debug|xen-blade13|333 unix-RPC||dummytaskhelper] task dispatch:host.get_other_config D:ff113d355e26 created by task R:2443e315259d [20110720T20:33:21.842Z| info|xen-blade13|330 unix-RPC|sm_exec D:93877d100714|xapi] Session.destroy trackid=b2fc5b2df176db87203ee5309986ee4d [20110720T20:33:21.845Z| info|xen-blade13|330 unix-RPC|VBD.plug R:2443e315259d|storage_impl] VDI.attach task:OpaqueRef:2443e315-259d-dc01-6a8d-7fa3dd107758 dp:vbd/0/xvda sr:OpaqueRef:36c23e3c-898b-0d33-76f5-8703be1328b8 vdi:OpaqueRef:841eec9c-900c-623f-d3d4-307d77cf63af read_write:true [20110720T20:33:21.846Z|debug|xen-blade13|330 unix-RPC|VBD.plug R:2443e315259d|dummytaskhelper] task VDI.attach D:8fd14d6230f1 created by task R:2443e315259d [20110720T20:33:21.846Z|debug|xen-blade13|330 unix-RPC|VDI.attach D:8fd14d6230f1|sm] SM ext vdi_attach sr=OpaqueRef:36c23e3c-898b-0d33-76f5-8703be1328b8 vdi=OpaqueRef:841eec9c-900c-623f-d3d4-307d77cf63af writable=true [20110720T20:33:21.851Z| info|xen-blade13|330 unix-RPC|sm_exec D:6af81148e49e|xapi] Session.create trackid=82759727193c578c68d892ce7d7b715a pool=false uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49 [20110720T20:33:21.856Z|debug|xen-blade13|334 unix-RPC||dummytaskhelper] task dispatch:session.get_uuid D:daa666b6a4f0 created by task D:6af81148e49e [20110720T20:33:21.972Z|debug|xen-blade13|335 unix-RPC||dummytaskhelper] task dispatch:host.get_other_config D:2f859b5d022c created by task D:8fd14d6230f1 [20110720T20:33:22.020Z| info|xen-blade13|330 unix-RPC|sm_exec D:6af81148e49e|xapi] Session.destroy trackid=82759727193c578c68d892ce7d7b715a [20110720T20:33:22.022Z|debug|xen-blade13|330 unix-RPC|VBD.plug R:2443e315259d|storage_impl] task:OpaqueRef:2443e315-259d-dc01-6a8d-7fa3dd107758 dp:vbd/0/xvda sr:OpaqueRef:36c23e3c-898b-0d33-76f5-8703be1328b8 vdi:OpaqueRef:841eec9c-900c-623f-d3d4-307d77cf63af superstate:attached RW [20110720T20:33:22.024Z| info|xen-blade13|330 unix-RPC|VBD.plug R:2443e315259d|storage_impl] VDI.activate task:OpaqueRef:2443e315-259d-dc01-6a8d-7fa3dd107758 dp:vbd/0/xvda sr:OpaqueRef:36c23e3c-898b-0d33-76f5-8703be1328b8 vdi:OpaqueRef:841eec9c-900c-623f-d3d4-307d77cf63af [20110720T20:33:22.025Z|debug|xen-blade13|330 unix-RPC|VBD.plug R:2443e315259d|dummytaskhelper] task VDI.activate D:894fa15eff96 created by task R:2443e315259d [20110720T20:33:22.026Z|debug|xen-blade13|330 unix-RPC|VDI.activate D:894fa15eff96|sm] SM ext vdi_activate sr=OpaqueRef:36c23e3c-898b-0d33-76f5-8703be1328b8 vdi=OpaqueRef:841eec9c-900c-623f-d3d4-307d77cf63af [20110720T20:33:22.031Z| info|xen-blade13|330 unix-RPC|sm_exec D:67ecaa331b5c|xapi] Session.create trackid=84c68786b28876e8ecb2771488527e62 pool=false uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49 [20110720T20:33:22.036Z|debug|xen-blade13|336 unix-RPC||dummytaskhelper] task dispatch:session.get_uuid D:1d4fbb3fe865 created by task D:67ecaa331b5c [20110720T20:33:22.150Z|debug|xen-blade13|337 unix-RPC||dummytaskhelper] task dispatch:host.get_other_config D:14a507377086 created by task D:894fa15eff96 [20110720T20:33:22.175Z|debug|xen-blade13|338 unix-RPC||dummytaskhelper] task dispatch:VDI.get_by_uuid D:44d5888a662a created by task D:894fa15eff96 [20110720T20:33:22.186Z|debug|xen-blade13|339 unix-RPC||dummytaskhelper] task dispatch:host.get_by_uuid D:5458e178c406 created by task D:894fa15eff96 [20110720T20:33:22.195Z|debug|xen-blade13|340 unix-RPC||dummytaskhelper] task dispatch:VDI.get_sm_config D:79341bb3d474 created by task D:894fa15eff96 [20110720T20:33:22.203Z|debug|xen-blade13|341 unix-RPC||dummytaskhelper] task dispatch:VDI.add_to_sm_config D:62e3ff86da38 created by task D:894fa15eff96 [20110720T20:33:22.206Z| info|xen-blade13|341 unix-RPC|dispatch:VDI.add_to_sm_config D:62e3ff86da38|api_effect] VDI.add_to_sm_config [20110720T20:33:22.213Z|debug|xen-blade13|342 unix-RPC||dummytaskhelper] task dispatch:VDI.get_sm_config D:3c2c523d3bf4 created by task D:894fa15eff96 [20110720T20:33:22.221Z|debug|xen-blade13|343 unix-RPC||dummytaskhelper] task dispatch:VDI.get_by_uuid D:26782a0ac3ca created by task D:894fa15eff96 [20110720T20:33:22.229Z|debug|xen-blade13|344 unix-RPC||dummytaskhelper] task dispatch:VDI.get_SR D:5f6d570588ae created by task D:894fa15eff96 [20110720T20:33:22.238Z|debug|xen-blade13|345 unix-RPC||dummytaskhelper] task dispatch:SR.get_uuid D:cd51b214fec8 created by task D:894fa15eff96 [20110720T20:33:22.246Z|debug|xen-blade13|346 unix-RPC||dummytaskhelper] task dispatch:SR.get_by_uuid D:586771e89377 created by task D:894fa15eff96 [20110720T20:33:22.255Z|debug|xen-blade13|347 unix-RPC||dummytaskhelper] task dispatch:SR.get_type D:d7183bbfb798 created by task D:894fa15eff96 [20110720T20:33:22.263Z|debug|xen-blade13|348 unix-RPC||dummytaskhelper] task dispatch:SM.get_all_records_where D:e0ccc00ca5ac created by task D:894fa15eff96 [20110720T20:33:22.273Z|debug|xen-blade13|349 unix-RPC||dummytaskhelper] task dispatch:SM.get_driver_filename D:cc4839882856 created by task D:894fa15eff96 [20110720T20:33:22.285Z|debug|xen-blade13|350 unix-RPC||dummytaskhelper] task dispatch:VM.get_all_records_where D:17d0b3d7d71b created by task D:894fa15eff96 [20110720T20:33:22.304Z|debug|xen-blade13|351 unix-RPC||dummytaskhelper] task dispatch:PBD.get_all_records_where D:5cfb1bce5d94 created by task D:894fa15eff96 [20110720T20:33:22.312Z|debug|xen-blade13|352 unix-RPC||dummytaskhelper] task dispatch:PBD.get_device_config D:d391de3983cb created by task D:894fa15eff96 [20110720T20:33:22.342Z| info|xen-blade13|354 unix-RPC|dispatch:VDI.remove_from_xenstore_data D:14506afd2ac3|api_effect] VDI.remove_from_xenstore_data [20110720T20:33:22.352Z| info|xen-blade13|355 unix-RPC|dispatch:VDI.remove_from_xenstore_data D:a86dee03d75b|api_effect] VDI.remove_from_xenstore_data [20110720T20:33:22.361Z| info|xen-blade13|356 unix-RPC|dispatch:VDI.add_to_xenstore_data D:8c89a4188b93|api_effect] VDI.add_to_xenstore_data [20110720T20:33:22.370Z| info|xen-blade13|357 unix-RPC|dispatch:VDI.remove_from_xenstore_data D:a3ae17e5eeed|api_effect] VDI.remove_from_xenstore_data [20110720T20:33:22.380Z| info|xen-blade13|358 unix-RPC|dispatch:VDI.remove_from_xenstore_data D:f27c75c20f8f|api_effect] VDI.remove_from_xenstore_data [20110720T20:33:22.389Z| info|xen-blade13|359 unix-RPC|dispatch:VDI.remove_from_xenstore_data D:df4b8d788bd1|api_effect] VDI.remove_from_xenstore_data [20110720T20:33:22.399Z| info|xen-blade13|360 unix-RPC|dispatch:VDI.remove_from_xenstore_data D:eac6e28c75e1|api_effect] VDI.remove_from_xenstore_data [20110720T20:33:22.408Z| info|xen-blade13|361 unix-RPC|dispatch:VDI.add_to_xenstore_data D:7b6aaf1079f0|api_effect] VDI.add_to_xenstore_data [20110720T20:33:22.417Z| info|xen-blade13|362 unix-RPC|dispatch:VDI.add_to_xenstore_data D:3dc6f9efa980|api_effect] VDI.add_to_xenstore_data [20110720T20:33:22.427Z| info|xen-blade13|363 unix-RPC|dispatch:VDI.add_to_xenstore_data D:855685f43d17|api_effect] VDI.add_to_xenstore_data [20110720T20:33:22.552Z| info|xen-blade13|330 unix-RPC|sm_exec D:67ecaa331b5c|xapi] Session.destroy trackid=84c68786b28876e8ecb2771488527e62 [20110720T20:33:22.555Z|debug|xen-blade13|330 unix-RPC|VBD.plug R:2443e315259d|storage_impl] task:OpaqueRef:2443e315-259d-dc01-6a8d-7fa3dd107758 dp:vbd/0/xvda sr:OpaqueRef:36c23e3c-898b-0d33-76f5-8703be1328b8 vdi:OpaqueRef:841eec9c-900c-623f-d3d4-307d77cf63af superstate:activated RW [20110720T20:33:22.556Z|debug|xen-blade13|330 unix-RPC|VBD.plug R:2443e315259d|xenops] Device.Vbd.add (device_number=Xen(0, 0) | physpath=/dev/sm/backend/66597906-20ae-5ec6-e60a-e0dec55df506/ca4ba7bd-2901-45fb-a3f5-18b7d78e0232 | phystype=vhd) [20110720T20:33:22.557Z|debug|xen-blade13|330 unix-RPC|VBD.plug R:2443e315259d|xenops] adding device B0[/local/domain/0/backend/vbd/0/51712] F0[/local/domain/0/device/vbd/51712] H[/xapi/0/hotplug/vbd/51712] [20110720T20:33:22.581Z|debug|xen-blade13|330 unix-RPC|VBD.plug R:2443e315259d|hotplug] Hotplug.wait_for_frontend_plug: frontend (domid=0 | kind=vbd | devid=51712); backend (domid=0 | kind=vbd | devid=51712) [20110720T20:33:22.581Z|debug|xen-blade13|330 unix-RPC|VBD.plug R:2443e315259d|xenops] watch: watching xenstore paths: [ /xapi/0/frontend/vbd/51712/hotplug; /local/domain/0/backend/vbd/0/51712/tapdisk-error; /local/domain/0/error/backend/vbd/0/51712/error ] with timeout 1200.000000 seconds