Skip to content

Instantly share code, notes, and snippets.

@snowch

snowch/vagrant_rsync_hang

Created May 24, 2014 19:02
Show Gist options
  • Save snowch/fa9b391c941a22a9ba0f to your computer and use it in GitHub Desktop.
Save snowch/fa9b391c941a22a9ba0f to your computer and use it in GitHub Desktop.
Download ZIP
Raw
vagrant_rsync_hang
INFO global: Vagrant version: 1.6.2
INFO global: Ruby version: 2.0.0
INFO global: RubyGems version: 2.0.14
INFO global: VAGRANT_EXECUTABLE="C:\\HashiCorp\\Vagrant\\embedded\\gems\\gems\\vagrant-1.6.2\\bin\\vagrant"
INFO global: VAGRANT_INSTALLER_EMBEDDED_DIR="C:\\HashiCorp\\Vagrant\\embedded"
INFO global: VAGRANT_INSTALLER_ENV="1"
INFO global: VAGRANT_INSTALLER_VERSION="2"
INFO global: VAGRANT_INTERNAL_BUNDLERIZED="1"
INFO global: VAGRANT_LOG="debug"
INFO global: Plugins:
INFO global: - bundler = 1.6.2
INFO global: - mime-types = 2.2
INFO global: - rest-client = 1.6.7
INFO global: - vagrant-login = 1.0.1
INFO global: - vagrant-share = 1.0.1
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/box/plugin.rb
INFO manager: Registered plugin: box command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/destroy/plugin.rb
INFO manager: Registered plugin: destroy command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/global-status/plugin.rb
INFO manager: Registered plugin: global-status command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/halt/plugin.rb
INFO manager: Registered plugin: halt command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/help/plugin.rb
INFO manager: Registered plugin: help command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/init/plugin.rb
INFO manager: Registered plugin: init command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/list-commands/plugin.rb
INFO manager: Registered plugin: list-commands command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/package/plugin.rb
INFO manager: Registered plugin: package command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/plugin/plugin.rb
INFO manager: Registered plugin: plugin command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/provision/plugin.rb
INFO manager: Registered plugin: provision command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/rdp/plugin.rb
INFO manager: Registered plugin: rdp command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/reload/plugin.rb
INFO manager: Registered plugin: reload command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/resume/plugin.rb
INFO manager: Registered plugin: resume command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/ssh/plugin.rb
INFO manager: Registered plugin: ssh command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/ssh_config/plugin.rb
INFO manager: Registered plugin: ssh-config command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/status/plugin.rb
INFO manager: Registered plugin: status command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/suspend/plugin.rb
INFO manager: Registered plugin: suspend command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/up/plugin.rb
INFO manager: Registered plugin: up command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/commands/version/plugin.rb
INFO manager: Registered plugin: version command
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/communicators/ssh/plugin.rb
INFO manager: Registered plugin: ssh communicator
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/communicators/winrm/plugin.rb
INFO manager: Registered plugin: winrm communicator
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/arch/plugin.rb
INFO manager: Registered plugin: Arch guest
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/coreos/plugin.rb
INFO manager: Registered plugin: CoreOS guest
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/darwin/plugin.rb
INFO manager: Registered plugin: Darwin guest
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/debian/plugin.rb
INFO manager: Registered plugin: Debian guest
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/esxi/plugin.rb
INFO manager: Registered plugin: ESXi guest.
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/fedora/plugin.rb
INFO manager: Registered plugin: Fedora guest
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/freebsd/plugin.rb
INFO manager: Registered plugin: FreeBSD guest
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/funtoo/plugin.rb
INFO manager: Registered plugin: Funtoo guest
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/gentoo/plugin.rb
INFO manager: Registered plugin: Gentoo guest
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/linux/plugin.rb
INFO manager: Registered plugin: Linux guest.
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/mint/plugin.rb
INFO manager: Registered plugin: Mint guest
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/netbsd/plugin.rb
INFO manager: Registered plugin: NetBSD guest
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/omnios/plugin.rb
INFO manager: Registered plugin: OmniOS guest.
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/openbsd/plugin.rb
INFO manager: Registered plugin: OpenBSD guest
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/pld/plugin.rb
INFO manager: Registered plugin: PLD Linux guest
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/redhat/plugin.rb
INFO manager: Registered plugin: RedHat guest
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/smartos/plugin.rb
INFO manager: Registered plugin: SmartOS guest.
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/solaris/plugin.rb
INFO manager: Registered plugin: Solaris guest.
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/solaris11/plugin.rb
INFO manager: Registered plugin: Solaris 11 guest.
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/suse/plugin.rb
INFO manager: Registered plugin: SUSE guest
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/tinycore/plugin.rb
INFO manager: Registered plugin: TinyCore Linux guest.
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/ubuntu/plugin.rb
INFO manager: Registered plugin: Ubuntu guest
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/guests/windows/plugin.rb
INFO manager: Registered plugin: Windows guest.
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/arch/plugin.rb
INFO manager: Registered plugin: Arch host
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/bsd/plugin.rb
INFO manager: Registered plugin: BSD host
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/darwin/plugin.rb
INFO manager: Registered plugin: Mac OS X host
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/freebsd/plugin.rb
INFO manager: Registered plugin: FreeBSD host
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/gentoo/plugin.rb
INFO manager: Registered plugin: Gentoo host
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/linux/plugin.rb
INFO manager: Registered plugin: Linux host
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/null/plugin.rb
INFO manager: Registered plugin: null host
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/opensuse/plugin.rb
INFO manager: Registered plugin: OpenSUSE host
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/redhat/plugin.rb
INFO manager: Registered plugin: Red Hat host
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/slackware/plugin.rb
INFO manager: Registered plugin: Slackware host
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/hosts/windows/plugin.rb
INFO manager: Registered plugin: Windows host
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/kernel_v1/plugin.rb
INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/kernel_v2/plugin.rb
INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/providers/docker/plugin.rb
INFO manager: Registered plugin: docker-provider
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/providers/hyperv/plugin.rb
INFO manager: Registered plugin: Hyper-V provider
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/providers/virtualbox/plugin.rb
INFO manager: Registered plugin: VirtualBox provider
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/provisioners/ansible/plugin.rb
INFO manager: Registered plugin: ansible
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/provisioners/cfengine/plugin.rb
INFO manager: Registered plugin: CFEngine Provisioner
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/provisioners/chef/plugin.rb
INFO manager: Registered plugin: chef
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/provisioners/docker/plugin.rb
INFO manager: Registered plugin: docker
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/provisioners/file/plugin.rb
INFO manager: Registered plugin: file
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/provisioners/puppet/plugin.rb
INFO manager: Registered plugin: puppet
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/provisioners/salt/plugin.rb
INFO manager: Registered plugin: salt
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/provisioners/shell/plugin.rb
INFO manager: Registered plugin: shell
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/synced_folders/nfs/plugin.rb
INFO manager: Registered plugin: NFS synced folders
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/synced_folders/rsync/plugin.rb
INFO manager: Registered plugin: RSync synced folders
DEBUG global: Loading core plugin: C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/synced_folders/smb/plugin.rb
INFO manager: Registered plugin: SMB synced folders
INFO global: Loading plugins!
INFO manager: Registered plugin: vagrant-login
INFO manager: Registered plugin: vagrant-share
INFO vagrant: `vagrant` invoked: ["up", "--provider=docker", "--debug"]
DEBUG vagrant: Creating Vagrant environment
INFO environment: Environment initialized (#<Vagrant::Environment:0x363ac80>)
INFO environment: - cwd: C:/dev/1_vagrant_machines/vagrant-docker-learn
INFO environment: Home path: C:/Users/snowc/.vagrant.d
INFO environment: Local data path: C:/dev/1_vagrant_machines/vagrant-docker-learn/.vagrant
DEBUG environment: Creating: C:/dev/1_vagrant_machines/vagrant-docker-learn/.vagrant
INFO environment: Running hook: environment_plugins_loaded
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x37afd78>
INFO environment: Running hook: environment_load
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x3868338>
INFO cli: CLI: [] "up" ["--provider=docker"]
DEBUG cli: Invoking command class: VagrantPlugins::CommandUp::Command ["--provider=docker"]
DEBUG command: 'Up' each target VM...
INFO loader: Set :root = #<Pathname:C:/dev/1_vagrant_machines/vagrant-docker-learn/Vagrantfile>
DEBUG loader: Populating proc cache for #<Pathname:C:/dev/1_vagrant_machines/vagrant-docker-learn/Vagrantfile>
DEBUG loader: Load procs for pathname: C:/dev/1_vagrant_machines/vagrant-docker-learn/Vagrantfile
INFO loader: Loading configuration in order: [:home, :root]
DEBUG loader: Loading from: root (evaluating)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG command: Getting target VMs for command. Arguments:
DEBUG command: -- names: ["default"]
DEBUG command: -- options: {:provider=>"docker"}
DEBUG command: Finding machine that match name: default
INFO environment: Getting machine: default (docker)
INFO environment: Uncached load of machine.
INFO loader: Set "23723112_machine_default" = []
INFO loader: Loading configuration in order: [:home, :root, "23723112_machine_default"]
DEBUG loader: Loading from: root (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
INFO machine: Initializing machine: default
INFO machine: - Provider: VagrantPlugins::DockerProvider::Provider
INFO machine: - Box:
INFO machine: - Data dir: C:/dev/1_vagrant_machines/vagrant-docker-learn/.vagrant/machines/default/docker
INFO command: With machine: default (#<VagrantPlugins::DockerProvider::Provider:0x3651168 @logger=#<Log4r::Logger:0x3651090 @fullname="vagrant::provider::docker", @outputters=[], @additive=true, @name="docker", @path="vagrant::provider", @parent=#<Log4r::Logger:0x3804b58 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x3837018 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x3836fd0>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x38351b8 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x3804a80 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @machine=#<Vagrant::Machine: default (VagrantPlugins::DockerProvider::Provider)>, @cap_logger=#<Log4r::Logger:0x28d0748 @fullname="vagrant::capability_host::vagrantplugins::dockerprovider::provider", @outputters=[], @additive=true, @name="provider", @path="vagrant::capability_host::vagrantplugins::dockerprovider", @parent=#<Log4r::Logger:0x3804b58 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x3837018 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x3836fd0>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x38351b8 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x3804a80 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @cap_host_chain=[[:docker, #<#<Class:0x28d0ec8>:0x295e990>]], @cap_args=[#<Vagrant::Machine: default (VagrantPlugins::DockerProvider::Provider)>], @cap_caps={:docker=>#<Vagrant::Registry:0x28d0c88 @items={:public_address=>#<Proc:0x382da78@C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/providers/docker/plugin.rb:54>}, @results_cache={}>, :hyperv=>#<Vagrant::Registry:0x28d0b50 @items={:public_address=>#<Proc:0x38360b8@C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/providers/hyperv/plugin.rb:25>}, @results_cache={}>, :virtualbox=>#<Vagrant::Registry:0x28d0a18 @items={:forwarded_ports=>#<Proc:0x3844878@C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/providers/virtualbox/plugin.rb:27>, :nic_mac_addresses=>#<Proc:0x3844818@C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/plugins/providers/virtualbox/plugin.rb:32>, :public_address=>#<Proc:0x25a5bd8@C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-share-1.0.1/lib/vagrant-share.rb:39>}, @results_cache={}>}>)
INFO interface: info: Bringing machine 'default' up with 'docker' provider...
Bringing machine 'default' up with 'docker' provider...
INFO batch_action: Enabling parallelization by default.
INFO batch_action: Disabling parallelization because only executing one action
INFO batch_action: Batch action will parallelize: false
INFO batch_action: Starting action: #<Vagrant::Machine:0x3780570> up {:destroy_on_error=>true, :parallel=>true, :provision_ignore_sentinel=>false, :provision_types=>nil, :provider=>"docker"}
INFO machine: Calling action: up on provider Docker (new container)
DEBUG environment: Attempting to acquire process-lock: machine-action-2a4085d3c4944e8134fe57472994a22c
DEBUG environment: Attempting to acquire process-lock: dotlock
INFO environment: Acquired process lock: dotlock
INFO environment: Released process lock: dotlock
INFO environment: Acquired process lock: machine-action-2a4085d3c4944e8134fe57472994a22c
INFO host: Autodetecting host type for [#<Vagrant::Environment: C:/dev/1_vagrant_machines/vagrant-docker-learn>]
DEBUG host: Trying: arch
DEBUG host: Trying: darwin
DEBUG host: Trying: freebsd
DEBUG host: Trying: gentoo
DEBUG host: Trying: opensuse
DEBUG host: Trying: redhat
DEBUG host: Trying: slackware
DEBUG host: Trying: bsd
DEBUG host: Trying: linux
DEBUG host: Trying: null
DEBUG host: Trying: windows
INFO host: Detected: windows!
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x2d9d0f8>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x354e0b8>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x354e058>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x3682d40>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::IsState:0x3680658>
DEBUG is_state: Checking if machine state is 'not_created'
INFO machine: New machine ID: nil
DEBUG is_state: -- Machine state: not_created
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::IsState:0x3680658>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x3749df0>
INFO warden: Calling IN action: #<Proc:0x37fd340@C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::HandleBox:0x3749da8>
INFO handle_box: Skipping HandleBox because no box is set
INFO warden: Calling IN action: #<Proc:0x378ed60@C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<VagrantPlugins::DockerProvider::Action::HostMachine:0x354dff8>
INFO interface: output: Docker host is required. One will be created if necessary...
INFO interface: output: ==> default: Docker host is required. One will be created if necessary...
==> default: Docker host is required. One will be created if necessary...
DEBUG environment: Attempting to acquire process-lock: docker-provider-hostvm
DEBUG environment: Attempting to acquire process-lock: dotlock
INFO environment: Acquired process lock: dotlock
INFO environment: Released process lock: dotlock
INFO environment: Acquired process lock: docker-provider-hostvm
INFO environment: Released process lock: docker-provider-hostvm
DEBUG environment: Attempting to acquire process-lock: dotlock
INFO environment: Acquired process lock: dotlock
INFO environment: Released process lock: dotlock
INFO environment: Environment initialized (#<Vagrant::Environment:0x39293e8>)
INFO environment: - cwd: C:/Users/snowc/.vagrant.d/data/docker-host
INFO environment: Home path: C:/Users/snowc/.vagrant.d
INFO environment: Local data path: C:/Users/snowc/.vagrant.d/data/docker-host/.vagrant
DEBUG environment: Creating: C:/Users/snowc/.vagrant.d/data/docker-host/.vagrant
INFO environment: Running hook: environment_plugins_loaded
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x3701070>
INFO environment: Running hook: environment_load
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x3643a58>
DEBUG base: Windows. Trying VBOX_INSTALL_PATH for VBoxManage
INFO base: VBoxManage path: VBoxManage
INFO subprocess: Starting process: ["C:\\Program Files\\Oracle\\VirtualBox/VBoxManage.EXE", "--version"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: 4.3.12r93733
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG meta: Finding driver for VirtualBox version: 4.3.12
INFO meta: Using VirtualBox driver: VagrantPlugins::ProviderVirtualBox::Driver::Version_4_3
DEBUG base: Windows. Trying VBOX_INSTALL_PATH for VBoxManage
INFO base: VBoxManage path: VBoxManage
INFO environment: Getting machine: default (virtualbox)
INFO environment: Uncached load of machine.
INFO loader: Set :root = #<Pathname:C:/Users/snowc/.vagrant.d/data/docker-host/Vagrantfile>
DEBUG loader: Populating proc cache for #<Pathname:C:/Users/snowc/.vagrant.d/data/docker-host/Vagrantfile>
DEBUG loader: Load procs for pathname: C:/Users/snowc/.vagrant.d/data/docker-host/Vagrantfile
INFO loader: Loading configuration in order: [:home, :root]
DEBUG loader: Loading from: root (evaluating)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG base: Windows. Trying VBOX_INSTALL_PATH for VBoxManage
INFO base: VBoxManage path: VBoxManage
INFO subprocess: Starting process: ["C:\\Program Files\\Oracle\\VirtualBox/VBoxManage.EXE", "--version"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: 4.3.12r93733
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG meta: Finding driver for VirtualBox version: 4.3.12
INFO meta: Using VirtualBox driver: VagrantPlugins::ProviderVirtualBox::Driver::Version_4_3
DEBUG base: Windows. Trying VBOX_INSTALL_PATH for VBoxManage
INFO base: VBoxManage path: VBoxManage
INFO loader: Set "29297724_machine_default" = []
INFO loader: Loading configuration in order: [:home, :root, "29297724_machine_default"]
DEBUG loader: Loading from: root (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
INFO box_collection: Box found: mitchellh/boot2docker (virtualbox)
INFO environment: Running hook: authenticate_box_url
INFO host: Autodetecting host type for [#<Vagrant::Environment: C:/Users/snowc/.vagrant.d/data/docker-host>]
DEBUG host: Trying: arch
DEBUG host: Trying: darwin
DEBUG host: Trying: freebsd
DEBUG host: Trying: gentoo
DEBUG host: Trying: opensuse
DEBUG host: Trying: redhat
DEBUG host: Trying: slackware
DEBUG host: Trying: bsd
DEBUG host: Trying: linux
DEBUG host: Trying: null
DEBUG host: Trying: windows
INFO host: Detected: windows!
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 2 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x2dd42f8>
INFO warden: Calling IN action: #<VagrantPlugins::Login::ActionAuthenticateBox:0x536a4c8>
INFO warden: Calling OUT action: #<VagrantPlugins::Login::ActionAuthenticateBox:0x536a4c8>
INFO loader: Set :"29055948_mitchellh/boot2docker_virtualbox" = #<Pathname:C:/Users/snowc/.vagrant.d/boxes/mitchellh-VAGRANTSLASH-boot2docker/0.8.0/virtualbox/Vagrantfile>
DEBUG loader: Populating proc cache for #<Pathname:C:/Users/snowc/.vagrant.d/boxes/mitchellh-VAGRANTSLASH-boot2docker/0.8.0/virtualbox/Vagrantfile>
DEBUG loader: Load procs for pathname: C:/Users/snowc/.vagrant.d/boxes/mitchellh-VAGRANTSLASH-boot2docker/0.8.0/virtualbox/Vagrantfile
INFO loader: Loading configuration in order: [:"29055948_mitchellh/boot2docker_virtualbox", :home, :root, "29297724_machine_default"]
DEBUG loader: Loading from: 29055948_mitchellh/boot2docker_virtualbox (evaluating)
DEBUG loader: Loading from: 29055948_mitchellh/boot2docker_virtualbox (evaluating)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
INFO machine: Initializing machine: default
INFO machine: - Provider: VagrantPlugins::ProviderVirtualBox::Provider
INFO machine: - Box: #<Vagrant::Box:0x53208d8>
INFO machine: - Data dir: C:/Users/snowc/.vagrant.d/data/docker-host/.vagrant/machines/default/virtualbox
DEBUG virtualbox: Instantiating the driver for machine ID: "5fa075e2-26b0-4e1b-8cac-23e299c8ac57"
DEBUG base: Windows. Trying VBOX_INSTALL_PATH for VBoxManage
INFO base: VBoxManage path: VBoxManage
INFO subprocess: Starting process: ["C:\\Program Files\\Oracle\\VirtualBox/VBoxManage.EXE", "--version"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: 4.3.12r93733
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG meta: Finding driver for VirtualBox version: 4.3.12
INFO meta: Using VirtualBox driver: VagrantPlugins::ProviderVirtualBox::Driver::Version_4_3
DEBUG base: Windows. Trying VBOX_INSTALL_PATH for VBoxManage
INFO base: VBoxManage path: VBoxManage
INFO subprocess: Starting process: ["C:\\Program Files\\Oracle\\VirtualBox/VBoxManage.EXE", "showvminfo", "5fa075e2-26b0-4e1b-8cac-23e299c8ac57"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: docker-host_default_1400698030155_65125
Groups: /
Guest OS: Other Linux (64-bit)
UUID: 5fa075e2-26b0-4e1b-8cac-23e299c8ac57
Config file: C:\Users\snowc\VirtualBox VMs\docker-host_default_1400698030155_65125\docker-host_default_1400698030155_65125.vbox
Snapshot folder: C:\Users\snowc\VirtualBox VMs\docker-host_default_1400698030155_65125\Snapshots
Log folder: C:\Users\snowc\VirtualBox VMs\docker-host_default_1400698030155_65125\Logs
Hardware UUID: 5fa075e2-26b0-4e1b-8cac-23e299c8ac57
Memory size: 512MB
Page Fusion: off
VRAM size: 8MB
CPU exec cap: 100%
HPET: off
Chipset: piix3
Firmware: BIOS
Number of CPUs: 1
PAE: on
Long Mode: on
Synthetic CPU: off
CPUID overrides: None
Boot menu mode: message and menu
Boot Device (1): HardDisk
Boot Device (2): DVD
Boot Device (3): Not Assigned
Boot Device (4): Not Assigned
ACPI: on
IOAPIC: on
Time offset: 0ms
RTC: UTC
Hardw. virt.ext: on
Nested Paging: on
Large Pages: on
VT-x VPID: on
VT-x unr. exec.: on
State: running (since 2014-05-24T18:50:26.997000000)
Monitor count: 1
3D Acceleration: off
2D Video Acceleration: off
Teleporter Enabled: off
Teleporter Port: 0
Teleporter Address:
Teleporter Password:
Tracing Enabled: off
Allow Tracing to Access VM: off
Tracing Configuration:
Autostart Enabled: off
Autostart Delay: 0
Default Frontend:
Storage Controller Name (0): IDE Controller
Storage Controller Type (0): PIIX4
Storage Controller Instance Number (0): 0
Storage Controller Max Port Count (0): 2
Storage Controller Port Count (0): 2
Storage Controller Bootable (0): on
IDE Controller (0, 0): C:\Users\snowc\VirtualBox VMs\packer-virtualbox-iso\packer-virtualbox-iso-disk1.vmdk (UUID: 1c5f93ae-19f2-4e99-90dc-4dff4431e89c)
IDE Controller (0, 1): C:\Users\snowc\.vagrant.d\boxes\mitchellh-VAGRANTSLASH-boot2docker\0.8.0\virtualbox\boot2docker-vagrant.iso (UUID: e41a6c36-ff4f-4bbc-b3c7-3c1c86886a81)
NIC 1: MAC: 0800272E82AE, Attachment: NAT, Cable connected: on, Trace: off (file: none), Type: 82540EM, Reported speed: 0 Mbps, Boot priority: 0, Promisc Policy: deny, Bandwidth group: none
NIC 1 Settings: MTU: 0, Socket (send: 64, receive: 64), TCP Window (send:64, receive: 64)
NIC 1 Rule(0): name = ssh, protocol = tcp, host ip = 127.0.0.1, host port = 2222, guest ip = , guest port = 22
NIC 1 Rule(1): name = tcp4243, protocol = tcp, host ip = , host port = 4243, guest ip = , guest port = 4243
NIC 2: disabled
NIC 3: disabled
NIC 4: disabled
NIC 5: disabled
NIC 6: disabled
NIC 7: disabled
NIC 8: disabled
Pointing Device: PS/2 Mouse
Keyboard Device: PS/2 Keyboard
UART 1: disabled
UART 2: disabled
LPT 1: disabled
LPT 2: disabled
Audio: disabled
Clipboard Mode: disabled
Drag'n'drop Mode: disabled
Session type: headless
Video mode: 720x400x0 at 0,0
VRDE: disabled
USB: disabled
EHCI: disabled
USB Device Filters:
<none>
Available remote USB devices:
<none>
Currently Attached USB Devices:
<none>
Bandwidth groups: <none>
Shared folders: <none>
VRDE Connection: not active
Clients so far: 0
Video capturing: not active
Capture screens: 0
Capture file: C:\Users\snowc\VirtualBox VMs\docker-host_default_1400698030155_65125\docker-host_default_1400698030155_65125.webm
Capture dimensions: 1024x768
Capture rate: 512 kbps
Capture FPS: 25
Guest:
Configured memory balloon size: 0 MB
OS type: Linux_64
Additions run level: 0
Guest Facilities:
No active facilities.
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG environment: Attempting to acquire process-lock: 83fd64cbb25f27d26c6984a6a5592dff
DEBUG environment: Attempting to acquire process-lock: dotlock
INFO environment: Acquired process lock: dotlock
INFO environment: Released process lock: dotlock
INFO environment: Acquired process lock: 83fd64cbb25f27d26c6984a6a5592dff
DEBUG ssh: Checking whether SSH is ready...
INFO subprocess: Starting process: ["C:\\Program Files\\Oracle\\VirtualBox/VBoxManage.EXE", "showvminfo", "5fa075e2-26b0-4e1b-8cac-23e299c8ac57", "--machinereadable"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: name="docker-host_default_1400698030155_65125"
groups="/"
ostype="Other Linux (64-bit)"
UUID="5fa075e2-26b0-4e1b-8cac-23e299c8ac57"
CfgFile="C:\\Users\\snowc\\VirtualBox VMs\\docker-host_default_1400698030155_65125\\docker-host_default_1400698030155_65125.vbox"
SnapFldr="C:\\Users\\snowc\\VirtualBox VMs\\docker-host_default_1400698030155_65125\\Snapshots"
LogFldr="C:\\Users\\snowc\\VirtualBox VMs\\docker-host_default_1400698030155_65125\\Logs"
hardwareuuid="5fa075e2-26b0-4e1b-8cac-23e299c8ac57"
memory=512
pagefusion="off"
vram=8
cpuexecutioncap=100
hpet="off"
chipset="piix3"
firmware="BIOS"
cpus=1
pae="on"
longmode="on"
synthcpu="off"
bootmenu="messageandmenu"
boot1="disk"
boot2="dvd"
boot3="none"
boot4="none"
acpi="on"
ioapic="on"
Time offset=0rtcuseutc="on"
hwvirtex="on"
nestedpaging="on"
largepages="on"
vtxvpid="on"
vtxux="on"
VMState="running"
VMStateChangeTime="2014-05-24T18:50:26.997000000"
monitorcount=1
accelerate3d="off"
accelerate2dvideo="off"
teleporterenabled="off"
teleporterport=0
teleporteraddress=""
teleporterpassword=""
tracing-enabled="off"
tracing-allow-vm-access="off"
tracing-config=""
autostart-enabled="off"
autostart-delay=0
defaultfrontend=""
storagecontrollername0="IDE Controller"
storagecontrollertype0="PIIX4"
storagecontrollerinstance0="0"
storagecontrollermaxportcount0="2"
storagecontrollerportcount0="2"
storagecontrollerbootable0="on"
"IDE Controller-0-0"="C:\Users\snowc\VirtualBox VMs\packer-virtualbox-iso\packer-virtualbox-iso-disk1.vmdk"
"IDE Controller-ImageUUID-0-0"="1c5f93ae-19f2-4e99-90dc-4dff4431e89c"
"IDE Controller-0-1"="C:\Users\snowc\.vagrant.d\boxes\mitchellh-VAGRANTSLASH-boot2docker\0.8.0\virtualbox\boot2docker-vagrant.iso"
"IDE Controller-ImageUUID-0-1"="e41a6c36-ff4f-4bbc-b3c7-3c1c86886a81"
"IDE Controller-tempeject"="off"
"IDE Controller-IsEjected"="off"
"IDE Controller-1-0"="none"
"IDE Controller-1-1"="none"
natnet1="nat"
macaddress1="0800272E82AE"
cableconnected1="on"
nic1="nat"
nictype1="82540EM"
nicspeed1="0"
mtu="0"
sockSnd="64"
sockRcv="64"
tcpWndSnd="64"
tcpWndRcv="64"
Forwarding(0)="ssh,tcp,127.0.0.1,2222,,22"
Forwarding(1)="tcp4243,tcp,,4243,,4243"
nic2="none"
nic3="none"
nic4="none"
nic5="none"
nic6="none"
nic7="none"
nic8="none"
hidpointing="ps2mouse"
hidkeyboard="ps2kbd"
uart1="off"
uart2="off"
lpt1="off"
lpt2="off"
audio="none"
clipboard="disabled"
draganddrop="disabled"
SessionType="headless"
VideoMode="720,400,0"@0,0
vrde="off"
usb="off"
ehci="off"
VRDEActiveConnection="off"
VRDEClients=0
vcpenabled="off"
vcpscreens=0
vcpfile="C:\Users\snowc\VirtualBox VMs\docker-host_default_1400698030155_65125\docker-host_default_1400698030155_65125.webm"
vcpwidth=1024
vcpheight=768
vcprate=512
vcpfps=25
GuestMemoryBalloon=0
GuestOSType="Linux_64"
GuestAdditionsRunLevel=0
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG virtualbox_4_3: Searching for SSH port: 22
DEBUG virtualbox_4_3: read_forward_ports: uuid=5fa075e2-26b0-4e1b-8cac-23e299c8ac57 active_only=false
INFO subprocess: Starting process: ["C:\\Program Files\\Oracle\\VirtualBox/VBoxManage.EXE", "showvminfo", "5fa075e2-26b0-4e1b-8cac-23e299c8ac57", "--machinereadable"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: name="docker-host_default_1400698030155_65125"
groups="/"
ostype="Other Linux (64-bit)"
UUID="5fa075e2-26b0-4e1b-8cac-23e299c8ac57"
CfgFile="C:\\Users\\snowc\\VirtualBox VMs\\docker-host_default_1400698030155_65125\\docker-host_default_1400698030155_65125.vbox"
SnapFldr="C:\\Users\\snowc\\VirtualBox VMs\\docker-host_default_1400698030155_65125\\Snapshots"
LogFldr="C:\\Users\\snowc\\VirtualBox VMs\\docker-host_default_1400698030155_65125\\Logs"
hardwareuuid="5fa075e2-26b0-4e1b-8cac-23e299c8ac57"
memory=512
pagefusion="off"
vram=8
cpuexecutioncap=100
hpet="off"
chipset="piix3"
firmware="BIOS"
cpus=1
pae="on"
longmode="on"
synthcpu="off"
bootmenu="messageandmenu"
boot1="disk"
boot2="dvd"
boot3="none"
boot4="none"
acpi="on"
ioapic="on"
Time offset=0rtcuseutc="on"
hwvirtex="on"
nestedpaging="on"
largepages="on"
vtxvpid="on"
vtxux="on"
VMState="running"
VMStateChangeTime="2014-05-24T18:50:26.997000000"
monitorcount=1
accelerate3d="off"
accelerate2dvideo="off"
teleporterenabled="off"
teleporterport=0
teleporteraddress=""
teleporterpassword=""
tracing-enabled="off"
tracing-allow-vm-access="off"
tracing-config=""
autostart-enabled="off"
autostart-delay=0
defaultfrontend=""
storagecontrollername0="IDE Controller"
storagecontrollertype0="PIIX4"
storagecontrollerinstance0="0"
storagecontrollermaxportcount0="2"
storagecontrollerportcount0="2"
storagecontrollerbootable0="on"
"IDE Controller-0-0"="C:\Users\snowc\VirtualBox VMs\packer-virtualbox-iso\packer-virtualbox-iso-disk1.vmdk"
"IDE Controller-ImageUUID-0-0"="1c5f93ae-19f2-4e99-90dc-4dff4431e89c"
"IDE Controller-0-1"="C:\Users\snowc\.vagrant.d\boxes\mitchellh-VAGRANTSLASH-boot2docker\0.8.0\virtualbox\boot2docker-vagrant.iso"
"IDE Controller-ImageUUID-0-1"="e41a6c36-ff4f-4bbc-b3c7-3c1c86886a81"
"IDE Controller-tempeject"="off"
"IDE Controller-IsEjected"="off"
"IDE Controller-1-0"="none"
"IDE Controller-1-1"="none"
natnet1="nat"
macaddress1="0800272E82AE"
cableconnected1="on"
nic1="nat"
nictype1="82540EM"
nicspeed1="0"
mtu="0"
sockSnd="64"
sockRcv="64"
tcpWndSnd="64"
tcpWndRcv="64"
Forwarding(0)="ssh,tcp,127.0.0.1,2222,,22"
Forwarding(1)="tcp4243,tcp,,4243,,4243"
nic2="none"
nic3="none"
nic4="none"
nic5="none"
nic6="none"
nic7="none"
nic8="none"
hidpointing="ps2mouse"
hidkeyboard="ps2kbd"
uart1="off"
uart2="off"
lpt1="off"
lpt2="off"
audio="none"
clipboard="disabled"
draganddrop="disabled"
SessionType="headless"
VideoMode="720,400,0"@0,0
vrde="off"
usb="off"
ehci="off"
VRDEActiveConnection="off"
VRDEClients=0
vcpenabled="off"
vcpscreens=0
vcpfile="C:\Users\snowc\VirtualBox VMs\docker-host_default_1400698030155_65125\docker-host_default_1400698030155_65125.webm"
vcpwidth=1024
vcpheight=768
vcprate=512
vcpfps=25
GuestMemoryBalloon=0
GuestOSType="Linux_64"
GuestAdditionsRunLevel=0
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG virtualbox_4_3: - [1, "ssh", 2222, 22]
DEBUG virtualbox_4_3: - [1, "tcp4243", 4243, 4243]
INFO ssh: Attempting SSH connnection...
INFO ssh: Attempting to connect to SSH...
INFO ssh: - Host: 127.0.0.1
INFO ssh: - Port: 2222
INFO ssh: - Username: docker
INFO ssh: - Password? false
INFO ssh: - Key Path: ["C:/Users/snowc/.vagrant.d/insecure_private_key"]
DEBUG ssh: == Net-SSH connection debug-level log START ==
DEBUG ssh: D, [2014-05-24T19:59:29.473150 #1928] DEBUG -- net.ssh.transport.session[227f3dc]: establishing connection to 127.0.0.1:2222
D, [2014-05-24T19:59:29.478650 #1928] DEBUG -- net.ssh.transport.session[227f3dc]: connection established
I, [2014-05-24T19:59:29.479150 #1928] INFO -- net.ssh.transport.server_version[227ec98]: negotiating protocol version
D, [2014-05-24T19:59:29.483650 #1928] DEBUG -- net.ssh.transport.server_version[227ec98]: remote is `SSH-2.0-OpenSSH_6.0'
D, [2014-05-24T19:59:29.483650 #1928] DEBUG -- net.ssh.transport.server_version[227ec98]: local is `SSH-2.0-Ruby/Net::SSH_2.9.1 i386-mingw32'
D, [2014-05-24T19:59:29.497650 #1928] DEBUG -- tcpsocket[227f0ac]: read 984 bytes
D, [2014-05-24T19:59:29.497650 #1928] DEBUG -- tcpsocket[227f0ac]: received packet nr 0 type 20 len 980
I, [2014-05-24T19:59:29.498150 #1928] INFO -- net.ssh.transport.algorithms[227e5f0]: got KEXINIT from server
I, [2014-05-24T19:59:29.498150 #1928] INFO -- net.ssh.transport.algorithms[227e5f0]: sending KEXINIT
D, [2014-05-24T19:59:29.498150 #1928] DEBUG -- tcpsocket[227f0ac]: queueing packet nr 0 type 20 len 2020
D, [2014-05-24T19:59:29.498650 #1928] DEBUG -- tcpsocket[227f0ac]: sent 2024 bytes
I, [2014-05-24T19:59:29.498650 #1928] INFO -- net.ssh.transport.algorithms[227e5f0]: negotiating algorithms
D, [2014-05-24T19:59:29.498650 #1928] DEBUG -- net.ssh.transport.algorithms[227e5f0]: negotiated:
* kex: diffie-hellman-group-exchange-sha1
* host_key: ssh-rsa
* encryption_server: aes128-cbc
* encryption_client: aes128-cbc
* hmac_client: hmac-sha1
* hmac_server: hmac-sha1
* compression_client: none
* compression_server: none
* language_client:
* language_server:
D, [2014-05-24T19:59:29.498650 #1928] DEBUG -- net.ssh.transport.algorithms[227e5f0]: exchanging keys
D, [2014-05-24T19:59:29.499150 #1928] DEBUG -- tcpsocket[227f0ac]: queueing packet nr 1 type 34 len 20
D, [2014-05-24T19:59:29.499150 #1928] DEBUG -- tcpsocket[227f0ac]: sent 24 bytes
D, [2014-05-24T19:59:29.509150 #1928] DEBUG -- tcpsocket[227f0ac]: read 152 bytes
D, [2014-05-24T19:59:29.509150 #1928] DEBUG -- tcpsocket[227f0ac]: received packet nr 1 type 31 len 148
D, [2014-05-24T19:59:32.964650 #1928] DEBUG -- tcpsocket[227f0ac]: queueing packet nr 2 type 32 len 140
D, [2014-05-24T19:59:32.964650 #1928] DEBUG -- tcpsocket[227f0ac]: sent 144 bytes
D, [2014-05-24T19:59:32.975150 #1928] DEBUG -- tcpsocket[227f0ac]: read 720 bytes
D, [2014-05-24T19:59:32.975150 #1928] DEBUG -- tcpsocket[227f0ac]: received packet nr 2 type 33 len 700
D, [2014-05-24T19:59:32.978150 #1928] DEBUG -- tcpsocket[227f0ac]: queueing packet nr 3 type 21 len 20
D, [2014-05-24T19:59:32.978650 #1928] DEBUG -- tcpsocket[227f0ac]: sent 24 bytes
D, [2014-05-24T19:59:32.978650 #1928] DEBUG -- tcpsocket[227f0ac]: received packet nr 3 type 21 len 12
D, [2014-05-24T19:59:32.979150 #1928] DEBUG -- net.ssh.authentication.session[155505c]: beginning authentication of `docker'
D, [2014-05-24T19:59:32.979150 #1928] DEBUG -- tcpsocket[227f0ac]: queueing packet nr 4 type 5 len 28
D, [2014-05-24T19:59:32.979150 #1928] DEBUG -- tcpsocket[227f0ac]: sent 52 bytes
D, [2014-05-24T19:59:32.989150 #1928] DEBUG -- tcpsocket[227f0ac]: read 52 bytes
D, [2014-05-24T19:59:32.989150 #1928] DEBUG -- tcpsocket[227f0ac]: received packet nr 4 type 6 len 28
D, [2014-05-24T19:59:32.989150 #1928] DEBUG -- net.ssh.authentication.session[155505c]: trying none
D, [2014-05-24T19:59:32.989650 #1928] DEBUG -- tcpsocket[227f0ac]: queueing packet nr 5 type 50 len 44
D, [2014-05-24T19:59:32.989650 #1928] DEBUG -- tcpsocket[227f0ac]: sent 68 bytes
D, [2014-05-24T19:59:33.089650 #1928] DEBUG -- tcpsocket[227f0ac]: read 84 bytes
D, [2014-05-24T19:59:33.090150 #1928] DEBUG -- tcpsocket[227f0ac]: received packet nr 5 type 51 len 60
D, [2014-05-24T19:59:33.090650 #1928] DEBUG -- net.ssh.authentication.session[155505c]: allowed methods: publickey,password,keyboard-interactive
D, [2014-05-24T19:59:33.090650 #1928] DEBUG -- net.ssh.authentication.methods.none[1569600]: none failed
D, [2014-05-24T19:59:33.091150 #1928] DEBUG -- net.ssh.authentication.session[155505c]: trying publickey
D, [2014-05-24T19:59:33.093150 #1928] DEBUG -- net.ssh.authentication.agent[168b2d4]: connecting to ssh-agent
E, [2014-05-24T19:59:33.093650 #1928] ERROR -- net.ssh.authentication.agent[168b2d4]: could not connect to ssh-agent
D, [2014-05-24T19:59:33.094150 #1928] DEBUG -- net.ssh.authentication.methods.publickey[16866d8]: trying publickey (dd:3b:b8:2e:85:04:06:e9:ab:ff:a8:0a:c0:04:6e:d6)
D, [2014-05-24T19:59:33.094650 #1928] DEBUG -- tcpsocket[227f0ac]: queueing packet nr 6 type 50 len 348
D, [2014-05-24T19:59:33.095150 #1928] DEBUG -- tcpsocket[227f0ac]: sent 372 bytes
D, [2014-05-24T19:59:33.105650 #1928] DEBUG -- tcpsocket[227f0ac]: read 324 bytes
D, [2014-05-24T19:59:33.106150 #1928] DEBUG -- tcpsocket[227f0ac]: received packet nr 6 type 60 len 300
D, [2014-05-24T19:59:33.126650 #1928] DEBUG -- tcpsocket[227f0ac]: queueing packet nr 7 type 50 len 620
D, [2014-05-24T19:59:33.126650 #1928] DEBUG -- tcpsocket[227f0ac]: sent 644 bytes
D, [2014-05-24T19:59:33.137150 #1928] DEBUG -- tcpsocket[227f0ac]: read 36 bytes
D, [2014-05-24T19:59:33.137150 #1928] DEBUG -- tcpsocket[227f0ac]: received packet nr 7 type 52 len 12
D, [2014-05-24T19:59:33.137650 #1928] DEBUG -- net.ssh.authentication.methods.publickey[16866d8]: publickey succeeded (dd:3b:b8:2e:85:04:06:e9:ab:ff:a8:0a:c0:04:6e:d6)
DEBUG ssh: == Net-SSH connection debug-level log END ==
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: stdout: boot2docker: 0.8.0
DEBUG ssh: Exit status: 0
INFO subprocess: Starting process: ["C:\\Program Files\\Oracle\\VirtualBox/VBoxManage.EXE", "showvminfo", "5fa075e2-26b0-4e1b-8cac-23e299c8ac57", "--machinereadable"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: name="docker-host_default_1400698030155_65125"
groups="/"
ostype="Other Linux (64-bit)"
UUID="5fa075e2-26b0-4e1b-8cac-23e299c8ac57"
CfgFile="C:\\Users\\snowc\\VirtualBox VMs\\docker-host_default_1400698030155_65125\\docker-host_default_1400698030155_65125.vbox"
SnapFldr="C:\\Users\\snowc\\VirtualBox VMs\\docker-host_default_1400698030155_65125\\Snapshots"
LogFldr="C:\\Users\\snowc\\VirtualBox VMs\\docker-host_default_1400698030155_65125\\Logs"
hardwareuuid="5fa075e2-26b0-4e1b-8cac-23e299c8ac57"
memory=512
pagefusion="off"
vram=8
cpuexecutioncap=100
hpet="off"
chipset="piix3"
firmware="BIOS"
cpus=1
pae="on"
longmode="on"
synthcpu="off"
bootmenu="messageandmenu"
boot1="disk"
boot2="dvd"
boot3="none"
boot4="none"
acpi="on"
ioapic="on"
Time offset=0rtcuseutc="on"
hwvirtex="on"
nestedpaging="on"
largepages="on"
vtxvpid="on"
vtxux="on"
VMState="running"
VMStateChangeTime="2014-05-24T18:50:26.997000000"
monitorcount=1
accelerate3d="off"
accelerate2dvideo="off"
teleporterenabled="off"
teleporterport=0
teleporteraddress=""
teleporterpassword=""
tracing-enabled="off"
tracing-allow-vm-access="off"
tracing-config=""
autostart-enabled="off"
autostart-delay=0
defaultfrontend=""
storagecontrollername0="IDE Controller"
storagecontrollertype0="PIIX4"
storagecontrollerinstance0="0"
storagecontrollermaxportcount0="2"
storagecontrollerportcount0="2"
storagecontrollerbootable0="on"
"IDE Controller-0-0"="C:\Users\snowc\VirtualBox VMs\packer-virtualbox-iso\packer-virtualbox-iso-disk1.vmdk"
"IDE Controller-ImageUUID-0-0"="1c5f93ae-19f2-4e99-90dc-4dff4431e89c"
"IDE Controller-0-1"="C:\Users\snowc\.vagrant.d\boxes\mitchellh-VAGRANTSLASH-boot2docker\0.8.0\virtualbox\boot2docker-vagrant.iso"
"IDE Controller-ImageUUID-0-1"="e41a6c36-ff4f-4bbc-b3c7-3c1c86886a81"
"IDE Controller-tempeject"="off"
"IDE Controller-IsEjected"="off"
"IDE Controller-1-0"="none"
"IDE Controller-1-1"="none"
natnet1="nat"
macaddress1="0800272E82AE"
cableconnected1="on"
nic1="nat"
nictype1="82540EM"
nicspeed1="0"
mtu="0"
sockSnd="64"
sockRcv="64"
tcpWndSnd="64"
tcpWndRcv="64"
Forwarding(0)="ssh,tcp,127.0.0.1,2222,,22"
Forwarding(1)="tcp4243,tcp,,4243,,4243"
nic2="none"
nic3="none"
nic4="none"
nic5="none"
nic6="none"
nic7="none"
nic8="none"
hidpointing="ps2mouse"
hidkeyboard="ps2kbd"
uart1="off"
uart2="off"
lpt1="off"
lpt2="off"
audio="none"
clipboard="disabled"
draganddrop="disabled"
SessionType="headless"
VideoMode="720,400,0"@0,0
vrde="off"
usb="off"
ehci="off"
VRDEActiveConnection="off"
VRDEClients=0
vcpenabled="off"
vcpscreens=0
vcpfile="C:\Users\snowc\VirtualBox VMs\docker-host_default_1400698030155_65125\docker-host_default_1400698030155_65125.webm"
vcpwidth=1024
vcpheight=768
vcprate=512
vcpfps=25
GuestMemoryBalloon=0
GuestOSType="Linux_64"
GuestAdditionsRunLevel=0
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG virtualbox_4_3: Searching for SSH port: 22
DEBUG virtualbox_4_3: read_forward_ports: uuid=5fa075e2-26b0-4e1b-8cac-23e299c8ac57 active_only=false
INFO subprocess: Starting process: ["C:\\Program Files\\Oracle\\VirtualBox/VBoxManage.EXE", "showvminfo", "5fa075e2-26b0-4e1b-8cac-23e299c8ac57", "--machinereadable"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: name="docker-host_default_1400698030155_65125"
groups="/"
ostype="Other Linux (64-bit)"
UUID="5fa075e2-26b0-4e1b-8cac-23e299c8ac57"
CfgFile="C:\\Users\\snowc\\VirtualBox VMs\\docker-host_default_1400698030155_65125\\docker-host_default_1400698030155_65125.vbox"
SnapFldr="C:\\Users\\snowc\\VirtualBox VMs\\docker-host_default_1400698030155_65125\\Snapshots"
LogFldr="C:\\Users\\snowc\\VirtualBox VMs\\docker-host_default_1400698030155_65125\\Logs"
hardwareuuid="5fa075e2-26b0-4e1b-8cac-23e299c8ac57"
memory=512
pagefusion="off"
vram=8
cpuexecutioncap=100
hpet="off"
chipset="piix3"
firmware="BIOS"
cpus=1
pae="on"
longmode="on"
synthcpu="off"
bootmenu="messageandmenu"
boot1="disk"
boot2="dvd"
boot3="none"
boot4="none"
acpi="on"
ioapic="on"
Time offset=0rtcuseutc="on"
hwvirtex="on"
nestedpaging="on"
largepages="on"
vtxvpid="on"
vtxux="on"
VMState="running"
VMStateChangeTime="2014-05-24T18:50:26.997000000"
monitorcount=1
accelerate3d="off"
accelerate2dvideo="off"
teleporterenabled="off"
teleporterport=0
teleporteraddress=""
teleporterpassword=""
tracing-enabled="off"
tracing-allow-vm-access="off"
tracing-config=""
autostart-enabled="off"
autostart-delay=0
defaultfrontend=""
storagecontrollername0="IDE Controller"
storagecontrollertype0="PIIX4"
storagecontrollerinstance0="0"
storagecontrollermaxportcount0="2"
storagecontrollerportcount0="2"
storagecontrollerbootable0="on"
"IDE Controller-0-0"="C:\Users\snowc\VirtualBox VMs\packer-virtualbox-iso\packer-virtualbox-iso-disk1.vmdk"
"IDE Controller-ImageUUID-0-0"="1c5f93ae-19f2-4e99-90dc-4dff4431e89c"
"IDE Controller-0-1"="C:\Users\snowc\.vagrant.d\boxes\mitchellh-VAGRANTSLASH-boot2docker\0.8.0\virtualbox\boot2docker-vagrant.iso"
"IDE Controller-ImageUUID-0-1"="e41a6c36-ff4f-4bbc-b3c7-3c1c86886a81"
"IDE Controller-tempeject"="off"
"IDE Controller-IsEjected"="off"
"IDE Controller-1-0"="none"
"IDE Controller-1-1"="none"
natnet1="nat"
macaddress1="0800272E82AE"
cableconnected1="on"
nic1="nat"
nictype1="82540EM"
nicspeed1="0"
mtu="0"
sockSnd="64"
sockRcv="64"
tcpWndSnd="64"
tcpWndRcv="64"
Forwarding(0)="ssh,tcp,127.0.0.1,2222,,22"
Forwarding(1)="tcp4243,tcp,,4243,,4243"
nic2="none"
nic3="none"
nic4="none"
nic5="none"
nic6="none"
nic7="none"
nic8="none"
hidpointing="ps2mouse"
hidkeyboard="ps2kbd"
uart1="off"
uart2="off"
lpt1="off"
lpt2="off"
audio="none"
clipboard="disabled"
draganddrop="disabled"
SessionType="headless"
VideoMode="720,400,0"@0,0
vrde="off"
usb="off"
ehci="off"
VRDEActiveConnection="off"
VRDEClients=0
vcpenabled="off"
vcpscreens=0
vcpfile="C:\Users\snowc\VirtualBox VMs\docker-host_default_1400698030155_65125\docker-host_default_1400698030155_65125.webm"
vcpwidth=1024
vcpheight=768
vcprate=512
vcpfps=25
GuestMemoryBalloon=0
GuestOSType="Linux_64"
GuestAdditionsRunLevel=0
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG virtualbox_4_3: - [1, "ssh", 2222, 22]
DEBUG virtualbox_4_3: - [1, "tcp4243", 4243, 4243]
INFO interface: detail: Docker host VM is already ready.
INFO interface: detail: default: Docker host VM is already ready.
default: Docker host VM is already ready.
INFO environment: Released process lock: 83fd64cbb25f27d26c6984a6a5592dff
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x35aefe8>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x3856620>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::IsState:0x3928f38>
DEBUG is_state: Checking if machine state is 'not_created'
INFO machine: New machine ID: nil
DEBUG is_state: -- Machine state: not_created
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::IsState:0x3928f38>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x2daf860>
INFO warden: Calling IN action: #<Proc:0x3735be8@C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::HandleBox:0x2daf7d0>
INFO handle_box: Skipping HandleBox because no box is set
INFO warden: Calling IN action: #<VagrantPlugins::DockerProvider::Action::DestroyBuildImage:0x353e6c8>
INFO warden: Calling IN action: #<Proc:0x35e9e30@C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x35aff78>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x43229d0>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::IsState:0x432b4c0>
DEBUG is_state: Checking if machine state is 'running'
INFO machine: New machine ID: nil
DEBUG is_state: -- Machine state: not_created
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::IsState:0x432b4c0>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x43ed6c8>
INFO warden: Calling IN action: #<Proc:0x450c300@C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x43ed638>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x454ec60>
INFO warden: Calling IN action: #<VagrantPlugins::DockerProvider::Action::HasSSH:0x454ef60>
INFO warden: Calling OUT action: #<VagrantPlugins::DockerProvider::Action::HasSSH:0x454ef60>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x456ed48>
INFO warden: Calling IN action: #<Proc:0x458ef98@C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Message:0x456ed00>
INFO warden: Calling IN action: #<Proc:0x456ecb8@C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x43ed5c0>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x45c59b8>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::IsState:0x45cfe70>
DEBUG is_state: Checking if machine state is 'not_created'
INFO machine: New machine ID: nil
DEBUG is_state: -- Machine state: not_created
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::IsState:0x45cfe70>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x4606f38>
INFO warden: Calling IN action: #<Proc:0x46276c8@C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Proc:0x4606ed8@C:/HashiCorp/Vagrant/embedded/gems/gems/vagrant-1.6.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<VagrantPlugins::DockerProvider::Action::HostMachineBuildDir:0x43ed548>
INFO warden: Calling IN action: #<VagrantPlugins::DockerProvider::Action::HostMachineSyncFolders:0x440e638>
DEBUG environment: Attempting to acquire process-lock: 83fd64cbb25f27d26c6984a6a5592dff
DEBUG environment: Attempting to acquire process-lock: dotlock
INFO environment: Acquired process lock: dotlock
INFO environment: Released process lock: dotlock
INFO environment: Acquired process lock: 83fd64cbb25f27d26c6984a6a5592dff
INFO interface: output: Syncing folders to the host VM...
INFO interface: output: ==> default: Syncing folders to the host VM...
==> default: Syncing folders to the host VM...
INFO machine: Calling action: sync_folders on provider VirtualBox (5fa075e2-26b0-4e1b-8cac-23e299c8ac57)
DEBUG environment: Attempting to acquire process-lock: machine-action-1028b18f80317c0bc04aba0120f92ede
DEBUG environment: Attempting to acquire process-lock: dotlock
INFO environment: Acquired process lock: dotlock
INFO environment: Released process lock: dotlock
INFO environment: Acquired process lock: machine-action-1028b18f80317c0bc04aba0120f92ede
INFO environment: Released process lock: machine-action-1028b18f80317c0bc04aba0120f92ede
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x2d3ce40>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SyncedFolders:0x2d7d988>
INFO subprocess: Starting process: ["C:\\Windows\\System32\\WindowsPowerShell\\v1.0\\/powershell.EXE", "-NoProfile", "-ExecutionPolicy", "Bypass", "$PSVersionTable.PSVersion.Major"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: 2
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment