[22:08 xcp65 ~]# grep "Oct 14 20:08" /var/log/xensource.log Oct 14 20:08:00 xcp65 xenopsd-xc: [debug||5 ||xenops_server] Received an event on managed VM ae01a342-3c3e-108f-dcd7-6cda287aadef Oct 14 20:08:00 xcp65 xcp-rrdd: [ info||9 ||rrdd_main] memfree has changed to 401332 in domain 6 Oct 14 20:08:00 xcp65 xenopsd-xc: [debug||5 |queue|xenops_server] Queue.push ["VM_check_state","ae01a342-3c3e-108f-dcd7-6cda287aadef"] onto ae01a342-3c3e-108f-dcd7-6cda287aadef:[ ] Oct 14 20:08:00 xcp65 xenopsd-xc: [debug||29 ||xenops_server] Queue.pop returned ["VM_check_state","ae01a342-3c3e-108f-dcd7-6cda287aadef"] Oct 14 20:08:00 xcp65 xenopsd-xc: [debug||29 |events|xenops_server] Task 5632 reference events: ["VM_check_state","ae01a342-3c3e-108f-dcd7-6cda287aadef"] Oct 14 20:08:00 xcp65 xenopsd-xc: [debug||29 |events|xenops_server] VM ae01a342-3c3e-108f-dcd7-6cda287aadef is not requesting any attention Oct 14 20:08:00 xcp65 xenopsd-xc: [debug||29 |events|xenops_server] VM_DB.signal ae01a342-3c3e-108f-dcd7-6cda287aadef Oct 14 20:08:00 xcp65 xenopsd-xc: [debug||29 |events|task_server] Task 5632 completed; duration = 0 Oct 14 20:08:00 xcp65 xenopsd-xc: [debug||29 ||xenops_server] TASK.signal 5632 (object deleted) Oct 14 20:08:00 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] Processing event: ["Vm","ae01a342-3c3e-108f-dcd7-6cda287aadef"] Oct 14 20:08:00 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenops event on VM ae01a342-3c3e-108f-dcd7-6cda287aadef Oct 14 20:08:00 xcp65 xenopsd-xc: [debug||9709 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops_server] VM.stat ae01a342-3c3e-108f-dcd7-6cda287aadef Oct 14 20:08:00 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenopsd event: processing event for VM ae01a342-3c3e-108f-dcd7-6cda287aadef Oct 14 20:08:00 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] Supressing VM.allowed_operations update because guest_agent data is largely the same Oct 14 20:08:00 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenopsd event: Updating VM ae01a342-3c3e-108f-dcd7-6cda287aadef domid 6 guest_agent Oct 14 20:08:05 xcp65 xcp-rrdd: [ info||9 ||rrdd_main] memfree has changed to 3244272 in domain 3 Oct 14 20:08:05 xcp65 xenopsd-xc: [debug||5 ||xenops_server] Received an event on managed VM baaf736f-35ce-d254-06ae-ad4fbed6c085 Oct 14 20:08:05 xcp65 xenopsd-xc: [debug||5 |queue|xenops_server] Queue.push ["VM_check_state","baaf736f-35ce-d254-06ae-ad4fbed6c085"] onto baaf736f-35ce-d254-06ae-ad4fbed6c085:[ ] Oct 14 20:08:05 xcp65 xenopsd-xc: [debug||33 ||xenops_server] Queue.pop returned ["VM_check_state","baaf736f-35ce-d254-06ae-ad4fbed6c085"] Oct 14 20:08:05 xcp65 xenopsd-xc: [debug||33 |events|xenops_server] Task 5633 reference events: ["VM_check_state","baaf736f-35ce-d254-06ae-ad4fbed6c085"] Oct 14 20:08:05 xcp65 xenopsd-xc: [debug||33 |events|xenops_server] VM baaf736f-35ce-d254-06ae-ad4fbed6c085 is not requesting any attention Oct 14 20:08:05 xcp65 xenopsd-xc: [debug||33 |events|xenops_server] VM_DB.signal baaf736f-35ce-d254-06ae-ad4fbed6c085 Oct 14 20:08:05 xcp65 xenopsd-xc: [debug||33 |events|task_server] Task 5633 completed; duration = 0 Oct 14 20:08:05 xcp65 xenopsd-xc: [debug||33 ||xenops_server] TASK.signal 5633 (object deleted) Oct 14 20:08:05 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] Processing event: ["Vm","baaf736f-35ce-d254-06ae-ad4fbed6c085"] Oct 14 20:08:05 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenops event on VM baaf736f-35ce-d254-06ae-ad4fbed6c085 Oct 14 20:08:05 xcp65 xenopsd-xc: [debug||9711 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops_server] VM.stat baaf736f-35ce-d254-06ae-ad4fbed6c085 Oct 14 20:08:05 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenopsd event: processing event for VM baaf736f-35ce-d254-06ae-ad4fbed6c085 Oct 14 20:08:05 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] Supressing VM.allowed_operations update because guest_agent data is largely the same Oct 14 20:08:05 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenopsd event: Updating VM baaf736f-35ce-d254-06ae-ad4fbed6c085 domid 3 guest_agent Oct 14 20:08:14 xcp65 xenopsd-xc: [debug||5 ||xenops_server] Received an event on managed VM 2975cda5-7192-0f55-39a6-23673b40c8f9 Oct 14 20:08:14 xcp65 xcp-rrdd: [ info||9 ||rrdd_main] memfree has changed to 13349760 in domain 4 Oct 14 20:08:14 xcp65 xenopsd-xc: [debug||5 |queue|xenops_server] Queue.push ["VM_check_state","2975cda5-7192-0f55-39a6-23673b40c8f9"] onto 2975cda5-7192-0f55-39a6-23673b40c8f9:[ ] Oct 14 20:08:14 xcp65 xenopsd-xc: [debug||31 ||xenops_server] Queue.pop returned ["VM_check_state","2975cda5-7192-0f55-39a6-23673b40c8f9"] Oct 14 20:08:14 xcp65 xenopsd-xc: [debug||31 |events|xenops_server] Task 5634 reference events: ["VM_check_state","2975cda5-7192-0f55-39a6-23673b40c8f9"] Oct 14 20:08:14 xcp65 xenopsd-xc: [debug||31 |events|xenops_server] VM 2975cda5-7192-0f55-39a6-23673b40c8f9 is not requesting any attention Oct 14 20:08:14 xcp65 xenopsd-xc: [debug||31 |events|xenops_server] VM_DB.signal 2975cda5-7192-0f55-39a6-23673b40c8f9 Oct 14 20:08:14 xcp65 xenopsd-xc: [debug||31 |events|task_server] Task 5634 completed; duration = 0 Oct 14 20:08:14 xcp65 xenopsd-xc: [debug||31 ||xenops_server] TASK.signal 5634 (object deleted) Oct 14 20:08:14 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] Processing event: ["Vm","2975cda5-7192-0f55-39a6-23673b40c8f9"] Oct 14 20:08:14 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenops event on VM 2975cda5-7192-0f55-39a6-23673b40c8f9 Oct 14 20:08:14 xcp65 xenopsd-xc: [debug||9713 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops_server] VM.stat 2975cda5-7192-0f55-39a6-23673b40c8f9 Oct 14 20:08:14 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenopsd event: processing event for VM 2975cda5-7192-0f55-39a6-23673b40c8f9 Oct 14 20:08:14 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] Supressing VM.allowed_operations update because guest_agent data is largely the same Oct 14 20:08:14 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenopsd event: Updating VM 2975cda5-7192-0f55-39a6-23673b40c8f9 domid 4 guest_agent Oct 14 20:08:29 xcp65 xapi: [debug||318 heartbeat|Heartbeat D:275787cef968|stunnel] stunnel start Oct 14 20:08:29 xcp65 xapi: [debug||318 heartbeat|Heartbeat D:275787cef968|xmlrpc_client] stunnel pid: 6923 (cached = false) connected to 10.10.10.5:443 Oct 14 20:08:29 xcp65 xapi: [debug||318 heartbeat|Heartbeat D:275787cef968|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=6923 Oct 14 20:08:35 xcp65 xapi: [debug||464 |watching networks for NBD-related changes D:a080e3b4dc81|xmlrpc_client] stunnel pid: 17599 (cached = true) returned stunnel to cache Oct 14 20:08:35 xcp65 xapi: [debug||464 |watching networks for NBD-related changes D:a080e3b4dc81|xmlrpc_client] stunnel pid: 21868 (cached = true) connected to 10.10.10.5:443 Oct 14 20:08:35 xcp65 xapi: [debug||464 |watching networks for NBD-related changes D:a080e3b4dc81|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=21868 Oct 14 20:08:35 xcp65 xapi: [debug||464 |watching networks for NBD-related changes D:a080e3b4dc81|xmlrpc_client] stunnel pid: 21868 (cached = true) returned stunnel to cache Oct 14 20:08:35 xcp65 xapi: [debug||464 |watching networks for NBD-related changes D:a080e3b4dc81|network_event_loop] Not updating the firewall, because the set of interfaces to use for NBD did not change: [] Oct 14 20:08:40 xcp65 xapi: [debug||464 |watching networks for NBD-related changes D:a080e3b4dc81|xmlrpc_client] stunnel pid: 17599 (cached = true) connected to 10.10.10.5:443 Oct 14 20:08:40 xcp65 xapi: [debug||464 |watching networks for NBD-related changes D:a080e3b4dc81|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=17599 Oct 14 20:08:40 xcp65 xapi: [debug||464 |watching networks for NBD-related changes D:a080e3b4dc81|xmlrpc_client] stunnel pid: 17599 (cached = true) returned stunnel to cache Oct 14 20:08:40 xcp65 xapi: [debug||464 |watching networks for NBD-related changes D:a080e3b4dc81|xmlrpc_client] stunnel pid: 21868 (cached = true) connected to 10.10.10.5:443 Oct 14 20:08:40 xcp65 xapi: [debug||464 |watching networks for NBD-related changes D:a080e3b4dc81|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=21868 Oct 14 20:08:46 xcp65 xapi: [debug||20347 :::80||dummytaskhelper] task dispatch:host.call_plugin D:73bc41fe1379 created by task R:90ea079f0ecb Oct 14 20:08:46 xcp65 xapi: [ info||20347 :::80||taskhelper] task host.call_plugin R:90ea079f0ecb forwarded (trackid=b56e635b6e8fb0e2a678848ed6f9d80d) Oct 14 20:08:48 xcp65 xcp-rrdd: [ info||0 monitor_write|main|rrdd_server] Failed to process plugin metrics file: xcp-rrdd-gpumon ((Invalid_argument\x0A "Cstruct.blit_to_bytes src=[0,0](0) dst=[11] src-off=0 len=11")) Oct 14 20:08:48 xcp65 xapi: [debug||463 |timeboxed_rpc D:bdbc463de8fb|xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:08:48 xcp65 xapi: [debug||463 ||dummytaskhelper] task timeboxed_rpc D:e4bb6b4763ae created by task D:63f103924ea0 Oct 14 20:08:48 xcp65 xapi: [debug||463 |timeboxed_rpc D:e4bb6b4763ae|xmlrpc_client] stunnel pid: 17599 (cached = true) connected to 10.10.10.5:443 Oct 14 20:08:48 xcp65 xapi: [debug||463 |timeboxed_rpc D:e4bb6b4763ae|xmlrpc_client] with_recorded_stunnelpid task_opt=DummyRef:|e4bb6b47-63ae-4713-96c8-959c39b606dc|timeboxed_rpc s_pid=17599 Oct 14 20:08:59 xcp65 xapi: [debug||318 heartbeat|Heartbeat D:275787cef968|stunnel] stunnel start Oct 14 20:08:59 xcp65 xapi: [debug||318 heartbeat|Heartbeat D:275787cef968|xmlrpc_client] stunnel pid: 7068 (cached = false) connected to 10.10.10.5:443 Oct 14 20:08:59 xcp65 xapi: [debug||318 heartbeat|Heartbeat D:275787cef968|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7068 [22:09 xcp65 ~]# grep "Oct 14 20:09" /var/log/xensource.log Oct 14 20:09:00 xcp65 xenopsd-xc: [debug||5 ||xenops_server] Received an event on managed VM ae01a342-3c3e-108f-dcd7-6cda287aadef Oct 14 20:09:00 xcp65 xenopsd-xc: [debug||5 |queue|xenops_server] Queue.push ["VM_check_state","ae01a342-3c3e-108f-dcd7-6cda287aadef"] onto ae01a342-3c3e-108f-dcd7-6cda287aadef:[ ] Oct 14 20:09:00 xcp65 xenopsd-xc: [debug||35 ||xenops_server] Queue.pop returned ["VM_check_state","ae01a342-3c3e-108f-dcd7-6cda287aadef"] Oct 14 20:09:00 xcp65 xenopsd-xc: [debug||35 |events|xenops_server] Task 5635 reference events: ["VM_check_state","ae01a342-3c3e-108f-dcd7-6cda287aadef"] Oct 14 20:09:00 xcp65 xenopsd-xc: [debug||35 |events|xenops_server] VM ae01a342-3c3e-108f-dcd7-6cda287aadef is not requesting any attention Oct 14 20:09:00 xcp65 xenopsd-xc: [debug||35 |events|xenops_server] VM_DB.signal ae01a342-3c3e-108f-dcd7-6cda287aadef Oct 14 20:09:00 xcp65 xenopsd-xc: [debug||35 |events|task_server] Task 5635 completed; duration = 0 Oct 14 20:09:00 xcp65 xenopsd-xc: [debug||35 ||xenops_server] TASK.signal 5635 (object deleted) Oct 14 20:09:00 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] Processing event: ["Vm","ae01a342-3c3e-108f-dcd7-6cda287aadef"] Oct 14 20:09:00 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenops event on VM ae01a342-3c3e-108f-dcd7-6cda287aadef Oct 14 20:09:00 xcp65 xenopsd-xc: [debug||9715 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops_server] VM.stat ae01a342-3c3e-108f-dcd7-6cda287aadef Oct 14 20:09:00 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenopsd event: processing event for VM ae01a342-3c3e-108f-dcd7-6cda287aadef Oct 14 20:09:00 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] Supressing VM.allowed_operations update because guest_agent data is largely the same Oct 14 20:09:00 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenopsd event: Updating VM ae01a342-3c3e-108f-dcd7-6cda287aadef domid 6 guest_agent Oct 14 20:09:05 xcp65 xenopsd-xc: [debug||5 ||xenops_server] Received an event on managed VM baaf736f-35ce-d254-06ae-ad4fbed6c085 Oct 14 20:09:05 xcp65 xenopsd-xc: [debug||5 |queue|xenops_server] Queue.push ["VM_check_state","baaf736f-35ce-d254-06ae-ad4fbed6c085"] onto baaf736f-35ce-d254-06ae-ad4fbed6c085:[ ] Oct 14 20:09:05 xcp65 xenopsd-xc: [debug||27 ||xenops_server] Queue.pop returned ["VM_check_state","baaf736f-35ce-d254-06ae-ad4fbed6c085"] Oct 14 20:09:05 xcp65 xenopsd-xc: [debug||27 |events|xenops_server] Task 5636 reference events: ["VM_check_state","baaf736f-35ce-d254-06ae-ad4fbed6c085"] Oct 14 20:09:05 xcp65 xenopsd-xc: [debug||27 |events|xenops_server] VM baaf736f-35ce-d254-06ae-ad4fbed6c085 is not requesting any attention Oct 14 20:09:05 xcp65 xenopsd-xc: [debug||27 |events|xenops_server] VM_DB.signal baaf736f-35ce-d254-06ae-ad4fbed6c085 Oct 14 20:09:05 xcp65 xenopsd-xc: [debug||27 |events|task_server] Task 5636 completed; duration = 0 Oct 14 20:09:05 xcp65 xenopsd-xc: [debug||27 ||xenops_server] TASK.signal 5636 (object deleted) Oct 14 20:09:05 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] Processing event: ["Vm","baaf736f-35ce-d254-06ae-ad4fbed6c085"] Oct 14 20:09:05 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenops event on VM baaf736f-35ce-d254-06ae-ad4fbed6c085 Oct 14 20:09:05 xcp65 xenopsd-xc: [debug||9717 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops_server] VM.stat baaf736f-35ce-d254-06ae-ad4fbed6c085 Oct 14 20:09:05 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenopsd event: processing event for VM baaf736f-35ce-d254-06ae-ad4fbed6c085 Oct 14 20:09:05 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] Supressing VM.allowed_operations update because guest_agent data is largely the same Oct 14 20:09:05 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenopsd event: Updating VM baaf736f-35ce-d254-06ae-ad4fbed6c085 domid 3 guest_agent Oct 14 20:09:07 xcp65 xapi: [ info||20834 HTTPS 10.10.1.7->:::80|Querying services D:2a4dbccf8a54|storage_impl] DP.destroy dbg:Async.VM.migrate_send R:326d34bf7507 dp:2238d061-2136-ee23-302b-10bf01ec426b allow_leak:false Oct 14 20:09:07 xcp65 xapi: [debug||20834 HTTPS 10.10.1.7->:::80|Querying services D:2a4dbccf8a54|storage_impl] [destroy_sr] Filtered VDI count:0 Oct 14 20:09:07 xcp65 xapi: [debug||20834 HTTPS 10.10.1.7->:::80|Querying services D:2a4dbccf8a54|storage_impl] [destroy_sr] Filtered VDI count:1 Oct 14 20:09:07 xcp65 xapi: [debug||20834 HTTPS 10.10.1.7->:::80|Querying services D:2a4dbccf8a54|storage_impl] [destroy_sr] VDI found with the dp is 8c24f393-1bce-4e4d-8e59-8cfe3fbaf700 Oct 14 20:09:07 xcp65 xapi: [debug||20835 ||dummytaskhelper] task VDI.deactivate D:6107efd5eeea created by task O:Async.VM.mig Oct 14 20:09:07 xcp65 xapi: [debug||20835 |VDI.deactivate D:6107efd5eeea|dummytaskhelper] task VDI.deactivate D:9877862b0dc4 created by task O:Async.VM.mig Oct 14 20:09:07 xcp65 xapi: [debug||20835 ||sm] SM nfs vdi_deactivate sr=OpaqueRef:39fb41e0-6c27-4806-aabb-f7d55f6d426b vdi=OpaqueRef:ec12c1d2-466e-4435-a3ab-8877d2faffec Oct 14 20:09:07 xcp65 xapi: [ info||20835 |sm_exec D:6f9b5c2d0ae7|xapi_session] Session.create trackid=72d18198a5543a6ea41b8a763bdec40e pool=false uname= originator=xapi is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49 Oct 14 20:09:07 xcp65 xapi: [debug||20835 |sm_exec D:6f9b5c2d0ae7|xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:07 xcp65 xapi: [debug||20835 |sm_exec D:6f9b5c2d0ae7|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:07 xcp65 xapi: [debug||20835 |sm_exec D:6f9b5c2d0ae7|xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:07 xcp65 xapi: [debug||20836 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:07 xcp65 xapi: [debug||20836 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:07 xcp65 xapi: [debug||20836 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:07 xcp65 xapi: [debug||20837 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:07 xcp65 xapi: [debug||20837 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:07 xcp65 xapi: [debug||20837 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:07 xcp65 xapi: [debug||20838 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:07 xcp65 xapi: [debug||20838 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:07 xcp65 xapi: [debug||20838 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:07 xcp65 xapi: [debug||20839 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:07 xcp65 xapi: [debug||20839 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:07 xcp65 xapi: [debug||20839 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:07 xcp65 xapi: [debug||20840 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:07 xcp65 xapi: [debug||20840 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:07 xcp65 xapi: [debug||20840 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:07 xcp65 xapi: [debug||20841 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:07 xcp65 xapi: [debug||20841 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:07 xcp65 xapi: [debug||20841 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20842 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20842 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20842 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20843 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20843 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20843 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20844 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20844 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20844 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20845 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20845 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20845 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20846 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20846 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20846 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20847 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20847 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20847 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20848 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20848 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20848 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20849 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20849 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20849 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20850 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20850 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20850 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20851 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20851 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20851 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20852 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20852 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20852 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20853 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20853 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20853 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20854 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20854 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20854 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20855 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20855 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20855 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20856 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20856 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20856 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20857 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20857 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20857 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20858 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20858 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20858 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [ info||20859 /var/lib/xcp/xapi|session.login_with_password D:f9c4450b075a|xapi_session] Session.create trackid=269b9028af9a074e4234028d8d4490b6 pool=false uname=root originator=SM is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49 Oct 14 20:09:08 xcp65 xapi: [debug||20859 /var/lib/xcp/xapi|session.login_with_password D:f9c4450b075a|xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20859 /var/lib/xcp/xapi|session.login_with_password D:f9c4450b075a|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20859 /var/lib/xcp/xapi|session.login_with_password D:f9c4450b075a|xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20860 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20860 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20860 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20861 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20861 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20861 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20862 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20862 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20862 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20863 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20863 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20863 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20864 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20864 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20864 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20865 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20865 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20865 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20866 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20866 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20866 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20867 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20867 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20867 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20868 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20868 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20868 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20869 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20869 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20869 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20870 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20870 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20870 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20871 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20871 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20871 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [ info||20835 |sm_exec D:6f9b5c2d0ae7|xapi_session] Session.destroy trackid=72d18198a5543a6ea41b8a763bdec40e Oct 14 20:09:08 xcp65 xapi: [debug||20834 HTTPS 10.10.1.7->:::80|Querying services D:2a4dbccf8a54|storage_impl] dbg:Async.VM.migrate_send R:326d34bf7507 dp:2238d061-2136-ee23-302b-10bf01ec426b sr:8da3d03e-4d2c-bab2-cd94-0d15168a58f3 vdi:8c24f393-1bce-4e4d-8e59-8cfe3fbaf700 superstate:attached RW Oct 14 20:09:08 xcp65 xapi: [debug||20872 ||dummytaskhelper] task VDI.detach D:9b2da35a5dce created by task O:Async.VM.mig Oct 14 20:09:08 xcp65 xapi: [debug||20872 |VDI.detach D:9b2da35a5dce|sm] SM nfs vdi_detach sr=OpaqueRef:39fb41e0-6c27-4806-aabb-f7d55f6d426b vdi=OpaqueRef:ec12c1d2-466e-4435-a3ab-8877d2faffec Oct 14 20:09:08 xcp65 xapi: [ info||20872 |sm_exec D:cb977d115441|xapi_session] Session.create trackid=1e47199693d0845356516c2813e65dc1 pool=false uname= originator=xapi is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49 Oct 14 20:09:08 xcp65 xapi: [debug||20872 |sm_exec D:cb977d115441|xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20872 |sm_exec D:cb977d115441|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20872 |sm_exec D:cb977d115441|xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20873 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20873 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20873 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20874 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20874 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20874 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20875 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20875 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20875 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [debug||20876 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:08 xcp65 xapi: [debug||20876 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:08 xcp65 xapi: [debug||20876 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:08 xcp65 xapi: [ info||20872 |sm_exec D:cb977d115441|xapi_session] Session.destroy trackid=1e47199693d0845356516c2813e65dc1 Oct 14 20:09:08 xcp65 xapi: [debug||20872 ||dummytaskhelper] task VDI.detach D:f62ea29cdab0 created by task O:Async.VM.mig Oct 14 20:09:08 xcp65 xapi: [debug||20834 HTTPS 10.10.1.7->:::80|Querying services D:2a4dbccf8a54|storage_impl] dbg:Async.VM.migrate_send R:326d34bf7507 dp:2238d061-2136-ee23-302b-10bf01ec426b sr:8da3d03e-4d2c-bab2-cd94-0d15168a58f3 vdi:8c24f393-1bce-4e4d-8e59-8cfe3fbaf700 superstate:detached Oct 14 20:09:08 xcp65 xapi: [debug||20834 HTTPS 10.10.1.7->:::80|Querying services D:2a4dbccf8a54|storage_impl] [destroy_sr] Filtered VDI count:0 Oct 14 20:09:08 xcp65 xapi: message repeated 4 times: [ [debug||20834 HTTPS 10.10.1.7->:::80|Querying services D:2a4dbccf8a54|storage_impl] [destroy_sr] Filtered VDI count:0] Oct 14 20:09:12 xcp65 xapi: [ info||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|storage_impl] DATA.MIRROR.receive_cancel dbg:Async.VM.migrate_send R:326d34bf7507 id:bb502d0d-3f9b-7ecd-723b-6363f5de9b96/d7db37b2-a897-469b-a352-33898ba24e2a Oct 14 20:09:12 xcp65 xapi: [debug||20878 :::80||stunnel] stunnel start Oct 14 20:09:12 xcp65 xapi: [debug||20878 :::80||xmlrpc_client] stunnel pid: 7210 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20878 :::80||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7210 Oct 14 20:09:12 xcp65 xapi: [ info||20878 HTTP 127.0.0.1->:::80|Querying services D:e4a67a1aa9ac|storage_impl] DP.destroy dbg:Async.VM.migrate_send R:326d34bf7507 dp:0fe24d00-3f47-84f3-f8f2-a44c0966bae7 allow_leak:false Oct 14 20:09:12 xcp65 xapi: [debug||20878 HTTP 127.0.0.1->:::80|Querying services D:e4a67a1aa9ac|storage_impl] [destroy_sr] Filtered VDI count:0 Oct 14 20:09:12 xcp65 xapi: [debug||20878 HTTP 127.0.0.1->:::80|Querying services D:e4a67a1aa9ac|storage_impl] [destroy_sr] Filtered VDI count:1 Oct 14 20:09:12 xcp65 xapi: [debug||20878 HTTP 127.0.0.1->:::80|Querying services D:e4a67a1aa9ac|storage_impl] [destroy_sr] VDI found with the dp is e6aaf200-136a-4a82-969b-5e80fee737ff Oct 14 20:09:12 xcp65 xapi: [debug||20879 ||dummytaskhelper] task VDI.deactivate D:285c05a768e7 created by task O:Async.VM.mig Oct 14 20:09:12 xcp65 xapi: [debug||20879 |VDI.deactivate D:285c05a768e7|dummytaskhelper] task VDI.deactivate D:0c2d25336ecb created by task O:Async.VM.mig Oct 14 20:09:12 xcp65 xapi: [debug||20879 ||sm] SM nfs vdi_deactivate sr=OpaqueRef:39fb41e0-6c27-4806-aabb-f7d55f6d426b vdi=OpaqueRef:090bd763-f89f-43cb-a439-36256664e13d Oct 14 20:09:12 xcp65 xapi: [ info||20879 |sm_exec D:389aed5d6f5f|xapi_session] Session.create trackid=2fbf923106df840ae36f4e77fcf76682 pool=false uname= originator=xapi is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49 Oct 14 20:09:12 xcp65 xapi: [debug||20879 |sm_exec D:389aed5d6f5f|xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20879 |sm_exec D:389aed5d6f5f|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20879 |sm_exec D:389aed5d6f5f|xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20880 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20880 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20880 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20881 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20881 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20881 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20882 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20882 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20882 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20883 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20883 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20883 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20884 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20884 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20884 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20885 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20885 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20885 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20886 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20886 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20886 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20887 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20887 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20887 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20888 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20888 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20888 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20889 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20889 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20889 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20890 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20890 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20890 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20891 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20891 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20891 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20892 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20892 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20892 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20893 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20893 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20893 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20894 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20894 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20894 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20895 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20895 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20895 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20896 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20896 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20896 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20897 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20897 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20897 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20898 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20898 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20898 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20899 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20899 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20899 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20900 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20900 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20900 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20901 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20901 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20901 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20902 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20902 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20902 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [ info||20903 /var/lib/xcp/xapi|session.login_with_password D:8c86496e6d5b|xapi_session] Session.create trackid=d5631310dfde64613c409b8ce69a177d pool=false uname=root originator=SM is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49 Oct 14 20:09:12 xcp65 xapi: [debug||20903 /var/lib/xcp/xapi|session.login_with_password D:8c86496e6d5b|xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20903 /var/lib/xcp/xapi|session.login_with_password D:8c86496e6d5b|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20903 /var/lib/xcp/xapi|session.login_with_password D:8c86496e6d5b|xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20904 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20904 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20904 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20905 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20905 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20905 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20906 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20906 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20906 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20907 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20907 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20907 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20908 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20908 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20908 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20909 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20909 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20909 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20910 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20910 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20910 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20911 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20911 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20911 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20912 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20912 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20912 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20913 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20913 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20913 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20914 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20914 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20914 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [debug||20915 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20915 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20915 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:12 xcp65 xapi: [ info||20879 |sm_exec D:389aed5d6f5f|xapi_session] Session.destroy trackid=2fbf923106df840ae36f4e77fcf76682 Oct 14 20:09:12 xcp65 xapi: [debug||20878 HTTP 127.0.0.1->:::80|Querying services D:e4a67a1aa9ac|storage_impl] dbg:Async.VM.migrate_send R:326d34bf7507 dp:0fe24d00-3f47-84f3-f8f2-a44c0966bae7 sr:8da3d03e-4d2c-bab2-cd94-0d15168a58f3 vdi:e6aaf200-136a-4a82-969b-5e80fee737ff superstate:attached RW Oct 14 20:09:12 xcp65 xapi: [debug||20916 ||dummytaskhelper] task VDI.detach D:7fa5551fdeeb created by task O:Async.VM.mig Oct 14 20:09:12 xcp65 xapi: [debug||20916 |VDI.detach D:7fa5551fdeeb|sm] SM nfs vdi_detach sr=OpaqueRef:39fb41e0-6c27-4806-aabb-f7d55f6d426b vdi=OpaqueRef:090bd763-f89f-43cb-a439-36256664e13d Oct 14 20:09:12 xcp65 xapi: [ info||20916 |sm_exec D:ab203066ccb4|xapi_session] Session.create trackid=a0c4872f123d18c0a750430ac8c2d031 pool=false uname= originator=xapi is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49 Oct 14 20:09:12 xcp65 xapi: [debug||20916 |sm_exec D:ab203066ccb4|xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:12 xcp65 xapi: [debug||20916 |sm_exec D:ab203066ccb4|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:12 xcp65 xapi: [debug||20916 |sm_exec D:ab203066ccb4|xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:13 xcp65 xapi: [debug||20917 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:13 xcp65 xapi: [debug||20917 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:13 xcp65 xapi: [debug||20917 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:13 xcp65 xapi: [debug||20918 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:13 xcp65 xapi: [debug||20918 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:13 xcp65 xapi: [debug||20918 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:13 xcp65 xapi: [debug||20919 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:13 xcp65 xapi: [debug||20919 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:13 xcp65 xapi: [debug||20919 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:13 xcp65 xapi: [debug||20920 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:13 xcp65 xapi: [debug||20920 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:13 xcp65 xapi: [debug||20920 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:13 xcp65 xapi: [ info||20916 |sm_exec D:ab203066ccb4|xapi_session] Session.destroy trackid=a0c4872f123d18c0a750430ac8c2d031 Oct 14 20:09:13 xcp65 xapi: [debug||20916 ||dummytaskhelper] task VDI.detach D:7f43a519989b created by task O:Async.VM.mig Oct 14 20:09:13 xcp65 xapi: [debug||20878 HTTP 127.0.0.1->:::80|Querying services D:e4a67a1aa9ac|storage_impl] dbg:Async.VM.migrate_send R:326d34bf7507 dp:0fe24d00-3f47-84f3-f8f2-a44c0966bae7 sr:8da3d03e-4d2c-bab2-cd94-0d15168a58f3 vdi:e6aaf200-136a-4a82-969b-5e80fee737ff superstate:detached Oct 14 20:09:13 xcp65 xapi: [debug||20878 HTTP 127.0.0.1->:::80|Querying services D:e4a67a1aa9ac|storage_impl] [destroy_sr] Filtered VDI count:0 Oct 14 20:09:13 xcp65 xapi: message repeated 4 times: [ [debug||20878 HTTP 127.0.0.1->:::80|Querying services D:e4a67a1aa9ac|storage_impl] [destroy_sr] Filtered VDI count:0] Oct 14 20:09:13 xcp65 xapi: [debug||20878 :::80||stunnel] stunnel start Oct 14 20:09:13 xcp65 xapi: [debug||20878 :::80||xmlrpc_client] stunnel pid: 7299 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:13 xcp65 xapi: [debug||20878 :::80||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7299 Oct 14 20:09:13 xcp65 xapi: [debug||20921 :::80||stunnel] stunnel start Oct 14 20:09:13 xcp65 xapi: [debug||20921 :::80||xmlrpc_client] stunnel pid: 7301 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:13 xcp65 xapi: [debug||20921 :::80||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7301 Oct 14 20:09:13 xcp65 xapi: [ info||20921 HTTP 127.0.0.1->:::80|Querying services D:ad98299874a8|storage_impl] VDI.destroy dbg:Async.VM.migrate_send R:326d34bf7507 sr:8da3d03e-4d2c-bab2-cd94-0d15168a58f3 vdi:214ca7df-8d9b-401d-aff0-3d6956b1ebc7 Oct 14 20:09:13 xcp65 xapi: [debug||20922 ||dummytaskhelper] task VDI.destroy D:feb8cb702d1a created by task O:Async.VM.mig Oct 14 20:09:13 xcp65 xapi: [error||20922 ||backtrace] VDI.destroy D:feb8cb702d1a failed with exception Storage_access.No_VDI Oct 14 20:09:13 xcp65 xapi: [error||20922 ||backtrace] Raised Storage_access.No_VDI Oct 14 20:09:13 xcp65 xapi: [error||20922 ||backtrace] 1/1 xapi Raised at file (Thread 20922 has no backtrace table. Was with_backtraces called?, line 0 Oct 14 20:09:13 xcp65 xapi: [error||20922 ||backtrace] Oct 14 20:09:13 xcp65 xapi: [error||20922 ||storage_interface] Storage_error ([S(Vdi_does_not_exist);S(214ca7df-8d9b-401d-aff0-3d6956b1ebc7)]) (File "storage/storage_interface.ml", line 436, characters 51-58) Oct 14 20:09:13 xcp65 xapi: [error||20921 HTTP 127.0.0.1->:::80|Querying services D:ad98299874a8|storage_interface] Storage_error ([S(Vdi_does_not_exist);S(214ca7df-8d9b-401d-aff0-3d6956b1ebc7)]) (File "storage/storage_interface.ml", line 431, characters 49-56) Oct 14 20:09:13 xcp65 xapi: [error||20921 HTTP 127.0.0.1->:::80|Querying services D:ad98299874a8|storage_interface] Storage_error ([S(Vdi_does_not_exist);S(214ca7df-8d9b-401d-aff0-3d6956b1ebc7)]) (File "storage/storage_interface.ml", line 436, characters 51-58) Oct 14 20:09:13 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|storage_utils] Got failure: checking for redirect, call was: -> VDI.destroy({vdi:S(214ca7df-8d9b-401d-aff0-3d6956b1ebc7);sr:S(8da3d03e-4d2c-bab2-cd94-0d15168a58f3);dbg:S(Async.VM.migrate_send R:326d34bf7507)}), results.contents: ["Vdi_does_not_exist","214ca7df-8d9b-401d-aff0-3d6956b1ebc7"] Oct 14 20:09:13 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|storage_utils] Not a redirect: -> VDI.destroy({vdi:S(214ca7df-8d9b-401d-aff0-3d6956b1ebc7);sr:S(8da3d03e-4d2c-bab2-cd94-0d15168a58f3);dbg:S(Async.VM.migrate_send R:326d34bf7507)}) Oct 14 20:09:13 xcp65 xapi: [error||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|storage_interface] Storage_error ([S(Vdi_does_not_exist);S(214ca7df-8d9b-401d-aff0-3d6956b1ebc7)]) (File "storage/storage_interface.ml", line 431, characters 49-56) Oct 14 20:09:13 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|backtrace] Raised Storage_error ([S(Vdi_does_not_exist);S(214ca7df-8d9b-401d-aff0-3d6956b1ebc7)]) Oct 14 20:09:13 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|backtrace] 1/3 xapi Raised at file storage/storage_interface.ml, line 432 Oct 14 20:09:13 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|backtrace] 2/3 xapi Called from file src/lib/idl.ml, line 558 Oct 14 20:09:13 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|backtrace] 3/3 xapi Called from file lib/debug.ml, line 310 Oct 14 20:09:13 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|backtrace] Oct 14 20:09:13 xcp65 xapi: [debug||20921 :::80||stunnel] stunnel start Oct 14 20:09:13 xcp65 xapi: [debug||20921 :::80||xmlrpc_client] stunnel pid: 7309 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:13 xcp65 xapi: [debug||20921 :::80||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7309 Oct 14 20:09:13 xcp65 xapi: [debug||20923 :::80||stunnel] stunnel start Oct 14 20:09:13 xcp65 xapi: [debug||20923 :::80||xmlrpc_client] stunnel pid: 7311 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:13 xcp65 xapi: [debug||20923 :::80||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7311 Oct 14 20:09:13 xcp65 xapi: [ info||20923 HTTP 127.0.0.1->:::80|Querying services D:925f7de8d203|storage_impl] VDI.destroy dbg:Async.VM.migrate_send R:326d34bf7507 sr:8da3d03e-4d2c-bab2-cd94-0d15168a58f3 vdi:e6aaf200-136a-4a82-969b-5e80fee737ff Oct 14 20:09:13 xcp65 xapi: [debug||20924 ||dummytaskhelper] task VDI.destroy D:0cb3779c6a6a created by task O:Async.VM.mig Oct 14 20:09:13 xcp65 xapi: [debug||20924 |VDI.destroy D:0cb3779c6a6a|sm] SM nfs vdi_delete sr=OpaqueRef:39fb41e0-6c27-4806-aabb-f7d55f6d426b vdi=OpaqueRef:090bd763-f89f-43cb-a439-36256664e13d Oct 14 20:09:13 xcp65 xapi: [ warn||20924 |VDI.destroy D:0cb3779c6a6a|sm] srmaster_only: Raising MasterOnly exception Oct 14 20:09:13 xcp65 xapi: [error||20924 ||backtrace] VDI.destroy D:0cb3779c6a6a failed with exception Sm.MasterOnly Oct 14 20:09:13 xcp65 xapi: [error||20924 ||backtrace] Raised Sm.MasterOnly Oct 14 20:09:13 xcp65 xapi: [error||20924 ||backtrace] 1/1 xapi Raised at file (Thread 20924 has no backtrace table. Was with_backtraces called?, line 0 Oct 14 20:09:13 xcp65 xapi: [error||20924 ||backtrace] Oct 14 20:09:13 xcp65 xapi: [error||20924 ||storage_interface] Storage_error ([S(Redirect);[S(10.10.10.5)]]) (File "storage/storage_interface.ml", line 436, characters 51-58) Oct 14 20:09:13 xcp65 xapi: [error||20923 HTTP 127.0.0.1->:::80|Querying services D:925f7de8d203|storage_interface] Storage_error ([S(Redirect);[S(10.10.10.5)]]) (File "storage/storage_interface.ml", line 431, characters 49-56) Oct 14 20:09:13 xcp65 xapi: [error||20923 HTTP 127.0.0.1->:::80|Querying services D:925f7de8d203|storage_interface] Storage_error ([S(Redirect);[S(10.10.10.5)]]) (File "storage/storage_interface.ml", line 436, characters 51-58) Oct 14 20:09:13 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|storage_utils] Got failure: checking for redirect, call was: -> VDI.destroy({vdi:S(e6aaf200-136a-4a82-969b-5e80fee737ff);sr:S(8da3d03e-4d2c-bab2-cd94-0d15168a58f3);dbg:S(Async.VM.migrate_send R:326d34bf7507)}), results.contents: ["Redirect",["10.10.10.5"]] Oct 14 20:09:13 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|storage_utils] Redirecting -> VDI.destroy({vdi:S(e6aaf200-136a-4a82-969b-5e80fee737ff);sr:S(8da3d03e-4d2c-bab2-cd94-0d15168a58f3);dbg:S(Async.VM.migrate_send R:326d34bf7507)}) to ip: 10.10.10.5 Oct 14 20:09:13 xcp65 xapi: [debug||20923 :::80||stunnel] stunnel start Oct 14 20:09:13 xcp65 xapi: [debug||20923 :::80||xmlrpc_client] stunnel pid: 7318 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:13 xcp65 xapi: [debug||20923 :::80||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7318 Oct 14 20:09:13 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|stunnel] stunnel start Oct 14 20:09:13 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|xmlrpc_client] stunnel pid: 7320 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:13 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7320 Oct 14 20:09:14 xcp65 xenopsd-xc: [debug||5 ||xenops_server] Received an event on managed VM 2975cda5-7192-0f55-39a6-23673b40c8f9 Oct 14 20:09:14 xcp65 xenopsd-xc: [debug||5 |queue|xenops_server] Queue.push ["VM_check_state","2975cda5-7192-0f55-39a6-23673b40c8f9"] onto 2975cda5-7192-0f55-39a6-23673b40c8f9:[ ] Oct 14 20:09:14 xcp65 xenopsd-xc: [debug||41 ||xenops_server] Queue.pop returned ["VM_check_state","2975cda5-7192-0f55-39a6-23673b40c8f9"] Oct 14 20:09:14 xcp65 xenopsd-xc: [debug||41 |events|xenops_server] Task 5637 reference events: ["VM_check_state","2975cda5-7192-0f55-39a6-23673b40c8f9"] Oct 14 20:09:14 xcp65 xenopsd-xc: [debug||41 |events|xenops_server] VM 2975cda5-7192-0f55-39a6-23673b40c8f9 is not requesting any attention Oct 14 20:09:14 xcp65 xenopsd-xc: [debug||41 |events|xenops_server] VM_DB.signal 2975cda5-7192-0f55-39a6-23673b40c8f9 Oct 14 20:09:14 xcp65 xenopsd-xc: [debug||41 |events|task_server] Task 5637 completed; duration = 0 Oct 14 20:09:14 xcp65 xenopsd-xc: [debug||41 ||xenops_server] TASK.signal 5637 (object deleted) Oct 14 20:09:14 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] Processing event: ["Vm","2975cda5-7192-0f55-39a6-23673b40c8f9"] Oct 14 20:09:14 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenops event on VM 2975cda5-7192-0f55-39a6-23673b40c8f9 Oct 14 20:09:14 xcp65 xenopsd-xc: [debug||9719 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops_server] VM.stat 2975cda5-7192-0f55-39a6-23673b40c8f9 Oct 14 20:09:14 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenopsd event: processing event for VM 2975cda5-7192-0f55-39a6-23673b40c8f9 Oct 14 20:09:14 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] Supressing VM.allowed_operations update because guest_agent data is largely the same Oct 14 20:09:14 xcp65 xapi: [debug||461 |org.xen.xapi.xenops.classic events D:62d62abd161d|xenops] xenopsd event: Updating VM 2975cda5-7192-0f55-39a6-23673b40c8f9 domid 4 guest_agent Oct 14 20:09:20 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|storage_utils] Successfully redirected -> VDI.destroy({vdi:S(e6aaf200-136a-4a82-969b-5e80fee737ff);sr:S(8da3d03e-4d2c-bab2-cd94-0d15168a58f3);dbg:S(Async.VM.migrate_send R:326d34bf7507)}). Returning Oct 14 20:09:20 xcp65 xapi: [debug||20925 :::80||stunnel] stunnel start Oct 14 20:09:20 xcp65 xapi: [debug||20925 :::80||xmlrpc_client] stunnel pid: 7351 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:20 xcp65 xapi: [debug||20925 :::80||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7351 Oct 14 20:09:20 xcp65 xapi: [ info||20925 HTTP 127.0.0.1->:::80|Querying services D:630002d0929f|storage_impl] VDI.destroy dbg:Async.VM.migrate_send R:326d34bf7507 sr:8da3d03e-4d2c-bab2-cd94-0d15168a58f3 vdi:492c765b-6886-4b5a-b26f-819deaf227d3 Oct 14 20:09:20 xcp65 xapi: [debug||20926 ||dummytaskhelper] task VDI.destroy D:5dfea337b0b5 created by task O:Async.VM.mig Oct 14 20:09:20 xcp65 xapi: [error||20926 ||backtrace] VDI.destroy D:5dfea337b0b5 failed with exception Storage_access.No_VDI Oct 14 20:09:20 xcp65 xapi: [error||20926 ||backtrace] Raised Storage_access.No_VDI Oct 14 20:09:20 xcp65 xapi: [error||20926 ||backtrace] 1/1 xapi Raised at file (Thread 20926 has no backtrace table. Was with_backtraces called?, line 0 Oct 14 20:09:20 xcp65 xapi: [error||20926 ||backtrace] Oct 14 20:09:20 xcp65 xapi: [error||20926 ||storage_interface] Storage_error ([S(Vdi_does_not_exist);S(492c765b-6886-4b5a-b26f-819deaf227d3)]) (File "storage/storage_interface.ml", line 436, characters 51-58) Oct 14 20:09:20 xcp65 xapi: [error||20925 HTTP 127.0.0.1->:::80|Querying services D:630002d0929f|storage_interface] Storage_error ([S(Vdi_does_not_exist);S(492c765b-6886-4b5a-b26f-819deaf227d3)]) (File "storage/storage_interface.ml", line 431, characters 49-56) Oct 14 20:09:20 xcp65 xapi: [error||20925 HTTP 127.0.0.1->:::80|Querying services D:630002d0929f|storage_interface] Storage_error ([S(Vdi_does_not_exist);S(492c765b-6886-4b5a-b26f-819deaf227d3)]) (File "storage/storage_interface.ml", line 436, characters 51-58) Oct 14 20:09:20 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|storage_utils] Got failure: checking for redirect, call was: -> VDI.destroy({vdi:S(492c765b-6886-4b5a-b26f-819deaf227d3);sr:S(8da3d03e-4d2c-bab2-cd94-0d15168a58f3);dbg:S(Async.VM.migrate_send R:326d34bf7507)}), results.contents: ["Vdi_does_not_exist","492c765b-6886-4b5a-b26f-819deaf227d3"] Oct 14 20:09:20 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|storage_utils] Not a redirect: -> VDI.destroy({vdi:S(492c765b-6886-4b5a-b26f-819deaf227d3);sr:S(8da3d03e-4d2c-bab2-cd94-0d15168a58f3);dbg:S(Async.VM.migrate_send R:326d34bf7507)}) Oct 14 20:09:20 xcp65 xapi: [error||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|storage_interface] Storage_error ([S(Vdi_does_not_exist);S(492c765b-6886-4b5a-b26f-819deaf227d3)]) (File "storage/storage_interface.ml", line 431, characters 49-56) Oct 14 20:09:20 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|backtrace] Raised Storage_error ([S(Vdi_does_not_exist);S(492c765b-6886-4b5a-b26f-819deaf227d3)]) Oct 14 20:09:20 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|backtrace] 1/3 xapi Raised at file storage/storage_interface.ml, line 432 Oct 14 20:09:20 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|backtrace] 2/3 xapi Called from file src/lib/idl.ml, line 558 Oct 14 20:09:20 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|backtrace] 3/3 xapi Called from file lib/debug.ml, line 310 Oct 14 20:09:20 xcp65 xapi: [debug||20877 HTTPS 10.10.1.7->:::80|Querying services D:25918bc79b88|backtrace] Oct 14 20:09:20 xcp65 xapi: [debug||20925 :::80||stunnel] stunnel start Oct 14 20:09:20 xcp65 xapi: [debug||20925 :::80||xmlrpc_client] stunnel pid: 7356 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:20 xcp65 xapi: [debug||20925 :::80||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7356 Oct 14 20:09:25 xcp65 xapi: [ info||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|storage_impl] DATA.MIRROR.receive_cancel dbg:Async.VM.migrate_send R:326d34bf7507 id:bb502d0d-3f9b-7ecd-723b-6363f5de9b96/b607eebc-49ad-4fc0-ad4a-b605fedfc51e Oct 14 20:09:25 xcp65 xapi: [debug||20928 :::80||stunnel] stunnel start Oct 14 20:09:25 xcp65 xapi: [debug||20928 :::80||xmlrpc_client] stunnel pid: 7389 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20928 :::80||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7389 Oct 14 20:09:25 xcp65 xapi: [ info||20928 HTTP 127.0.0.1->:::80|Querying services D:80b558e22ec1|storage_impl] DP.destroy dbg:Async.VM.migrate_send R:326d34bf7507 dp:0478f2d9-4ccd-7da2-1b63-59afa88138fc allow_leak:false Oct 14 20:09:25 xcp65 xapi: [debug||20928 HTTP 127.0.0.1->:::80|Querying services D:80b558e22ec1|storage_impl] [destroy_sr] Filtered VDI count:0 Oct 14 20:09:25 xcp65 xapi: [debug||20928 HTTP 127.0.0.1->:::80|Querying services D:80b558e22ec1|storage_impl] [destroy_sr] Filtered VDI count:1 Oct 14 20:09:25 xcp65 xapi: [debug||20928 HTTP 127.0.0.1->:::80|Querying services D:80b558e22ec1|storage_impl] [destroy_sr] VDI found with the dp is afdd7601-4a08-4cf4-a601-c34f8015c80e Oct 14 20:09:25 xcp65 xapi: [debug||20929 ||dummytaskhelper] task VDI.deactivate D:f6e57d034520 created by task O:Async.VM.mig Oct 14 20:09:25 xcp65 xapi: [debug||20929 |VDI.deactivate D:f6e57d034520|dummytaskhelper] task VDI.deactivate D:04d6b6074cb5 created by task O:Async.VM.mig Oct 14 20:09:25 xcp65 xapi: [debug||20929 ||sm] SM nfs vdi_deactivate sr=OpaqueRef:39fb41e0-6c27-4806-aabb-f7d55f6d426b vdi=OpaqueRef:ce88a469-b45a-42bd-9994-185516afac9c Oct 14 20:09:25 xcp65 xapi: [ info||20929 |sm_exec D:edf554a3faa5|xapi_session] Session.create trackid=61ac616fec67430cf236dfde2ed3b018 pool=false uname= originator=xapi is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49 Oct 14 20:09:25 xcp65 xapi: [debug||20929 |sm_exec D:edf554a3faa5|xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20929 |sm_exec D:edf554a3faa5|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20929 |sm_exec D:edf554a3faa5|xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20930 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20930 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20930 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20931 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20931 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20931 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20932 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20932 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20932 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20933 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20933 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20933 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20934 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20934 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20934 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20935 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20935 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20935 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20936 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20936 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20936 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20937 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20937 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20937 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20938 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20938 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20938 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20939 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20939 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20939 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20940 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20940 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20940 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20941 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20941 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20941 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20942 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20942 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20942 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20943 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20943 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20943 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20944 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20944 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20944 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20945 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20945 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20945 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20946 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20946 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20946 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20947 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20947 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20947 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20948 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20948 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20948 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20949 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20949 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20949 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20950 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20950 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20950 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20951 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20951 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20951 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20952 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20952 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20952 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [ info||20953 /var/lib/xcp/xapi|session.login_with_password D:aeef229a9141|xapi_session] Session.create trackid=ab60a58cdbe35241e58f48e83b982574 pool=false uname=root originator=SM is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49 Oct 14 20:09:25 xcp65 xapi: [debug||20953 /var/lib/xcp/xapi|session.login_with_password D:aeef229a9141|xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20953 /var/lib/xcp/xapi|session.login_with_password D:aeef229a9141|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20953 /var/lib/xcp/xapi|session.login_with_password D:aeef229a9141|xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20954 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20954 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20954 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20955 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20955 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20955 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20956 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20956 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20956 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20957 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20957 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20957 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20958 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20958 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20958 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20959 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20959 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20959 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20960 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20960 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20960 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20961 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20961 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20961 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20962 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20962 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20962 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20963 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20963 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20963 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20964 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20964 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20964 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20965 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20965 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20965 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [ info||20929 |sm_exec D:edf554a3faa5|xapi_session] Session.destroy trackid=61ac616fec67430cf236dfde2ed3b018 Oct 14 20:09:25 xcp65 xapi: [debug||20928 HTTP 127.0.0.1->:::80|Querying services D:80b558e22ec1|storage_impl] dbg:Async.VM.migrate_send R:326d34bf7507 dp:0478f2d9-4ccd-7da2-1b63-59afa88138fc sr:8da3d03e-4d2c-bab2-cd94-0d15168a58f3 vdi:afdd7601-4a08-4cf4-a601-c34f8015c80e superstate:attached RW Oct 14 20:09:25 xcp65 xapi: [debug||20966 ||dummytaskhelper] task VDI.detach D:8c4a2e431245 created by task O:Async.VM.mig Oct 14 20:09:25 xcp65 xapi: [debug||20966 |VDI.detach D:8c4a2e431245|sm] SM nfs vdi_detach sr=OpaqueRef:39fb41e0-6c27-4806-aabb-f7d55f6d426b vdi=OpaqueRef:ce88a469-b45a-42bd-9994-185516afac9c Oct 14 20:09:25 xcp65 xapi: [ info||20966 |sm_exec D:d01d3166854d|xapi_session] Session.create trackid=a9cfed4075fef1d683331d478e8b1d00 pool=false uname= originator=xapi is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49 Oct 14 20:09:25 xcp65 xapi: [debug||20966 |sm_exec D:d01d3166854d|xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20966 |sm_exec D:d01d3166854d|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20966 |sm_exec D:d01d3166854d|xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20967 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20967 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20967 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20968 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20968 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20968 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20969 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20969 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20969 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [debug||20970 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20970 /var/lib/xcp/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=5784 Oct 14 20:09:25 xcp65 xapi: [debug||20970 /var/lib/xcp/xapi||xmlrpc_client] stunnel pid: 5784 (cached = true) returned stunnel to cache Oct 14 20:09:25 xcp65 xapi: [ info||20966 |sm_exec D:d01d3166854d|xapi_session] Session.destroy trackid=a9cfed4075fef1d683331d478e8b1d00 Oct 14 20:09:25 xcp65 xapi: [debug||20966 ||dummytaskhelper] task VDI.detach D:e93cd67156c4 created by task O:Async.VM.mig Oct 14 20:09:25 xcp65 xapi: [debug||20928 HTTP 127.0.0.1->:::80|Querying services D:80b558e22ec1|storage_impl] dbg:Async.VM.migrate_send R:326d34bf7507 dp:0478f2d9-4ccd-7da2-1b63-59afa88138fc sr:8da3d03e-4d2c-bab2-cd94-0d15168a58f3 vdi:afdd7601-4a08-4cf4-a601-c34f8015c80e superstate:detached Oct 14 20:09:25 xcp65 xapi: [debug||20928 HTTP 127.0.0.1->:::80|Querying services D:80b558e22ec1|storage_impl] [destroy_sr] Filtered VDI count:0 Oct 14 20:09:25 xcp65 xapi: message repeated 4 times: [ [debug||20928 HTTP 127.0.0.1->:::80|Querying services D:80b558e22ec1|storage_impl] [destroy_sr] Filtered VDI count:0] Oct 14 20:09:25 xcp65 xapi: [debug||20928 :::80||stunnel] stunnel start Oct 14 20:09:25 xcp65 xapi: [debug||20928 :::80||xmlrpc_client] stunnel pid: 7461 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20928 :::80||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7461 Oct 14 20:09:25 xcp65 xapi: [debug||20971 :::80||stunnel] stunnel start Oct 14 20:09:25 xcp65 xapi: [debug||20971 :::80||xmlrpc_client] stunnel pid: 7463 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20971 :::80||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7463 Oct 14 20:09:25 xcp65 xapi: [ info||20971 HTTP 127.0.0.1->:::80|Querying services D:ffe3ae12a2d5|storage_impl] VDI.destroy dbg:Async.VM.migrate_send R:326d34bf7507 sr:8da3d03e-4d2c-bab2-cd94-0d15168a58f3 vdi:0603ad1a-599b-438e-a2eb-1442c187cd54 Oct 14 20:09:25 xcp65 xapi: [debug||20972 ||dummytaskhelper] task VDI.destroy D:61c888f3a7fc created by task O:Async.VM.mig Oct 14 20:09:25 xcp65 xapi: [error||20972 ||backtrace] VDI.destroy D:61c888f3a7fc failed with exception Storage_access.No_VDI Oct 14 20:09:25 xcp65 xapi: [error||20972 ||backtrace] Raised Storage_access.No_VDI Oct 14 20:09:25 xcp65 xapi: [error||20972 ||backtrace] 1/1 xapi Raised at file (Thread 20972 has no backtrace table. Was with_backtraces called?, line 0 Oct 14 20:09:25 xcp65 xapi: [error||20972 ||backtrace] Oct 14 20:09:25 xcp65 xapi: [error||20972 ||storage_interface] Storage_error ([S(Vdi_does_not_exist);S(0603ad1a-599b-438e-a2eb-1442c187cd54)]) (File "storage/storage_interface.ml", line 436, characters 51-58) Oct 14 20:09:25 xcp65 xapi: [error||20971 HTTP 127.0.0.1->:::80|Querying services D:ffe3ae12a2d5|storage_interface] Storage_error ([S(Vdi_does_not_exist);S(0603ad1a-599b-438e-a2eb-1442c187cd54)]) (File "storage/storage_interface.ml", line 431, characters 49-56) Oct 14 20:09:25 xcp65 xapi: [error||20971 HTTP 127.0.0.1->:::80|Querying services D:ffe3ae12a2d5|storage_interface] Storage_error ([S(Vdi_does_not_exist);S(0603ad1a-599b-438e-a2eb-1442c187cd54)]) (File "storage/storage_interface.ml", line 436, characters 51-58) Oct 14 20:09:25 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|storage_utils] Got failure: checking for redirect, call was: -> VDI.destroy({vdi:S(0603ad1a-599b-438e-a2eb-1442c187cd54);sr:S(8da3d03e-4d2c-bab2-cd94-0d15168a58f3);dbg:S(Async.VM.migrate_send R:326d34bf7507)}), results.contents: ["Vdi_does_not_exist","0603ad1a-599b-438e-a2eb-1442c187cd54"] Oct 14 20:09:25 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|storage_utils] Not a redirect: -> VDI.destroy({vdi:S(0603ad1a-599b-438e-a2eb-1442c187cd54);sr:S(8da3d03e-4d2c-bab2-cd94-0d15168a58f3);dbg:S(Async.VM.migrate_send R:326d34bf7507)}) Oct 14 20:09:25 xcp65 xapi: [error||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|storage_interface] Storage_error ([S(Vdi_does_not_exist);S(0603ad1a-599b-438e-a2eb-1442c187cd54)]) (File "storage/storage_interface.ml", line 431, characters 49-56) Oct 14 20:09:25 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|backtrace] Raised Storage_error ([S(Vdi_does_not_exist);S(0603ad1a-599b-438e-a2eb-1442c187cd54)]) Oct 14 20:09:25 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|backtrace] 1/3 xapi Raised at file storage/storage_interface.ml, line 432 Oct 14 20:09:25 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|backtrace] 2/3 xapi Called from file src/lib/idl.ml, line 558 Oct 14 20:09:25 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|backtrace] 3/3 xapi Called from file lib/debug.ml, line 310 Oct 14 20:09:25 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|backtrace] Oct 14 20:09:25 xcp65 xapi: [debug||20971 :::80||stunnel] stunnel start Oct 14 20:09:25 xcp65 xapi: [debug||20971 :::80||xmlrpc_client] stunnel pid: 7471 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20971 :::80||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7471 Oct 14 20:09:25 xcp65 xapi: [debug||20973 :::80||stunnel] stunnel start Oct 14 20:09:25 xcp65 xapi: [debug||20973 :::80||xmlrpc_client] stunnel pid: 7473 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20973 :::80||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7473 Oct 14 20:09:25 xcp65 xapi: [ info||20973 HTTP 127.0.0.1->:::80|Querying services D:9b14da27af4e|storage_impl] VDI.destroy dbg:Async.VM.migrate_send R:326d34bf7507 sr:8da3d03e-4d2c-bab2-cd94-0d15168a58f3 vdi:afdd7601-4a08-4cf4-a601-c34f8015c80e Oct 14 20:09:25 xcp65 xapi: [debug||20974 ||dummytaskhelper] task VDI.destroy D:fc9a082fcc82 created by task O:Async.VM.mig Oct 14 20:09:25 xcp65 xapi: [debug||20974 |VDI.destroy D:fc9a082fcc82|sm] SM nfs vdi_delete sr=OpaqueRef:39fb41e0-6c27-4806-aabb-f7d55f6d426b vdi=OpaqueRef:ce88a469-b45a-42bd-9994-185516afac9c Oct 14 20:09:25 xcp65 xapi: [ warn||20974 |VDI.destroy D:fc9a082fcc82|sm] srmaster_only: Raising MasterOnly exception Oct 14 20:09:25 xcp65 xapi: [error||20974 ||backtrace] VDI.destroy D:fc9a082fcc82 failed with exception Sm.MasterOnly Oct 14 20:09:25 xcp65 xapi: [error||20974 ||backtrace] Raised Sm.MasterOnly Oct 14 20:09:25 xcp65 xapi: [error||20974 ||backtrace] 1/1 xapi Raised at file (Thread 20974 has no backtrace table. Was with_backtraces called?, line 0 Oct 14 20:09:25 xcp65 xapi: [error||20974 ||backtrace] Oct 14 20:09:25 xcp65 xapi: [error||20974 ||storage_interface] Storage_error ([S(Redirect);[S(10.10.10.5)]]) (File "storage/storage_interface.ml", line 436, characters 51-58) Oct 14 20:09:25 xcp65 xapi: [error||20973 HTTP 127.0.0.1->:::80|Querying services D:9b14da27af4e|storage_interface] Storage_error ([S(Redirect);[S(10.10.10.5)]]) (File "storage/storage_interface.ml", line 431, characters 49-56) Oct 14 20:09:25 xcp65 xapi: [error||20973 HTTP 127.0.0.1->:::80|Querying services D:9b14da27af4e|storage_interface] Storage_error ([S(Redirect);[S(10.10.10.5)]]) (File "storage/storage_interface.ml", line 436, characters 51-58) Oct 14 20:09:25 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|storage_utils] Got failure: checking for redirect, call was: -> VDI.destroy({vdi:S(afdd7601-4a08-4cf4-a601-c34f8015c80e);sr:S(8da3d03e-4d2c-bab2-cd94-0d15168a58f3);dbg:S(Async.VM.migrate_send R:326d34bf7507)}), results.contents: ["Redirect",["10.10.10.5"]] Oct 14 20:09:25 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|storage_utils] Redirecting -> VDI.destroy({vdi:S(afdd7601-4a08-4cf4-a601-c34f8015c80e);sr:S(8da3d03e-4d2c-bab2-cd94-0d15168a58f3);dbg:S(Async.VM.migrate_send R:326d34bf7507)}) to ip: 10.10.10.5 Oct 14 20:09:25 xcp65 xapi: [debug||20973 :::80||stunnel] stunnel start Oct 14 20:09:25 xcp65 xapi: [debug||20973 :::80||xmlrpc_client] stunnel pid: 7480 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20973 :::80||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7480 Oct 14 20:09:25 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|stunnel] stunnel start Oct 14 20:09:25 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|xmlrpc_client] stunnel pid: 7482 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:25 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7482 Oct 14 20:09:26 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|storage_utils] Successfully redirected -> VDI.destroy({vdi:S(afdd7601-4a08-4cf4-a601-c34f8015c80e);sr:S(8da3d03e-4d2c-bab2-cd94-0d15168a58f3);dbg:S(Async.VM.migrate_send R:326d34bf7507)}). Returning Oct 14 20:09:27 xcp65 xapi: [debug||20975 :::80||stunnel] stunnel start Oct 14 20:09:27 xcp65 xapi: [debug||20975 :::80||xmlrpc_client] stunnel pid: 7494 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:27 xcp65 xapi: [debug||20975 :::80||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7494 Oct 14 20:09:27 xcp65 xapi: [ info||20975 HTTP 127.0.0.1->:::80|Querying services D:aff2b3f528c3|storage_impl] VDI.destroy dbg:Async.VM.migrate_send R:326d34bf7507 sr:8da3d03e-4d2c-bab2-cd94-0d15168a58f3 vdi:daeffc9e-96cc-4de6-bbc0-e1bcebe97cfc Oct 14 20:09:27 xcp65 xapi: [debug||20976 ||dummytaskhelper] task VDI.destroy D:7be7a5f515ff created by task O:Async.VM.mig Oct 14 20:09:27 xcp65 xapi: [error||20976 ||backtrace] VDI.destroy D:7be7a5f515ff failed with exception Storage_access.No_VDI Oct 14 20:09:27 xcp65 xapi: [error||20976 ||backtrace] Raised Storage_access.No_VDI Oct 14 20:09:27 xcp65 xapi: [error||20976 ||backtrace] 1/1 xapi Raised at file (Thread 20976 has no backtrace table. Was with_backtraces called?, line 0 Oct 14 20:09:27 xcp65 xapi: [error||20976 ||backtrace] Oct 14 20:09:27 xcp65 xapi: [error||20976 ||storage_interface] Storage_error ([S(Vdi_does_not_exist);S(daeffc9e-96cc-4de6-bbc0-e1bcebe97cfc)]) (File "storage/storage_interface.ml", line 436, characters 51-58) Oct 14 20:09:27 xcp65 xapi: [error||20975 HTTP 127.0.0.1->:::80|Querying services D:aff2b3f528c3|storage_interface] Storage_error ([S(Vdi_does_not_exist);S(daeffc9e-96cc-4de6-bbc0-e1bcebe97cfc)]) (File "storage/storage_interface.ml", line 431, characters 49-56) Oct 14 20:09:27 xcp65 xapi: [error||20975 HTTP 127.0.0.1->:::80|Querying services D:aff2b3f528c3|storage_interface] Storage_error ([S(Vdi_does_not_exist);S(daeffc9e-96cc-4de6-bbc0-e1bcebe97cfc)]) (File "storage/storage_interface.ml", line 436, characters 51-58) Oct 14 20:09:27 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|storage_utils] Got failure: checking for redirect, call was: -> VDI.destroy({vdi:S(daeffc9e-96cc-4de6-bbc0-e1bcebe97cfc);sr:S(8da3d03e-4d2c-bab2-cd94-0d15168a58f3);dbg:S(Async.VM.migrate_send R:326d34bf7507)}), results.contents: ["Vdi_does_not_exist","daeffc9e-96cc-4de6-bbc0-e1bcebe97cfc"] Oct 14 20:09:27 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|storage_utils] Not a redirect: -> VDI.destroy({vdi:S(daeffc9e-96cc-4de6-bbc0-e1bcebe97cfc);sr:S(8da3d03e-4d2c-bab2-cd94-0d15168a58f3);dbg:S(Async.VM.migrate_send R:326d34bf7507)}) Oct 14 20:09:27 xcp65 xapi: [error||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|storage_interface] Storage_error ([S(Vdi_does_not_exist);S(daeffc9e-96cc-4de6-bbc0-e1bcebe97cfc)]) (File "storage/storage_interface.ml", line 431, characters 49-56) Oct 14 20:09:27 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|backtrace] Raised Storage_error ([S(Vdi_does_not_exist);S(daeffc9e-96cc-4de6-bbc0-e1bcebe97cfc)]) Oct 14 20:09:27 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|backtrace] 1/3 xapi Raised at file storage/storage_interface.ml, line 432 Oct 14 20:09:27 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|backtrace] 2/3 xapi Called from file src/lib/idl.ml, line 558 Oct 14 20:09:27 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|backtrace] 3/3 xapi Called from file lib/debug.ml, line 310 Oct 14 20:09:27 xcp65 xapi: [debug||20927 HTTPS 10.10.1.7->:::80|Querying services D:44a4e4ed8941|backtrace] Oct 14 20:09:27 xcp65 xapi: [debug||20975 :::80||stunnel] stunnel start Oct 14 20:09:27 xcp65 xapi: [debug||20975 :::80||xmlrpc_client] stunnel pid: 7499 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:27 xcp65 xapi: [debug||20975 :::80||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7499 Oct 14 20:09:29 xcp65 xapi: [debug||318 heartbeat|Heartbeat D:275787cef968|stunnel] stunnel start Oct 14 20:09:29 xcp65 xapi: [debug||318 heartbeat|Heartbeat D:275787cef968|xmlrpc_client] stunnel pid: 7520 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:29 xcp65 xapi: [debug||318 heartbeat|Heartbeat D:275787cef968|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7520 Oct 14 20:09:48 xcp65 xapi: [debug||20347 :::80||dummytaskhelper] task dispatch:host.call_plugin D:71cf0aafa22a created by task R:d5f9e88be7e2 Oct 14 20:09:48 xcp65 xapi: [ info||20347 :::80||taskhelper] task host.call_plugin R:d5f9e88be7e2 forwarded (trackid=f6607a750071e21ff3ba7acb4dc57c45) Oct 14 20:09:48 xcp65 xapi: [debug||463 |timeboxed_rpc D:e4bb6b4763ae|xmlrpc_client] stunnel pid: 17599 (cached = true) returned stunnel to cache Oct 14 20:09:48 xcp65 xapi: [debug||463 ||dummytaskhelper] task timeboxed_rpc D:294ac2b6f562 created by task D:63f103924ea0 Oct 14 20:09:48 xcp65 xapi: [debug||463 |timeboxed_rpc D:294ac2b6f562|xmlrpc_client] stunnel pid: 5784 (cached = true) connected to 10.10.10.5:443 Oct 14 20:09:48 xcp65 xapi: [debug||463 |timeboxed_rpc D:294ac2b6f562|xmlrpc_client] with_recorded_stunnelpid task_opt=DummyRef:|294ac2b6-f562-413a-8c50-6b7e2ba8f464|timeboxed_rpc s_pid=5784 Oct 14 20:09:59 xcp65 xapi: [debug||318 heartbeat|Heartbeat D:275787cef968|stunnel] stunnel start Oct 14 20:09:59 xcp65 xapi: [debug||318 heartbeat|Heartbeat D:275787cef968|xmlrpc_client] stunnel pid: 7673 (cached = false) connected to 10.10.10.5:443 Oct 14 20:09:59 xcp65 xapi: [debug||318 heartbeat|Heartbeat D:275787cef968|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=7673