Skip to content

Instantly share code, notes, and snippets.

@tuminoid
Created January 19, 2015 08:06
Show Gist options
  • Save tuminoid/137f1b4431e89fb36fd3 to your computer and use it in GitHub Desktop.
Save tuminoid/137f1b4431e89fb36fd3 to your computer and use it in GitHub Desktop.
vagrant-lxc with centos hangs
$ VAGRANT_LOG=debug vagrant up
INFO global: Vagrant version: 1.7.2
INFO global: Ruby version: 2.0.0
INFO global: RubyGems version: 2.0.14
INFO global: VAGRANT_DEFAULT_PROVIDER="lxc"
INFO global: VAGRANT_EXECUTABLE="/opt/vagrant/bin/../embedded/gems/gems/vagrant-1.7.2/bin/vagrant"
INFO global: VAGRANT_LOG="debug"
INFO global: VAGRANT_INSTALLER_EMBEDDED_DIR="/opt/vagrant/bin/../embedded"
INFO global: VAGRANT_INSTALLER_VERSION="2"
INFO global: VAGRANT_DETECTED_OS="Linux"
INFO global: VAGRANT_INSTALLER_ENV="1"
INFO global: VAGRANT_INTERNAL_BUNDLERIZED="1"
INFO global: Plugins:
INFO global: - bundler = 1.7.11
INFO global: - json = 1.8.1
INFO global: - mime-types = 1.25.1
INFO global: - rdoc = 4.2.0
INFO global: - rest-client = 1.6.8
INFO global: - vagrant-cachier = 1.1.0
INFO global: - vagrant-lxc = 1.0.1
INFO global: - vagrant-omnibus = 1.4.1
INFO global: - vagrant-proxyconf = 1.4.0
INFO global: - vagrant-share = 1.1.3
INFO global: - vagrant-timezone = 1.0.0
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/reload/plugin.rb
INFO manager: Registered plugin: reload command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/package/plugin.rb
INFO manager: Registered plugin: package command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/help/plugin.rb
INFO manager: Registered plugin: help command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/global-status/plugin.rb
INFO manager: Registered plugin: global-status command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/destroy/plugin.rb
INFO manager: Registered plugin: destroy command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/ssh/plugin.rb
INFO manager: Registered plugin: ssh command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/suspend/plugin.rb
INFO manager: Registered plugin: suspend command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/status/plugin.rb
INFO manager: Registered plugin: status command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/rdp/plugin.rb
INFO manager: Registered plugin: rdp command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/push/plugin.rb
INFO manager: Registered plugin: push command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/list-commands/plugin.rb
INFO manager: Registered plugin: list-commands command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/provision/plugin.rb
INFO manager: Registered plugin: provision command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/version/plugin.rb
INFO manager: Registered plugin: version command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/box/plugin.rb
INFO manager: Registered plugin: box command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/up/plugin.rb
INFO manager: Registered plugin: up command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/login/plugin.rb
INFO manager: Registered plugin: vagrant-login
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/plugin/plugin.rb
INFO manager: Registered plugin: plugin command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/init/plugin.rb
INFO manager: Registered plugin: init command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/ssh_config/plugin.rb
INFO manager: Registered plugin: ssh-config command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/resume/plugin.rb
INFO manager: Registered plugin: resume command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/commands/halt/plugin.rb
INFO manager: Registered plugin: halt command
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/debian/plugin.rb
INFO manager: Registered plugin: Debian guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/freebsd/plugin.rb
INFO manager: Registered plugin: FreeBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/openbsd/plugin.rb
INFO manager: Registered plugin: OpenBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/darwin/plugin.rb
INFO manager: Registered plugin: Darwin guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/fedora/plugin.rb
INFO manager: Registered plugin: Fedora guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/solaris11/plugin.rb
INFO manager: Registered plugin: Solaris 11 guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/tinycore/plugin.rb
INFO manager: Registered plugin: TinyCore Linux guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/ubuntu/plugin.rb
INFO manager: Registered plugin: Ubuntu guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/netbsd/plugin.rb
INFO manager: Registered plugin: NetBSD guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/linux/plugin.rb
INFO manager: Registered plugin: Linux guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/redhat/plugin.rb
INFO manager: Registered plugin: RedHat guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/smartos/plugin.rb
INFO manager: Registered plugin: SmartOS guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/pld/plugin.rb
INFO manager: Registered plugin: PLD Linux guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/suse/plugin.rb
INFO manager: Registered plugin: SUSE guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/funtoo/plugin.rb
INFO manager: Registered plugin: Funtoo guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/arch/plugin.rb
INFO manager: Registered plugin: Arch guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/nixos/plugin.rb
INFO manager: Registered plugin: NixOS guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/gentoo/plugin.rb
INFO manager: Registered plugin: Gentoo guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/esxi/plugin.rb
INFO manager: Registered plugin: ESXi guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/mint/plugin.rb
INFO manager: Registered plugin: Mint guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/coreos/plugin.rb
INFO manager: Registered plugin: CoreOS guest
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/omnios/plugin.rb
INFO manager: Registered plugin: OmniOS guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/windows/plugin.rb
INFO manager: Registered plugin: Windows guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/guests/solaris/plugin.rb
INFO manager: Registered plugin: Solaris guest.
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/kernel_v2/plugin.rb
INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/provisioners/salt/plugin.rb
INFO manager: Registered plugin: salt
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/provisioners/docker/plugin.rb
INFO manager: Registered plugin: docker
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/provisioners/file/plugin.rb
INFO manager: Registered plugin: file
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/provisioners/chef/plugin.rb
INFO manager: Registered plugin: chef
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/provisioners/shell/plugin.rb
INFO manager: Registered plugin: shell
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/provisioners/cfengine/plugin.rb
INFO manager: Registered plugin: CFEngine Provisioner
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/provisioners/ansible/plugin.rb
INFO manager: Registered plugin: ansible
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/provisioners/puppet/plugin.rb
INFO manager: Registered plugin: puppet
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/communicators/winrm/plugin.rb
INFO manager: Registered plugin: winrm communicator
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/communicators/ssh/plugin.rb
INFO manager: Registered plugin: ssh communicator
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/providers/hyperv/plugin.rb
INFO manager: Registered plugin: Hyper-V provider
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/providers/docker/plugin.rb
INFO manager: Registered plugin: docker-provider
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/providers/virtualbox/plugin.rb
INFO manager: Registered plugin: VirtualBox provider
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/kernel_v1/plugin.rb
INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/pushes/ftp/plugin.rb
INFO manager: Registered plugin: ftp
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/pushes/local-exec/plugin.rb
INFO manager: Registered plugin: local-exec
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/pushes/heroku/plugin.rb
INFO manager: Registered plugin: heroku
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/pushes/atlas/plugin.rb
INFO manager: Registered plugin: atlas
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/pushes/noop/plugin.rb
INFO manager: Registered plugin: noop
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/hosts/null/plugin.rb
INFO manager: Registered plugin: null host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/hosts/freebsd/plugin.rb
INFO manager: Registered plugin: FreeBSD host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/hosts/darwin/plugin.rb
INFO manager: Registered plugin: Mac OS X host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/hosts/linux/plugin.rb
INFO manager: Registered plugin: Linux host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/hosts/redhat/plugin.rb
INFO manager: Registered plugin: Red Hat host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/hosts/suse/plugin.rb
INFO manager: Registered plugin: SUSE host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/hosts/arch/plugin.rb
INFO manager: Registered plugin: Arch host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/hosts/bsd/plugin.rb
INFO manager: Registered plugin: BSD host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/hosts/gentoo/plugin.rb
INFO manager: Registered plugin: Gentoo host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/hosts/windows/plugin.rb
INFO manager: Registered plugin: Windows host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/hosts/slackware/plugin.rb
INFO manager: Registered plugin: Slackware host
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/synced_folders/rsync/plugin.rb
INFO manager: Registered plugin: RSync synced folders
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/synced_folders/smb/plugin.rb
INFO manager: Registered plugin: SMB synced folders
DEBUG global: Loading core plugin: /opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/synced_folders/nfs/plugin.rb
INFO manager: Registered plugin: NFS synced folders
INFO global: Loading plugins!
INFO manager: Registered plugin: vagrant-share
INFO manager: Registered plugin: vagrant-cachier
INFO manager: Registered plugin: vagrant-lxc
INFO proxyconf: Trying to load vagrant-aws
INFO proxyconf: Failed to load vagrant-aws: #<LoadError: cannot load such file -- vagrant-aws>
INFO proxyconf: Ignoring the error
INFO proxyconf: Trying to load vagrant-omnibus
INFO manager: Registered plugin: vagrant-omnibus
INFO proxyconf: Trying to load vagrant-vbguest
INFO proxyconf: Failed to load vagrant-vbguest: #<LoadError: cannot load such file -- vagrant-vbguest>
INFO proxyconf: Ignoring the error
INFO manager: Registered plugin: vagrant-proxyconf
INFO manager: Registered plugin: vagrant-timezone
INFO vagrant: `vagrant` invoked: ["up"]
DEBUG vagrant: Creating Vagrant environment
INFO environment: Environment initialized (#<Vagrant::Environment:0x00000001bb4940>)
INFO environment: - cwd: /home/tumi/git/centostest
INFO environment: Home path: /home/tumi/.vagrant.d
INFO environment: Local data path: /home/tumi/git/centostest/.vagrant
DEBUG environment: Creating: /home/tumi/git/centostest/.vagrant
INFO environment: Running hook: environment_plugins_loaded
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 5 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000002ae0d38>
INFO environment: Running hook: environment_load
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 5 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000002dbdda8>
INFO cli: CLI: [] "up" []
DEBUG cli: Invoking command class: VagrantPlugins::CommandUp::Command []
DEBUG command: 'Up' each target VM...
INFO loader: Set :home = #<Pathname:/home/tumi/.vagrant.d/Vagrantfile>
DEBUG loader: Populating proc cache for #<Pathname:/home/tumi/.vagrant.d/Vagrantfile>
DEBUG loader: Load procs for pathname: /home/tumi/.vagrant.d/Vagrantfile
INFO loader: Set :root = #<Pathname:/home/tumi/git/centostest/Vagrantfile>
DEBUG loader: Populating proc cache for #<Pathname:/home/tumi/git/centostest/Vagrantfile>
DEBUG loader: Load procs for pathname: /home/tumi/git/centostest/Vagrantfile
INFO root: Version requirements from Vagrantfile: [">= 1.5.0"]
INFO root: - Version requirements satisfied!
INFO loader: Loading configuration in order: [:home, :root]
DEBUG loader: Loading from: home (evaluating)
DEBUG loader: Loading from: root (evaluating)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
DEBUG command: Getting target VMs for command. Arguments:
DEBUG command: -- names: ["centostest"]
DEBUG command: -- options: {:provider=>nil}
DEBUG command: Finding machine that match name: centostest
INFO command: Active machine found with name centostest. Using provider: lxc
INFO environment: Getting machine: centostest (lxc)
INFO environment: Uncached load of machine.
INFO loader: Set "22895940_machine_centostest" = [["2", #<Proc:0x00000001df84b8@/home/tumi/git/centostest/Vagrantfile:12>]]
DEBUG loader: Populating proc cache for ["2", #<Proc:0x00000001df84b8@/home/tumi/git/centostest/Vagrantfile:12>]
INFO loader: Loading configuration in order: [:home, :root, "22895940_machine_centostest"]
DEBUG loader: Loading from: home (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 22895940_machine_centostest (evaluating)
DEBUG provisioner: Provisioner defined:
DEBUG provisioner: Provisioner defined:
DEBUG provisioner: Provisioner defined:
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
INFO loader: Set :"22895940_vm_centostest__lxc" = [["2", #<Proc:0x00000002d17840>], ["2", #<Proc:0x00000002d49d90>], ["2", #<Proc:0x00000002b23778>]]
DEBUG loader: Populating proc cache for ["2", #<Proc:0x00000002d17840>]
DEBUG loader: Populating proc cache for ["2", #<Proc:0x00000002d49d90>]
DEBUG loader: Populating proc cache for ["2", #<Proc:0x00000002b23778>]
INFO loader: Loading configuration in order: [:home, :root, "22895940_machine_centostest", :"22895940_vm_centostest__lxc"]
DEBUG loader: Loading from: home (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 22895940_machine_centostest (cache)
DEBUG loader: Loading from: 22895940_vm_centostest__lxc (evaluating)
DEBUG loader: Loading from: 22895940_vm_centostest__lxc (evaluating)
DEBUG loader: Loading from: 22895940_vm_centostest__lxc (evaluating)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
INFO box_collection: Box found: fgrehm/centos-6-64-lxc (lxc)
INFO environment: Running hook: authenticate_box_url
INFO host: Autodetecting host type for [#<Vagrant::Environment: /home/tumi/git/centostest>]
DEBUG host: Trying: freebsd
DEBUG host: Trying: darwin
DEBUG host: Trying: redhat
DEBUG host: Trying: suse
DEBUG host: Trying: arch
DEBUG host: Trying: gentoo
DEBUG host: Trying: slackware
DEBUG host: Trying: null
DEBUG host: Trying: linux
INFO host: Detected: linux!
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 6 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000001d83e60>
INFO warden: Calling IN action: #<VagrantPlugins::LoginCommand::AddAuthentication:0x00000003bbd598>
INFO warden: Calling OUT action: #<VagrantPlugins::LoginCommand::AddAuthentication:0x00000003bbd598>
INFO loader: Set :"22895940_vm_centostest_fgrehm/centos-6-64-lxc_lxc" = [["2", #<Proc:0x00000002d17840>], ["2", #<Proc:0x00000002d49d90>], ["2", #<Proc:0x00000002b23778>]]
INFO loader: Loading configuration in order: [:home, :root, "22895940_machine_centostest", :"22895940_vm_centostest_fgrehm/centos-6-64-lxc_lxc"]
ERROR loader: Unknown config sources: [:"22895940_vm_centostest__lxc"]
DEBUG loader: Loading from: home (cache)
DEBUG loader: Loading from: root (cache)
DEBUG loader: Loading from: 22895940_machine_centostest (cache)
DEBUG loader: Loading from: 22895940_vm_centostest_fgrehm/centos-6-64-lxc_lxc (cache)
DEBUG loader: Loading from: 22895940_vm_centostest_fgrehm/centos-6-64-lxc_lxc (cache)
DEBUG loader: Loading from: 22895940_vm_centostest_fgrehm/centos-6-64-lxc_lxc (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
INFO machine: Initializing machine: centostest
INFO machine: - Provider: Vagrant::LXC::Provider
INFO machine: - Box: #<Vagrant::Box:0x00000001f44538>
INFO machine: - Data dir: /home/tumi/git/centostest/.vagrant/machines/centostest/lxc
INFO subprocess: Starting process: ["/usr/bin/sudo", "which", "lxc-create"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: /usr/bin/lxc-create
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG lxc: Instantiating the container for: "centos-test_1421654032249_30243"
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-ls"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG lxc: Container not found! Clearing saved machine ID and reloading.
DEBUG lxc: Instantiating the container for: nil
INFO machine: New machine ID: nil
DEBUG lxc: Instantiating the container for: nil
INFO command: With machine: centostest (#<Vagrant::LXC::Provider:0x00000002d45128 @logger=#<Log4r::Logger:0x00000002d45010 @fullname="vagrant::provider::lxc", @outputters=[], @additive=true, @name="lxc", @path="vagrant::provider", @parent=#<Log4r::Logger:0x00000001e0c800 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001f1d7f8 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001f1d6b8>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001f32658 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000001e0c5a8 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @machine=#<Vagrant::Machine: centostest (Vagrant::LXC::Provider)>, @shell=#<Vagrant::LXC::SudoWrapper:0x00000002d94f98 @wrapper_path=nil, @logger=#<Log4r::Logger:0x00000002d94ea8 @fullname="vagrant::lxc::sudo_wrapper", @outputters=[], @additive=true, @name="sudo_wrapper", @path="vagrant::lxc", @parent=#<Log4r::Logger:0x00000001e0c800 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001f1d7f8 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001f1d6b8>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001f32658 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000001e0c5a8 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>>, @driver=#<Vagrant::LXC::Driver:0x00000003851bc0 @container_name=nil, @sudo_wrapper=#<Vagrant::LXC::SudoWrapper:0x00000002d94f98 @wrapper_path=nil, @logger=#<Log4r::Logger:0x00000002d94ea8 @fullname="vagrant::lxc::sudo_wrapper", @outputters=[], @additive=true, @name="sudo_wrapper", @path="vagrant::lxc", @parent=#<Log4r::Logger:0x00000001e0c800 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001f1d7f8 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001f1d6b8>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001f32658 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000001e0c5a8 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>>, @cli=#<Vagrant::LXC::Driver::CLI:0x00000003851b98 @sudo_wrapper=#<Vagrant::LXC::SudoWrapper:0x00000002d94f98 @wrapper_path=nil, @logger=#<Log4r::Logger:0x00000002d94ea8 @fullname="vagrant::lxc::sudo_wrapper", @outputters=[], @additive=true, @name="sudo_wrapper", @path="vagrant::lxc", @parent=#<Log4r::Logger:0x00000001e0c800 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001f1d7f8 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001f1d6b8>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001f32658 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000001e0c5a8 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>>, @name=nil, @logger=#<Log4r::Logger:0x00000003851b48 @fullname="vagrant::provider::lxc::container::cli", @outputters=[], @additive=true, @name="cli", @path="vagrant::provider::lxc::container", @parent=#<Log4r::Logger:0x00000002d45010 @fullname="vagrant::provider::lxc", @outputters=[], @additive=true, @name="lxc", @path="vagrant::provider", @parent=#<Log4r::Logger:0x00000001e0c800 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001f1d7f8 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001f1d6b8>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001f32658 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000001e0c5a8 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @level=1, @trace=false>>, @logger=#<Log4r::Logger:0x0000000388abf0 @fullname="vagrant::provider::lxc::driver", @outputters=[], @additive=true, @name="driver", @path="vagrant::provider::lxc", @parent=#<Log4r::Logger:0x00000002d45010 @fullname="vagrant::provider::lxc", @outputters=[], @additive=true, @name="lxc", @path="vagrant::provider", @parent=#<Log4r::Logger:0x00000001e0c800 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001f1d7f8 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001f1d6b8>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001f32658 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000001e0c5a8 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @level=1, @trace=false>, @customizations=[]>, @cap_logger=#<Log4r::Logger:0x0000000374ca40 @fullname="vagrant::capability_host::vagrant::lxc::provider", @outputters=[], @additive=true, @name="provider", @path="vagrant::capability_host::vagrant::lxc", @parent=#<Log4r::Logger:0x00000001e0c800 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001f1d7f8 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001f1d6b8>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001f32658 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000001e0c5a8 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @cap_host_chain=[[:lxc, #<#<Class:0x0000000374d170>:0x00000003769578>]], @cap_args=[#<Vagrant::Machine: centostest (Vagrant::LXC::Provider)>], @cap_caps={:hyperv=>#<Vagrant::Registry:0x0000000374d008 @items={:public_address=>#<Proc:0x00000002094848@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/providers/hyperv/plugin.rb:25>}, @results_cache={}>, :docker=>#<Vagrant::Registry:0x0000000374ce78 @items={:public_address=>#<Proc:0x000000020f8758@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/providers/docker/plugin.rb:54>, :proxy_machine=>#<Proc:0x000000020f8618@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/providers/docker/plugin.rb:59>}, @results_cache={}>, :virtualbox=>#<Vagrant::Registry:0x0000000374cd38 @items={:forwarded_ports=>#<Proc:0x00000002139de8@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/providers/virtualbox/plugin.rb:27>, :nic_mac_addresses=>#<Proc:0x00000002138e20@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/plugins/providers/virtualbox/plugin.rb:32>, :public_address=>#<Proc:0x00000001bb7f78@/opt/vagrant/embedded/gems/gems/vagrant-share-1.1.3/lib/vagrant-share.rb:39>}, @results_cache={}>, :lxc=>#<Vagrant::Registry:0x0000000374cc20 @items={:public_address=>#<Proc:0x00000002ae26d8@/home/tumi/.vagrant.d/gems/gems/vagrant-lxc-1.0.1/lib/vagrant-lxc/plugin.rb:42>}, @results_cache={}>}>)
INFO interface: info: Bringing machine 'centostest' up with 'lxc' provider...
Bringing machine 'centostest' up with 'lxc' 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:0x00000002bbf2e0> up {:destroy_on_error=>true, :parallel=>true, :provision_ignore_sentinel=>false, :provision_types=>nil}
INFO machine: Calling action: up on provider LXC (new VM)
DEBUG environment: Attempting to acquire process-lock: machine-action-702432e437884e1531096df052a6ba25
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-702432e437884e1531096df052a6ba25
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 6 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x000000038dd8f0>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x00000003910200>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x000000039101d8>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 6 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000002ae0450>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::IsState:0x00000002b0c550>
DEBUG is_state: Checking if machine state is 'not_created'
DEBUG is_state: -- Machine state: not_created
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::IsState:0x00000002b0c550>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 6 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x00000002d18cb8>
INFO warden: Calling IN action: #<Proc:0x00000002e07bd8@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::HandleBox:0x00000002d18ba0>
INFO handle_box: Machine already has box. HandleBox will not run.
INFO warden: Calling IN action: #<Vagrant::LXC::Action::HandleBoxMetadata:0x00000002d5fe60>
INFO interface: info: Importing base box 'fgrehm/centos-6-64-lxc'...
INFO interface: info: ==> centostest: Importing base box 'fgrehm/centos-6-64-lxc'...
==> centostest: Importing base box 'fgrehm/centos-6-64-lxc'...
INFO handle_box_metadata: Validating box contents
INFO handle_box_metadata: Setting box options on environment
INFO warden: Calling IN action: #<Vagrant::LXC::Action::Create:0x00000002daf4d8>
INFO driver: Copying LXC template into place
INFO subprocess: Starting process: ["/usr/bin/sudo", "cp", "/home/tumi/.vagrant.d/gems/gems/vagrant-lxc-1.0.1/scripts/lxc-template", "/usr/share/lxc/templates/lxc-vagrant-tmp-centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "chmod", "+x", "/usr/share/lxc/templates/lxc-vagrant-tmp-centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG driver: Creating container...
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-create", "-B", "best", "--template", "vagrant-tmp-centos-test_1421654214387_8629", "--name", "centos-test_1421654214387_8629", "--", "--tarball", "/home/tumi/.vagrant.d/boxes/fgrehm-VAGRANTSLASH-centos-6-64-lxc/1.0.0/lxc/rootfs.tar.gz", "--config", "/home/tumi/.vagrant.d/boxes/fgrehm-VAGRANTSLASH-centos-6-64-lxc/1.0.0/lxc/lxc-config"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stderr: File descriptor 3 (/var/lib/lxc/centos-test_1421654214387_8629/partial) leaked on lvcreate invocation. Parent PID 31119: lxc-create
DEBUG subprocess: stderr: Volume group "lxc" not found
DEBUG subprocess: stderr: lxc_container: Error creating new lvm blockdev /dev/lxc/centos-test_1421654214387_8629 size 1073741824 bytes
DEBUG subprocess: stdout: Unpacking the rootfs
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 31998
DEBUG subprocess: Exit status: 0
INFO driver: Removing LXC template
INFO subprocess: Starting process: ["/usr/bin/sudo", "rm", "/usr/share/lxc/templates/lxc-vagrant-tmp-centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO machine: New machine ID: "centos-test_1421654214387_8629"
DEBUG lxc: Instantiating the container for: "centos-test_1421654214387_8629"
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-ls"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: centos-test_1421654214387_8629
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO warden: Calling IN action: #<Proc:0x00000002daf410@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x00000003910188>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::BoxCheckOutdated:0x00000003910160>
DEBUG box_check_outdated: Not checking for update: no force and no update config
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00000001daba78>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 6 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x0000000385a3d8>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::IsState:0x00000003860df0>
DEBUG is_state: Checking if machine state is 'running'
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: STOPPED
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: STOPPED
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: STOPPED
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: STOPPED
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: STOPPED
Name: centos-test_1421654214387_8629
State: STOPPED
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG is_state: -- Machine state: stopped
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::IsState:0x00000003860df0>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 6 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x00000002bd74a8>
INFO warden: Calling IN action: #<Proc:0x000000037abd60@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<VagrantPlugins::Cachier::Action::ConfigureBucketRoot:0x00000002bd7430>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Provision:0x00000002cb1a18>
INFO provision: Checking provisioner sentinel file...
INFO warden: Calling IN action: #<VagrantPlugins::TimeZone::Action::SetTimeZone:0x00000002d3d9a0>
INFO warden: Calling IN action: #<VagrantPlugins::ProxyConf::Action::OnlyOnce:0x00000002d3d900>
INFO warden: Calling IN action: #<VagrantPlugins::Omnibus::Action::InstallChef:0x00000002d3d8d8>
INFO warden: Calling IN action: #<VagrantPlugins::ProxyConf::Action::OnlyOnce:0x00000002d89058>
INFO warden: Calling IN action: #<VagrantPlugins::Cachier::Action::InstallBuckets:0x00000002d88fb8>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::EnvSet:0x00000002de1ff0>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::HandleForwardedPortCollisions:0x00000002de2130>
DEBUG environment: Attempting to acquire process-lock: fpcollision
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: fpcollision
INFO handle_port_collisions: Detecting any forwarded port collisions...
DEBUG handle_port_collisions: Extra in use: []
DEBUG handle_port_collisions: Remap: {}
DEBUG handle_port_collisions: Repair: true
INFO warden: Calling IN action: #<Vagrant::LXC::Action::PrepareNFSValidIds:0x000000036c3998>
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-ls"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: centos-test_1421654214387_8629
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO warden: Calling IN action: #<VagrantPlugins::SyncedFolderNFS::ActionCleanup:0x000000036dde38>
DEBUG host: Searching for cap: nfs_prune
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_prune in linux
INFO nfs: NFS pruning. Valid IDs: ["centos-test_1421654214387_8629"]
DEBUG host: Searching for cap: nfs_prune
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_prune in linux
INFO host: Execute capability: nfs_prune [#<Vagrant::Environment: /home/tumi/git/centostest>, #<Vagrant::UI::Prefixed:0x00000002cf0880 @logger=#<Log4r::Logger:0x00000002cf07e0 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x00000001e0c800 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001f1d7f8 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001f1d6b8>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001f32658 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000001e0c5a8 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={}, @prefix=:centostest, @ui=#<Vagrant::UI::Colored:0x00000001bbf598 @logger=#<Log4r::Logger:0x00000001bbf3e0 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x00000001e0c800 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001f1d7f8 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001f1d6b8>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001f32658 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000001e0c5a8 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={:color=>:default}, @lock=#<Mutex:0x00000001dba3c0>>>, ["centos-test_1421654214387_8629"]] (linux)
INFO linux: Pruning invalid NFS entries...
INFO interface: info: Pruning invalid NFS exports. Administrator privileges will be required...
INFO interface: info: ==> centostest: Pruning invalid NFS exports. Administrator privileges will be required...
==> centostest: Pruning invalid NFS exports. Administrator privileges will be required...
INFO linux: Invalid ID, pruning: playbooks_trusty64_1421136550235_88582
INFO linux: Invalid ID, pruning: centos-test_1421149974603_15772
INFO linux: Invalid ID, pruning: kisakone-dev_kisakone_1421642188179_56674
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SyncedFolderCleanup:0x000000036f9228>
DEBUG host: Searching for cap: nfs_installed
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_installed in linux
INFO host: Execute capability: nfs_installed [#<Vagrant::Environment: /home/tumi/git/centostest>] (linux)
INFO synced_folder_cleanup: Invoking synced folder cleanup for: nfs
INFO nfs: NFS pruning. Valid IDs: ["centos-test_1421654214387_8629"]
DEBUG host: Searching for cap: nfs_prune
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_prune in linux
INFO host: Execute capability: nfs_prune [#<Vagrant::Environment: /home/tumi/git/centostest>, #<Vagrant::UI::Prefixed:0x00000002cf0880 @logger=#<Log4r::Logger:0x00000002cf07e0 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x00000001e0c800 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001f1d7f8 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001f1d6b8>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001f32658 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000001e0c5a8 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={}, @prefix=:centostest, @ui=#<Vagrant::UI::Colored:0x00000001bbf598 @logger=#<Log4r::Logger:0x00000001bbf3e0 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x00000001e0c800 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x00000001f1d7f8 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Mutex:0x00000001f1d6b8>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x00000001f32658 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x00000001e0c5a8 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={:color=>:default}, @lock=#<Mutex:0x00000001dba3c0>>>, ["centos-test_1421654214387_8629"]] (linux)
INFO linux: Pruning invalid NFS entries...
INFO synced_folder_cleanup: Invoking synced folder cleanup for: lxc
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SyncedFolders:0x0000000371be40>
DEBUG host: Searching for cap: nfs_installed
DEBUG host: Checking in: linux
DEBUG host: Found cap: nfs_installed in linux
INFO host: Execute capability: nfs_installed [#<Vagrant::Environment: /home/tumi/git/centostest>] (linux)
INFO synced_folders: Synced Folder Implementation: nfs
INFO synced_folders: - vagrant-cache: /home/tumi/git/centostest/.vagrant/machines/centostest/cache => /tmp/vagrant-cache
INFO synced_folders: Synced Folder Implementation: lxc
INFO synced_folders: - /vagrant: . => /vagrant
INFO synced_folders: Invoking synced folder prepare for: nfs
INFO synced_folders: Invoking synced folder prepare for: lxc
INFO interface: output: Setting up mount entries for shared folders...
INFO interface: output: ==> centostest: Setting up mount entries for shared folders...
==> centostest: Setting up mount entries for shared folders...
INFO subprocess: Starting process: ["/usr/bin/sudo", "which", "lxc-version"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 1
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-create", "--version"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: 1.0.6
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-config", "lxc.lxcpath"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: /var/lib/lxc
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "cat", "/var/lib/lxc/centos-test_1421654214387_8629/config"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: # Template used to create this container: /usr/share/lxc/templates/lxc-vagrant-tmp-centos-test_1421654214387_8629
# Parameters passed to the template: --tarball /home/tumi/.vagrant.d/boxes/fgrehm-VAGRANTSLASH-centos-6-64-lxc/1.0.0/lxc/rootfs.tar.gz --config /home/tumi/.vagrant.d/boxes/fgrehm-VAGRANTSLASH-centos-6-64-lxc/1.0.0/lxc/lxc-config
# For additional config options, please look at lxc.container.conf(5)
##############################################
# Container specific configuration (automatically set)
lxc.rootfs = /var/lib/lxc/centos-test_1421654214387_8629/rootfs
lxc.utsname = centos-test_1421654214387_8629
##############################################
# Network configuration (automatically set)
lxc.network.type = veth
lxc.network.flags = up
lxc.network.link = lxcbr0
lxc.network.hwaddr = 00:16:3e:59:f6:0c
##############################################
# vagrant-lxc base box specific configuration
# Taken from the oracle.common.conf.in
# Console settings
lxc.devttydir = lxc
lxc.tty = 4
lxc.pts = 1024
# Mount entries
lxc.mount.auto = proc:mixed sys:ro
# Ensure hostname is changed on clone
lxc.hook.clone = /usr/share/lxc/hooks/clonehostname
# Capabilities
# Uncomment these if you don't run anything that needs the capability, and
# would like the container to run with less privilege.
#
# Dropping sys_admin disables container root from doing a lot of things
# that could be bad like re-mounting lxc fstab entries rw for example,
# but also disables some useful things like being able to nfs mount, and
# things that are already namespaced with ns_capable() kernel checks, like
# hostname(1).
# lxc.cap.drop = sys_admin
# lxc.cap.drop = net_raw # breaks dhcp/ping
# lxc.cap.drop = setgid # breaks login (initgroups/setgroups)
# lxc.cap.drop = dac_read_search # breaks login (pam unix_chkpwd)
# lxc.cap.drop = setuid # breaks sshd,nfs statd
# lxc.cap.drop = audit_control # breaks sshd (set_loginuid failed)
# lxc.cap.drop = audit_write
#
lxc.cap.drop = mac_admin mac_override setfcap setpcap
lxc.cap.drop = sys_module sys_nice sys_pacct
lxc.cap.drop = sys_rawio sys_time
# Control Group devices: all denied except those whitelisted
lxc.cgroup.devices.deny = a
# Allow any mknod (but not reading/writing the node)
lxc.cgroup.devices.allow = c *:* m
lxc.cgroup.devices.allow = b *:* m
lxc.cgroup.devices.allow = c 1:3 rwm # /dev/null
lxc.cgroup.devices.allow = c 1:5 rwm # /dev/zero
lxc.cgroup.devices.allow = c 1:7 rwm # /dev/full
lxc.cgroup.devices.allow = c 5:0 rwm # /dev/tty
lxc.cgroup.devices.allow = c 1:8 rwm # /dev/random
lxc.cgroup.devices.allow = c 1:9 rwm # /dev/urandom
lxc.cgroup.devices.allow = c 136:* rwm # /dev/tty[1-4] ptys and lxc console
lxc.cgroup.devices.allow = c 5:2 rwm # /dev/ptmx pty master
# Blacklist some syscalls which are not safe in privileged
# containers
lxc.seccomp = /usr/share/lxc/config/common.seccomp
##############################################
# vagrant-lxc container specific configuration
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG driver: Guest path doesn't exist, creating: /var/lib/lxc/centos-test_1421654214387_8629/rootfs/vagrant
INFO subprocess: Starting process: ["/usr/bin/sudo", "mkdir", "-p", "/var/lib/lxc/centos-test_1421654214387_8629/rootfs/vagrant"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO interface: detail: /vagrant => /home/tumi/git/centostest
INFO interface: detail: centostest: /vagrant => /home/tumi/git/centostest
centostest: /vagrant => /home/tumi/git/centostest
INFO warden: Calling IN action: #<VagrantPlugins::ProxyConf::Action::OnlyOnce:0x000000037363f8>
INFO warden: Calling IN action: #<Vagrant::LXC::Action::PrepareNFSSettings:0x000000037363d0>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SetHostname:0x000000037508e8>
INFO warden: Calling IN action: #<Vagrant::LXC::Action::WarnNetworks:0x000000037508c0>
INFO warden: Calling IN action: #<Vagrant::LXC::Action::ForwardPorts:0x00000003750898>
INFO warden: Calling IN action: #<Vagrant::LXC::Action::Boot:0x000000037758a0>
INFO interface: info: Starting container...
INFO interface: info: ==> centostest: Starting container...
==> centostest: Starting container...
INFO driver: Starting container...
DEBUG driver: Prunning vagrant-lxc customizations
INFO subprocess: Starting process: ["/usr/bin/sudo", "cat", "/var/lib/lxc/centos-test_1421654214387_8629/config"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: # Template used to create this container: /usr/share/lxc/templates/lxc-vagrant-tmp-centos-test_1421654214387_8629
# Parameters passed to the template: --tarball /home/tumi/.vagrant.d/boxes/fgrehm-VAGRANTSLASH-centos-6-64-lxc/1.0.0/lxc/rootfs.tar.gz --config /home/tumi/.vagrant.d/boxes/fgrehm-VAGRANTSLASH-centos-6-64-lxc/1.0.0/lxc/lxc-config
# For additional config options, please look at lxc.container.conf(5)
##############################################
# Container specific configuration (automatically set)
lxc.rootfs = /var/lib/lxc/centos-test_1421654214387_8629/rootfs
lxc.utsname = centos-test_1421654214387_8629
##############################################
# Network configuration (automatically set)
lxc.network.type = veth
lxc.network.flags = up
lxc.network.link = lxcbr0
lxc.network.hwaddr = 00:16:3e:59:f6:0c
##############################################
# vagrant-lxc base box specific configuration
# Taken from the oracle.common.conf.in
# Console settings
lxc.devttydir = lxc
lxc.tty = 4
lxc.pts = 1024
# Mount entries
lxc.mount.auto = proc:mixed sys:ro
# Ensure hostname is changed on clone
lxc.hook.clone = /usr/share/lxc/hooks/clonehostname
# Capabilities
# Uncomment these if you don't run anything that needs the capability, and
# would like the container to run with less privilege.
#
# Dropping sys_admin disables container root from doing a lot of things
# that could be bad like re-mounting lxc fstab entries rw for example,
# but also disables some useful things like being able to nfs mount, and
# things that are already namespaced with ns_capable() kernel checks, like
# hostname(1).
# lxc.cap.drop = sys_admin
# lxc.cap.drop = net_raw # breaks dhcp/ping
# lxc.cap.drop = setgid # breaks login (initgroups/setgroups)
# lxc.cap.drop = dac_read_search # breaks login (pam unix_chkpwd)
# lxc.cap.drop = setuid # breaks sshd,nfs statd
# lxc.cap.drop = audit_control # breaks sshd (set_loginuid failed)
# lxc.cap.drop = audit_write
#
lxc.cap.drop = mac_admin mac_override setfcap setpcap
lxc.cap.drop = sys_module sys_nice sys_pacct
lxc.cap.drop = sys_rawio sys_time
# Control Group devices: all denied except those whitelisted
lxc.cgroup.devices.deny = a
# Allow any mknod (but not reading/writing the node)
lxc.cgroup.devices.allow = c *:* m
lxc.cgroup.devices.allow = b *:* m
lxc.cgroup.devices.allow = c 1:3 rwm # /dev/null
lxc.cgroup.devices.allow = c 1:5 rwm # /dev/zero
lxc.cgroup.devices.allow = c 1:7 rwm # /dev/full
lxc.cgroup.devices.allow = c 5:0 rwm # /dev/tty
lxc.cgroup.devices.allow = c 1:8 rwm # /dev/random
lxc.cgroup.devices.allow = c 1:9 rwm # /dev/urandom
lxc.cgroup.devices.allow = c 136:* rwm # /dev/tty[1-4] ptys and lxc console
lxc.cgroup.devices.allow = c 5:2 rwm # /dev/ptmx pty master
# Blacklist some syscalls which are not safe in privileged
# containers
lxc.seccomp = /usr/share/lxc/config/common.seccomp
##############################################
# vagrant-lxc container specific configuration
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "cp", "-f", "/tmp/lxc-config20150119-31085-abghdm", "/var/lib/lxc/centos-test_1421654214387_8629/config"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "chown", "root:root", "/var/lib/lxc/centos-test_1421654214387_8629/config"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "cat", "/var/lib/lxc/centos-test_1421654214387_8629/config"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: # Template used to create this container: /usr/share/lxc/templates/lxc-vagrant-tmp-centos-test_1421654214387_8629
# Parameters passed to the template: --tarball /home/tumi/.vagrant.d/boxes/fgrehm-VAGRANTSLASH-centos-6-64-lxc/1.0.0/lxc/rootfs.tar.gz --config /home/tumi/.vagrant.d/boxes/fgrehm-VAGRANTSLASH-centos-6-64-lxc/1.0.0/lxc/lxc-config
# For additional config options, please look at lxc.container.conf(5)
##############################################
# Container specific configuration (automatically set)
lxc.rootfs = /var/lib/lxc/centos-test_1421654214387_8629/rootfs
lxc.utsname = centos-test_1421654214387_8629
##############################################
# Network configuration (automatically set)
lxc.network.type = veth
lxc.network.flags = up
lxc.network.link = lxcbr0
lxc.network.hwaddr = 00:16:3e:59:f6:0c
##############################################
# vagrant-lxc base box specific configuration
# Taken from the oracle.common.conf.in
# Console settings
lxc.devttydir = lxc
lxc.tty = 4
lxc.pts = 1024
# Mount entries
lxc.mount.auto = proc:mixed sys:ro
# Ensure hostname is changed on clone
lxc.hook.clone = /usr/share/lxc/hooks/clonehostname
# Capabilities
# Uncomment these if you don't run anything that needs the capability, and
# would like the container to run with less privilege.
#
# Dropping sys_admin disables container root from doing a lot of things
# that could be bad like re-mounting lxc fstab entries rw for example,
# but also disables some useful things like being able to nfs mount, and
# things that are already namespaced with ns_capable() kernel checks, like
# hostname(1).
# lxc.cap.drop = sys_admin
# lxc.cap.drop = net_raw # breaks dhcp/ping
# lxc.cap.drop = setgid # breaks login (initgroups/setgroups)
# lxc.cap.drop = dac_read_search # breaks login (pam unix_chkpwd)
# lxc.cap.drop = setuid # breaks sshd,nfs statd
# lxc.cap.drop = audit_control # breaks sshd (set_loginuid failed)
# lxc.cap.drop = audit_write
#
lxc.cap.drop = mac_admin mac_override setfcap setpcap
lxc.cap.drop = sys_module sys_nice sys_pacct
lxc.cap.drop = sys_rawio sys_time
# Control Group devices: all denied except those whitelisted
lxc.cgroup.devices.deny = a
# Allow any mknod (but not reading/writing the node)
lxc.cgroup.devices.allow = c *:* m
lxc.cgroup.devices.allow = b *:* m
lxc.cgroup.devices.allow = c 1:3 rwm # /dev/null
lxc.cgroup.devices.allow = c 1:5 rwm # /dev/zero
lxc.cgroup.devices.allow = c 1:7 rwm # /dev/full
lxc.cgroup.devices.allow = c 5:0 rwm # /dev/tty
lxc.cgroup.devices.allow = c 1:8 rwm # /dev/random
lxc.cgroup.devices.allow = c 1:9 rwm # /dev/urandom
lxc.cgroup.devices.allow = c 136:* rwm # /dev/tty[1-4] ptys and lxc console
lxc.cgroup.devices.allow = c 5:2 rwm # /dev/ptmx pty master
# Blacklist some syscalls which are not safe in privileged
# containers
lxc.seccomp = /usr/share/lxc/config/common.seccomp
##############################################
# vagrant-lxc container specific configuration
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "cp", "-f", "/tmp/lxc-config20150119-31085-1cvnty7", "/var/lib/lxc/centos-test_1421654214387_8629/config"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "chown", "root:root", "/var/lib/lxc/centos-test_1421654214387_8629/config"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-start", "-d", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 31999
DEBUG subprocess: Exit status: 0
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::WaitForCommunicator:0x00000003775be8>
INFO interface: output: Waiting for machine to boot. This may take a few minutes...
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
INFO interface: output: ==> centostest: Waiting for machine to boot. This may take a few minutes...
DEBUG subprocess: Selecting on IO
==> centostest: Waiting for machine to boot. This may take a few minutes... INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
Name: centos-test_1421654214387_8629
State: RUNNING
Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
CPU use: 0.08 seconds
CPU use: 0.08 seconds
CPU use: 0.08 seconds
BlkIO use: 0 bytes
CPU use: 0.08 seconds
BlkIO use: 0 bytes
CPU use: 0.08 seconds
BlkIO use: 0 bytes
Memory use: 1.92 MiB
CPU use: 0.08 seconds
BlkIO use: 0 bytes
Memory use: 1.92 MiB
CPU use: 0.08 seconds
BlkIO use: 0 bytes
Memory use: 1.92 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 180 bytes
RX bytes: 90 bytes
Total bytes: 270 bytes
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: CPU use: 0.14 seconds
DEBUG subprocess: stdout: CPU use: 0.14 seconds
DEBUG subprocess: stdout: CPU use: 0.14 seconds
BlkIO use: 0 bytes
DEBUG subprocess: stdout: CPU use: 0.14 seconds
BlkIO use: 0 bytes
DEBUG subprocess: stdout: CPU use: 0.14 seconds
BlkIO use: 0 bytes
Memory use: 3.23 MiB
DEBUG subprocess: stdout: CPU use: 0.14 seconds
BlkIO use: 0 bytes
Memory use: 3.23 MiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG subprocess: stdout: CPU use: 0.14 seconds
BlkIO use: 0 bytes
Memory use: 3.23 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 180 bytes
RX bytes: 90 bytes
Total bytes: 270 bytes
INFO machine: Calling action: ssh_ip on provider LXC (centos-test_1421654214387_8629)
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 5 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x0000000376b940>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00000003773758>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 5 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000003822348>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x0000000383c680>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x0000000383c680>
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-attach", "--name", "centos-test_1421654214387_8629", "--", "/bin/true"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 5 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x00000002000918>
INFO warden: Calling IN action: #<Proc:0x00000002d53b60@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::LXC::Action::FetchIpWithLxcAttach:0x00000002000260>
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-attach", "-h"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stderr: Usage: lxc-attach --name=NAME [-- COMMAND]
Execute the specified COMMAND - enter the container NAME
Options :
-n, --name=NAME NAME for name of the container
-e, --elevated-privileges=PRIVILEGES
Use elevated privileges instead of those of the
container. If you don't specify privileges to be
elevated as OR'd list: CAP, CGROUP and LSM (capabilities,
cgroup and restrictions, respectively) then all of them
will be elevated.
WARNING: This may leak privileges into the container.
Use with care.
-a, --arch=ARCH Use ARCH for program instead of container's own
architecture.
-s, --namespaces=FLAGS
Don't attach to all the namespaces of the container
but just to the following OR'd list of flags:
MOUNT, PID, UTSNAME, IPC, USER or NETWORK.
WARNING: Using -s implies -e with all privileges
elevated, it may therefore leak privileges into the
container. Use with care.
-R, --remount-sys-proc
Remount /sys and /proc if not attaching to the
mount namespace when using -s in order to properly
reflect the correct namespace context. See the
lxc-attach(1) manual page for details.
--clear-env Clear all environment variables before attaching.
The attached shell/program will start with only
container=lxc set.
--keep-env Keep all current environment variables. This
is the current default behaviour, but is likely to
change in the future.
-v, --set-var Set an additional variable that is seen by the
attached program in the container. May be specified
multiple times.
--keep-var Keep an additional environment variable. Only
applicable if --clear-env is specified. May be used
multiple times.
Common options :
-o, --logfile=FILE Output log to FILE instead of stderr
-l, --logpriority=LEVEL Set log priority to LEVEL
-q, --quiet Don't produce any output
-P, --lxcpath=PATH Use specified container path
-?, --help Give this help list
--usage Give a short usage message
--version Print the version number
Mandatory or optional arguments to long options are also mandatory or optional
for any corresponding short options.
See the lxc-attach man page for further information.
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-attach", "--name", "centos-test_1421654214387_8629", "--namespaces", "NETWORK|MOUNT", "--", "/sbin/ip", "-4", "addr", "show", "scope", "global", "eth0"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO retryable: Retryable exception raised: #<Vagrant::LXC::Errors::ExecuteError: There was an error executing lxc-attach
For more information on the failure, enable detailed logging by setting
the environment variable VAGRANT_LOG to DEBUG.>
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: CPU use: 0.15 seconds
DEBUG subprocess: stdout: CPU use: 0.15 seconds
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
Memory use: 2.57 MiB
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
Memory use: 2.57 MiB
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
Memory use: 2.57 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 258 bytes
RX bytes: 258 bytes
Total bytes: 516 bytes
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: CPU use: 0.15 seconds
DEBUG subprocess: stdout: CPU use: 0.15 seconds
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
Memory use: 2.59 MiB
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
Memory use: 2.59 MiB
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
Memory use: 2.59 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 760 bytes
RX bytes: 1.62 KiB
Total bytes: 2.37 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: CPU use: 0.15 seconds
DEBUG subprocess: stdout: CPU use: 0.15 seconds
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
Memory use: 2.59 MiB
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
Memory use: 2.59 MiB
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
Memory use: 2.59 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 850 bytes
RX bytes: 2.54 KiB
Total bytes: 3.37 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-attach", "--name", "centos-test_1421654214387_8629", "--namespaces", "NETWORK|MOUNT", "--", "/sbin/ip", "-4", "addr", "show", "scope", "global", "eth0"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO retryable: Retryable exception raised: #<Vagrant::LXC::Errors::ExecuteError: There was an error executing lxc-attach
For more information on the failure, enable detailed logging by setting
the environment variable VAGRANT_LOG to DEBUG.>
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: CPU use: 0.15 seconds
DEBUG subprocess: stdout: CPU use: 0.15 seconds
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
Memory use: 2.59 MiB
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
Memory use: 2.59 MiB
DEBUG subprocess: stdout: CPU use: 0.15 seconds
BlkIO use: 0 bytes
Memory use: 2.59 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 850 bytes
RX bytes: 2.87 KiB
Total bytes: 3.70 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: CPU use: 0.17 seconds
DEBUG subprocess: stdout: CPU use: 0.17 seconds
DEBUG subprocess: stdout: CPU use: 0.17 seconds
BlkIO use: 0 bytes
DEBUG subprocess: stdout: CPU use: 0.17 seconds
BlkIO use: 0 bytes
DEBUG subprocess: stdout: CPU use: 0.17 seconds
BlkIO use: 0 bytes
Memory use: 3.71 MiB
DEBUG subprocess: stdout: CPU use: 0.17 seconds
BlkIO use: 0 bytes
Memory use: 3.71 MiB
DEBUG subprocess: stdout: CPU use: 0.17 seconds
BlkIO use: 0 bytes
Memory use: 3.71 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 1.20 KiB
RX bytes: 3.96 KiB
Total bytes: 5.17 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-attach", "--name", "centos-test_1421654214387_8629", "--namespaces", "NETWORK|MOUNT", "--", "/sbin/ip", "-4", "addr", "show", "scope", "global", "eth0"]
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: CPU use: 0.17 seconds
DEBUG subprocess: stdout: CPU use: 0.17 seconds
DEBUG subprocess: stdout: CPU use: 0.17 seconds
BlkIO use: 0 bytes
DEBUG subprocess: stdout: CPU use: 0.17 seconds
BlkIO use: 0 bytes
DEBUG subprocess: stdout: CPU use: 0.17 seconds
BlkIO use: 0 bytes
Memory use: 3.69 MiB
DEBUG subprocess: stdout: CPU use: 0.17 seconds
BlkIO use: 0 bytes
Memory use: 3.69 MiB
DEBUG subprocess: stdout: CPU use: 0.17 seconds
BlkIO use: 0 bytes
Memory use: 3.69 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 1.31 KiB
RX bytes: 4.32 KiB
Total bytes: 5.64 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO retryable: Retryable exception raised: #<Vagrant::LXC::Errors::ExecuteError: There was an error executing lxc-attach
For more information on the failure, enable detailed logging by setting
the environment variable VAGRANT_LOG to DEBUG.>
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
Memory use: 2.31 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
Memory use: 2.31 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
Memory use: 2.31 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 1.45 KiB
RX bytes: 4.54 KiB
Total bytes: 5.99 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
Memory use: 2.31 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
Memory use: 2.31 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
Memory use: 2.31 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 1.45 KiB
RX bytes: 4.54 KiB
Total bytes: 5.99 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-attach", "--name", "centos-test_1421654214387_8629", "--namespaces", "NETWORK|MOUNT", "--", "/sbin/ip", "-4", "addr", "show", "scope", "global", "eth0"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: 66: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP qlen 1000
inet 10.0.3.105/24 brd 10.0.3.255 scope global eth0
valid_lft forever preferred_lft forever
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO warden: Calling IN action: #<Vagrant::LXC::Action::FetchIpFromDnsmasqLeases:0x00000002b1ff10>
INFO warden: Calling IN action: #<Proc:0x00000002c6ff28@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Proc:0x00000002c6ff28@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::LXC::Action::FetchIpFromDnsmasqLeases:0x00000002b1ff10>
INFO warden: Calling OUT action: #<Vagrant::LXC::Action::FetchIpWithLxcAttach:0x00000002000260>
INFO warden: Calling OUT action: #<Proc:0x00000002d53b60@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Call:0x00000003773758>
DEBUG ssh: Checking key permissions: /home/tumi/.vagrant.d/insecure_private_key
INFO interface: detail: SSH address: 10.0.3.105:22
INFO interface: detail: centostest: SSH address: 10.0.3.105:22
centostest: SSH address: 10.0.3.105:22
INFO interface: detail: SSH username: vagrant
INFO interface: detail: centostest: SSH username: vagrant
centostest: SSH username: vagrant
INFO interface: detail: SSH auth method: private key
INFO interface: detail: centostest: SSH auth method: private key
centostest: SSH auth method: private key
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
Memory use: 2.31 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
Memory use: 2.31 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
Memory use: 2.31 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 1.45 KiB
RX bytes: 4.64 KiB
Total bytes: 6.09 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO machine: Calling action: ssh_ip on provider LXC (centos-test_1421654214387_8629)
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 5 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x0000000378cbb8>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x0000000379cdd8>
INFO runner: Preparing hooks for middleware sequence...
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.25 seconds
IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
Memory use: 2.31 MiB
IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
Memory use: 2.31 MiB
IP: 10.0.3.105
CPU use: 0.25 seconds
BlkIO use: 16.00 KiB
Memory use: 2.31 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 1.45 KiB
RX bytes: 4.64 KiB
Total bytes: 6.09 KiB
INFO runner: 5 hooks defined.
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000003853308>
DEBUG subprocess: Exit status: 0
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x00000003858880>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x00000003858880>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 5 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x000000038b2268>
INFO warden: Calling IN action: #<Proc:0x0000000397c928@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::LXC::Action::FetchIpWithLxcAttach:0x000000038b21f0>
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-attach", "--name", "centos-test_1421654214387_8629", "--namespaces", "NETWORK|MOUNT", "--", "/sbin/ip", "-4", "addr", "show", "scope", "global", "eth0"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: 66: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP qlen 1000
inet 10.0.3.105/24 brd 10.0.3.255 scope global eth0
valid_lft forever preferred_lft forever
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO warden: Calling IN action: #<Vagrant::LXC::Action::FetchIpFromDnsmasqLeases:0x0000000390ab98>
INFO warden: Calling IN action: #<Proc:0x00000003943c40@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Proc:0x00000003943c40@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::LXC::Action::FetchIpFromDnsmasqLeases:0x0000000390ab98>
INFO warden: Calling OUT action: #<Vagrant::LXC::Action::FetchIpWithLxcAttach:0x000000038b21f0>
INFO warden: Calling OUT action: #<Proc:0x0000000397c928@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Call:0x0000000379cdd8>
DEBUG ssh: Checking key permissions: /home/tumi/.vagrant.d/insecure_private_key
INFO ssh: Attempting SSH connection...
INFO ssh: Attempting to connect to SSH...
INFO ssh: - Host: 10.0.3.105
INFO ssh: - Port: 22
INFO ssh: - Username: vagrant
INFO ssh: - Password? false
INFO ssh: - Key Path: ["/home/tumi/.vagrant.d/insecure_private_key"]
DEBUG ssh: == Net-SSH connection debug-level log START ==
DEBUG ssh: D, [2015-01-19T09:57:06.648029 #31085] DEBUG -- net.ssh.transport.session[f25878]: establishing connection to 10.0.3.105:22
D, [2015-01-19T09:57:06.648356 #31085] DEBUG -- net.ssh.transport.session[f25878]: connection established
I, [2015-01-19T09:57:06.648485 #31085] INFO -- net.ssh.transport.server_version[f50e38]: negotiating protocol version
D, [2015-01-19T09:57:06.651360 #31085] DEBUG -- net.ssh.transport.server_version[f50e38]: remote is `SSH-2.0-OpenSSH_5.3'
D, [2015-01-19T09:57:06.651432 #31085] DEBUG -- net.ssh.transport.server_version[f50e38]: local is `SSH-2.0-Ruby/Net::SSH_2.9.1 x86_64-linux'
D, [2015-01-19T09:57:06.652292 #31085] DEBUG -- tcpsocket[f2461c]: read 840 bytes
D, [2015-01-19T09:57:06.652442 #31085] DEBUG -- tcpsocket[f2461c]: received packet nr 0 type 20 len 836
I, [2015-01-19T09:57:06.652531 #31085] INFO -- net.ssh.transport.algorithms[f61abc]: got KEXINIT from server
I, [2015-01-19T09:57:06.652730 #31085] INFO -- net.ssh.transport.algorithms[f61abc]: sending KEXINIT
D, [2015-01-19T09:57:06.652944 #31085] DEBUG -- tcpsocket[f2461c]: queueing packet nr 0 type 20 len 2020
D, [2015-01-19T09:57:06.653037 #31085] DEBUG -- tcpsocket[f2461c]: sent 2024 bytes
I, [2015-01-19T09:57:06.653072 #31085] INFO -- net.ssh.transport.algorithms[f61abc]: negotiating algorithms
D, [2015-01-19T09:57:06.653214 #31085] DEBUG -- net.ssh.transport.algorithms[f61abc]: 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, [2015-01-19T09:57:06.653247 #31085] DEBUG -- net.ssh.transport.algorithms[f61abc]: exchanging keys
D, [2015-01-19T09:57:06.653456 #31085] DEBUG -- tcpsocket[f2461c]: queueing packet nr 1 type 34 len 20
D, [2015-01-19T09:57:06.653511 #31085] DEBUG -- tcpsocket[f2461c]: sent 24 bytes
D, [2015-01-19T09:57:06.654174 #31085] DEBUG -- tcpsocket[f2461c]: read 152 bytes
D, [2015-01-19T09:57:06.654303 #31085] DEBUG -- tcpsocket[f2461c]: received packet nr 1 type 31 len 148
D, [2015-01-19T09:57:06.656427 #31085] DEBUG -- tcpsocket[f2461c]: queueing packet nr 2 type 32 len 140
D, [2015-01-19T09:57:06.656555 #31085] DEBUG -- tcpsocket[f2461c]: sent 144 bytes
D, [2015-01-19T09:57:06.658199 #31085] DEBUG -- tcpsocket[f2461c]: read 720 bytes
D, [2015-01-19T09:57:06.658307 #31085] DEBUG -- tcpsocket[f2461c]: received packet nr 2 type 33 len 700
D, [2015-01-19T09:57:06.659418 #31085] DEBUG -- tcpsocket[f2461c]: queueing packet nr 3 type 21 len 20
D, [2015-01-19T09:57:06.659499 #31085] DEBUG -- tcpsocket[f2461c]: sent 24 bytes
D, [2015-01-19T09:57:06.659572 #31085] DEBUG -- tcpsocket[f2461c]: received packet nr 3 type 21 len 12
D, [2015-01-19T09:57:06.659865 #31085] DEBUG -- net.ssh.authentication.session[155a180]: beginning authentication of `vagrant'
D, [2015-01-19T09:57:06.659963 #31085] DEBUG -- tcpsocket[f2461c]: queueing packet nr 4 type 5 len 28
D, [2015-01-19T09:57:06.659996 #31085] DEBUG -- tcpsocket[f2461c]: sent 52 bytes
D, [2015-01-19T09:57:06.698847 #31085] DEBUG -- tcpsocket[f2461c]: read 52 bytes
D, [2015-01-19T09:57:06.698966 #31085] DEBUG -- tcpsocket[f2461c]: received packet nr 4 type 6 len 28
D, [2015-01-19T09:57:06.699082 #31085] DEBUG -- net.ssh.authentication.session[155a180]: trying none
D, [2015-01-19T09:57:06.699224 #31085] DEBUG -- tcpsocket[f2461c]: queueing packet nr 5 type 50 len 44
D, [2015-01-19T09:57:06.699288 #31085] DEBUG -- tcpsocket[f2461c]: sent 68 bytes
D, [2015-01-19T09:57:06.700112 #31085] DEBUG -- tcpsocket[f2461c]: read 84 bytes
D, [2015-01-19T09:57:06.700214 #31085] DEBUG -- tcpsocket[f2461c]: received packet nr 5 type 51 len 60
D, [2015-01-19T09:57:06.700297 #31085] DEBUG -- net.ssh.authentication.session[155a180]: allowed methods: publickey,gssapi-keyex,gssapi-with-mic,password
D, [2015-01-19T09:57:06.700359 #31085] DEBUG -- net.ssh.authentication.methods.none[15719c0]: none failed
D, [2015-01-19T09:57:06.700425 #31085] DEBUG -- net.ssh.authentication.session[155a180]: trying publickey
D, [2015-01-19T09:57:06.700693 #31085] DEBUG -- net.ssh.authentication.agent[157e97c]: connecting to ssh-agent
D, [2015-01-19T09:57:06.700810 #31085] DEBUG -- net.ssh.authentication.agent[157e97c]: sending agent request 1 len 44
D, [2015-01-19T09:57:06.701174 #31085] DEBUG -- net.ssh.authentication.agent[157e97c]: received agent packet 2 len 5
D, [2015-01-19T09:57:06.701248 #31085] DEBUG -- net.ssh.authentication.agent[157e97c]: sending agent request 11 len 0
D, [2015-01-19T09:57:06.701708 #31085] DEBUG -- net.ssh.authentication.agent[157e97c]: received agent packet 12 len 302
D, [2015-01-19T09:57:06.701971 #31085] DEBUG -- net.ssh.authentication.methods.publickey[157f110]: trying publickey (dd:3b:b8:2e:85:04:06:e9:ab:ff:a8:0a:c0:04:6e:d6)
D, [2015-01-19T09:57:06.702143 #31085] DEBUG -- tcpsocket[f2461c]: queueing packet nr 6 type 50 len 348
D, [2015-01-19T09:57:06.702221 #31085] DEBUG -- tcpsocket[f2461c]: sent 372 bytes
D, [2015-01-19T09:57:06.702482 #31085] DEBUG -- tcpsocket[f2461c]: read 324 bytes
D, [2015-01-19T09:57:06.702615 #31085] DEBUG -- tcpsocket[f2461c]: received packet nr 6 type 60 len 300
D, [2015-01-19T09:57:06.704842 #31085] DEBUG -- tcpsocket[f2461c]: queueing packet nr 7 type 50 len 620
D, [2015-01-19T09:57:06.704914 #31085] DEBUG -- tcpsocket[f2461c]: sent 644 bytes
D, [2015-01-19T09:57:06.705546 #31085] DEBUG -- tcpsocket[f2461c]: read 36 bytes
D, [2015-01-19T09:57:06.705638 #31085] DEBUG -- tcpsocket[f2461c]: received packet nr 7 type 52 len 12
D, [2015-01-19T09:57:06.705709 #31085] DEBUG -- net.ssh.authentication.methods.publickey[157f110]: 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 ==
DEBUG ssh: Checking whether SSH is ready...
INFO ssh: Connection errored, not re-using. Will reconnect.
DEBUG ssh: #<RuntimeError: could not execute command: "">
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.26 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.26 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.26 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.26 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.26 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.26 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.26 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 6.04 KiB
RX bytes: 10.56 KiB
Total bytes: 16.59 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO machine: Calling action: ssh_ip on provider LXC (centos-test_1421654214387_8629)
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 5 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x00000002d3fbd8>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00000002d4f3f8>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 5 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x000000036c9708>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x000000036d3118>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x000000036d3118>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 5 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x00000003700a50>
INFO warden: Calling IN action: #<Proc:0x000000037706e8@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::LXC::Action::FetchIpWithLxcAttach:0x000000037009d8>
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-attach", "--name", "centos-test_1421654214387_8629", "--namespaces", "NETWORK|MOUNT", "--", "/sbin/ip", "-4", "addr", "show", "scope", "global", "eth0"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: 66: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP qlen 1000
inet 10.0.3.105/24 brd 10.0.3.255 scope global eth0
valid_lft forever preferred_lft forever
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO warden: Calling IN action: #<Vagrant::LXC::Action::FetchIpFromDnsmasqLeases:0x00000003725260>
INFO warden: Calling IN action: #<Proc:0x000000037449a8@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Proc:0x000000037449a8@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::LXC::Action::FetchIpFromDnsmasqLeases:0x00000003725260>
INFO warden: Calling OUT action: #<Vagrant::LXC::Action::FetchIpWithLxcAttach:0x000000037009d8>
INFO warden: Calling OUT action: #<Proc:0x000000037706e8@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Call:0x00000002d4f3f8>
DEBUG ssh: Checking key permissions: /home/tumi/.vagrant.d/insecure_private_key
INFO ssh: Attempting SSH connection...
INFO ssh: Attempting to connect to SSH...
INFO ssh: - Host: 10.0.3.105
INFO ssh: - Port: 22
INFO ssh: - Username: vagrant
INFO ssh: - Password? false
INFO ssh: - Key Path: ["/home/tumi/.vagrant.d/insecure_private_key"]
DEBUG ssh: == Net-SSH connection debug-level log START ==
DEBUG ssh: D, [2015-01-19T09:57:06.792122 #31085] DEBUG -- net.ssh.transport.session[1c19840]: establishing connection to 10.0.3.105:22
D, [2015-01-19T09:57:06.792454 #31085] DEBUG -- net.ssh.transport.session[1c19840]: connection established
I, [2015-01-19T09:57:06.792597 #31085] INFO -- net.ssh.transport.server_version[1c18c38]: negotiating protocol version
D, [2015-01-19T09:57:06.795602 #31085] DEBUG -- net.ssh.transport.server_version[1c18c38]: remote is `SSH-2.0-OpenSSH_5.3'
D, [2015-01-19T09:57:06.795657 #31085] DEBUG -- net.ssh.transport.server_version[1c18c38]: local is `SSH-2.0-Ruby/Net::SSH_2.9.1 x86_64-linux'
D, [2015-01-19T09:57:06.796487 #31085] DEBUG -- tcpsocket[1c19368]: read 840 bytes
D, [2015-01-19T09:57:06.796703 #31085] DEBUG -- tcpsocket[1c19368]: received packet nr 0 type 20 len 836
I, [2015-01-19T09:57:06.796794 #31085] INFO -- net.ssh.transport.algorithms[1c1bc94]: got KEXINIT from server
I, [2015-01-19T09:57:06.796911 #31085] INFO -- net.ssh.transport.algorithms[1c1bc94]: sending KEXINIT
D, [2015-01-19T09:57:06.797092 #31085] DEBUG -- tcpsocket[1c19368]: queueing packet nr 0 type 20 len 2020
D, [2015-01-19T09:57:06.797173 #31085] DEBUG -- tcpsocket[1c19368]: sent 2024 bytes
I, [2015-01-19T09:57:06.797222 #31085] INFO -- net.ssh.transport.algorithms[1c1bc94]: negotiating algorithms
D, [2015-01-19T09:57:06.797387 #31085] DEBUG -- net.ssh.transport.algorithms[1c1bc94]: 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, [2015-01-19T09:57:06.797422 #31085] DEBUG -- net.ssh.transport.algorithms[1c1bc94]: exchanging keys
D, [2015-01-19T09:57:06.797624 #31085] DEBUG -- tcpsocket[1c19368]: queueing packet nr 1 type 34 len 20
D, [2015-01-19T09:57:06.797699 #31085] DEBUG -- tcpsocket[1c19368]: sent 24 bytes
D, [2015-01-19T09:57:06.798471 #31085] DEBUG -- tcpsocket[1c19368]: read 152 bytes
D, [2015-01-19T09:57:06.798576 #31085] DEBUG -- tcpsocket[1c19368]: received packet nr 1 type 31 len 148
D, [2015-01-19T09:57:06.800032 #31085] DEBUG -- tcpsocket[1c19368]: queueing packet nr 2 type 32 len 140
D, [2015-01-19T09:57:06.800136 #31085] DEBUG -- tcpsocket[1c19368]: sent 144 bytes
D, [2015-01-19T09:57:06.801759 #31085] DEBUG -- tcpsocket[1c19368]: read 720 bytes
D, [2015-01-19T09:57:06.801854 #31085] DEBUG -- tcpsocket[1c19368]: received packet nr 2 type 33 len 700
D, [2015-01-19T09:57:06.802912 #31085] DEBUG -- tcpsocket[1c19368]: queueing packet nr 3 type 21 len 20
D, [2015-01-19T09:57:06.802988 #31085] DEBUG -- tcpsocket[1c19368]: sent 24 bytes
D, [2015-01-19T09:57:06.803061 #31085] DEBUG -- tcpsocket[1c19368]: received packet nr 3 type 21 len 12
D, [2015-01-19T09:57:06.803321 #31085] DEBUG -- net.ssh.authentication.session[1c3d1a0]: beginning authentication of `vagrant'
D, [2015-01-19T09:57:06.803420 #31085] DEBUG -- tcpsocket[1c19368]: queueing packet nr 4 type 5 len 28
D, [2015-01-19T09:57:06.803451 #31085] DEBUG -- tcpsocket[1c19368]: sent 52 bytes
D, [2015-01-19T09:57:06.842832 #31085] DEBUG -- tcpsocket[1c19368]: read 52 bytes
D, [2015-01-19T09:57:06.842957 #31085] DEBUG -- tcpsocket[1c19368]: received packet nr 4 type 6 len 28
D, [2015-01-19T09:57:06.843070 #31085] DEBUG -- net.ssh.authentication.session[1c3d1a0]: trying none
D, [2015-01-19T09:57:06.843199 #31085] DEBUG -- tcpsocket[1c19368]: queueing packet nr 5 type 50 len 44
D, [2015-01-19T09:57:06.843258 #31085] DEBUG -- tcpsocket[1c19368]: sent 68 bytes
D, [2015-01-19T09:57:06.844093 #31085] DEBUG -- tcpsocket[1c19368]: read 84 bytes
D, [2015-01-19T09:57:06.844187 #31085] DEBUG -- tcpsocket[1c19368]: received packet nr 5 type 51 len 60
D, [2015-01-19T09:57:06.844266 #31085] DEBUG -- net.ssh.authentication.session[1c3d1a0]: allowed methods: publickey,gssapi-keyex,gssapi-with-mic,password
D, [2015-01-19T09:57:06.844331 #31085] DEBUG -- net.ssh.authentication.methods.none[1c41714]: none failed
D, [2015-01-19T09:57:06.844387 #31085] DEBUG -- net.ssh.authentication.session[1c3d1a0]: trying publickey
D, [2015-01-19T09:57:06.844611 #31085] DEBUG -- net.ssh.authentication.agent[1c45314]: connecting to ssh-agent
D, [2015-01-19T09:57:06.844737 #31085] DEBUG -- net.ssh.authentication.agent[1c45314]: sending agent request 1 len 44
D, [2015-01-19T09:57:06.845108 #31085] DEBUG -- net.ssh.authentication.agent[1c45314]: received agent packet 2 len 5
D, [2015-01-19T09:57:06.845164 #31085] DEBUG -- net.ssh.authentication.agent[1c45314]: sending agent request 11 len 0
D, [2015-01-19T09:57:06.845621 #31085] DEBUG -- net.ssh.authentication.agent[1c45314]: received agent packet 12 len 302
D, [2015-01-19T09:57:06.845900 #31085] DEBUG -- net.ssh.authentication.methods.publickey[1c45580]: trying publickey (dd:3b:b8:2e:85:04:06:e9:ab:ff:a8:0a:c0:04:6e:d6)
D, [2015-01-19T09:57:06.846087 #31085] DEBUG -- tcpsocket[1c19368]: queueing packet nr 6 type 50 len 348
D, [2015-01-19T09:57:06.846157 #31085] DEBUG -- tcpsocket[1c19368]: sent 372 bytes
D, [2015-01-19T09:57:06.846439 #31085] DEBUG -- tcpsocket[1c19368]: read 324 bytes
D, [2015-01-19T09:57:06.846553 #31085] DEBUG -- tcpsocket[1c19368]: received packet nr 6 type 60 len 300
D, [2015-01-19T09:57:06.848641 #31085] DEBUG -- tcpsocket[1c19368]: queueing packet nr 7 type 50 len 620
D, [2015-01-19T09:57:06.848726 #31085] DEBUG -- tcpsocket[1c19368]: sent 644 bytes
D, [2015-01-19T09:57:06.849331 #31085] DEBUG -- tcpsocket[1c19368]: read 36 bytes
D, [2015-01-19T09:57:06.849428 #31085] DEBUG -- tcpsocket[1c19368]: received packet nr 7 type 52 len 12
D, [2015-01-19T09:57:06.849487 #31085] DEBUG -- net.ssh.authentication.methods.publickey[1c45580]: 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!
INFO ssh: Connection errored, not re-using. Will reconnect.
DEBUG ssh: #<RuntimeError: could not execute command: "">
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.28 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.28 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.28 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.28 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.28 seconds
BlkIO use: 16.00 KiB
Memory use: 4.54 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.28 seconds
BlkIO use: 16.00 KiB
Memory use: 4.54 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.28 seconds
BlkIO use: 16.00 KiB
Memory use: 4.54 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 10.69 KiB
RX bytes: 16.47 KiB
Total bytes: 27.16 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO machine: Calling action: ssh_ip on provider LXC (centos-test_1421654214387_8629)
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 5 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x0000000391ed78>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x00000003925650>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 5 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Builder:0x000000039940a0>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x000000039a71f0>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x000000039a71f0>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 5 hooks defined.
INFO runner: Running action: #<Vagrant::Action::Warden:0x000000039f65e8>
INFO warden: Calling IN action: #<Proc:0x00000002c26ad0@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::LXC::Action::FetchIpWithLxcAttach:0x000000039f6548>
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-attach", "--name", "centos-test_1421654214387_8629", "--namespaces", "NETWORK|MOUNT", "--", "/sbin/ip", "-4", "addr", "show", "scope", "global", "eth0"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: 66: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP qlen 1000
inet 10.0.3.105/24 brd 10.0.3.255 scope global eth0
valid_lft forever preferred_lft forever
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO warden: Calling IN action: #<Vagrant::LXC::Action::FetchIpFromDnsmasqLeases:0x00000001dd2c90>
INFO warden: Calling IN action: #<Proc:0x00000002ac35f8@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Proc:0x00000002ac35f8@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::LXC::Action::FetchIpFromDnsmasqLeases:0x00000001dd2c90>
INFO warden: Calling OUT action: #<Vagrant::LXC::Action::FetchIpWithLxcAttach:0x000000039f6548>
INFO warden: Calling OUT action: #<Proc:0x00000002c26ad0@/opt/vagrant/embedded/gems/gems/vagrant-1.7.2/lib/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Call:0x00000003925650>
DEBUG ssh: Checking key permissions: /home/tumi/.vagrant.d/insecure_private_key
INFO ssh: Attempting SSH connection...
INFO ssh: Attempting to connect to SSH...
INFO ssh: - Host: 10.0.3.105
INFO ssh: - Port: 22
INFO ssh: - Username: vagrant
INFO ssh: - Password? false
INFO ssh: - Key Path: ["/home/tumi/.vagrant.d/insecure_private_key"]
DEBUG ssh: == Net-SSH connection debug-level log START ==
DEBUG ssh: D, [2015-01-19T09:57:06.971211 #31085] DEBUG -- net.ssh.transport.session[16f1598]: establishing connection to 10.0.3.105:22
D, [2015-01-19T09:57:06.971827 #31085] DEBUG -- net.ssh.transport.session[16f1598]: connection established
I, [2015-01-19T09:57:06.972029 #31085] INFO -- net.ssh.transport.server_version[16f3c80]: negotiating protocol version
D, [2015-01-19T09:57:06.975205 #31085] DEBUG -- net.ssh.transport.server_version[16f3c80]: remote is `SSH-2.0-OpenSSH_5.3'
D, [2015-01-19T09:57:06.975268 #31085] DEBUG -- net.ssh.transport.server_version[16f3c80]: local is `SSH-2.0-Ruby/Net::SSH_2.9.1 x86_64-linux'
D, [2015-01-19T09:57:06.976006 #31085] DEBUG -- tcpsocket[16f0df0]: read 840 bytes
D, [2015-01-19T09:57:06.976142 #31085] DEBUG -- tcpsocket[16f0df0]: received packet nr 0 type 20 len 836
I, [2015-01-19T09:57:06.976228 #31085] INFO -- net.ssh.transport.algorithms[16f5c10]: got KEXINIT from server
I, [2015-01-19T09:57:06.976353 #31085] INFO -- net.ssh.transport.algorithms[16f5c10]: sending KEXINIT
D, [2015-01-19T09:57:06.976557 #31085] DEBUG -- tcpsocket[16f0df0]: queueing packet nr 0 type 20 len 2020
D, [2015-01-19T09:57:06.976645 #31085] DEBUG -- tcpsocket[16f0df0]: sent 2024 bytes
I, [2015-01-19T09:57:06.976682 #31085] INFO -- net.ssh.transport.algorithms[16f5c10]: negotiating algorithms
D, [2015-01-19T09:57:06.976800 #31085] DEBUG -- net.ssh.transport.algorithms[16f5c10]: 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, [2015-01-19T09:57:06.976838 #31085] DEBUG -- net.ssh.transport.algorithms[16f5c10]: exchanging keys
D, [2015-01-19T09:57:06.977019 #31085] DEBUG -- tcpsocket[16f0df0]: queueing packet nr 1 type 34 len 20
D, [2015-01-19T09:57:06.977080 #31085] DEBUG -- tcpsocket[16f0df0]: sent 24 bytes
D, [2015-01-19T09:57:06.977718 #31085] DEBUG -- tcpsocket[16f0df0]: read 152 bytes
D, [2015-01-19T09:57:06.977809 #31085] DEBUG -- tcpsocket[16f0df0]: received packet nr 1 type 31 len 148
D, [2015-01-19T09:57:06.979232 #31085] DEBUG -- tcpsocket[16f0df0]: queueing packet nr 2 type 32 len 140
D, [2015-01-19T09:57:06.979304 #31085] DEBUG -- tcpsocket[16f0df0]: sent 144 bytes
D, [2015-01-19T09:57:06.981042 #31085] DEBUG -- tcpsocket[16f0df0]: read 720 bytes
D, [2015-01-19T09:57:06.981108 #31085] DEBUG -- tcpsocket[16f0df0]: received packet nr 2 type 33 len 700
D, [2015-01-19T09:57:06.982088 #31085] DEBUG -- tcpsocket[16f0df0]: queueing packet nr 3 type 21 len 20
D, [2015-01-19T09:57:06.982154 #31085] DEBUG -- tcpsocket[16f0df0]: sent 24 bytes
D, [2015-01-19T09:57:06.982221 #31085] DEBUG -- tcpsocket[16f0df0]: received packet nr 3 type 21 len 12
D, [2015-01-19T09:57:06.982497 #31085] DEBUG -- net.ssh.authentication.session[1b6b448]: beginning authentication of `vagrant'
D, [2015-01-19T09:57:06.982614 #31085] DEBUG -- tcpsocket[16f0df0]: queueing packet nr 4 type 5 len 28
D, [2015-01-19T09:57:06.982645 #31085] DEBUG -- tcpsocket[16f0df0]: sent 52 bytes
D, [2015-01-19T09:57:07.021906 #31085] DEBUG -- tcpsocket[16f0df0]: read 52 bytes
D, [2015-01-19T09:57:07.022087 #31085] DEBUG -- tcpsocket[16f0df0]: received packet nr 4 type 6 len 28
D, [2015-01-19T09:57:07.022221 #31085] DEBUG -- net.ssh.authentication.session[1b6b448]: trying none
D, [2015-01-19T09:57:07.022381 #31085] DEBUG -- tcpsocket[16f0df0]: queueing packet nr 5 type 50 len 44
D, [2015-01-19T09:57:07.022473 #31085] DEBUG -- tcpsocket[16f0df0]: sent 68 bytes
D, [2015-01-19T09:57:07.023407 #31085] DEBUG -- tcpsocket[16f0df0]: read 84 bytes
D, [2015-01-19T09:57:07.023610 #31085] DEBUG -- tcpsocket[16f0df0]: received packet nr 5 type 51 len 60
D, [2015-01-19T09:57:07.023744 #31085] DEBUG -- net.ssh.authentication.session[1b6b448]: allowed methods: publickey,gssapi-keyex,gssapi-with-mic,password
D, [2015-01-19T09:57:07.023846 #31085] DEBUG -- net.ssh.authentication.methods.none[1b6cc1c]: none failed
D, [2015-01-19T09:57:07.023945 #31085] DEBUG -- net.ssh.authentication.session[1b6b448]: trying publickey
D, [2015-01-19T09:57:07.024264 #31085] DEBUG -- net.ssh.authentication.agent[1b719ec]: connecting to ssh-agent
D, [2015-01-19T09:57:07.024410 #31085] DEBUG -- net.ssh.authentication.agent[1b719ec]: sending agent request 1 len 44
D, [2015-01-19T09:57:07.024759 #31085] DEBUG -- net.ssh.authentication.agent[1b719ec]: received agent packet 2 len 5
D, [2015-01-19T09:57:07.024818 #31085] DEBUG -- net.ssh.authentication.agent[1b719ec]: sending agent request 11 len 0
D, [2015-01-19T09:57:07.030855 #31085] DEBUG -- net.ssh.authentication.agent[1b719ec]: received agent packet 12 len 302
D, [2015-01-19T09:57:07.031180 #31085] DEBUG -- net.ssh.authentication.methods.publickey[1b71c94]: trying publickey (dd:3b:b8:2e:85:04:06:e9:ab:ff:a8:0a:c0:04:6e:d6)
D, [2015-01-19T09:57:07.031379 #31085] DEBUG -- tcpsocket[16f0df0]: queueing packet nr 6 type 50 len 348
D, [2015-01-19T09:57:07.031464 #31085] DEBUG -- tcpsocket[16f0df0]: sent 372 bytes
D, [2015-01-19T09:57:07.031766 #31085] DEBUG -- tcpsocket[16f0df0]: read 324 bytes
D, [2015-01-19T09:57:07.031860 #31085] DEBUG -- tcpsocket[16f0df0]: received packet nr 6 type 60 len 300
D, [2015-01-19T09:57:07.033914 #31085] DEBUG -- tcpsocket[16f0df0]: queueing packet nr 7 type 50 len 620
D, [2015-01-19T09:57:07.033980 #31085] DEBUG -- tcpsocket[16f0df0]: sent 644 bytes
D, [2015-01-19T09:57:07.034658 #31085] DEBUG -- tcpsocket[16f0df0]: read 36 bytes
D, [2015-01-19T09:57:07.034782 #31085] DEBUG -- tcpsocket[16f0df0]: received packet nr 7 type 52 len 12
D, [2015-01-19T09:57:07.034858 #31085] DEBUG -- net.ssh.authentication.methods.publickey[1b71c94]: 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: Execute: (sudo=false)
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.41 KiB
Total bytes: 41.37 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.41 KiB
Total bytes: 41.37 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.41 KiB
Total bytes: 41.37 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.41 KiB
Total bytes: 41.37 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.41 KiB
Total bytes: 41.37 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG ssh: Sending SSH keep-alive...
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.41 KiB
Total bytes: 41.37 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.51 KiB
Total bytes: 41.47 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.51 KiB
Total bytes: 41.47 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG ssh: Sending SSH keep-alive...
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.51 KiB
Total bytes: 41.47 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.51 KiB
Total bytes: 41.47 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.51 KiB
Total bytes: 41.47 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.51 KiB
Total bytes: 41.47 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.51 KiB
Total bytes: 41.47 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG ssh: Sending SSH keep-alive...
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.51 KiB
Total bytes: 41.47 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.51 KiB
Total bytes: 41.47 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.51 KiB
Total bytes: 41.47 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.51 KiB
Total bytes: 41.47 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.51 KiB
Total bytes: 41.47 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG ssh: Sending SSH keep-alive...
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.51 KiB
Total bytes: 41.47 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.51 KiB
Total bytes: 41.47 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.62 KiB
Total bytes: 41.58 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.62 KiB
Total bytes: 41.58 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.62 KiB
Total bytes: 41.58 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
DEBUG ssh: Sending SSH keep-alive...
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.62 KiB
Total bytes: 41.58 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 31999
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.62 KiB
Total bytes: 41.58 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "lxc-info", "--name", "centos-test_1421654214387_8629"]
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
DEBUG subprocess: stdout: Name: centos-test_1421654214387_8629
State: RUNNING
PID: 31258
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
DEBUG subprocess: stdout: IP: 10.0.3.105
CPU use: 0.29 seconds
BlkIO use: 16.00 KiB
Memory use: 3.43 MiB
KMem use: 0 bytes
Link: vethFNOD11
TX bytes: 16.96 KiB
RX bytes: 24.62 KiB
Total bytes: 41.58 KiB
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment