{"action":"opened","issue":{"url":"https://api.github.com/repos/vmware/vic/issues/3264","repository_url":"https://api.github.com/repos/vmware/vic","labels_url":"https://api.github.com/repos/vmware/vic/issues/3264/labels{/name}","comments_url":"https://api.github.com/repos/vmware/vic/issues/3264/comments","events_url":"https://api.github.com/repos/vmware/vic/issues/3264/events","html_url":"https://github.com/vmware/vic/issues/3264","id":190772985,"number":3264,"title":"VCH's deploying but not finishing in reasonably-complex environment.","user":{"login":"Chopper3","id":23527100,"avatar_url":"https://avatars.githubusercontent.com/u/23527100?v=3","gravatar_id":"","url":"https://api.github.com/users/Chopper3","html_url":"https://github.com/Chopper3","followers_url":"https://api.github.com/users/Chopper3/followers","following_url":"https://api.github.com/users/Chopper3/following{/other_user}","gists_url":"https://api.github.com/users/Chopper3/gists{/gist_id}","starred_url":"https://api.github.com/users/Chopper3/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/Chopper3/subscriptions","organizations_url":"https://api.github.com/users/Chopper3/orgs","repos_url":"https://api.github.com/users/Chopper3/repos","events_url":"https://api.github.com/users/Chopper3/events{/privacy}","received_events_url":"https://api.github.com/users/Chopper3/received_events","type":"User","site_admin":false},"labels":[],"state":"open","locked":false,"assignee":null,"assignees":[],"milestone":null,"comments":0,"created_at":"2016-11-21T16:46:37Z","updated_at":"2016-11-21T16:46:37Z","closed_at":null,"body":"v 0.7.0\r\n\r\nI'm an infrastructure guy at a large UK telco - we're very interested in VIC so I've been following its development and test deploying most major releases. 0.7.0 seems to do everything I need of it in my home lab (simple 1 x vCenter, 2 x host environment with no firewalls or proxies), so I decided to test deploying into one of our lab environments (1 x vCenter, 20 x HPE hosts all on 6.0u2). After a fair bit of faffing about with the cluster/port-group/storage pathing (see CormacH's recent blog post) I can now build VCHs - but they don't 'finish'.\r\n\r\nHere's the output of a 'create' using --debug;\r\n\r\nroot@rm101vicc [ ~/vic ]# ./vic-machine-linux create  --target 'rm101vcenter.room101.com' --user vicadmin@vsphere.local --password 29Ph05il69BM! --compute-resource '/Room101/host/Main-Cluster/Resources/vSphere Integrated Containers/VCHs' --thumbprint D1:D5:00:99:80:28:51:7D:BB:A2:03:8D:7A:5E:7D:83:1E:B9:45:51 --no-tlsverify  --insecure-registry http://rm101harbor.room101.com --image-store 'NotMe-rm101nas01-vms/vSphere Integrated Containers - ImageStore/rm101vch4050' --volume-store 'NotMe-rm101nas01-vms/vSphere Integrated Containers - VolumeStore/rm101vch4050:rm101vch4050' --bridge-network '/Room101/network/VOSP Networks/rm101vch4050' --bridge-network-range '192.168.100.0/24' --external-network '/Room101/network/VOSP Networks/vSphere Integrated Containers' --external-network-ip '10.101.33.50/24' --external-network-gateway '10.101.33.1/24' --name 'rm101vch4050' --dns-server=10.101.5.7 --dns-server=10.101.32.137 --http-proxy http://proxy.room101.com:8080 --https-proxy https://proxy.room101.com:8080 --timeout '10m0s' --debug 1\r\ntime=2016-11-17T14:52:41.623946876Z level=debug msg=[BEGIN] [github.com/vmware/vic/cmd/vic-machine/create.(*Create).processParams:455]\r\nDEBU[2016-11-17T14:52:41Z] external network: IP {\"10.101.33.50\" \"ffffff00\"} gateway {\"10.101.33.1\" \"ffffff00\"}\r\nDEBU[2016-11-17T14:52:41Z] VCH DNS servers: [10.101.5.7 10.101.32.137]\r\ntime=2016-11-17T14:52:41.624256868Z level=debug msg=[BEGIN] [github.com/vmware/vic/cmd/vic-machine/create.(*Create).loadCertificates:782]\r\ntime=2016-11-17T14:52:41.624287687Z level=debug msg=[ END ] [github.com/vmware/vic/cmd/vic-machine/create.(*Create).loadCertificates:782] [29.596µs]\r\ntime=2016-11-17T14:52:41.624332410Z level=debug msg=[BEGIN] [github.com/vmware/vic/cmd/vic-machine/create.(*Create).generateCertificates:815]\r\nINFO[2016-11-17T14:52:41Z] Generating self-signed certificate/key pair - private key in ./rm101vch4050/key.pem\r\ntime=2016-11-17T14:52:41.624489239Z level=debug msg=[BEGIN] [github.com/vmware/vic/pkg/certificate.CreateSelfSigned:251]\r\ntime=2016-11-17T14:52:41.892337750Z level=debug msg=[BEGIN] [github.com/vmware/vic/pkg/certificate.createCertificate:148]\r\ntime=2016-11-17T14:52:41.896256832Z level=debug msg=[ END ] [github.com/vmware/vic/pkg/certificate.createCertificate:148] [3.921139ms]\r\ntime=2016-11-17T14:52:41.896320226Z level=debug msg=[ END ] [github.com/vmware/vic/pkg/certificate.CreateSelfSigned:251] [271.824032ms]\r\ntime=2016-11-17T14:52:41.896362411Z level=debug msg=[ END ] [github.com/vmware/vic/cmd/vic-machine/create.(*Create).generateCertificates:815] [272.028536ms]\r\nWARN[2016-11-17T14:52:41Z] Configuring without TLS verify - client authentication disabled\r\ntime=2016-11-17T14:52:41.896457804Z level=debug msg=[BEGIN] [github.com/vmware/vic/cmd/vic-machine/create.(*Create).processVolumeStores:737]\r\ntime=2016-11-17T14:52:41.896512484Z level=debug msg=[ END ] [github.com/vmware/vic/cmd/vic-machine/create.(*Create).processVolumeStores:737] [53.963µs]\r\ntime=2016-11-17T14:52:41.896562113Z level=debug msg=[ END ] [github.com/vmware/vic/cmd/vic-machine/create.(*Create).processParams:455] [272.616665ms]\r\ntime=2016-11-17T14:52:41.896614243Z level=debug msg=[BEGIN] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).CheckImagesFiles:77]\r\ntime=2016-11-17T14:52:41.896709730Z level=debug msg=[BEGIN] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).checkImageVersion:162]\r\ntime=2016-11-17T14:52:41.896759853Z level=debug msg=[BEGIN] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).GetImageVersion:133]\r\ntime=2016-11-17T14:52:41.896823862Z level=debug msg=[ END ] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).GetImageVersion:133] [63.442µs]\r\ntime=2016-11-17T14:52:41.896863612Z level=debug msg=[BEGIN] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).getNoCommitHashVersion:188]\r\ntime=2016-11-17T14:52:41.896888656Z level=debug msg=[ END ] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).getNoCommitHashVersion:188] [22.78µs]\r\ntime=2016-11-17T14:52:41.896922695Z level=debug msg=[BEGIN] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).getNoCommitHashVersion:188]\r\ntime=2016-11-17T14:52:41.896948247Z level=debug msg=[ END ] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).getNoCommitHashVersion:188] [24.589µs]\r\ntime=2016-11-17T14:52:41.896967231Z level=debug msg=[ END ] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).checkImageVersion:162] [301.998µs]\r\ntime=2016-11-17T14:52:41.897021185Z level=debug msg=[BEGIN] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).checkImageVersion:162]\r\ntime=2016-11-17T14:52:41.897059337Z level=debug msg=[BEGIN] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).GetImageVersion:133]\r\ntime=2016-11-17T14:52:41.897093997Z level=debug msg=[ END ] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).GetImageVersion:133] [44.73µs]\r\ntime=2016-11-17T14:52:41.897141049Z level=debug msg=[BEGIN] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).getNoCommitHashVersion:188]\r\ntime=2016-11-17T14:52:41.897180780Z level=debug msg=[ END ] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).getNoCommitHashVersion:188] [38.517µs]\r\ntime=2016-11-17T14:52:41.897208242Z level=debug msg=[BEGIN] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).getNoCommitHashVersion:188]\r\ntime=2016-11-17T14:52:41.897239385Z level=debug msg=[ END ] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).getNoCommitHashVersion:188] [29.303µs]\r\ntime=2016-11-17T14:52:41.897261607Z level=debug msg=[ END ] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).checkImageVersion:162] [239.763µs]\r\ntime=2016-11-17T14:52:41.897287844Z level=debug msg=[ END ] [github.com/vmware/vic/cmd/vic-machine/common.(*Images).CheckImagesFiles:77] [671.031µs]\r\nINFO[2016-11-17T14:52:41Z] ### Installing VCH ####\r\ntime=2016-11-17T14:52:41.897392864Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.CreateNoDCCheck:80]\r\nDEBU[2016-11-17T14:52:41Z] Using to login by username/password\r\nDEBU[2016-11-17T14:52:42Z] Session Environment Info:                     API Type=VirtualCenter API Version=6.0 Build=3634794 Name=VMware vCenter Server OS Type=linux-x64 Product ID=vpx UUID=4f14e124-d8f9-4f57-a354-0197f9c9f3b6 Vendor=VMware, Inc. Version=6.0.0\r\nDEBU[2016-11-17T14:52:42Z] vSphere resource cache populating...\r\nDEBU[2016-11-17T14:52:42Z] Cached dc:\r\nDEBU[2016-11-17T14:52:42Z] Error count populating vSphere cache: (3)\r\ntime=2016-11-17T14:52:42.084490790Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.CreateNoDCCheck:80] [187.090758ms]\r\ntime=2016-11-17T14:52:42.084614691Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).Validate:231]\r\nINFO[2016-11-17T14:52:42Z] Validating supplied configuration\r\ntime=2016-11-17T14:52:42.084893113Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).basics:267]\r\nDEBU[2016-11-17T14:52:42Z] Setting scratch image size to 8000000 KB in VCHConfig\r\ntime=2016-11-17T14:52:42.085121330Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).basics:267] [224.51µs]\r\ntime=2016-11-17T14:52:42.085233897Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).target:311]\r\ntime=2016-11-17T14:52:42.085353776Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).managedbyVC:337]\r\ntime=2016-11-17T14:52:42.085474521Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).managedbyVC:337] [117.389µs]\r\ntime=2016-11-17T14:52:42.085562210Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).target:311] [333.753µs]\r\ntime=2016-11-17T14:52:42.085649618Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).compute:36]\r\ntime=2016-11-17T14:52:42.085759408Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).ResourcePoolHelper:78] /Room101/host/Main-Cluster/Resources/vSphere Integrated Containers/VCHs\r\ntime=2016-11-17T14:52:42.085831396Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).computePathToInventoryPath:513] /Room101/host/Main-Cluster/Resources/vSphere Integrated Containers/VCHs\r\nDEBU[2016-11-17T14:52:42Z] Path is treated as absolute given datacenter prefix \"/Room101\"\r\ntime=2016-11-17T14:52:42.085908572Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).computePathToInventoryPath:513] [73.473µs] /Room101/host/Main-Cluster/Resources/vSphere Integrated Containers/VCHs\r\nDEBU[2016-11-17T14:52:42Z] Converted original path \"/Room101/host/Main-Cluster/Resources/vSphere Integrated Containers/VCHs\" to \"/Room101/host/Main-Cluster/Resources/vSphere Integrated Containers/VCHs\"\r\nDEBU[2016-11-17T14:52:42Z] Selected compute resource \"/Room101/host/Main-Cluster/Resources/vSphere Integrated Containers/VCHs\"\r\ntime=2016-11-17T14:52:42.097924900Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).ResourcePoolHelper:78] [12.16821ms] /Room101/host/Main-Cluster/Resources/vSphere Integrated Containers/VCHs\r\ntime=2016-11-17T14:52:42.097969959Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inventoryPathToCluster:582] /Room101/host/Main-Cluster/Resources/vSphere Integrated Containers/VCHs\r\ntime=2016-11-17T14:52:42.098018115Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inventoryPathToCluster:582] [47.357µs] /Room101/host/Main-Cluster/Resources/vSphere Integrated Containers/VCHs\r\ntime=2016-11-17T14:52:42.103399676Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).compute:36] [17.751294ms]\r\ntime=2016-11-17T14:52:42.103462897Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).storage:34]\r\ntime=2016-11-17T14:52:42.103506772Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).DatastoreHelper:70] NotMe-rm101nas01-vms/vSphere Integrated Containers - ImageStore/rm101vch4050\r\ntime=2016-11-17T14:52:42.106903496Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).DatastoreHelper:70] [3.406007ms] NotMe-rm101nas01-vms/vSphere Integrated Containers - ImageStore/rm101vch4050\r\ntime=2016-11-17T14:52:42.106963805Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).DatastoreHelper:70] NotMe-rm101nas01-vms/vSphere Integrated Containers - VolumeStore/rm101vch4050\r\ntime=2016-11-17T14:52:42.110561161Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).DatastoreHelper:70] [3.600339ms] NotMe-rm101nas01-vms/vSphere Integrated Containers - VolumeStore/rm101vch4050\r\ntime=2016-11-17T14:52:42.110702374Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).storage:34] [7.20672ms]\r\ntime=2016-11-17T14:52:42.110815055Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).network:168]\r\ntime=2016-11-17T14:52:42.110914379Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).portGroupConfig:86]\r\ntime=2016-11-17T14:52:42.111003359Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).portGroupConfig:86] [87.101µs]\r\ntime=2016-11-17T14:52:42.111087132Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).checkPortGroups:112]\r\ntime=2016-11-17T14:52:42.111202139Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).checkPortGroups:112] [113.469µs]\r\ntime=2016-11-17T14:52:42.111304301Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).configureSharedPortGroups:145]\r\nINFO[2016-11-17T14:52:42Z] Configuring static IP for additional networks using port group \"/Room101/network/VOSP Networks/vSphere Integrated Containers\"\r\ntime=2016-11-17T14:52:42.111514812Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).configureSharedPortGroups:145] [200.568µs]\r\ntime=2016-11-17T14:52:42.111612476Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).getEndpoint:39]\r\nDEBU[2016-11-17T14:52:42Z] Setting static IP for \"external\" on port group \"/Room101/network/VOSP Networks/vSphere Integrated Containers\"\r\ntime=2016-11-17T14:52:42.111860788Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).networkHelper:406] /Room101/network/VOSP Networks/vSphere Integrated Containers\r\ntime=2016-11-17T14:52:42.111977298Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).getNetwork:388] /Room101/network/VOSP Networks/vSphere Integrated Containers\r\ntime=2016-11-17T14:52:42.122800960Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).getNetwork:388] [10.833191ms] /Room101/network/VOSP Networks/vSphere Integrated Containers\r\ntime=2016-11-17T14:52:42.122868778Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).networkHelper:406] [11.001674ms] /Room101/network/VOSP Networks/vSphere Integrated Containers\r\nDEBU[2016-11-17T14:52:42Z] NetworkEndpoint: &{{  external } true 10.101.33.50/24 {<nil> <nil>} {{ DistributedVirtualPortgroup:dvportgroup-1761 external }  {10.101.33.1 ffffff00} true [10.101.5.7 10.101.32.137] [] []} []}\r\ntime=2016-11-17T14:52:42.123003955Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).getEndpoint:39] [11.392497ms]\r\ntime=2016-11-17T14:52:42.123059955Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).getEndpoint:39]\r\nDEBU[2016-11-17T14:52:42Z] Setting static IP for \"client\" on port group \"/Room101/network/VOSP Networks/vSphere Integrated Containers\"\r\ntime=2016-11-17T14:52:42.123174608Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).networkHelper:406] /Room101/network/VOSP Networks/vSphere Integrated Containers\r\ntime=2016-11-17T14:52:42.123222446Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).getNetwork:388] /Room101/network/VOSP Networks/vSphere Integrated Containers\r\ntime=2016-11-17T14:52:42.138050251Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).getNetwork:388] [14.821055ms] /Room101/network/VOSP Networks/vSphere Integrated Containers\r\ntime=2016-11-17T14:52:42.138182374Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).networkHelper:406] [15.002023ms] /Room101/network/VOSP Networks/vSphere Integrated Containers\r\nDEBU[2016-11-17T14:52:42Z] NetworkEndpoint: &{{  client } true 10.101.33.50/24 {<nil> <nil>} {{ DistributedVirtualPortgroup:dvportgroup-1761 client }  {10.101.33.1 ffffff00} false [10.101.5.7 10.101.32.137] [] []} []}\r\ntime=2016-11-17T14:52:42.138479295Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).getEndpoint:39] [15.412106ms]\r\ntime=2016-11-17T14:52:42.138588641Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).getEndpoint:39]\r\nDEBU[2016-11-17T14:52:42Z] Setting static IP for \"management\" on port group \"/Room101/network/VOSP Networks/vSphere Integrated Containers\"\r\ntime=2016-11-17T14:52:42.138809209Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).networkHelper:406] /Room101/network/VOSP Networks/vSphere Integrated Containers\r\ntime=2016-11-17T14:52:42.138904302Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).getNetwork:388] /Room101/network/VOSP Networks/vSphere Integrated Containers\r\ntime=2016-11-17T14:52:42.157170554Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).getNetwork:388] [18.266683ms] /Room101/network/VOSP Networks/vSphere Integrated Containers\r\ntime=2016-11-17T14:52:42.157243089Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).networkHelper:406] [18.431636ms] /Room101/network/VOSP Networks/vSphere Integrated Containers\r\nDEBU[2016-11-17T14:52:42Z] NetworkEndpoint: &{{   } true 10.101.33.50/24 {<nil> <nil>} {{ DistributedVirtualPortgroup:dvportgroup-1761 management }  {10.101.33.1 ffffff00} false [10.101.5.7 10.101.32.137] [] []} []}\r\ntime=2016-11-17T14:52:42.157357144Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).getEndpoint:39] [18.769454ms]\r\nDEBU[2016-11-17T14:52:42Z] Network configuration:\r\nDEBU[2016-11-17T14:52:42Z]      Network: external NetworkEndpoint: &{{  external } true 10.101.33.50/24 {<nil> <nil>} {{ DistributedVirtualPortgroup:dvportgroup-1761 external }  {10.101.33.1 ffffff00} true [10.101.5.7 10.101.32.137] [] []} []}\r\nDEBU[2016-11-17T14:52:42Z]      Network: client NetworkEndpoint: &{{  client } true 10.101.33.50/24 {<nil> <nil>} {{ DistributedVirtualPortgroup:dvportgroup-1761 client }  {10.101.33.1 ffffff00} false [10.101.5.7 10.101.32.137] [] []} []}\r\nDEBU[2016-11-17T14:52:42Z]      Network: management NetworkEndpoint: &{{   } true 10.101.33.50/24 {<nil> <nil>} {{ DistributedVirtualPortgroup:dvportgroup-1761 management }  {10.101.33.1 ffffff00} false [10.101.5.7 10.101.32.137] [] []} []}\r\ntime=2016-11-17T14:52:42.157675630Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).dpgHelper:445] /Room101/network/VOSP Networks/rm101vch4050\r\ntime=2016-11-17T14:52:42.157716346Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).getNetwork:388] /Room101/network/VOSP Networks/rm101vch4050\r\ntime=2016-11-17T14:52:42.167375117Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).getNetwork:388] [9.656644ms] /Room101/network/VOSP Networks/rm101vch4050\r\ntime=2016-11-17T14:52:42.167433713Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).dpgHelper:445] [9.756778ms] /Room101/network/VOSP Networks/rm101vch4050\r\ntime=2016-11-17T14:52:42.167475600Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).checkVDSMembership:478] dvportgroup-1757\r\ntime=2016-11-17T14:52:42.174464591Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.174533283Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [67.081µs]\r\ntime=2016-11-17T14:52:42.174575095Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.174632534Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [57.303µs]\r\ntime=2016-11-17T14:52:42.174668165Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.174700874Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [23.551µs]\r\ntime=2016-11-17T14:52:42.174724722Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.174761216Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [35.223µs]\r\ntime=2016-11-17T14:52:42.174792268Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.174828359Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [34.918µs]\r\ntime=2016-11-17T14:52:42.174856497Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.174907772Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [49.58µs]\r\ntime=2016-11-17T14:52:42.174968136Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.175005309Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [36.51µs]\r\ntime=2016-11-17T14:52:42.175046680Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.175082439Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [35.18µs]\r\ntime=2016-11-17T14:52:42.175131389Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.175168993Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [37.172µs]\r\ntime=2016-11-17T14:52:42.175215153Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.175240106Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [24.498µs]\r\ntime=2016-11-17T14:52:42.175304622Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.175354831Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [50.38µs]\r\ntime=2016-11-17T14:52:42.175406660Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.175453778Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [45.414µs]\r\ntime=2016-11-17T14:52:42.175504422Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.175543429Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [39.541µs]\r\ntime=2016-11-17T14:52:42.175604108Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.175646905Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [52.785µs]\r\ntime=2016-11-17T14:52:42.175685189Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.175733376Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [47.385µs]\r\ntime=2016-11-17T14:52:42.175772161Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.175796233Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [24.078µs]\r\ntime=2016-11-17T14:52:42.175842398Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.175869440Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [34.315µs]\r\ntime=2016-11-17T14:52:42.175919634Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466]\r\ntime=2016-11-17T14:52:42.175965914Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).inDVP:466] [52.816µs]\r\nINFO[2016-11-17T14:52:42Z] vDS configuration OK on \"/Room101/network/VOSP Networks/rm101vch4050\"\r\ntime=2016-11-17T14:52:42.176049758Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).checkVDSMembership:478] [8.573357ms] dvportgroup-1757\r\ntime=2016-11-17T14:52:42.176101529Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).nicNumbers:369]\r\ntime=2016-11-17T14:52:42.176140048Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).nicNumbers:369] [27.942µs]\r\ntime=2016-11-17T14:52:42.176181421Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).network:168] [65.368267ms]\r\ntime=2016-11-17T14:52:42.176240735Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).CheckFirewall:37]\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc01bl02.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc01bl12.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc02bl09.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc02bl10.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc01bl11.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc01bl03.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc01bl04.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc01bl09.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc01bl10.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc02bl01.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc02bl02.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc02bl03.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc02bl04.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc02bl05.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc02bl06.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc01bl01.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc02bl07.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall status: ENABLED on \"/Room101/host/Main-Cluster/rm101enc02bl08.room101.com\"\r\nINFO[2016-11-17T14:52:42Z] Firewall configuration OK on hosts:\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc01bl02.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc01bl12.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc02bl09.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc02bl10.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc01bl11.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc01bl03.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc01bl04.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc01bl09.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc01bl10.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc02bl01.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc02bl02.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc02bl03.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc02bl04.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc02bl05.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc02bl06.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc01bl01.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc02bl07.room101.com\"\r\nINFO[2016-11-17T14:52:42Z]   \"/Room101/host/Main-Cluster/rm101enc02bl08.room101.com\"\r\ntime=2016-11-17T14:52:42.996739075Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).CheckFirewall:37] [820.494291ms]\r\nINFO[2016-11-17T14:52:43Z] License check OK on hosts:\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc01bl02.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc01bl12.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc02bl09.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc02bl10.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc01bl11.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc01bl03.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc01bl04.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc01bl09.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc01bl10.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc02bl01.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc02bl02.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc02bl03.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc02bl04.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc02bl05.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc02bl06.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc01bl01.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc02bl07.room101.com\"\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/rm101enc02bl08.room101.com\"\r\ntime=2016-11-17T14:52:43.720368445Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).CheckDrs:273]\r\nINFO[2016-11-17T14:52:43Z] DRS check OK on:\r\nINFO[2016-11-17T14:52:43Z]   \"/Room101/host/Main-Cluster/Resources/vSphere Integrated Containers/VCHs\"\r\ntime=2016-11-17T14:52:43.725409691Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).CheckDrs:273] [5.04694ms]\r\ntime=2016-11-17T14:52:43.725461903Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).certificate:362]\r\ntime=2016-11-17T14:52:43.726107253Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).certificate:362] [644.029µs]\r\ntime=2016-11-17T14:52:43.726172053Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).certificateAuthorities:381]\r\nDEBU[2016-11-17T14:52:43Z] Configuring without client verification due to empty certificate authorities\r\ntime=2016-11-17T14:52:43.726275320Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).certificateAuthorities:381] [102.358µs]\r\ntime=2016-11-17T14:52:43.726320156Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).compatibility:406]\r\ntime=2016-11-17T14:52:43.778830788Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).checkDatastoresAreWriteable:457]\r\ntime=2016-11-17T14:52:43.841104490Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).checkDatastoresAreWriteable:457] [62.271369ms]\r\ntime=2016-11-17T14:52:43.841274747Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).compatibility:406] [114.947631ms]\r\ntime=2016-11-17T14:52:43.841387867Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).ListIssues:206]\r\ntime=2016-11-17T14:52:43.841486562Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).ListIssues:206] [96.895µs]\r\ntime=2016-11-17T14:52:43.841605302Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).Validate:231] [1.756980934s]\r\ntime=2016-11-17T14:52:43.841701530Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/validate.(*Validator).AddDeprecatedFields:601]\r\nDEBU[2016-11-17T14:52:43Z] Datacenter: \"Room101\", Cluster: \"/Room101/host/Main-Cluster\", Resource Pool: \"/Room101/host/Main-Cluster/Resources/vSphere Integrated Containers/VCHs\"\r\ntime=2016-11-17T14:52:43.841979252Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/validate.(*Validator).AddDeprecatedFields:601] [273.551µs]\r\ntime=2016-11-17T14:52:43.842114425Z level=debug msg=[BEGIN] [github.com/vmware/vic/pkg/certificate.CreateSelfSigned:251]\r\ntime=2016-11-17T14:52:44.080867328Z level=debug msg=[BEGIN] [github.com/vmware/vic/pkg/certificate.createCertificate:148]\r\ntime=2016-11-17T14:52:44.085224843Z level=debug msg=[ END ] [github.com/vmware/vic/pkg/certificate.createCertificate:148] [4.362158ms]\r\ntime=2016-11-17T14:52:44.085305547Z level=debug msg=[ END ] [github.com/vmware/vic/pkg/certificate.CreateSelfSigned:251] [243.189737ms]\r\nINFO[2016-11-17T14:52:44Z]\r\ntime=2016-11-17T14:52:44.085460132Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.NewDispatcher:78]\r\ntime=2016-11-17T14:52:44.085537061Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).InitDiagnosticLogs:95]\r\ntime=2016-11-17T14:52:46.817712154Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).InitDiagnosticLogs:95] [2.732165985s]\r\ntime=2016-11-17T14:52:46.817835037Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.NewDispatcher:78] [2.732373903s]\r\ntime=2016-11-17T14:52:46.817917611Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).CreateVCH:37] rm101vch4050\r\ntime=2016-11-17T14:52:46.817960749Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).checkExistence:93]\r\ntime=2016-11-17T14:52:46.818026571Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).findVirtualApp:107] /Room101/host/Main-Cluster/Resources/vSphere Integrated Containers/VCHs/rm101vch4050\r\ntime=2016-11-17T14:52:46.832672591Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).findVirtualApp:107] [14.646942ms] /Room101/host/Main-Cluster/Resources/vSphere Integrated Containers/VCHs/rm101vch4050\r\ntime=2016-11-17T14:52:46.832735354Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).findResourcePool:133] /Room101/host/Main-Cluster/Resources/vSphere Integrated Containers/VCHs/rm101vch4050\r\ntime=2016-11-17T14:52:46.845384418Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).findResourcePool:133] [12.635167ms] /Room101/host/Main-Cluster/Resources/vSphere Integrated Containers/VCHs/rm101vch4050\r\ntime=2016-11-17T14:52:46.845435096Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).checkExistence:93] [27.472761ms]\r\ntime=2016-11-17T14:52:46.845492550Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).createVApp:31]\r\nINFO[2016-11-17T14:52:46Z] Creating virtual app \"rm101vch4050\"\r\ntime=2016-11-17T14:52:46.871374436Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).createVApp:31] [25.861446ms]\r\ntime=2016-11-17T14:52:46.871524150Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).createBridgeNetwork:28]\r\ntime=2016-11-17T14:52:46.871639937Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).createBridgeNetwork:28] [114.142µs]\r\ntime=2016-11-17T14:52:46.871754580Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).createVolumeStores:270]\r\nINFO[2016-11-17T14:52:46Z] Creating directory [NotMe-rm101nas01-vms] vSphere Integrated Containers - VolumeStore/rm101vch4050\r\nINFO[2016-11-17T14:52:46Z] Datastore path is [NotMe-rm101nas01-vms] vSphere Integrated Containers - VolumeStore/rm101vch4050\r\ntime=2016-11-17T14:52:46.884030430Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).createVolumeStores:270] [12.277344ms]\r\ntime=2016-11-17T14:52:46.884083222Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).createAppliance:405]\r\nINFO[2016-11-17T14:52:46Z] Creating appliance on target\r\ntime=2016-11-17T14:52:46.884158713Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).createApplianceSpec:289]\r\nDEBU[2016-11-17T14:52:46Z] generating new config secret key\r\ntime=2016-11-17T14:52:46.884950825Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).addIDEController:265]\r\ntime=2016-11-17T14:52:46.884997813Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).addIDEController:265] [44.618µs]\r\ntime=2016-11-17T14:52:46.885046506Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).addParaVirtualSCSIController:277]\r\ntime=2016-11-17T14:52:46.887289015Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).addParaVirtualSCSIController:277] [2.236207ms]\r\ntime=2016-11-17T14:52:46.887359855Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).addNetworkDevices:209]\r\nDEBU[2016-11-17T14:52:46Z] Setting \"bridge\" to slot 192\r\nDEBU[2016-11-17T14:52:46Z] Setting \"external\" to slot 224\r\nINFO[2016-11-17T14:52:46Z] Network role \"client\" is sharing NIC with \"external\"\r\nINFO[2016-11-17T14:52:46Z] Network role \"management\" is sharing NIC with \"external\"\r\ntime=2016-11-17T14:52:46.897134475Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).addNetworkDevices:209] [9.773444ms]\r\ntime=2016-11-17T14:52:46.897171031Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).createApplianceSpec:289] [13.011469ms]\r\nDEBU[2016-11-17T14:52:57Z] vm folder name: \"rm101vch4050\"\r\nDEBU[2016-11-17T14:52:57Z] vm inventory path: \"/\"\r\ntime=2016-11-17T14:52:57.762051770Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).GenerateExtensionName:366]\r\ntime=2016-11-17T14:52:57.763917622Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).GenerateExtensionName:366] [1.863777ms]\r\ntime=2016-11-17T14:52:57.763989495Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).reconfigureApplianceSpec:598]\r\ntime=2016-11-17T14:52:57.764020397Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).configIso:379]\r\ntime=2016-11-17T14:52:57.766963846Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).configIso:379] [2.941772ms]\r\ntime=2016-11-17T14:52:57.767904284Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).reconfigureApplianceSpec:598] [3.912776ms]\r\ntime=2016-11-17T14:52:58.308283853Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).createAppliance:405] [11.424196111s]\r\ntime=2016-11-17T14:52:58.308387411Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).uploadImages:115]\r\nINFO[2016-11-17T14:52:58Z] Uploading images for container\r\nINFO[2016-11-17T14:52:58Z]      \"appliance.iso\"\r\nINFO[2016-11-17T14:52:58Z]      \"bootstrap.iso\"\r\ntime=2016-11-17T14:53:00.512652209Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).uploadImages:115] [2.204239695s]\r\ntime=2016-11-17T14:53:00.512764927Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).RegisterExtension:156] com.vmware.vic.4203199f-23e0-e159-37c8-1bef8d75a10e\r\nINFO[2016-11-17T14:53:00Z] Registering VCH as a vSphere extension\r\ntime=2016-11-17T14:53:04.659785193Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).RegisterExtension:156] [4.147011567s] com.vmware.vic.4203199f-23e0-e159-37c8-1bef8d75a10e\r\ntime=2016-11-17T14:53:04.659818820Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).startAppliance:96]\r\ntime=2016-11-17T14:53:07.126439566Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).ensureApplianceInitializes:857]\r\nINFO[2016-11-17T14:53:07Z] Waiting for IP information\r\ntime=2016-11-17T14:53:07.126493906Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).waitForKey:646] guestinfo.vice..init.networks|client.assigned.IP\r\ntime=2016-11-17T14:53:20.172484407Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).waitForKey:646] [13.045982632s] guestinfo.vice..init.networks|client.assigned.IP\r\nINFO[2016-11-17T14:53:20Z] Waiting for major appliance components to launch\r\nDEBU[2016-11-17T14:53:20Z] waiting for vicadmin to start\r\ntime=2016-11-17T14:53:20.172777347Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).waitForKey:646] guestinfo.vice..init.sessions|vicadmin.started\r\ntime=2016-11-17T14:53:20.185235920Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).waitForKey:646] [12.458324ms] guestinfo.vice..init.sessions|vicadmin.started\r\nDEBU[2016-11-17T14:53:20Z] waiting for docker personality to start\r\ntime=2016-11-17T14:53:20.185379651Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).waitForKey:646] guestinfo.vice..init.sessions|docker-personality.started\r\ntime=2016-11-17T14:53:20.196617042Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).waitForKey:646] [11.243187ms] guestinfo.vice..init.sessions|docker-personality.started\r\nDEBU[2016-11-17T14:53:20Z] waiting for port layer to start\r\ntime=2016-11-17T14:53:20.196743745Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).waitForKey:646] guestinfo.vice..init.sessions|port-layer.started\r\ntime=2016-11-17T14:53:20.209869215Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).waitForKey:646] [13.115127ms] guestinfo.vice..init.sessions|port-layer.started\r\ntime=2016-11-17T14:53:20.209922592Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).applianceConfiguration:633]\r\ntime=2016-11-17T14:53:20.219844478Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).applianceConfiguration:633] [9.920912ms]\r\nDEBU[2016-11-17T14:53:20Z] Obtained IP address for client interface: \"10.101.33.50\"\r\ntime=2016-11-17T14:53:20.219960391Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).ensureApplianceInitializes:857] [13.093520675s]\r\ntime=2016-11-17T14:53:20.220006497Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).startAppliance:96] [15.560185687s]\r\ntime=2016-11-17T14:53:20.220031540Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).CreateVCH:37] [33.402114885s] rm101vch4050\r\ntime=2016-11-17T14:53:20.220054462Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).CheckDockerAPI:678]\r\nDEBU[2016-11-17T14:53:20Z] connection refused\r\nDEBU[2016-11-17T14:53:21Z] Components not yet initialized, retrying\r\nDEBU[2016-11-17T14:53:21Z] connection refused\r\n \r\nThen lots of these last two lines for nearly ten minutes then this lot;\r\n \r\nDEBU[2016-11-17T15:02:41Z] Components not yet initialized, retrying\r\nDEBU[2016-11-17T15:02:41Z] connection refused\r\ntime=2016-11-17T15:02:41.897587672Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).CheckDockerAPI:678] [9m21.677503474s]\r\ntime=2016-11-17T15:02:41.897659013Z level=debug msg=[BEGIN] [github.com/vmware/vic/lib/install/management.(*Dispatcher).CollectDiagnosticLogs:165]\r\nINFO[2016-11-17T15:02:41Z] Collecting 4f14e124-d8f9-4f57-a354-0197f9c9f3b6 vpxd.log\r\nERRO[2016-11-17T15:02:41Z] Failed to collect 4f14e124-d8f9-4f57-a354-0197f9c9f3b6 vpxd.log: context deadline exceeded\r\nWARN[2016-11-17T15:02:41Z] No log data for 4f14e124-d8f9-4f57-a354-0197f9c9f3b6 vpxd.log\r\ntime=2016-11-17T15:02:41.897895174Z level=debug msg=[ END ] [github.com/vmware/vic/lib/install/management.(*Dispatcher).CollectDiagnosticLogs:165] [241.858µs]\r\nERRO[2016-11-17T15:02:41Z] --------------------\r\nERRO[2016-11-17T15:02:41Z] vic-machine-linux failed: Create timed out: use --timeout to add more time\r\n \r\nMaybe you might spot something in this?"}}
{"actor":{"display_login":"Chopper3"}}