“Ruben S. Montero” forum@opennebula.org writes:
Hi Daniel,
Hello,
It seems quite strange a direct transition from PENDING to RUNNING without
any TM activity… Could you look for the entries in oned.log for that VM,
those at 16:17:00 - 16:18:00 should do…
Here are the logs:
Mon Mar 9 16:17:13 2015 [Z0][ReM][D]: Req:8736 UID:0 VirtualMachinePoolInfo invoked , -2, -1, -1, -1
Mon Mar 9 16:17:13 2015 [Z0][ReM][D]: Req:8736 UID:0 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL><VM><ID>0</..."
Mon Mar 9 16:17:13 2015 [Z0][ReM][D]: Req:9968 UID:0 VirtualMachinePoolInfo invoked , -2, -1, -1, -1
Mon Mar 9 16:17:13 2015 [Z0][ReM][D]: Req:9968 UID:0 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL><VM><ID>0</..."
Mon Mar 9 16:17:13 2015 [Z0][ReM][D]: Req:208 UID:0 DatastorePoolInfo invoked
Mon Mar 9 16:17:13 2015 [Z0][ReM][D]: Req:208 UID:0 DatastorePoolInfo result SUCCESS, "<DATASTORE_POOL><DAT..."
Mon Mar 9 16:17:13 2015 [Z0][ReM][D]: Req:4912 UID:0 DatastorePoolInfo invoked
Mon Mar 9 16:17:13 2015 [Z0][ReM][D]: Req:4912 UID:0 DatastorePoolInfo result SUCCESS, "<DATASTORE_POOL><DAT..."
Mon Mar 9 16:17:14 2015 [Z0][ReM][D]: Req:7424 UID:0 HostPoolInfo invoked
Mon Mar 9 16:17:14 2015 [Z0][ReM][D]: Req:7424 UID:0 HostPoolInfo result SUCCESS, "<HOST_POOL><HOST><ID..."
Mon Mar 9 16:17:14 2015 [Z0][ReM][D]: Req:3616 UID:0 ClusterPoolInfo invoked
Mon Mar 9 16:17:14 2015 [Z0][ReM][D]: Req:3616 UID:0 ClusterPoolInfo result SUCCESS, "<CLUSTER_POOL><CLUST..."
Mon Mar 9 16:17:14 2015 [Z0][ReM][D]: Req:2992 UID:0 AclInfo invoked
Mon Mar 9 16:17:14 2015 [Z0][ReM][D]: Req:2992 UID:0 AclInfo result SUCCESS, "<ACL_POOL><ACL><ID>0..."
Mon Mar 9 16:17:14 2015 [Z0][ReM][D]: Req:9792 UID:0 VirtualMachineDeploy invoked , 0, 2, false, 0
Mon Mar 9 16:17:14 2015 [Z0][DiM][D]: Deploying VM 0
Mon Mar 9 16:17:14 2015 [Z0][ReM][D]: Req:9792 UID:0 VirtualMachineDeploy result SUCCESS, 0
Mon Mar 9 16:17:14 2015 [Z0][ImM][D]: Datastore system (0) successfully monitored.
Mon Mar 9 16:17:14 2015 [Z0][InM][D]: Host openneb3 (2) successfully monitored.
Mon Mar 9 16:17:15 2015 [Z0][TM][D]: Message received: TRANSFER SUCCESS 0 -
Mon Mar 9 16:17:15 2015 [Z0][VMM][D]: Message received: LOG I 0 ExitCode: 0
Mon Mar 9 16:17:15 2015 [Z0][VMM][D]: Message received: LOG I 0 Successfully execute network driver operation: pre.
Mon Mar 9 16:17:16 2015 [Z0][VMM][D]: Message received: LOG I 0 ExitCode: 0
Mon Mar 9 16:17:16 2015 [Z0][VMM][D]: Message received: LOG I 0 Successfully execute virtualization driver operation: deploy.
Mon Mar 9 16:17:16 2015 [Z0][VMM][D]: Message received: LOG I 0 post: Executed "sudo ovs-vsctl set Port vnet0 tag=4".
Mon Mar 9 16:17:16 2015 [Z0][VMM][D]: Message received: LOG I 0 post: Executed "sudo ovs-ofctl add-flow nebula in_port=2,arp,dl_src=02:00:c0:a8:e6:64,priority=45000,actions=drop".
Mon Mar 9 16:17:16 2015 [Z0][VMM][D]: Message received: LOG I 0 post: Executed "sudo ovs-ofctl add-flow nebula in_port=2,arp,dl_src=02:00:c0:a8:e6:64,nw_src=192.168.230.100,priority=46000,actions=normal".
Mon Mar 9 16:17:16 2015 [Z0][VMM][D]: Message received: LOG I 0 post: Executed "sudo ovs-ofctl add-flow nebula in_port=2,dl_src=02:00:c0:a8:e6:64,priority=40000,actions=normal".
Mon Mar 9 16:17:16 2015 [Z0][VMM][D]: Message received: LOG I 0 post: Executed "sudo ovs-ofctl add-flow nebula in_port=2,priority=39000,actions=drop".
Mon Mar 9 16:17:16 2015 [Z0][VMM][D]: Message received: LOG I 0 ExitCode: 0
Mon Mar 9 16:17:16 2015 [Z0][VMM][D]: Message received: LOG I 0 Successfully execute network driver operation: post.
Mon Mar 9 16:17:16 2015 [Z0][VMM][D]: Message received: DEPLOY SUCCESS 0 one-0
Mon Mar 9 16:17:21 2015 [Z0][ReM][D]: Req:3040 UID:0 SystemConfig invoked
Mon Mar 9 16:17:21 2015 [Z0][ReM][D]: Req:3040 UID:0 SystemConfig result SUCCESS, "<TEMPLATE><AUTH_MAD>..."
Mon Mar 9 16:17:21 2015 [Z0][ReM][D]: Req:5424 UID:0 HostPoolInfo invoked
Mon Mar 9 16:17:21 2015 [Z0][ReM][D]: Req:5424 UID:0 HostPoolInfo result SUCCESS, "<HOST_POOL><HOST><ID..."
Mon Mar 9 16:17:22 2015 [Z0][ReM][D]: Req:4416 UID:0 HostUpdateTemplate invoked , 2, "ARCH="x86_64"...", 0
Mon Mar 9 16:17:22 2015 [Z0][ReM][D]: Req:4416 UID:0 HostUpdateTemplate result SUCCESS, 2
Mon Mar 9 16:17:25 2015 [Z0][AuM][D]: Message received: AUTHENTICATE SUCCESS 40 -
Mon Mar 9 16:17:25 2015 [Z0][ReM][D]: Req:6128 UID:2 VirtualMachinePoolInfo invoked , -2, 0, -2000, -1
Mon Mar 9 16:17:25 2015 [Z0][ReM][D]: Req:6128 UID:2 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL><VM><ID>0</..."
Mon Mar 9 16:17:27 2015 [Z0][AuM][D]: Message received: AUTHENTICATE SUCCESS 41 -
Mon Mar 9 16:17:27 2015 [Z0][ReM][D]: Req:7104 UID:2 VirtualMachineInfo invoked , 0
Mon Mar 9 16:17:27 2015 [Z0][ReM][D]: Req:7104 UID:2 VirtualMachineInfo result SUCCESS, "<VM><ID>0</ID><UID>2..."
Mon Mar 9 16:17:41 2015 [Z0][AuM][D]: Message received: AUTHENTICATE SUCCESS 42 -
Mon Mar 9 16:17:41 2015 [Z0][ReM][D]: Req:2288 UID:2 VirtualMachineInfo invoked , 0
Mon Mar 9 16:17:41 2015 [Z0][ReM][D]: Req:2288 UID:2 VirtualMachineInfo result SUCCESS, "<VM><ID>0</ID><UID>2..."
Mon Mar 9 16:17:41 2015 [Z0][AuM][D]: Message received: AUTHENTICATE SUCCESS 43 -
Mon Mar 9 16:17:41 2015 [Z0][ReM][D]: Req:5840 UID:2 VirtualMachineAction invoked , "delete-recreate", 0
Mon Mar 9 16:17:41 2015 [Z0][ReM][D]: Req:5840 UID:2 VirtualMachineAction result SUCCESS, 0
Mon Mar 9 16:17:41 2015 [Z0][AuM][D]: Message received: AUTHENTICATE SUCCESS 44 -
Mon Mar 9 16:17:41 2015 [Z0][ReM][D]: Req:3248 UID:2 VirtualMachineInfo invoked , 0
Mon Mar 9 16:17:41 2015 [Z0][ReM][D]: Req:3248 UID:2 VirtualMachineInfo result SUCCESS, "<VM><ID>0</ID><UID>2..."
Mon Mar 9 16:17:41 2015 [Z0][VMM][D]: Message received: LOG I 0 Driver command for 0 cancelled
Mon Mar 9 16:17:42 2015 [Z0][VMM][D]: Message received: LOG I 0 error: failed to get domain 'one-0'
Mon Mar 9 16:17:42 2015 [Z0][VMM][D]: Message received: LOG I 0 error: Domain not found: no domain with matching name 'one-0'
Mon Mar 9 16:17:42 2015 [Z0][VMM][D]: Message received: LOG I 0 ExitCode: 0
Mon Mar 9 16:17:42 2015 [Z0][VMM][D]: Message received: LOG I 0 Successfully execute virtualization driver operation: cancel.
Mon Mar 9 16:17:42 2015 [Z0][VMM][D]: Message received: LOG I 0 clean: Executed "sudo ovs-ofctl del-flows nebula ,in_port=2,dl_src=02:00:c0:a8:e6:64".
Mon Mar 9 16:17:42 2015 [Z0][VMM][D]: Message received: LOG I 0 clean: Executed "sudo ovs-ofctl del-flows nebula ,arp,in_port=2,dl_src=02:00:c0:a8:e6:64,arp_spa=192.168.230.100".
Mon Mar 9 16:17:42 2015 [Z0][VMM][D]: Message received: LOG I 0 clean: Executed "sudo ovs-ofctl del-flows nebula ,arp,in_port=2,dl_src=02:00:c0:a8:e6:64".
Mon Mar 9 16:17:42 2015 [Z0][VMM][D]: Message received: LOG I 0 clean: Executed "sudo ovs-ofctl del-flows nebula in_port=2".
Mon Mar 9 16:17:42 2015 [Z0][VMM][D]: Message received: LOG I 0 ExitCode: 0
Mon Mar 9 16:17:42 2015 [Z0][VMM][D]: Message received: LOG I 0 Successfully execute network driver operation: clean.
Mon Mar 9 16:17:42 2015 [Z0][VMM][D]: Message received: LOG I 0 Successfully execute transfer manager driver operation: tm_delete.
Mon Mar 9 16:17:42 2015 [Z0][VMM][D]: Message received: LOG I 0 Successfully execute transfer manager driver operation: tm_delete.
Mon Mar 9 16:17:42 2015 [Z0][VMM][D]: Message received: CLEANUP SUCCESS 0
Mon Mar 9 16:17:43 2015 [Z0][ReM][D]: Req:8240 UID:0 VirtualMachinePoolInfo invoked , -2, -1, -1, -1
Mon Mar 9 16:17:43 2015 [Z0][ReM][D]: Req:8240 UID:0 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL><VM><ID>0</..."
Mon Mar 9 16:17:43 2015 [Z0][ReM][D]: Req:1968 UID:0 VirtualMachinePoolInfo invoked , -2, -1, -1, -1
Mon Mar 9 16:17:43 2015 [Z0][ReM][D]: Req:1968 UID:0 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL><VM><ID>0</..."
Mon Mar 9 16:17:43 2015 [Z0][ReM][D]: Req:4128 UID:0 DatastorePoolInfo invoked
Mon Mar 9 16:17:43 2015 [Z0][ReM][D]: Req:4128 UID:0 DatastorePoolInfo result SUCCESS, "<DATASTORE_POOL><DAT..."
Mon Mar 9 16:17:43 2015 [Z0][ReM][D]: Req:6048 UID:0 DatastorePoolInfo invoked
Mon Mar 9 16:17:43 2015 [Z0][ReM][D]: Req:6048 UID:0 DatastorePoolInfo result SUCCESS, "<DATASTORE_POOL><DAT..."
Mon Mar 9 16:17:44 2015 [Z0][ReM][D]: Req:4544 UID:0 HostPoolInfo invoked
Mon Mar 9 16:17:44 2015 [Z0][ReM][D]: Req:4544 UID:0 HostPoolInfo result SUCCESS, "<HOST_POOL><HOST><ID..."
Mon Mar 9 16:17:44 2015 [Z0][ReM][D]: Req:1536 UID:0 ClusterPoolInfo invoked
Mon Mar 9 16:17:44 2015 [Z0][ReM][D]: Req:1536 UID:0 ClusterPoolInfo result SUCCESS, "<CLUSTER_POOL><CLUST..."
Mon Mar 9 16:17:44 2015 [Z0][ReM][D]: Req:8240 UID:0 AclInfo invoked
Mon Mar 9 16:17:44 2015 [Z0][ReM][D]: Req:8240 UID:0 AclInfo result SUCCESS, "<ACL_POOL><ACL><ID>0..."
Mon Mar 9 16:17:44 2015 [Z0][ReM][D]: Req:944 UID:0 VirtualMachineDeploy invoked , 0, 2, false, 0
Mon Mar 9 16:17:44 2015 [Z0][AuM][D]: Message received: AUTHENTICATE SUCCESS 45 -
Mon Mar 9 16:17:44 2015 [Z0][ReM][D]: Req:5664 UID:2 VirtualMachinePoolInfo invoked , -2, 0, -2000, -1
Mon Mar 9 16:17:44 2015 [Z0][ReM][D]: Req:5664 UID:2 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL><VM><ID>0</..."
Mon Mar 9 16:17:44 2015 [Z0][ReM][D]: Req:944 UID:0 VirtualMachineDeploy result SUCCESS, 0
Mon Mar 9 16:17:47 2015 [Z0][AuM][D]: Message received: AUTHENTICATE SUCCESS 46 -
Mon Mar 9 16:17:47 2015 [Z0][ReM][D]: Req:3568 UID:2 VirtualMachinePoolInfo invoked , -2, 0, -2000, -1
Mon Mar 9 16:17:47 2015 [Z0][ReM][D]: Req:3568 UID:2 VirtualMachinePoolInfo result SUCCESS, "<VM_POOL><VM><ID>0</..."
Mon Mar 9 16:17:47 2015 [Z0][InM][D]: Monitoring datastore default (1)
Mon Mar 9 16:17:47 2015 [Z0][InM][D]: Monitoring datastore files (2)
Mon Mar 9 16:17:48 2015 [Z0][ImM][D]: Datastore default (1) successfully monitored.
Mon Mar 9 16:17:48 2015 [Z0][ImM][D]: Datastore files (2) successfully monitored.
Mon Mar 9 16:17:50 2015 [Z0][AuM][D]: Message received: AUTHENTICATE SUCCESS 47 -
Mon Mar 9 16:17:50 2015 [Z0][ReM][D]: Req:9824 UID:2 VirtualMachineInfo invoked , 0
Mon Mar 9 16:17:50 2015 [Z0][ReM][D]: Req:9824 UID:2 VirtualMachineInfo result SUCCESS, "<VM><ID>0</ID><UID>2..."
Mon Mar 9 16:18:00 2015 [Z0][AuM][D]: Message received: AUTHENTICATE SUCCESS 48 -
Mon Mar 9 16:18:00 2015 [Z0][ReM][D]: Req:9376 UID:2 VirtualMachineInfo invoked , 0
Regards.
Daniel Dehennin
Récupérer ma clef GPG: gpg --recv-keys 0xCC1E9E5B7A6FE2DF
Fingerprint: 3E69 014E 5C23 50E8 9ED6 2AAD CC1E 9E5B 7A6F E2DF