[Pkg-xen-devel] Bug#682979: sample logfile

Fri Jul 27 15:47:41 UTC 2012

This logfile was recorded while the vm-start was running.
-------------- next part --------------
[20120727T14:43:34.919Z| info|vh10|1021 UNIX /var/lib/xcp/xapi|session.logout D:dd291648d150|xapi] Session.destroy trackid=e3179eeb95ba96c64ab771d0cb1557b6
[20120727T14:44:02.180Z|debug|vh10|25|Starting periodic scheduler D:641021a392d0|backgroundscheduler] Adding function Logrotate to queue, start=300.000000, type=Periodic(300.000000)
[20120727T14:44:02.180Z|debug|vh10|25|Starting periodic scheduler D:641021a392d0|backgroundscheduler] Sleeping until next event (179.833285 seconds)
[20120727T14:44:24.760Z| info|vh10|1023 UNIX /var/lib/xcp/xapi||cli] xe vm-start uuid=5a56791e-9f6a-bae9-f408-3e481c34276a username=root password=null
[20120727T14:44:24.762Z| info|vh10|1023 UNIX /var/lib/xcp/xapi|session.login_with_password D:88ab98dc6692|xapi] Session.create trackid=2be0585523df012af5c776e6b126fa52 pool=false uname=root is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20120727T14:44:24.763Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|session.login_with_password D:88ab98dc6692|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:44:24.763Z|debug|vh10|1024 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:06d59281517c created by task D:88ab98dc6692
[20120727T14:44:24.835Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|audit] VM.start: VM = '5a56791e-9f6a-bae9-f408-3e481c34276a (ds)'
[20120727T14:44:24.841Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] No operations are valid because current-operations = [ OpaqueRef:34d80756-38bd-7084-24d3-e503cf5c3f83 -> attach ]
[20120727T14:44:24.843Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] Using wlb recommendations for choosing a host has been disabled or wlb is not available. Using original algorithm
[20120727T14:44:24.843Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi_ha_vm_failover] assert_configuration_change_preserves_ha_plan c = configuration_change = { old_vms_leaving = [  ]; new_vms_arriving = [ 9a637ad4 (vh10) 4f326183 (ds) ]; hosts_to_disable = [  ]; num_failures = no change; new_vms = [  ] }
[20120727T14:44:24.848Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|thread_queue] push(vm_lifecycle_op, VM.start VM.start R:34d8075638bd): queue = [ VM.start VM.start R:34d8075638bd ](1)
[20120727T14:44:24.848Z|debug|vh10|1025||thread_queue] pop(vm_lifecycle_op) = VM.start VM.start R:34d8075638bd
[20120727T14:44:24.848Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|locking_helpers] Acquired lock on VM OpaqueRef:4f326183-3a01-71cc-6cb0-96e3007694f9 with token 3
[20120727T14:44:24.848Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] start: making sure the VM really is halted
[20120727T14:44:24.848Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] start: checking that VM can run on this host
[20120727T14:44:24.849Z| info|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] The VM's BIOS strings were not yet filled in. The VM is now made BIOS-generic.
[20120727T14:44:24.850Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] start: bringing up domain in the paused state
[20120727T14:44:24.851Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|memory_control] logging into ballooning service
[20120727T14:44:24.851Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xenstore-rpc] Checking pid 2181
[20120727T14:44:24.852Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xenstore-rpc] Written request using id: f6c9fcf7-60a8-cdb8-8984-03c503ab3634
[20120727T14:44:24.852Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xenops] watch: watching xenstore paths: [ /squeezed/rpc/response/login/f6c9fcf7-60a8-cdb8-8984-03c503ab3634 ] with timeout 300.000000 seconds
[20120727T14:44:24.855Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|memory_control] reserve_memory_range min=266240 max=266240
[20120727T14:44:24.855Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xenstore-rpc] Checking pid 2181
[20120727T14:44:24.857Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xenstore-rpc] Written request using id: f458237b-ee82-86d9-e95d-4c2ab1df6154
[20120727T14:44:24.857Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xenops] watch: watching xenstore paths: [ /squeezed/rpc/response/reserve-memory-range/f458237b-ee82-86d9-e95d-4c2ab1df6154 ] with timeout 300.000000 seconds
[20120727T14:44:29.871Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|memory_control] reserve_memory_range actual = 266240
[20120727T14:44:29.871Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|memory_control] reserved_memory = 266240; min = 266240; max = 266240
[20120727T14:44:29.871Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] VM will have 1 hotplugged vcpus out of 1
[20120727T14:44:29.871Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] xen reports max 256 pCPUs
[20120727T14:44:29.871Z| info|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] Memory free = 47516600; scrub = 0
[20120727T14:44:29.871Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xenops] Regenerating the xenstored tree under: [/local/domain/1]
[20120727T14:44:29.881Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xenops] Created domain with id: 1
[20120727T14:44:29.881Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] Created domain with domid: 1
[20120727T14:44:29.881Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|memory_control] transfer_reservation_to_domain 7a62eeee-8528-a458-be97-f76cbf640d3d -> 1
[20120727T14:44:29.881Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xenstore-rpc] Checking pid 2181
[20120727T14:44:29.883Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xenstore-rpc] Written request using id: 3fdbb2c5-ad40-8bf7-55ec-1d8a6ca1cc62
[20120727T14:44:29.883Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xenops] watch: watching xenstore paths: [ /squeezed/rpc/response/transfer-reservation-to-domain/3fdbb2c5-ad40-8bf7-55ec-1d8a6ca1cc62 ] with timeout 300.000000 seconds
[20120727T14:44:29.887Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|memory_control] delete_reservation 7a62eeee-8528-a458-be97-f76cbf640d3d
[20120727T14:44:29.888Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xenstore-rpc] Checking pid 2181
[20120727T14:44:29.890Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xenstore-rpc] Written request using id: 274ff77f-9f54-70b5-fbd1-96878db83ac4
[20120727T14:44:29.890Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xenops] watch: watching xenstore paths: [ /squeezed/rpc/response/delete-reservation/274ff77f-9f54-70b5-fbd1-96878db83ac4 ] with timeout 300.000000 seconds
[20120727T14:44:29.898Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] Verifying VDI records exist
[20120727T14:44:29.898Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] creating kernel
[20120727T14:44:29.898Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:44:29.899Z|debug|vh10|1026 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.slave_login D:216b9ecf1ad1 created by task R:34d8075638bd
[20120727T14:44:29.900Z| info|vh10|1026 UNIX /var/lib/xcp/xapi|session.slave_login D:ffee7dd0681e|xapi] Session.create trackid=9fdb5b775cb1202f65209184bb3b7ff9 pool=true uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20120727T14:44:29.901Z|debug|vh10|1026 UNIX /var/lib/xcp/xapi|session.slave_login D:ffee7dd0681e|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:44:29.902Z|debug|vh10|1027 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:fbc729e07d42 created by task D:ffee7dd0681e
[20120727T14:44:29.904Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:44:29.905Z|debug|vh10|1028 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VBD.create D:76b723217feb created by task R:34d8075638bd
[20120727T14:44:29.907Z| info|vh10|1028 UNIX /var/lib/xcp/xapi|dispatch:VBD.create D:76b723217feb|taskhelper] task VBD.create R:d3cdbcc1dafe (uuid:7e542dbc-3dd3-ffc3-206f-d20cceacea65) created (trackid=9fdb5b775cb1202f65209184bb3b7ff9) by task R:34d8075638bd
[20120727T14:44:29.907Z|debug|vh10|1028 UNIX /var/lib/xcp/xapi|VBD.create R:d3cdbcc1dafe|audit] VBD.create: VM = 'e5fb11a8-2fd3-0fc5-8d37-10cff982f17f (Control domain on host: vh10)'; VDI = 'b44bdaee-ba91-4612-ae4a-0f0b1c92ea6b'
[20120727T14:44:29.908Z|debug|vh10|1028 UNIX /var/lib/xcp/xapi|VBD.create R:d3cdbcc1dafe|xapi] VBD.create (device = 0; uuid = 0426a0af-a0e9-b579-ef45-4bf5f3bad20e; ref = OpaqueRef:39c24536-a933-d735-1319-457e41f3f9e9)
[20120727T14:44:29.915Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:44:29.916Z|debug|vh10|1029 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VBD.get_uuid D:84e67058f555 created by task R:34d8075638bd
[20120727T14:44:29.918Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:44:29.918Z|debug|vh10|1030 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VM.get_uuid D:9f6596970618 created by task R:34d8075638bd
[20120727T14:44:29.920Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] created VBD (uuid 0426a0af-a0e9-b579-ef45-4bf5f3bad20e); attempting to hotplug to VM (uuid: e5fb11a8-2fd3-0fc5-8d37-10cff982f17f)
[20120727T14:44:29.920Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:44:29.921Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VBD.plug D:b3fdd0cde1fd created by task R:34d8075638bd
[20120727T14:44:29.923Z| info|vh10|1031 UNIX /var/lib/xcp/xapi|dispatch:VBD.plug D:b3fdd0cde1fd|taskhelper] task VBD.plug R:9610dfb27742 (uuid:14a79b98-3346-26ce-1d1a-2c167aed9835) created (trackid=9fdb5b775cb1202f65209184bb3b7ff9) by task R:34d8075638bd
[20120727T14:44:29.923Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi|VBD.plug R:9610dfb27742|audit] VBD.plug: VBD = '0426a0af-a0e9-b579-ef45-4bf5f3bad20e'
[20120727T14:44:29.926Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi|VBD.plug R:9610dfb27742|locking_helpers] Acquired lock on VM OpaqueRef:abbd2bea-bb77-16bc-7c69-63d8b898e40e with token 4
[20120727T14:44:29.926Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi|VBD.plug R:9610dfb27742|xapi] vbd_plug: attempting to attach vbd
[20120727T14:44:29.926Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi|VBD.plug R:9610dfb27742|xapi] Attempting to dynamically attach VBD to domid 0
[20120727T14:44:29.926Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi|VBD.plug R:9610dfb27742|xapi] VBD device name 0 interpreted as Xen(0, 0) (hvm = false)
[20120727T14:44:29.926Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi|VBD.plug R:9610dfb27742|xapi] VBD.plug of loopback VBD 'OpaqueRef:39c24536-a933-d735-1319-457e41f3f9e9'
[20120727T14:44:29.926Z| info|vh10|1031 UNIX /var/lib/xcp/xapi|VBD.plug R:9610dfb27742|storage_impl] VDI.attach task:OpaqueRef:9610dfb2-7742-b5ee-12e9-c00e90757713 dp:vbd/0/0 sr:ebbcf285-f891-e0e1-744c-ccfa0f6c6b7c vdi:b44bdaee-ba91-4612-ae4a-0f0b1c92ea6b read_write:false
[20120727T14:44:29.927Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi|VBD.plug R:9610dfb27742|dummytaskhelper] task VDI.attach D:5fb6a492a964 created by task R:9610dfb27742
[20120727T14:44:29.927Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi|VDI.attach D:5fb6a492a964|sm] SM ext vdi_attach sr=OpaqueRef:78ba2425-84b4-939d-f9da-60c09f11fc9f vdi=OpaqueRef:299e62ca-7ee5-7e93-c17d-6d21d00b707f writable=false
[20120727T14:44:29.929Z| info|vh10|1031 UNIX /var/lib/xcp/xapi|sm_exec D:b5fd8018f25b|xapi] Session.create trackid=0514105cc2982f1b48f84a4f9364566b pool=false uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20120727T14:44:29.929Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi|sm_exec D:b5fd8018f25b|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:44:29.930Z|debug|vh10|1032 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:3ef1e59ad301 created by task D:b5fd8018f25b
[20120727T14:44:30.007Z|debug|vh10|1033 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:host.get_other_config D:18c5067d89ab created by task D:5fb6a492a964
[20120727T14:44:30.012Z|debug|vh10|1033 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.034Z| info|vh10|1031 UNIX /var/lib/xcp/xapi|sm_exec D:b5fd8018f25b|xapi] Session.destroy trackid=0514105cc2982f1b48f84a4f9364566b
[20120727T14:44:30.035Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi|VBD.plug R:9610dfb27742|storage_impl] task:OpaqueRef:9610dfb2-7742-b5ee-12e9-c00e90757713 dp:vbd/0/0 sr:ebbcf285-f891-e0e1-744c-ccfa0f6c6b7c vdi:b44bdaee-ba91-4612-ae4a-0f0b1c92ea6b superstate:attached  RO
[20120727T14:44:30.036Z| info|vh10|1031 UNIX /var/lib/xcp/xapi|VBD.plug R:9610dfb27742|storage_impl] VDI.activate task:OpaqueRef:9610dfb2-7742-b5ee-12e9-c00e90757713 dp:vbd/0/0 sr:ebbcf285-f891-e0e1-744c-ccfa0f6c6b7c vdi:b44bdaee-ba91-4612-ae4a-0f0b1c92ea6b
[20120727T14:44:30.036Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi|VBD.plug R:9610dfb27742|dummytaskhelper] task VDI.activate D:f2fb840478fd created by task R:9610dfb27742
[20120727T14:44:30.037Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi|VDI.activate D:f2fb840478fd|sm] SM ext vdi_activate sr=OpaqueRef:78ba2425-84b4-939d-f9da-60c09f11fc9f vdi=OpaqueRef:299e62ca-7ee5-7e93-c17d-6d21d00b707f
[20120727T14:44:30.038Z| info|vh10|1031 UNIX /var/lib/xcp/xapi|sm_exec D:d01873688873|xapi] Session.create trackid=80d5f99705244d054ec67ab1d5cbca3d pool=false uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20120727T14:44:30.039Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi|sm_exec D:d01873688873|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:44:30.040Z|debug|vh10|1034 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:af4400655f43 created by task D:d01873688873
[20120727T14:44:30.115Z|debug|vh10|1035 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:host.get_other_config D:874a2b9d8ced created by task D:f2fb840478fd
[20120727T14:44:30.120Z|debug|vh10|1035 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.127Z|debug|vh10|1036 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VDI.get_by_uuid D:92f309c79941 created by task D:f2fb840478fd
[20120727T14:44:30.130Z|debug|vh10|1036 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.135Z|debug|vh10|1037 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:host.get_by_uuid D:e5cc2dff12c5 created by task D:f2fb840478fd
[20120727T14:44:30.137Z|debug|vh10|1037 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.137Z|debug|vh10|1038 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VDI.get_sm_config D:3d4edc5cab32 created by task D:f2fb840478fd
[20120727T14:44:30.139Z|debug|vh10|1038 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.140Z|debug|vh10|1039 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VDI.add_to_sm_config D:7ab122783707 created by task D:f2fb840478fd
[20120727T14:44:30.141Z| info|vh10|1039 UNIX /var/lib/xcp/xapi|dispatch:VDI.add_to_sm_config D:7ab122783707|api_effect] VDI.add_to_sm_config
[20120727T14:44:30.143Z|debug|vh10|1039 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.144Z|debug|vh10|1040 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VDI.get_sm_config D:337fe4a9ce50 created by task D:f2fb840478fd
[20120727T14:44:30.146Z|debug|vh10|1040 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.147Z|debug|vh10|1041 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VDI.get_by_uuid D:51d5a096f20b created by task D:f2fb840478fd
[20120727T14:44:30.149Z|debug|vh10|1041 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.149Z|debug|vh10|1042 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VDI.get_SR D:8c50dc25157d created by task D:f2fb840478fd
[20120727T14:44:30.151Z|debug|vh10|1042 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.152Z|debug|vh10|1043 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_uuid D:1d8aa2fc12a7 created by task D:f2fb840478fd
[20120727T14:44:30.154Z|debug|vh10|1043 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.155Z|debug|vh10|1044 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_by_uuid D:a0b8261f9894 created by task D:f2fb840478fd
[20120727T14:44:30.156Z|debug|vh10|1044 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.157Z|debug|vh10|1045 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_type D:e89c56640c89 created by task D:f2fb840478fd
[20120727T14:44:30.159Z|debug|vh10|1045 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.160Z|debug|vh10|1046 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SM.get_all_records_where D:3cdf7ac6c4f2 created by task D:f2fb840478fd
[20120727T14:44:30.162Z|debug|vh10|1046 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.163Z|debug|vh10|1047 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SM.get_driver_filename D:480fa3931fc8 created by task D:f2fb840478fd
[20120727T14:44:30.165Z|debug|vh10|1047 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.167Z|debug|vh10|1048 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VM.get_all_records_where D:b57ea9df3a66 created by task D:f2fb840478fd
[20120727T14:44:30.171Z|debug|vh10|1048 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.172Z|debug|vh10|1049 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:PBD.get_all_records_where D:ebda8571af84 created by task D:f2fb840478fd
[20120727T14:44:30.174Z|debug|vh10|1049 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.175Z|debug|vh10|1050 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:PBD.get_device_config D:6c069b215594 created by task D:f2fb840478fd
[20120727T14:44:30.177Z|debug|vh10|1050 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.180Z|debug|vh10|1051 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.189Z| info|vh10|1052 UNIX /var/lib/xcp/xapi|dispatch:VDI.remove_from_xenstore_data D:4b89de178e84|api_effect] VDI.remove_from_xenstore_data
[20120727T14:44:30.191Z|debug|vh10|1052 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.193Z| info|vh10|1053 UNIX /var/lib/xcp/xapi|dispatch:VDI.remove_from_xenstore_data D:c987011cd42c|api_effect] VDI.remove_from_xenstore_data
[20120727T14:44:30.195Z|debug|vh10|1053 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.196Z| info|vh10|1054 UNIX /var/lib/xcp/xapi|dispatch:VDI.add_to_xenstore_data D:c0d7696054d4|api_effect] VDI.add_to_xenstore_data
[20120727T14:44:30.198Z|debug|vh10|1054 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.199Z| info|vh10|1055 UNIX /var/lib/xcp/xapi|dispatch:VDI.remove_from_xenstore_data D:39ac9244a52e|api_effect] VDI.remove_from_xenstore_data
[20120727T14:44:30.201Z|debug|vh10|1055 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.202Z| info|vh10|1056 UNIX /var/lib/xcp/xapi|dispatch:VDI.remove_from_xenstore_data D:c130999f1832|api_effect] VDI.remove_from_xenstore_data
[20120727T14:44:30.204Z|debug|vh10|1056 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.206Z| info|vh10|1057 UNIX /var/lib/xcp/xapi|dispatch:VDI.remove_from_xenstore_data D:a71c245e23d8|api_effect] VDI.remove_from_xenstore_data
[20120727T14:44:30.207Z|debug|vh10|1057 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.209Z| info|vh10|1058 UNIX /var/lib/xcp/xapi|dispatch:VDI.remove_from_xenstore_data D:e48a555b38f2|api_effect] VDI.remove_from_xenstore_data
[20120727T14:44:30.211Z|debug|vh10|1058 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.212Z| info|vh10|1059 UNIX /var/lib/xcp/xapi|dispatch:VDI.add_to_xenstore_data D:e76fe75f56e7|api_effect] VDI.add_to_xenstore_data
[20120727T14:44:30.214Z|debug|vh10|1059 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.215Z| info|vh10|1060 UNIX /var/lib/xcp/xapi|dispatch:VDI.add_to_xenstore_data D:1174464173cd|api_effect] VDI.add_to_xenstore_data
[20120727T14:44:30.217Z|debug|vh10|1060 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.218Z| info|vh10|1061 UNIX /var/lib/xcp/xapi|dispatch:VDI.add_to_xenstore_data D:871177545da9|api_effect] VDI.add_to_xenstore_data
[20120727T14:44:30.223Z|debug|vh10|1061 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.307Z| info|vh10|1031 UNIX /var/lib/xcp/xapi|sm_exec D:d01873688873|xapi] Session.destroy trackid=80d5f99705244d054ec67ab1d5cbca3d
[20120727T14:44:30.308Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi|VBD.plug R:9610dfb27742|storage_impl] task:OpaqueRef:9610dfb2-7742-b5ee-12e9-c00e90757713 dp:vbd/0/0 sr:ebbcf285-f891-e0e1-744c-ccfa0f6c6b7c vdi:b44bdaee-ba91-4612-ae4a-0f0b1c92ea6b superstate:activated RO
[20120727T14:44:30.310Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi|VBD.plug R:9610dfb27742|xapi] vbd_plug: successfully hotplugged device
[20120727T14:44:30.310Z|debug|vh10|1031 UNIX /var/lib/xcp/xapi|VBD.plug R:9610dfb27742|locking_helpers] Released lock on VM OpaqueRef:abbd2bea-bb77-16bc-7c69-63d8b898e40e with token 4
[20120727T14:44:30.317Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|bootloader] Bootloader commandline: /usr/bin/eliloader -q --default_args= --extra_args=-- quiet console=hvc0 --vm=5a56791e-9f6a-bae9-f408-3e481c34276a /dev/sm/backend/ebbcf285-f891-e0e1-744c-ccfa0f6c6b7c/b44bdaee-ba91-4612-ae4a-0f0b1c92ea6b

[20120727T14:44:30.396Z| info|vh10|1062 UNIX /var/lib/xcp/xapi|session.login_with_password D:48a5d59290c2|xapi] Session.create trackid=666e14df943c70715afb8e7e6a8b78fd pool=false uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20120727T14:44:30.397Z|debug|vh10|1062 UNIX /var/lib/xcp/xapi|session.login_with_password D:48a5d59290c2|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:44:30.398Z|debug|vh10|1063 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:dbb780b704ec created by task D:48a5d59290c2
[20120727T14:44:30.400Z|debug|vh10|1062 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.403Z|debug|vh10|1064 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.405Z|debug|vh10|1065 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.407Z|debug|vh10|1066 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.410Z|debug|vh10|1067 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.413Z|debug|vh10|1068 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.416Z|debug|vh10|1069 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:30.417Z|debug|vh10|1070 UNIX /var/lib/xcp/xapi|dispatch:logout D:d3648fc1962d|dispatcher] Unknown rpc "logout"
[20120727T14:44:30.419Z|debug|vh10|1070 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.243Z| info|vh10|1071 UNIX /var/lib/xcp/xapi|session.login_with_password D:48827730e1d9|xapi] Session.create trackid=bfb0f75604f2be6b80864dcd3c43244d pool=false uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20120727T14:44:41.243Z|debug|vh10|1071 UNIX /var/lib/xcp/xapi|session.login_with_password D:48827730e1d9|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:44:41.244Z|debug|vh10|1072 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:7308c3234e08 created by task D:48827730e1d9
[20120727T14:44:41.246Z|debug|vh10|1071 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.249Z|debug|vh10|1073 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.251Z|debug|vh10|1074 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.254Z|debug|vh10|1075 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.256Z|debug|vh10|1076 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.259Z|debug|vh10|1077 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.261Z| info|vh10|1078 UNIX /var/lib/xcp/xapi|dispatch:VM.set_PV_bootloader D:116558e9f618|api_effect] VM.set_PV_bootloader
[20120727T14:44:41.264Z|debug|vh10|1078 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.265Z|debug|vh10|1079 UNIX /var/lib/xcp/xapi|dispatch:logout D:2b50dee2c794|dispatcher] Unknown rpc "logout"
[20120727T14:44:41.265Z|debug|vh10|1079 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.267Z| info|vh10|1080 UNIX /var/lib/xcp/xapi|session.login_with_password D:de605f9f7d3b|xapi] Session.create trackid=aa46926deeaef1ac276d83f0801c1cdb pool=false uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20120727T14:44:41.268Z|debug|vh10|1080 UNIX /var/lib/xcp/xapi|session.login_with_password D:de605f9f7d3b|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:44:41.269Z|debug|vh10|1081 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:70ee7ad2956c created by task D:de605f9f7d3b
[20120727T14:44:41.272Z|debug|vh10|1080 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.274Z|debug|vh10|1082 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.276Z|debug|vh10|1083 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.279Z|debug|vh10|1084 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.281Z|debug|vh10|1085 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.284Z|debug|vh10|1086 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.285Z| info|vh10|1087 UNIX /var/lib/xcp/xapi|dispatch:VM.remove_from_other_config D:7d46964d10f3|api_effect] VM.remove_from_other_config
[20120727T14:44:41.287Z|debug|vh10|1087 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.289Z| info|vh10|1088 UNIX /var/lib/xcp/xapi|dispatch:VM.remove_from_other_config D:d1511c736daf|api_effect] VM.remove_from_other_config
[20120727T14:44:41.291Z|debug|vh10|1088 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.292Z|debug|vh10|1089 UNIX /var/lib/xcp/xapi|dispatch:logout D:49fdefcfe7e9|dispatcher] Unknown rpc "logout"
[20120727T14:44:41.296Z|debug|vh10|1089 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.299Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:44:41.300Z|debug|vh10|1090 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.slave_login D:dc0a539b605c created by task R:34d8075638bd
[20120727T14:44:41.301Z| info|vh10|1090 UNIX /var/lib/xcp/xapi|session.slave_login D:e945799a4ee9|xapi] Session.create trackid=e48a295c6aa3d4de8c43b7e69cbc3720 pool=true uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20120727T14:44:41.301Z|debug|vh10|1090 UNIX /var/lib/xcp/xapi|session.slave_login D:e945799a4ee9|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:44:41.302Z|debug|vh10|1091 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:b3de17482756 created by task D:e945799a4ee9
[20120727T14:44:41.305Z|debug|vh10|1023 UNIX /var/lib/xcp/xapi|VM.start R:34d8075638bd|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:44:41.305Z|debug|vh10|1092 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VBD.unplug D:4660b2dc58e2 created by task R:34d8075638bd
[20120727T14:44:41.308Z| info|vh10|1092 UNIX /var/lib/xcp/xapi|dispatch:VBD.unplug D:4660b2dc58e2|taskhelper] task VBD.unplug R:7f6f65ee3649 (uuid:c54dcfb9-1b69-649b-d375-72c97b5970e0) created (trackid=e48a295c6aa3d4de8c43b7e69cbc3720) by task R:34d8075638bd
[20120727T14:44:41.308Z|debug|vh10|1092 UNIX /var/lib/xcp/xapi|VBD.unplug R:7f6f65ee3649|audit] VBD.unplug: VBD = '0426a0af-a0e9-b579-ef45-4bf5f3bad20e'
[20120727T14:44:41.310Z|debug|vh10|1092 UNIX /var/lib/xcp/xapi|VBD.unplug R:7f6f65ee3649|locking_helpers] Acquired lock on VM OpaqueRef:abbd2bea-bb77-16bc-7c69-63d8b898e40e with token 5
[20120727T14:44:41.310Z|debug|vh10|1092 UNIX /var/lib/xcp/xapi|VBD.unplug R:7f6f65ee3649|xapi] VBD.unplug of loopback VBD 'OpaqueRef:39c24536-a933-d735-1319-457e41f3f9e9'
[20120727T14:44:41.310Z| info|vh10|1092 UNIX /var/lib/xcp/xapi|VBD.unplug R:7f6f65ee3649|storage_impl] DP.destroy task:OpaqueRef:7f6f65ee-3649-d66d-465e-212eed582650 dp:vbd/0/0 allow_leak:false
[20120727T14:44:41.311Z|debug|vh10|1092 UNIX /var/lib/xcp/xapi|VBD.unplug R:7f6f65ee3649|dummytaskhelper] task VDI.deactivate D:5e35ace81ebb created by task R:7f6f65ee3649
[20120727T14:44:41.311Z|debug|vh10|1092 UNIX /var/lib/xcp/xapi|VDI.deactivate D:5e35ace81ebb|sm] SM ext vdi_deactivate sr=OpaqueRef:78ba2425-84b4-939d-f9da-60c09f11fc9f vdi=OpaqueRef:299e62ca-7ee5-7e93-c17d-6d21d00b707f
[20120727T14:44:41.313Z| info|vh10|1092 UNIX /var/lib/xcp/xapi|sm_exec D:4fb6aeda68e9|xapi] Session.create trackid=d59c3e5ebf16d1e3251579fbb470a929 pool=false uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20120727T14:44:41.313Z|debug|vh10|1092 UNIX /var/lib/xcp/xapi|sm_exec D:4fb6aeda68e9|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:44:41.314Z|debug|vh10|1093 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:4815c98eea55 created by task D:4fb6aeda68e9
[20120727T14:44:41.390Z|debug|vh10|1094 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:host.get_other_config D:750707953a0c created by task D:5e35ace81ebb
[20120727T14:44:41.396Z|debug|vh10|1094 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.402Z|debug|vh10|1095 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VDI.get_by_uuid D:e89e33d77157 created by task D:5e35ace81ebb
[20120727T14:44:41.405Z|debug|vh10|1095 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:44:41.405Z|debug|vh10|1096 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VDI.get_sm_config D:d48197f65e23 created by task D:5e35ace81ebb
[20120727T14:44:41.408Z|debug|vh10|1096 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20120727T14:45:15.776Z|debug|vh10|1097 UNIX /var/lib/xcp/xapi||cli] xe task-list username=root password=null
[20120727T14:45:15.778Z| info|vh10|1097 UNIX /var/lib/xcp/xapi|session.login_with_password D:aa8c0032d755|xapi] Session.create trackid=d7939d6dcdfbb7dda06fa5a4bda4bf4c pool=false uname=root is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20120727T14:45:15.779Z|debug|vh10|1097 UNIX /var/lib/xcp/xapi|session.login_with_password D:aa8c0032d755|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:45:15.779Z|debug|vh10|1098 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:941f24e0de9e created by task D:aa8c0032d755
[20120727T14:45:15.785Z| info|vh10|1097 UNIX /var/lib/xcp/xapi|session.logout D:533104ed024b|xapi] Session.destroy trackid=d7939d6dcdfbb7dda06fa5a4bda4bf4c
[20120727T14:45:32.049Z| info|vh10|1015 INET 127.0.0.1:80|session.logout D:55b8798e85a6|xapi] Session.destroy trackid=e98ecf0f43c316812376188fc705dd43
[20120727T14:45:32.049Z|debug|vh10|1013 INET 127.0.0.1:80|event.next D:cbfd83e18267|backtrace] Raised at 
[20120727T14:45:32.050Z|debug|vh10|1013 INET 127.0.0.1:80|event.next D:cbfd83e18267|dispatcher] Server_helpers.exec exception_handler: Got exception SESSION_INVALID: [ OpaqueRef:6faae39e-d683-b52e-fe33-136515c58c7d ]
[20120727T14:45:32.050Z|debug|vh10|1013 INET 127.0.0.1:80|event.next D:cbfd83e18267|dispatcher] Raised at 
[20120727T14:45:32.050Z|debug|vh10|1013 INET 127.0.0.1:80|event.next D:cbfd83e18267|backtrace] Raised at 
[20120727T14:45:32.050Z|debug|vh10|1013 INET 127.0.0.1:80|event.next D:cbfd83e18267|xapi] Raised at 
[20120727T14:45:32.050Z|debug|vh10|1013 INET 127.0.0.1:80|event.next D:cbfd83e18267|xapi] Raised at 
[20120727T14:45:32.050Z|debug|vh10|1013 INET 127.0.0.1:80|dispatch:event.next D:9f39cb7aa860|xapi] Raised at 
[20120727T14:45:32.050Z|debug|vh10|1013 INET 127.0.0.1:80|dispatch:event.next D:9f39cb7aa860|backtrace] Raised at 
[20120727T14:47:02.114Z|debug|vh10|25|session_revalidation_func D:fb11bea9b1d6|xapi] revalidating all external sessions in the local host
[20120727T14:47:02.114Z|debug|vh10|25|Starting periodic scheduler D:641021a392d0|backgroundscheduler] Adding function Revalidating externally-authenticated sessions to queue, start=300.000000, type=Periodic(300.000000)
[20120727T14:47:02.114Z|debug|vh10|25|Starting periodic scheduler D:641021a392d0|backgroundscheduler] Sleeping until next event (120.067008 seconds)
[20120727T14:47:03.524Z| info|vh10|5 dbflush [/var/lib/xcp/state.db]||redo_log] Flushing database to all active redo-logs
[20120727T14:47:12.098Z|debug|vh10|1013 INET 127.0.0.1:80||http_critical] Premature termination of connection!
[20120727T14:47:12.105Z|debug|vh10|1015 INET 127.0.0.1:80||http_critical] Premature termination of connection!
[20120727T14:48:08.238Z|debug|vh10|1100 UNIX /var/lib/xcp/xapi||cli] xe task-list username=root password=null
[20120727T14:48:08.240Z| info|vh10|1100 UNIX /var/lib/xcp/xapi|session.login_with_password D:9041089ac272|xapi] Session.create trackid=58d54b6d00760c847211cf5464181cf4 pool=false uname=root is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20120727T14:48:08.241Z|debug|vh10|1100 UNIX /var/lib/xcp/xapi|session.login_with_password D:9041089ac272|xapi] Attempting to open /var/lib/xcp/xapi
[20120727T14:48:08.241Z|debug|vh10|1101 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:ff18a2220100 created by task D:9041089ac272
[20120727T14:48:08.247Z| info|vh10|1100 UNIX /var/lib/xcp/xapi|session.logout D:13cd2d53aa77|xapi] Session.destroy trackid=58d54b6d00760c847211cf5464181cf4