111c111 < INFO environment: Environment initialized (#) --- > INFO environment: Environment initialized (#) 118c118 < INFO runner: Running action: environment_plugins_loaded # --- > INFO runner: Running action: environment_plugins_loaded # 122c122 < INFO runner: Running action: environment_load # --- > INFO runner: Running action: environment_load # 140,141c140,141 < INFO loader: Set "70251599303140_machine_vm1" = [["2", #]] < INFO loader: Loading configuration in order: [:home, :root, "70251599303140_machine_vm1"] --- > INFO loader: Set "70119360249620_machine_vm1" = [["2", #]] > INFO loader: Loading configuration in order: [:home, :root, "70119360249620_machine_vm1"] 144,145c144,145 < INFO loader: Set "70251599303140_machine_vm1" = [["2", #]] < INFO loader: Loading configuration in order: [:home, :root, "70251599303140_machine_vm1"] --- > INFO loader: Set "70119360249620_machine_vm1" = [["2", #]] > INFO loader: Loading configuration in order: [:home, :root, "70119360249620_machine_vm1"] 147,148c147,148 < INFO loader: Set :"70251599303140_vm_vm1_skytap-dummy_skytap" = [["2", #], ["2", #]] < INFO loader: Loading configuration in order: [:home, :root, "70251599303140_machine_vm1", :"70251599303140_vm_vm1_skytap-dummy_skytap"] --- > INFO loader: Set :"70119360249620_vm_vm1_skytap-dummy_skytap" = [["2", #], ["2", #]] > INFO loader: Loading configuration in order: [:home, :root, "70119360249620_machine_vm1", :"70119360249620_vm_vm1_skytap-dummy_skytap"] 151c151 < INFO machine: - Box: # --- > INFO machine: - Box: # 161,163c161,163 < INFO runner: Running action: machine_action_read_state # < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # --- > INFO runner: Running action: machine_action_read_state # > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # 166,171c166,171 < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # --- > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # 176c176 < INFO command: With machine: vm1 (#, @cap_logger=#, @name="stderr", @level=0, @formatter=#, @out=#>>], @additive=true, @name="vagrant", @path="", @parent=#, @level=2, @trace=false>, @level=2, @trace=false>, @cap_host_chain=[[:skytap, #<#:0x007fc97132d038>]], @cap_args=[#], @cap_caps={:docker=>##, :proxy_machine=>#}, @results_cache={}>, :hyperv=>##}, @results_cache={}>, :virtualbox=>##, :nic_mac_addresses=>#}, @results_cache={}>}>) --- > INFO command: With machine: vm1 (#, @cap_logger=#, @name="stderr", @level=0, @formatter=#, @out=#>>], @additive=true, @name="vagrant", @path="", @parent=#, @level=2, @trace=false>, @level=2, @trace=false>, @cap_host_chain=[[:skytap, #<#:0x007f8bde2721f0>]], @cap_args=[#], @cap_caps={:docker=>##, :proxy_machine=>#}, @results_cache={}>, :hyperv=>##}, @results_cache={}>, :virtualbox=>##, :nic_mac_addresses=>#}, @results_cache={}>}>) 185,187c185,187 < INFO runner: Running action: machine_action_read_state # < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # --- > INFO runner: Running action: machine_action_read_state # > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # 190,195c190,195 < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # --- > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # 202c202 < INFO batch_action: Starting action: # up {:destroy_on_error=>true, :parallel=>true, :provision_ignore_sentinel=>false, :provision_types=>nil} --- > INFO batch_action: Starting action: # up {:destroy_on_error=>true, :parallel=>true, :provision_ignore_sentinel=>false, :provision_types=>nil} 209,210c209,210 < INFO runner: Running action: machine_action_up # < INFO warden: Calling IN action: # --- > INFO runner: Running action: machine_action_up # > INFO warden: Calling IN action: # 212,213c212,213 < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # --- > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # 216,217c216,217 < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # --- > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # 220,222c220,222 < INFO runner: Running action: machine_action_up # < INFO warden: Calling IN action: # < INFO warden: Calling OUT action: # --- > INFO runner: Running action: machine_action_up # > INFO warden: Calling IN action: # > INFO warden: Calling OUT action: # 225,227c225,227 < INFO runner: Running action: machine_action_up # < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # --- > INFO runner: Running action: machine_action_up # > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # 230,238c230,239 < INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026316 < INFO machine: New machine ID: "7499098" < INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026316 < INFO warden: Calling IN action: # < INFO interface: info: New Skytap environment created: https://cloud.skytap.com/configurations/6026316 < INFO interface: info: ==> vm1: New Skytap environment created: https://cloud.skytap.com/configurations/6026316 < ==> vm1: New Skytap environment created: https://cloud.skytap.com/configurations/6026316 < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # --- > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO machine: New machine ID: "7499148" > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO warden: Calling IN action: # > INFO interface: info: New Skytap environment created: https://cloud.skytap.com/configurations/6026384 > INFO interface: info: ==> vm1: New Skytap environment created: https://cloud.skytap.com/configurations/6026384 > ==> vm1: New Skytap environment created: https://cloud.skytap.com/configurations/6026384 > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # 264c265 < INFO api_client: REST API call: GET /vms/7499098 --- > INFO api_client: REST API call: GET /vms/7499148 286,287c287,288 < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # --- > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # 290,292c291,293 < INFO runner: Running action: machine_action_up # < INFO warden: Calling IN action: # < INFO machine: Calling action: read_state on provider Skytap (7499098) --- > INFO runner: Running action: machine_action_up # > INFO warden: Calling IN action: # > INFO machine: Calling action: read_state on provider Skytap (7499148) 295,297c296,298 < INFO runner: Running action: machine_action_read_state # < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # --- > INFO runner: Running action: machine_action_read_state # > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # 300,308c301,309 < INFO warden: Calling IN action: # < INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026316 < INFO warden: Calling IN action: # < INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026316 < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # --- > INFO warden: Calling IN action: # > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO warden: Calling IN action: # > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # 311,313c312,314 < INFO runner: Running action: machine_action_up # < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # --- > INFO runner: Running action: machine_action_up # > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # 316,318c317,319 < INFO runner: Running action: machine_action_up # < INFO warden: Calling IN action: # < INFO machine: Calling action: read_state on provider Skytap (7499098) --- > INFO runner: Running action: machine_action_up # > INFO warden: Calling IN action: # > INFO machine: Calling action: read_state on provider Skytap (7499148) 321,323c322,324 < INFO runner: Running action: machine_action_read_state # < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # --- > INFO runner: Running action: machine_action_read_state # > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # 326,334c327,335 < INFO warden: Calling IN action: # < INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026316 < INFO warden: Calling IN action: # < INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026316 < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # --- > INFO warden: Calling IN action: # > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO warden: Calling IN action: # > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # 337,339c338,340 < INFO runner: Running action: machine_action_up # < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # --- > INFO runner: Running action: machine_action_up # > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # 341,342c342,343 < INFO api_client: REST API call: PUT /vms/7499098 body: {"hardware":{"cpus":2}} < INFO warden: Calling IN action: # --- > INFO api_client: REST API call: PUT /vms/7499148 body: {"hardware":{"cpus":2}} > INFO warden: Calling IN action: # 344,377c345,378 < INFO api_client: REST API call: PUT /configurations/6026316/vms/7499098/interfaces/nic-3323758-7615800-0 body: {"hostname":"vm1"} < INFO warden: Calling IN action: # < INFO machine: Calling action: read_ssh_info on provider Skytap (7499098) < INFO runner: Preparing hooks for middleware sequence... < INFO runner: 1 hooks defined. < INFO runner: Running action: machine_action_read_ssh_info # < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # < INFO initialize_api_client: Connecting to Skytap... < INFO api_client: REST API call: GET /configurations < INFO warden: Calling IN action: # < INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026316 < INFO warden: Calling IN action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO machine: Calling action: read_ssh_info on provider Skytap (7499098) < INFO runner: Preparing hooks for middleware sequence... < INFO runner: 1 hooks defined. < INFO runner: Running action: machine_action_read_ssh_info # < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # < INFO initialize_api_client: Connecting to Skytap... < INFO api_client: REST API call: GET /configurations < INFO warden: Calling IN action: # < INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026316 < INFO warden: Calling IN action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # --- > INFO api_client: REST API call: PUT /configurations/6026384/vms/7499148/interfaces/nic-3323790-7615844-0 body: {"hostname":"vm1"} > INFO warden: Calling IN action: # > INFO machine: Calling action: read_ssh_info on provider Skytap (7499148) > INFO runner: Preparing hooks for middleware sequence... > INFO runner: 1 hooks defined. > INFO runner: Running action: machine_action_read_ssh_info # > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # > INFO initialize_api_client: Connecting to Skytap... > INFO api_client: REST API call: GET /configurations > INFO warden: Calling IN action: # > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO warden: Calling IN action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO machine: Calling action: read_ssh_info on provider Skytap (7499148) > INFO runner: Preparing hooks for middleware sequence... > INFO runner: 1 hooks defined. > INFO runner: Running action: machine_action_read_ssh_info # > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # > INFO initialize_api_client: Connecting to Skytap... > INFO api_client: REST API call: GET /configurations > INFO warden: Calling IN action: # > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO warden: Calling IN action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # 379,381c380,382 < INFO warden: Calling IN action: # < INFO nfs: NFS pruning. Valid IDs: ["7373244", "7398302", "7397946", "7398178", "7413448", "7426570", "7428872", "7457184", "7470406", "7471690", "7471882", "7471920", "7485318", "7499098"] < INFO host: Execute capability: nfs_prune [#, #, @name="stderr", @level=0, @formatter=#, @out=#>>], @additive=true, @name="vagrant", @path="", @parent=#, @level=2, @trace=false>, @level=2, @trace=false>, @opts={}, @stdin=#>, @stdout=#>, @stderr=#>, @prefix=:vm1, @ui=#, @name="stderr", @level=0, @formatter=#, @out=#>>], @additive=true, @name="vagrant", @path="", @parent=#, @level=2, @trace=false>, @level=2, @trace=false>, @opts={:color=>:default}, @stdin=#>, @stdout=#>, @stderr=#>, @lock=#>>, ["7373244", "7398302", "7397946", "7398178", "7413448", "7426570", "7428872", "7457184", "7470406", "7471690", "7471882", "7471920", "7485318", "7499098"]] (darwin) --- > INFO warden: Calling IN action: # > INFO nfs: NFS pruning. Valid IDs: ["7373244", "7398302", "7397946", "7398178", "7413448", "7426570", "7428872", "7457184", "7470406", "7471690", "7471882", "7471920", "7485318", "7499148"] > INFO host: Execute capability: nfs_prune [#, #, @name="stderr", @level=0, @formatter=#, @out=#>>], @additive=true, @name="vagrant", @path="", @parent=#, @level=2, @trace=false>, @level=2, @trace=false>, @opts={}, @stdin=#>, @stdout=#>, @stderr=#>, @prefix=:vm1, @ui=#, @name="stderr", @level=0, @formatter=#, @out=#>>], @additive=true, @name="vagrant", @path="", @parent=#, @level=2, @trace=false>, @level=2, @trace=false>, @opts={:color=>:default}, @stdin=#>, @stdout=#>, @stderr=#>, @lock=#>>, ["7373244", "7398302", "7397946", "7398178", "7413448", "7426570", "7428872", "7457184", "7470406", "7471690", "7471882", "7471920", "7485318", "7499148"]] (darwin) 386,387c387,388 < INFO bsd: Invalid ID, pruning: 7499058 < INFO warden: Calling IN action: # --- > INFO bsd: Invalid ID, pruning: 7499120 > INFO warden: Calling IN action: # 390,391c391,392 < INFO nfs: NFS pruning. Valid IDs: ["7373244", "7398302", "7397946", "7398178", "7413448", "7426570", "7428872", "7457184", "7470406", "7471690", "7471882", "7471920", "7485318", "7499098"] < INFO host: Execute capability: nfs_prune [#, #, @name="stderr", @level=0, @formatter=#, @out=#>>], @additive=true, @name="vagrant", @path="", @parent=#, @level=2, @trace=false>, @level=2, @trace=false>, @opts={}, @stdin=#>, @stdout=#>, @stderr=#>, @prefix=:vm1, @ui=#, @name="stderr", @level=0, @formatter=#, @out=#>>], @additive=true, @name="vagrant", @path="", @parent=#, @level=2, @trace=false>, @level=2, @trace=false>, @opts={:color=>:default}, @stdin=#>, @stdout=#>, @stderr=#>, @lock=#>>, ["7373244", "7398302", "7397946", "7398178", "7413448", "7426570", "7428872", "7457184", "7470406", "7471690", "7471882", "7471920", "7485318", "7499098"]] (darwin) --- > INFO nfs: NFS pruning. Valid IDs: ["7373244", "7398302", "7397946", "7398178", "7413448", "7426570", "7428872", "7457184", "7470406", "7471690", "7471882", "7471920", "7485318", "7499148"] > INFO host: Execute capability: nfs_prune [#, #, @name="stderr", @level=0, @formatter=#, @out=#>>], @additive=true, @name="vagrant", @path="", @parent=#, @level=2, @trace=false>, @level=2, @trace=false>, @opts={}, @stdin=#>, @stdout=#>, @stderr=#>, @prefix=:vm1, @ui=#, @name="stderr", @level=0, @formatter=#, @out=#>>], @additive=true, @name="vagrant", @path="", @parent=#, @level=2, @trace=false>, @level=2, @trace=false>, @opts={:color=>:default}, @stdin=#>, @stdout=#>, @stderr=#>, @lock=#>>, ["7373244", "7398302", "7397946", "7398178", "7413448", "7426570", "7428872", "7457184", "7470406", "7471690", "7471882", "7471920", "7485318", "7499148"]] (darwin) 394c395 < INFO warden: Calling IN action: # --- > INFO warden: Calling IN action: # 402c403 < INFO warden: Calling IN action: # --- > INFO warden: Calling IN action: # 406,453c407,491 < INFO api_client: REST API call: PUT /vms/7499098 body: {"runstate":"running"} < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO api_client: REST API call: GET /vms/7499098 < INFO warden: Calling IN action: # < INFO wait_for_communicator: Entered hacked version of WaitForCommunicator < INFO machine: Calling action: read_ssh_info on provider Skytap (7499098) < INFO runner: Preparing hooks for middleware sequence... < INFO runner: 1 hooks defined. < INFO runner: Running action: machine_action_read_ssh_info # < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # < INFO initialize_api_client: Connecting to Skytap... < INFO api_client: REST API call: GET /configurations < INFO warden: Calling IN action: # < INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026316 < INFO warden: Calling IN action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # --- > INFO api_client: REST API call: PUT /vms/7499148 body: {"runstate":"running"} > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO api_client: REST API call: GET /vms/7499148 > INFO warden: Calling IN action: # > INFO wait_for_communicator: Entered WaitForCommunicator > INFO interface: output: Waiting for machine to boot. This may take a few minutes... > INFO interface: output: ==> vm1: Waiting for machine to boot. This may take a few minutes... > ==> vm1: Waiting for machine to boot. This may take a few minutes... > INFO machine: Calling action: read_state on provider Skytap (7499148) > INFO runner: Preparing hooks for middleware sequence... > INFO runner: 1 hooks defined. > INFO runner: Running action: machine_action_read_state # > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # > INFO initialize_api_client: Connecting to Skytap... > INFO api_client: REST API call: GET /configurations > INFO machine: Calling action: read_ssh_info on provider Skytap (7499148) > INFO runner: Preparing hooks for middleware sequence... > INFO runner: 1 hooks defined. > INFO runner: Running action: machine_action_read_ssh_info # > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # > INFO initialize_api_client: Connecting to Skytap... > INFO api_client: REST API call: GET /configurations > INFO warden: Calling IN action: # > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO warden: Calling IN action: # > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO warden: Calling IN action: # > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO warden: Calling IN action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO interface: detail: SSH address: 10.1.129.94:22 > INFO interface: detail: vm1: SSH address: 10.1.129.94:22 > vm1: SSH address: 10.1.129.94:22 > INFO interface: detail: SSH username: skytap > INFO interface: detail: vm1: SSH username: skytap > vm1: SSH username: skytap > INFO interface: detail: SSH auth method: password > INFO interface: detail: vm1: SSH auth method: password > vm1: SSH auth method: password > INFO machine: Calling action: read_ssh_info on provider Skytap (7499148) > INFO runner: Preparing hooks for middleware sequence... > INFO runner: 1 hooks defined. > INFO runner: Running action: machine_action_read_ssh_info # > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # > INFO initialize_api_client: Connecting to Skytap... > INFO api_client: REST API call: GET /configurations > INFO warden: Calling IN action: # > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling IN action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # 456c494 < INFO ssh: - Host: 10.1.129.86 --- > INFO ssh: - Host: 10.1.129.94 461,805c499,598 < INFO retryable: Retryable exception raised: # < INFO ssh: Attempting to connect to SSH... < INFO ssh: - Host: 10.1.129.86 < INFO ssh: - Port: 22 < INFO ssh: - Username: skytap < INFO ssh: - Password? true < INFO ssh: - Key Path: [] < INFO retryable: Retryable exception raised: # < INFO ssh: Attempting to connect to SSH... < INFO ssh: - Host: 10.1.129.86 < INFO ssh: - Port: 22 < INFO ssh: - Username: skytap < INFO ssh: - Password? true < INFO ssh: - Key Path: [] < INFO retryable: Retryable exception raised: # < INFO ssh: Attempting to connect to SSH... < INFO ssh: - Host: 10.1.129.86 < INFO ssh: - Port: 22 < INFO ssh: - Username: skytap < INFO ssh: - Password? true < INFO ssh: - Key Path: [] < INFO retryable: Retryable exception raised: # < INFO ssh: Attempting to connect to SSH... < INFO ssh: - Host: 10.1.129.86 < INFO ssh: - Port: 22 < INFO ssh: - Username: skytap < INFO ssh: - Password? true < INFO ssh: - Key Path: [] < INFO ssh: SSH not up: # < INFO machine: Calling action: read_ssh_info on provider Skytap (7499098) < INFO runner: Preparing hooks for middleware sequence... < INFO runner: 1 hooks defined. < INFO runner: Running action: machine_action_read_ssh_info # < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # < INFO initialize_api_client: Connecting to Skytap... < INFO api_client: REST API call: GET /configurations < INFO warden: Calling IN action: # < INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026316 < INFO warden: Calling IN action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO ssh: Attempting SSH connection... < INFO ssh: Attempting to connect to SSH... < INFO ssh: - Host: 10.1.129.86 < INFO ssh: - Port: 22 < INFO ssh: - Username: skytap < INFO ssh: - Password? true < INFO ssh: - Key Path: [] < INFO retryable: Retryable exception raised: # < INFO ssh: Attempting to connect to SSH... < INFO ssh: - Host: 10.1.129.86 < INFO ssh: - Port: 22 < INFO ssh: - Username: skytap < INFO ssh: - Password? true < INFO ssh: - Key Path: [] < INFO retryable: Retryable exception raised: # < INFO ssh: Attempting to connect to SSH... < INFO ssh: - Host: 10.1.129.86 < INFO ssh: - Port: 22 < INFO ssh: - Username: skytap < INFO ssh: - Password? true < INFO ssh: - Key Path: [] < INFO retryable: Retryable exception raised: # < INFO ssh: Attempting to connect to SSH... < INFO ssh: - Host: 10.1.129.86 < INFO ssh: - Port: 22 < INFO ssh: - Username: skytap < INFO ssh: - Password? true < INFO ssh: - Key Path: [] < INFO retryable: Retryable exception raised: # < INFO ssh: Attempting to connect to SSH... < INFO ssh: - Host: 10.1.129.86 < INFO ssh: - Port: 22 < INFO ssh: - Username: skytap < INFO ssh: - Password? true < INFO ssh: - Key Path: [] < INFO ssh: SSH not up: # < INFO machine: Calling action: read_ssh_info on provider Skytap (7499098) < INFO runner: Preparing hooks for middleware sequence... < INFO runner: 1 hooks defined. < INFO runner: Running action: machine_action_read_ssh_info # < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # < INFO initialize_api_client: Connecting to Skytap... < INFO api_client: REST API call: GET /configurations < INFO warden: Calling IN action: # < INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026316 < INFO warden: Calling IN action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO ssh: Attempting SSH connection... < INFO ssh: Attempting to connect to SSH... < INFO ssh: - Host: 10.1.129.86 < INFO ssh: - Port: 22 < INFO ssh: - Username: skytap < INFO ssh: - Password? true < INFO ssh: - Key Path: [] < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO machine: Calling action: read_ssh_info on provider Skytap (7499098) < INFO runner: Preparing hooks for middleware sequence... < INFO runner: 1 hooks defined. < INFO runner: Running action: machine_action_read_ssh_info # < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # < INFO initialize_api_client: Connecting to Skytap... < INFO api_client: REST API call: GET /configurations < INFO warden: Calling IN action: # < INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026316 < INFO warden: Calling IN action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO guest: Autodetecting host type for [#] < INFO ssh: Execute: grep 'ostree=' /proc/cmdline (sudo=false) < INFO ssh: Execute: cat /etc/issue | grep 'Linux Mint' (sudo=false) < INFO ssh: Execute: cat /etc/issue | grep 'Debian' | grep '8' (sudo=false) < INFO ssh: Execute: grep 'Fedora release 1[6789]\|Fedora release 2[0-9]' /etc/redhat-release (sudo=false) < INFO ssh: Execute: cat /etc/pld-release (sudo=false) < INFO ssh: Execute: [ -x /usr/bin/lsb_release ] && /usr/bin/lsb_release -i 2>/dev/null | grep Ubuntu (sudo=false) < INFO guest: Detected: ubuntu! < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO ssh: Inserting key to avoid password: ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDGBnF0xbaBTqMciD5HVkcNp66xGUGdy7EgwOFYurCebms2fZZNBAyY/7Ldz3o5E1yiKBf5VvvHKOZuxBwVZOFTzo/lCcrOW03P8/yWEZJ2eUNPc5WtAIjx2yXbJ5obOOWvPdvuYSKaEEBirOfjvdMjiiZWoRfaaTG3lwy63AfgoMPrDl8toKEoWt9gZGNoF1PzlzvqjMdSW1GzrOjwjQiB5AgiyC+leHPdGDmyFmMWS7Rimu4kTiyfi3QJvJH4XgPodrQNwQZP6KxAXn3xYlJ7RUBb1FE0JsfmfmjlNbpdCNR96M5zylDqQKNnQMZCYxYTl39vMBmr4dANaJTVAHwb vagrant < INFO interface: detail: < Inserting generated public key within guest... < INFO interface: detail: vm1: < vm1: Inserting generated public key within guest... < vm1: < vm1: Inserting generated public key within guest... < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO guest: Execute capability: insert_public_key [#, "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDGBnF0xbaBTqMciD5HVkcNp66xGUGdy7EgwOFYurCebms2fZZNBAyY/7Ldz3o5E1yiKBf5VvvHKOZuxBwVZOFTzo/lCcrOW03P8/yWEZJ2eUNPc5WtAIjx2yXbJ5obOOWvPdvuYSKaEEBirOfjvdMjiiZWoRfaaTG3lwy63AfgoMPrDl8toKEoWt9gZGNoF1PzlzvqjMdSW1GzrOjwjQiB5AgiyC+leHPdGDmyFmMWS7Rimu4kTiyfi3QJvJH4XgPodrQNwQZP6KxAXn3xYlJ7RUBb1FE0JsfmfmjlNbpdCNR96M5zylDqQKNnQMZCYxYTl39vMBmr4dANaJTVAHwb vagrant"] (ubuntu) < INFO ssh: Execute: mkdir -p ~/.ssh (sudo=false) < INFO ssh: Execute: chmod 0700 ~/.ssh (sudo=false) < INFO ssh: Execute: printf 'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDGBnF0xbaBTqMciD5HVkcNp66xGUGdy7EgwOFYurCebms2fZZNBAyY/7Ldz3o5E1yiKBf5VvvHKOZuxBwVZOFTzo/lCcrOW03P8/yWEZJ2eUNPc5WtAIjx2yXbJ5obOOWvPdvuYSKaEEBirOfjvdMjiiZWoRfaaTG3lwy63AfgoMPrDl8toKEoWt9gZGNoF1PzlzvqjMdSW1GzrOjwjQiB5AgiyC+leHPdGDmyFmMWS7Rimu4kTiyfi3QJvJH4XgPodrQNwQZP6KxAXn3xYlJ7RUBb1FE0JsfmfmjlNbpdCNR96M5zylDqQKNnQMZCYxYTl39vMBmr4dANaJTVAHwb vagrant\n' >> ~/.ssh/authorized_keys (sudo=false) < INFO ssh: Execute: chmod 0600 ~/.ssh/authorized_keys (sudo=false) < INFO interface: detail: Removing insecure key from the guest if it's present... < INFO interface: detail: vm1: Removing insecure key from the guest if it's present... < vm1: Removing insecure key from the guest if it's present... < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO guest: Execute capability: remove_public_key [#, "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA6NF8iallvQVp22WDkTkyrtvp9eWW6A8YVr+kz4TjGYe7gHzIw+niNltGEFHzD8+v1I2YJ6oXevct1YeS0o9HZyN1Q9qgCgzUFtdOKLv6IedplqoPkcmF0aYet2PkEDo3MlTBckFXPITAMzF8dJSIFo9D8HfdOV0IAdx4O7PtixWKn5y2hMNG0zQPyUecp4pzC6kivAIhyfHilFR61RGL+GPXQ2MWZWFYbAGjyiYJnAmCP3NOTd0jMZEnDkbUvxhMmBYSdETk1rRgm+R4LOzFUGaHqHDLKLX+FIPKcF96hrucXzcWyLbIbEgE98OHlnVYCzRdK8jlqm8tehUc9c9WhQ== vagrant insecure public key"] (ubuntu) < INFO ssh: Execute: test -f ~/.ssh/authorized_keys (sudo=false) < INFO ssh: Execute: sed -i '/^.*ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA6NF8iallvQVp22WDkTkyrtvp9eWW6A8YVr+kz4TjGYe7gHzIw+niNltGEFHzD8+v1I2YJ6oXevct1YeS0o9HZyN1Q9qgCgzUFtdOKLv6IedplqoPkcmF0aYet2PkEDo3MlTBckFXPITAMzF8dJSIFo9D8HfdOV0IAdx4O7PtixWKn5y2hMNG0zQPyUecp4pzC6kivAIhyfHilFR61RGL+GPXQ2MWZWFYbAGjyiYJnAmCP3NOTd0jMZEnDkbUvxhMmBYSdETk1rRgm+R4LOzFUGaHqHDLKLX+FIPKcF96hrucXzcWyLbIbEgE98OHlnVYCzRdK8jlqm8tehUc9c9WhQ== vagrant insecure public key.*$/d' ~/.ssh/authorized_keys (sudo=false) < INFO interface: detail: Key inserted! Disconnecting and reconnecting using new SSH key... < INFO interface: detail: vm1: Key inserted! Disconnecting and reconnecting using new SSH key... < vm1: Key inserted! Disconnecting and reconnecting using new SSH key... < INFO machine: Calling action: read_ssh_info on provider Skytap (7499098) < INFO runner: Preparing hooks for middleware sequence... < INFO runner: 1 hooks defined. < INFO runner: Running action: machine_action_read_ssh_info # < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # < INFO initialize_api_client: Connecting to Skytap... < INFO api_client: REST API call: GET /configurations < INFO warden: Calling IN action: # < INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026316 < INFO warden: Calling IN action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO ssh: Attempting to correct key permissions to 0600 < INFO ssh: Attempting SSH connection... < INFO ssh: Attempting to connect to SSH... < INFO ssh: - Host: 10.1.129.86 < INFO ssh: - Port: 22 < INFO ssh: - Username: skytap < INFO ssh: - Password? true < INFO ssh: - Key Path: ["/Users/etrue/src/vagrant-skytap/.vagrant/machines/vm1/skytap/private_key"] < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO synced_folders: Invoking synced folder enable: nfs < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO guest: Execute capability: nfs_client_installed [#] (ubuntu) < INFO ssh: Execute: test -x /sbin/mount.nfs (sudo=false) < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO interface: info: Installing NFS client... < INFO interface: info: ==> vm1: Installing NFS client... < ==> vm1: Installing NFS client... < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO guest: Execute capability: nfs_client_install [#] (ubuntu) < INFO ssh: Execute: apt-get -y update (sudo=true) < INFO ssh: Execute: apt-get -y install nfs-common portmap (sudo=true) < INFO environment: Acquired process lock: dotlock < INFO environment: Released process lock: dotlock < INFO environment: Acquired process lock: nfs-export < INFO interface: info: Exporting NFS shared folders... < INFO interface: info: ==> vm1: Exporting NFS shared folders... < ==> vm1: Exporting NFS shared folders... < INFO host: Execute capability: nfs_export [#, #, @name="stderr", @level=0, @formatter=#, @out=#>>], @additive=true, @name="vagrant", @path="", @parent=#, @level=2, @trace=false>, @level=2, @trace=false>, @opts={}, @stdin=#>, @stdout=#>, @stderr=#>, @prefix=:vm1, @ui=#, @name="stderr", @level=0, @formatter=#, @out=#>>], @additive=true, @name="vagrant", @path="", @parent=#, @level=2, @trace=false>, @level=2, @trace=false>, @opts={:color=>:default}, @stdin=#>, @stdout=#>, @stderr=#>, @lock=#>>, "7499098", ["10.1.129.86"], {"/synced"=>{:type=>:nfs, :guestpath=>"/synced", :hostpath=>"/Users/etrue/src/vagrant-skytap/vm1", :disabled=>false, :map_uid=>502, :map_gid=>20, :nfs_udp=>true, :nfs_version=>3, :uuid=>"2221235798"}}] (darwin) < INFO host: Execute capability: nfs_exports_template [#] (darwin) < INFO host: Execute capability: nfs_restart_command [#] (darwin) < INFO subprocess: Starting process: ["/sbin/nfsd", "checkexports"] < INFO subprocess: Vagrant not running in installer, restoring original environment... < INFO bsd: Exporting the following for NFS... < INFO bsd: NFS DIR: ["/Users/etrue/src/vagrant-skytap/vm1"] < INFO bsd: NFS OPTS: {:type=>:nfs, :guestpath=>"/synced", :hostpath=>"/Users/etrue/src/vagrant-skytap/vm1", :disabled=>false, :map_uid=>502, :map_gid=>20, :nfs_udp=>true, :nfs_version=>3, :uuid=>"2221235798", :bsd__nfs_options=>["alldirs", "mapall=502:20"], :bsd__compiled_nfs_options=>"-alldirs -mapall=502:20"} < INFO interface: info: Preparing to edit /etc/exports. Administrator privileges will be required... < INFO interface: info: ==> vm1: Preparing to edit /etc/exports. Administrator privileges will be required... < ==> vm1: Preparing to edit /etc/exports. Administrator privileges will be required... < INFO environment: Released process lock: nfs-export < INFO environment: Acquired process lock: dotlock < INFO environment: Released process lock: dotlock < INFO interface: info: Mounting NFS shared folders... < INFO interface: info: ==> vm1: Mounting NFS shared folders... < ==> vm1: Mounting NFS shared folders... < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO guest: Execute capability: mount_nfs_folder [#, "10.1.16.40", {"/synced"=>{:type=>:nfs, :guestpath=>"/synced", :hostpath=>"/Users/etrue/src/vagrant-skytap/vm1", :disabled=>false, :map_uid=>502, :map_gid=>20, :nfs_udp=>true, :nfs_version=>3, :uuid=>"2221235798"}}] (ubuntu) < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO guest: Execute capability: shell_expand_guest_path [#, "/synced"] (ubuntu) < INFO ssh: Execute: echo; printf /synced (sudo=false) < INFO ssh: Execute: mkdir -p /synced (sudo=true) < INFO ssh: Execute: mount -o 'vers=3,udp' 10.1.16.40:'/Users/etrue/src/vagrant-skytap/vm1' /synced (sudo=true) < INFO retryable: Retryable exception raised: # < INFO ssh: Execute: mount -o 'vers=3,udp' 10.1.16.40:'/Users/etrue/src/vagrant-skytap/vm1' /synced (sudo=true) < INFO retryable: Retryable exception raised: # < INFO ssh: Execute: mount -o 'vers=3,udp' 10.1.16.40:'/Users/etrue/src/vagrant-skytap/vm1' /synced (sudo=true) < INFO ssh: Execute: test -x /sbin/initctl && test 'upstart' = $(basename $(sudo readlink /proc/1/exe)) (sudo=false) < INFO synced_folders: Invoking synced folder enable: rsync < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO guest: Execute capability: rsync_installed [#] (ubuntu) < INFO ssh: Execute: which rsync (sudo=false) < INFO machine: Calling action: read_ssh_info on provider Skytap (7499098) < INFO runner: Preparing hooks for middleware sequence... < INFO runner: 1 hooks defined. < INFO runner: Running action: machine_action_read_ssh_info # < INFO warden: Calling IN action: # < INFO warden: Calling IN action: # < INFO initialize_api_client: Connecting to Skytap... < INFO api_client: REST API call: GET /configurations < INFO warden: Calling IN action: # < INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026316 < INFO warden: Calling IN action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO guest: Execute capability: rsync_command [#] (ubuntu) < INFO interface: info: Rsyncing folder: /Users/etrue/src/vagrant-skytap/ => /vagrant < INFO interface: info: ==> vm1: Rsyncing folder: /Users/etrue/src/vagrant-skytap/ => /vagrant < ==> vm1: Rsyncing folder: /Users/etrue/src/vagrant-skytap/ => /vagrant < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO guest: Execute capability: rsync_pre [#, {:guestpath=>"/vagrant", :hostpath=>"/Users/etrue/src/vagrant-skytap", :disabled=>false, :owner=>"skytap", :group=>"skytap"}] (ubuntu) < INFO ssh: Execute: mkdir -p '/vagrant' (sudo=true) < INFO subprocess: Starting process: ["/usr/bin/rsync", "--verbose", "--archive", "--delete", "-z", "--copy-links", "--no-owner", "--no-group", "--rsync-path", "sudo rsync", "-e", "ssh -p 22 -o StrictHostKeyChecking=no -o IdentitiesOnly=true -o UserKnownHostsFile=/dev/null -i '/Users/etrue/src/vagrant-skytap/.vagrant/machines/vm1/skytap/private_key'", "--exclude", ".vagrant/", "/Users/etrue/src/vagrant-skytap/", "skytap@10.1.129.86:/vagrant"] < INFO subprocess: Vagrant not running in installer, restoring original environment... < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO ssh: SSH is ready! < INFO ssh: Execute: (sudo=false) < INFO guest: Execute capability: rsync_post [#, {:guestpath=>"/vagrant", :hostpath=>"/Users/etrue/src/vagrant-skytap", :disabled=>false, :owner=>"skytap", :group=>"skytap"}] (ubuntu) < INFO ssh: Execute: find '/vagrant' '!' -type l -a '(' ! -user skytap -or ! -group skytap ')' -print0 | xargs -0 -r chown skytap:skytap (sudo=true) < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO provision: Writing provisioning sentinel so we don't provision again < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # < INFO warden: Calling OUT action: # --- > INFO machine: Calling action: read_state on provider Skytap (7499148) > INFO runner: Preparing hooks for middleware sequence... > INFO runner: 1 hooks defined. > INFO runner: Running action: machine_action_read_state # > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # > INFO initialize_api_client: Connecting to Skytap... > INFO api_client: REST API call: GET /configurations > INFO warden: Calling IN action: # > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO warden: Calling IN action: # > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO machine: Calling action: read_state on provider Skytap (7499148) > INFO runner: Preparing hooks for middleware sequence... > INFO runner: 1 hooks defined. > INFO runner: Running action: machine_action_read_state # > INFO warden: Calling IN action: # > INFO warden: Calling IN action: # > INFO initialize_api_client: Connecting to Skytap... > INFO api_client: REST API call: GET /configurations > INFO warden: Calling IN action: # > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO warden: Calling IN action: # > INFO api_client: REST API call: GET https://cloud.skytap.com/configurations/6026384 > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO warden: Calling OUT action: # > INFO wait_for_communicator: WaitForCommunicator rescued #, re-raising > INFO wait_for_communicator: WaitForCommunicator killing threads preparing to exit > ERROR warden: Error occurred: Network is unreachable - connect(2) for "10.1.129.94" port 22 > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > ERROR warden: Error occurred: Network is unreachable - connect(2) for "10.1.129.94" port 22 > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > ERROR warden: Error occurred: Network is unreachable - connect(2) for "10.1.129.94" port 22 > INFO warden: Beginning recovery process... > INFO warden: Calling recover: # > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Recovery complete. > ERROR warden: Error occurred: Network is unreachable - connect(2) for "10.1.129.94" port 22 > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > ERROR warden: Error occurred: Network is unreachable - connect(2) for "10.1.129.94" port 22 > INFO warden: Beginning recovery process... > INFO warden: Calling recover: # > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Calling recover: # > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Recovery complete. > ERROR warden: Error occurred: Network is unreachable - connect(2) for "10.1.129.94" port 22 > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > ERROR warden: Error occurred: Network is unreachable - connect(2) for "10.1.129.94" port 22 > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. > INFO warden: Beginning recovery process... > INFO warden: Recovery complete. 807,808d599 < INFO environment: Acquired process lock: dotlock < INFO environment: Released process lock: dotlock 812c603,632 < INFO runner: Running action: environment_unload # --- > INFO runner: Running action: environment_unload # > /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/gems/2.2.0/gems/net-ssh-2.9.2/lib/net/ssh/transport/session.rb:70:in `initialize': Network is unreachable - connect(2) for "10.1.129.94" port 22 (Errno::ENETUNREACH) > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/gems/2.2.0/gems/net-ssh-2.9.2/lib/net/ssh/transport/session.rb:70:in `open' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/gems/2.2.0/gems/net-ssh-2.9.2/lib/net/ssh/transport/session.rb:70:in `block in initialize' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/2.2.0/timeout.rb:89:in `block in timeout' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/2.2.0/timeout.rb:34:in `block in catch' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/2.2.0/timeout.rb:34:in `catch' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/2.2.0/timeout.rb:34:in `catch' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/2.2.0/timeout.rb:104:in `timeout' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/2.2.0/timeout.rb:125:in `timeout' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/gems/2.2.0/gems/net-ssh-2.9.2/lib/net/ssh/transport/session.rb:67:in `initialize' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/gems/2.2.0/gems/net-ssh-2.9.2/lib/net/ssh.rb:207:in `new' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/gems/2.2.0/gems/net-ssh-2.9.2/lib/net/ssh.rb:207:in `start' > from /Users/etrue/src/vagrant/plugins/communicators/ssh/communicator.rb:388:in `block (2 levels) in connect' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/2.2.0/timeout.rb:89:in `block in timeout' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/2.2.0/timeout.rb:34:in `block in catch' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/2.2.0/timeout.rb:34:in `catch' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/2.2.0/timeout.rb:34:in `catch' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/2.2.0/timeout.rb:104:in `timeout' > from /Users/etrue/src/vagrant/plugins/communicators/ssh/communicator.rb:367:in `block in connect' > from /Users/etrue/src/vagrant/lib/vagrant/util/retryable.rb:17:in `retryable' > from /Users/etrue/src/vagrant/plugins/communicators/ssh/communicator.rb:366:in `connect' > from /Users/etrue/src/vagrant/plugins/communicators/ssh/communicator.rb:64:in `block in wait_for_ready' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/2.2.0/timeout.rb:89:in `block in timeout' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/2.2.0/timeout.rb:34:in `block in catch' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/2.2.0/timeout.rb:34:in `catch' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/2.2.0/timeout.rb:34:in `catch' > from /Users/etrue/.rbenv/versions/2.2.2/lib/ruby/2.2.0/timeout.rb:104:in `timeout' > from /Users/etrue/src/vagrant/plugins/communicators/ssh/communicator.rb:42:in `wait_for_ready' > from /Users/etrue/src/vagrant/lib/vagrant/action/builtin/wait_for_communicator.rb:37:in `block in call_orig'