Error :-PROLOG_FAILURE

Hi Team,
I’m new in OpenNebula,I use OpenNebula 5.6 in Ubuntu, When I just created vm Using opennebula defaults template,
Its show this error, Please Help me To resolve this error:-

Error executing image transfer script: Error copying server.skilledrich.xyz:/var/lib/one//datastores/1/e6e9b921601daa8cecb81ba6bd1af49d to host01:/var/lib/one//datastores/0/44/disk.0


Hello @Bhupender_Pareek

Can you connect ssh password less to the host from the frontend?

I already done, but issue are same.

Could you please send me the oned.log with the full error?

#oned.Logs are below :-
Mon Oct 7 10:22:56 2019 [Z0][ReM][D]: Req:8512 UID:0 one.template.info invoked , 0, false
Mon Oct 7 10:22:56 2019 [Z0][ReM][D]: Req:8512 UID:0 one.template.info result SUCCESS, β€œ0</I…”
Mon Oct 7 10:22:56 2019 [Z0][ReM][D]: Req:2064 UID:0 one.template.instantiate invoked , 0, β€œtest101”, false, "DISK=[
IMAGE_ID=β€œ0…”, false
Mon Oct 7 10:22:56 2019 [Z0][ReM][D]: Req:2064 UID:0 one.template.instantiate result SUCCESS, 2
Mon Oct 7 10:22:56 2019 [Z0][ReM][D]: Req:4624 UID:0 one.vmpool.info invoked , -2, 0, -200, -1
Mon Oct 7 10:22:56 2019 [Z0][ReM][D]: Req:4624 UID:0 one.vmpool.info result SUCCESS, β€œ<VM_POOL>2</…”
Mon Oct 7 10:22:57 2019 [Z0][ReM][D]: Req:5600 UID:0 one.user.info invoked , 0
Mon Oct 7 10:22:57 2019 [Z0][ReM][D]: Req:5600 UID:0 one.user.info result SUCCESS, β€œ0<GID…”
Mon Oct 7 10:22:59 2019 [Z0][ReM][D]: Req:4528 UID:0 one.vmpool.info invoked , -2, 0, -200, -1
Mon Oct 7 10:22:59 2019 [Z0][ReM][D]: Req:4528 UID:0 one.vmpool.info result SUCCESS, β€œ<VM_POOL>2</…”
Mon Oct 7 10:22:59 2019 [Z0][MKP][D]: Monitoring marketplace OpenNebula Public (0)
Mon Oct 7 10:22:59 2019 [Z0][InM][D]: Monitoring datastore default (1)
Mon Oct 7 10:22:59 2019 [Z0][InM][D]: Monitoring datastore files (2)
Mon Oct 7 10:22:59 2019 [Z0][ReM][D]: Req:9200 UID:0 one.user.info invoked , 0
Mon Oct 7 10:22:59 2019 [Z0][ReM][D]: Req:9200 UID:0 one.user.info result SUCCESS, β€œ0<GID…”
Mon Oct 7 10:23:00 2019 [Z0][ImM][D]: Datastore files (2) successfully monitored.
Mon Oct 7 10:23:00 2019 [Z0][ImM][D]: Datastore default (1) successfully monitored.
Mon Oct 7 10:23:00 2019 [Z0][MKP][D]: Marketplace OpenNebula Public (0) successfully monitored.
Mon Oct 7 10:23:12 2019 [Z0][InM][D]: Host server01 (0) successfully monitored.
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:4560 UID:0 one.zone.raftstatus invoked
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:4560 UID:0 one.zone.raftstatus result SUCCESS, β€œ<SERVER_ID>-1<…”
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:3168 UID:0 one.vmpool.info invoked , -2, -1, -1, -1
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:3168 UID:0 one.vmpool.info result SUCCESS, β€œ<VM_POOL>2</…”
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:3600 UID:0 one.vmpool.info invoked , -2, -1, -1, -1
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:3600 UID:0 one.vmpool.info result SUCCESS, β€œ<VM_POOL>2</…”
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:9568 UID:0 one.datastorepool.info invoked
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:9568 UID:0 one.datastorepool.info result SUCCESS, β€œ<DATASTORE_POOL><DAT…”
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:2016 UID:0 one.datastorepool.info invoked
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:2016 UID:0 one.datastorepool.info result SUCCESS, β€œ<DATASTORE_POOL><DAT…”
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:6800 UID:0 one.userpool.info invoked
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:6800 UID:0 one.userpool.info result SUCCESS, β€œ<USER_POOL><ID…”
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:4864 UID:0 one.hostpool.info invoked
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:4864 UID:0 one.hostpool.info result SUCCESS, β€œ<HOST_POOL><ID…”
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:3408 UID:0 one.clusterpool.info invoked
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:3408 UID:0 one.clusterpool.info result SUCCESS, β€œ<CLUSTER_POOL><CLUST…”
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:3888 UID:0 one.acl.info invoked
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:3888 UID:0 one.acl.info result SUCCESS, β€œ<ACL_POOL>0…”
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:5088 UID:0 one.vmgrouppool.info invoked , -2, -1, -1
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:5088 UID:0 one.vmgrouppool.info result SUCCESS, β€œ<VM_GROUP_POOL></VM_…”
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:8304 UID:0 one.vmpool.info invoked , -2, -1, -1, -1
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:8304 UID:0 one.vmpool.info result SUCCESS, β€œ<VM_POOL>2</…”
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:9680 UID:0 one.vm.deploy invoked , 2, 0, false, 0
Mon Oct 7 10:23:20 2019 [Z0][DiM][D]: Deploying VM 2
Mon Oct 7 10:23:20 2019 [Z0][ReM][D]: Req:9680 UID:0 one.vm.deploy result SUCCESS, 2
Mon Oct 7 10:23:21 2019 [Z0][ReM][D]: Req:4976 UID:0 one.vm.info invoked , 2
Mon Oct 7 10:23:21 2019 [Z0][ReM][D]: Req:4976 UID:0 one.vm.info result SUCCESS, β€œ20…”
Mon Oct 7 10:23:21 2019 [Z0][TM][D]: Message received: LOG I 2 Command execution failed (exit code: 1): /var/lib/one/remotes/tm/ssh/clone server.skilledrich.xyz:/var/lib/one//datastores/1/ce103a226ba71db9acb89ac210508698 server01:/var/lib/one//datastores/0/2/disk.0 2 1

Mon Oct 7 10:23:21 2019 [Z0][TM][D]: Message received: LOG I 2 clone: Cloning server.skilledrich.xyz:/var/lib/one//datastores/1/ce103a226ba71db9acb89ac210508698 in /var/lib/one/datastores/0/2/disk.0

Mon Oct 7 10:23:21 2019 [Z0][TM][D]: Message received: LOG E 2 clone: Command β€œscp -r server.skilledrich.xyz:/var/lib/one//datastores/1/ce103a226ba71db9acb89ac210508698 server01:/var/lib/one//datastores/0/2/disk.0” failed: Host key verification failed.
Mon Oct 7 10:23:21 2019 [Z0][TM][D]: Message received: LOG E 2 Error copying server.skilledrich.xyz:/var/lib/one//datastores/1/ce103a226ba71db9acb89ac210508698 to server01:/var/lib/one//datastores/0/2/disk.0

Mon Oct 7 10:23:21 2019 [Z0][TM][D]: Message received: TRANSFER FAILURE 2 Error copying server.skilledrich.xyz:/var/lib/one//datastores/1/ce103a226ba71db9acb89ac210508698 to server01:/var/lib/one//datastores/0/2/disk.0

Mon Oct 7 10:23:50 2019 [Z0][ReM][D]: Req:5840 UID:0 one.zone.raftstatus invoked
Mon Oct 7 10:23:50 2019 [Z0][ReM][D]: Req:5840 UID:0 one.zone.raftstatus result SUCCESS, β€œ<SERVER_ID>-1<…”
Mon Oct 7 10:23:50 2019 [Z0][ReM][D]: Req:1648 UID:0 one.vmpool.info invoked , -2, -1, -1, -1
Mon Oct 7 10:23:50 2019 [Z0][ReM][D]: Req:1648 UID:0 one.vmpool.info result SUCCESS, β€œ<VM_POOL>2</…”
Mon Oct 7 10:23:50 2019 [Z0][ReM][D]: Req:448 UID:0 one.vmpool.info invoked , -2, -1, -1, -1
Mon Oct 7 10:23:50 2019 [Z0][ReM][D]: Req:448 UID:0 one.vmpool.info result SUCCESS, β€œ<VM_POOL>2</…”
Mon Oct 7 10:24:07 2019 [Z0][AuM][D]: Message received: AUTHENTICATE SUCCESS 22 -

Mon Oct 7 10:24:07 2019 [Z0][ReM][D]: Req:8400 UID:0 one.vmpool.info invoked , -2, 0, -200, -1
Mon Oct 7 10:24:07 2019 [Z0][ReM][D]: Req:8400 UID:0 one.vmpool.info result SUCCESS, β€œ<VM_POOL>2</…”
Mon Oct 7 10:24:07 2019 [Z0][ReM][D]: Req:0 UID:0 one.user.info invoked , 0
Mon Oct 7 10:24:07 2019 [Z0][ReM][D]: Req:0 UID:0 one.user.info result SUCCESS, β€œ0<GID…”
Mon Oct 7 10:24:09 2019 [Z0][ReM][D]: Req:2400 UID:0 one.user.info invoked , 0
Mon Oct 7 10:24:09 2019 [Z0][ReM][D]: Req:5008 UID:0 one.vm.info invoked , 2
Mon Oct 7 10:24:09 2019 [Z0][ReM][D]: Req:2400 UID:0 one.user.info result SUCCESS, β€œ0<GID…”
Mon Oct 7 10:24:09 2019 [Z0][ReM][D]: Req:5008 UID:0 one.vm.info result SUCCESS, β€œ20…”
Mon Oct 7 10:24:12 2019 [Z0][InM][D]: Host server01 (0) successfully monitored.
Mon Oct 7 10:24:20 2019 [Z0][ReM][D]: Req:8480 UID:0 one.zone.raftstatus invoked
Mon Oct 7 10:24:20 2019 [Z0][ReM][D]: Req:8480 UID:0 one.zone.raftstatus result SUCCESS, β€œ<SERVER_ID>-1<…”
Mon Oct 7 10:24:20 2019 [Z0][ReM][D]: Req:7760 UID:0 one.vmpool.info invoked , -2, -1, -1, -1
Mon Oct 7 10:24:20 2019 [Z0][ReM][D]: Req:7760 UID:0 one.vmpool.info result SUCCESS, β€œ<VM_POOL>2</…”
Mon Oct 7 10:24:20 2019 [Z0][ReM][D]: Req:8528 UID:0 one.vmpool.info invoked , -2, -1, -1, -1
Mon Oct 7 10:24:20 2019 [Z0][ReM][D]: Req:8528 UID:0 one.vmpool.info result SUCCESS, β€œ<VM_POOL>2</…”
Mon Oct 7 10:24:50 2019 [Z0][ReM][D]: Req:2960 UID:0 one.zone.raftstatus invoked
Mon Oct 7 10:24:50 2019 [Z0][ReM][D]: Req:2960 UID:0 one.zone.raftstatus result SUCCESS, β€œ<SERVER_ID>-1<…”
Mon Oct 7 10:24:50 2019 [Z0][ReM][D]: Req:1392 UID:0 one.vmpool.info invoked , -2, -1, -1, -1
Mon Oct 7 10:24:50 2019 [Z0][ReM][D]: Req:1392 UID:0 one.vmpool.info result SUCCESS, β€œ<VM_POOL>2</…”
1148,94 99%

Mon Oct 7 10:25:13 2019 [Z0][InM][D]: Host server01 (0) successfully monitored.
Mon Oct 7 10:25:20 2019 [Z0][ReM][D]: Req:8800 UID:0 one.zone.raftstatus invoked
Mon Oct 7 10:25:20 2019 [Z0][ReM][D]: Req:8800 UID:0 one.zone.raftstatus result SUCCESS, β€œ<SERVER_ID>-1<…”
Mon Oct 7 10:25:20 2019 [Z0][ReM][D]: Req:6016 UID:0 one.vmpool.info invoked , -2, -1, -1, -1
Mon Oct 7 10:25:20 2019 [Z0][ReM][D]: Req:6016 UID:0 one.vmpool.info result SUCCESS, β€œ<VM_POOL>2</…”
Mon Oct 7 10:25:20 2019 [Z0][ReM][D]: Req:2560 UID:0 one.vmpool.info invoked , -2, -1, -1, -1
Mon Oct 7 10:25:20 2019 [Z0][ReM][D]: Req:2560 UID:0 one.vmpool.info result SUCCESS, β€œ<VM_POOL>2</…”

Here you can see the error, please check that the scp works passwordless too.

1 Like

Thanks Sir,

It’s working…