ccesario
July 5, 2018, 5:51pm
1
Hi folks, we are trying instantiate a VM template (vCenter based using ISO and Datablock disk images) and we are getting the follow error.
Thu Jul 5 14:05:07 2018 [Z0][ReM][D]: Req:3040 UID:0 one.vmpool.info invoked , -2, 0, -200, -1wait_for_completion'", "/usr/lib/one/ruby/vcenter_driver/datastore.rb:349:incopy_virtual_disk’", “/var/lib/one/remotes/datastore/vcenter/clone:68:in `’”]
Could someone has idea about fix it!?
Carlos
Versions of the related components and OS (frontend, hypervisors, VMs):
Steps to reproduce:
1 - Create a image Type Readonly CDROM using the vCenter Datasore
Current results:
Expected results:
ccesario
July 5, 2018, 7:00pm
2
Only to update…
On vOne 3.0.1 appliance with vCenter/ESX 6.5
On new vOne deployment, import host, datastore, network + 1 VMware image
Create a VM template in vOne
Create instance of this template
Current results:
Creation fails. log says:
Tue Sep 5 20:47:49 2017 [Z0][VMM][D]: Message received: LOG E 1 Deploy of VM 1 on vCenter cluster vCluster-382080-01_[foobar.com-382123_Murks_G
mbH]_d28233279365 with /var/lib/one/vms/1/deployment.0 failed due to "ManagedObjectNotFound: The object 'vim.VirtualMachine…
Semedi
July 10, 2018, 4:13pm
3
as i said in IRC this file looks suspicious: Core-current.iso.iso
Normally rbvmomi gem will complain about any typo or weird names regarding file extensions.
Regards.
ccesario
July 12, 2018, 2:42pm
4
Hi @Semedi ,
I think that there are any problem in this process.
1 - Create a image Type Readonly CDROM using the vCenter Datasore2 - Create a image Type Generic Storage Datablocks using the vCenter Datasore3 - Create a VM Template and in Storage option choose the first disk0: Cdrom image and disk1: Storage datablock image previous created4 - Try create a VM instace using this Template and the error will happen
Thu Jul 12 11:48:26 2018 [Z0][ImM][I]: Cloning image one/38/11116544-Core-currentiso.iso to repository as image 39
Thu Jul 12 11:48:26 2018 [Z0][ImM][I]: Cloning image one/37/one-37.vmdk to repository as image 40
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:1808 UID:0 one.template.instantiate result SUCCESS, 21
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:0 UID:0 one.image.info invoked , 38
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:0 UID:0 one.image.info result SUCCESS, "<IMAGE><ID>38</ID><U..."
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:592 UID:0 one.datastore.info invoked , 104
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:592 UID:0 one.datastore.info result SUCCESS, "<DATASTORE><ID>104</..."
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:4816 UID:0 one.datastore.info invoked , 104
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:4816 UID:0 one.datastore.info result SUCCESS, "<DATASTORE><ID>104</..."
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:2928 UID:0 one.system.config invoked
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:2928 UID:0 one.system.config result SUCCESS, "<TEMPLATE><AUTH_MAD>..."
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:0 UID:0 one.image.info invoked , 37
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:0 UID:0 one.image.info result SUCCESS, "<IMAGE><ID>37</ID><U..."
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:8096 UID:0 one.datastore.info invoked , 104
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:8096 UID:0 one.datastore.info result SUCCESS, "<DATASTORE><ID>104</..."
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:2912 UID:0 one.datastore.info invoked , 104
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:2912 UID:0 one.datastore.info result SUCCESS, "<DATASTORE><ID>104</..."
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:7264 UID:0 one.system.config invoked
Thu Jul 12 11:48:26 2018 [Z0][ReM][D]: Req:7264 UID:0 one.system.config result SUCCESS, "<TEMPLATE><AUTH_MAD>..."
Thu Jul 12 11:48:27 2018 [Z0][AuM][D]: Message received: AUTHENTICATE SUCCESS 492 -
Thu Jul 12 11:48:27 2018 [Z0][ReM][D]: Req:3136 UID:0 one.template.info invoked , 18, false
Thu Jul 12 11:48:27 2018 [Z0][ReM][D]: Req:3136 UID:0 one.template.info result SUCCESS, "<VMTEMPLATE><ID>18</..."
Thu Jul 12 11:48:27 2018 [Z0][AuM][D]: Message received: AUTHENTICATE SUCCESS 493 -
Thu Jul 12 11:48:27 2018 [Z0][ReM][D]: Req:8608 UID:0 one.templatepool.info invoked , -2, -1, -1
Thu Jul 12 11:48:27 2018 [Z0][ReM][D]: Req:8608 UID:0 one.templatepool.info result SUCCESS, "<VMTEMPLATE_POOL><VM..."
Thu Jul 12 11:48:27 2018 [Z0][ReM][D]: Req:5232 UID:0 one.user.info invoked , 0
Thu Jul 12 11:48:27 2018 [Z0][ReM][D]: Req:5232 UID:0 one.user.info result SUCCESS, "<USER><ID>0</ID><GID..."
Thu Jul 12 11:48:27 2018 [Z0][ImM][I]: Command execution failed (exit code: 255): /var/lib/one/remotes/datastore/vcenter/clone PERTX0RSSVZFUl9BQ1RJT05fREFUQT48SU1BR0U+PElEPjM5PC9JRD48VUlEPjA8L1VJRD48R0lEPjA8L0dJRD48VU5BTUU+b25lYWRtaW48L1VOQU1FPjxHTkFNRT5vbmVhZG1pbjwvR05BTUU+PE5BTUU+Q29yZS1JU08tSW5zdGFsbC1jb3B5LWRpc2stMDwvTkFNRT48TE9DSz48TE9DS0VEPjE8L0xPQ0tFRD48T1dORVI+LTE8L09XTkVSPjxUSU1FPjE1MzE0MDY5MDY8L1RJTUU+PFJFUV9JRD4tMTwvUkVRX0lEPjwvTE9DSz48UEVSTUlTU0lPTlM+PE9XTkVSX1U+MTwvT1dORVJfVT48T1dORVJfTT4xPC9PV05FUl9NPjxPV05FUl9BPjA8L09XTkVSX0E+PEdST1VQX1U+MDwvR1JPVVBfVT48R1JPVVBfTT4wPC9HUk9VUF9NPjxHUk9VUF9BPjA8L0dST1VQX0E+PE9USEVSX1U+MDwvT1RIRVJfVT48T1RIRVJfTT4wPC9PVEhFUl9NPjxPVEhFUl9BPjA8L09USEVSX0E+PC9QRVJNSVNTSU9OUz48VFlQRT4xPC9UWVBFPjxESVNLX1RZUEU+MDwvRElTS19UWVBFPjxQRVJTSVNURU5UPjE8L1BFUlNJU1RFTlQ+PFJFR1RJTUU+MTUzMTQwNjkwNjwvUkVHVElNRT48U09VUkNFPjwhW0NEQVRBW11dPjwvU09VUkNFPjxQQVRIPjwhW0NEQVRBW29uZS8zOC8xMTExNjU0NC1Db3JlLWN1cnJlbnRpc28uaXNvXV0+PC9QQVRIPjxGU1RZUEU+PCFbQ0RBVEFbXV0+PC9GU1RZUEU+PFNJWkU+MTE8L1NJWkU+PFNUQVRFPjQ8L1NUQVRFPjxSVU5OSU5HX1ZNUz4wPC9SVU5OSU5HX1ZNUz48Q0xPTklOR19PUFM+MDwvQ0xPTklOR19PUFM+PENMT05JTkdfSUQ+Mzg8L0NMT05JTkdfSUQ+PFRBUkdFVF9TTkFQU0hPVD4tMTwvVEFSR0VUX1NOQVBTSE9UPjxEQVRBU1RPUkVfSUQ+MTA0PC9EQVRBU1RPUkVfSUQ+PERBVEFTVE9SRT5kYXRhc3RvcmUxKElNRyk8L0RBVEFTVE9SRT48Vk1TPjwvVk1TPjxDTE9ORVM+PC9DTE9ORVM+PEFQUF9DTE9ORVM+PC9BUFBfQ0xPTkVTPjxURU1QTEFURT48REVWX1BSRUZJWD48IVtDREFUQVtoZF1dPjwvREVWX1BSRUZJWD48RFJJVkVSPjwhW0NEQVRBW3Jhd11dPjwvRFJJVkVSPjwvVEVNUExBVEU+PFNOQVBTSE9UUz48QUxMT1dfT1JQSEFOUz48IVtDREFUQVtOT11dPjwvQUxMT1dfT1JQSEFOUz48TkVYVF9TTkFQU0hPVD48IVtDREFUQVswXV0+PC9ORVhUX1NOQVBTSE9UPjwvU05BUFNIT1RTPjwvSU1BR0U+PERBVEFTVE9SRT48SUQ+MTA0PC9JRD48VUlEPjA8L1VJRD48R0lEPjA8L0dJRD48VU5BTUU+b25lYWRtaW48L1VOQU1FPjxHTkFNRT5vbmVhZG1pbjwvR05BTUU+PE5BTUU+ZGF0YXN0b3JlMShJTUcpPC9OQU1FPjxQRVJNSVNTSU9OUz48T1dORVJfVT4xPC9PV05FUl9VPjxPV05FUl9NPjE8L09XTkVSX00+PE9XTkVSX0E+MDwvT1dORVJfQT48R1JPVVBfVT4xPC9HUk9VUF9VPjxHUk9VUF9NPjA8L0dST1VQX00+PEdST1VQX0E+MDwvR1JPVVBfQT48T1RIRVJfVT4wPC9PVEhFUl9VPjxPVEhFUl9NPjA8L09USEVSX00+PE9USEVSX0E+MDwvT1RIRVJfQT48L1BFUk1JU1NJT05TPjxEU19NQUQ+PCFbQ0RBVEFbdmNlbnRlcl1dPjwvRFNfTUFEPjxUTV9NQUQ+PCFbQ0RBVEFbdmNlbnRlcl1dPjwvVE1fTUFEPjxCQVNFX1BBVEg+PCFbQ0RBVEFbL3Zhci9saWIvb25lLy9kYXRhc3RvcmVzLzEwNF1dPjwvQkFTRV9QQVRIPjxUWVBFPjA8L1RZUEU+PERJU0tfVFlQRT4wPC9ESVNLX1RZUEU+PFNUQVRFPjA8L1NUQVRFPjxDTFVTVEVSUz48SUQ+MTAxPC9JRD48L0NMVVNURVJTPjxUT1RBTF9NQj4zMzI4MDwvVE9UQUxfTUI+PEZSRUVfTUI+MjA5ODc8L0ZSRUVfTUI+PFVTRURfTUI+MTIyOTM8L1VTRURfTUI+PElNQUdFUz48SUQ+Mzc8L0lEPjxJRD4zODwvSUQ+PC9JTUFHRVM+PFRFTVBMQVRFPjxBTExPV19PUlBIQU5TPjwhW0NEQVRBW05PXV0+PC9BTExPV19PUlBIQU5TPjxDTE9ORV9UQVJHRVQ+PCFbQ0RBVEFbU1lTVEVNXV0+PC9DTE9ORV9UQVJHRVQ+PERJU0tfVFlQRT48IVtDREFUQVtGSUxFXV0+PC9ESVNLX1RZUEU+PERTX01BRD48IVtDREFUQVt2Y2VudGVyXV0+PC9EU19NQUQ+PExOX1RBUkdFVD48IVtDREFUQVtOT05FXV0+PC9MTl9UQVJHRVQ+PFJFU1RSSUNURURfRElSUz48IVtDREFUQVsvXV0+PC9SRVNUUklDVEVEX0RJUlM+PFNBRkVfRElSUz48IVtDREFUQVsvdmFyL3RtcF1dPjwvU0FGRV9ESVJTPjxUTV9NQUQ+PCFbQ0RBVEFbdmNlbnRlcl1dPjwvVE1fTUFEPjxUWVBFPjwhW0NEQVRBW0lNQUdFX0RTXV0+PC9UWVBFPjxWQ0VOVEVSX0RDX05BTUU+PCFbQ0RBVEFbQmx1ZUlUXV0+PC9WQ0VOVEVSX0RDX05BTUU+PFZDRU5URVJfRENfUkVGPjwhW0NEQVRBW2RhdGFjZW50ZXItMl1dPjwvVkNFTlRFUl9EQ19SRUY+PFZDRU5URVJfRFNfTkFNRT48IVtDREFUQVtkYXRhc3RvcmUxXV0+PC9WQ0VOVEVSX0RTX05BTUU+PFZDRU5URVJfRFNfUkVGPjwhW0NEQVRBW2RhdGFzdG9yZS0xMF1dPjwvVkNFTlRFUl9EU19SRUY+PFZDRU5URVJfSE9TVD48IVtDREFUQVt2Y2VudGVyLmNsb3VkLmJsdWVpdC5jb20uYnJdXT48L1ZDRU5URVJfSE9TVD48VkNFTlRFUl9JTlNUQU5DRV9JRD48IVtDREFUQVthNmRhYTc5MC01MGNhLTQ5YmEtYjVmMS0xMThhN2RkMjY3YTVdXT48L1ZDRU5URVJfSU5TVEFOQ0VfSUQ+PFZDRU5URVJfUEFTU1dPUkQ+PCFbQ0RBVEFbdkh6TkRNVU5tMU9hbExEeWp3T296Zz09XV0+PC9WQ0VOVEVSX1BBU1NXT1JEPjxWQ0VOVEVSX1VTRVI+PCFbQ0RBVEFbYWRtaW5pc3RyYXRvckBibHVlaXQuY2xvdWRdXT48L1ZDRU5URVJfVVNFUj48L1RFTVBMQVRFPjwvREFUQVNUT1JFPjwvRFNfRFJJVkVSX0FDVElPTl9EQVRBPg== 39
Thu Jul 12 11:48:27 2018 [Z0][ImM][E]: Error cloning img one/38/11116544-Core-currentiso.iso to datastore1 Reason: "InvalidArgument: A specified parameter was not correct: fileType"
Thu Jul 12 11:48:27 2018 [Z0][ImM][E]: ["/usr/lib/one/ruby/vendors/rbvmomi/lib/rbvmomi/vim/Task.rb:11:in `wait_for_completion'", "/usr/lib/one/ruby/vcenter_driver/datastore.rb:349:in `copy_virtual_disk'", "/var/lib/one/remotes/datastore/vcenter/clone:68:in `<main>'"]
Thu Jul 12 11:48:27 2018 [Z0][ImM][E]: Error cloning from Image 38: Error cloning img one/38/11116544-Core-currentiso.iso to datastore1 Reason: "InvalidArgument: A specified parameter was not correct: fileType"
Thu Jul 12 11:48:27 2018 [Z0][InM][D]: Monitoring datastore datastore1(IMG) (104)
Thu Jul 12 11:48:28 2018 [Z0][ReM][D]: Req:8096 UID:0 one.datastore.info invoked , 104
Thu Jul 12 11:48:28 2018 [Z0][ReM][D]: Req:8096 UID:0 one.datastore.info result SUCCESS, "<DATASTORE><ID>104</..."
Thu Jul 12 11:48:28 2018 [Z0][ReM][D]: Req:7840 UID:0 one.system.config invoked
Thu Jul 12 11:48:28 2018 [Z0][ReM][D]: Req:7840 UID:0 one.system.config result SUCCESS, "<TEMPLATE><AUTH_MAD>..."
Thu Jul 12 11:48:28 2018 [Z0][ImM][D]: Datastore datastore1(IMG) (104) successfully monitored.
Thu Jul 12 11:48:29 2018 [Z0][ImM][I]: Image cloned and ready to use.
Thu Jul 12 11:48:29 2018 [Z0][InM][D]: Monitoring datastore datastore1(IMG) (104)
Regards
Carlos