Invalid arguments for command /usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework
Closed this issue · 12 comments
Deleted user commented
Hi,
I'm on Debian Jessie, lxc 2.0.1 and vagrant-lxc 2.1
Using vagrant up, I got the following output:
Bringing machine 'default' up with 'lxc' provider...
==> default: Importing base box 'debian/jessie64'...
==> default: Setting up mount entries for shared folders...
default: /vagrant => /var/lib/jenkins/workspace/checkout-infra-ansible-repo/bluesolutions
==> default: Starting container...
==> default: Waiting for machine to boot. This may take a few minutes...
default: SSH address: 192.168.150.193:22
default: SSH username: vagrant
default: SSH auth method: private key
default:
default: Vagrant insecure key detected. Vagrant will automatically replace
default: this with a newly generated keypair for better security.
default:
default: Inserting generated public key within guest...
default: Removing insecure key from the guest if it's present...
default: Key inserted! Disconnecting and reconnecting using new SSH key...
==> default: Machine booted and ready!
==> default: Setting up private networks...
There was an error executing ["sudo", "/usr/local/bin/vagrant-lxc-wrapper", "/usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework", "lxcbr0", "vb1.staging.acme", "192.168.150.100/24"]
Trying to execute the command itself, I had the following output:
$ sudo /usr/local/bin/vagrant-lxc-wrapper /usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework lxcbr0 vb1.staging.acme 192.168.150.100/24
Invalid arguments for command /usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework, provided args: ["lxcbr0", "vb1.staging.acme", "192.168.150.100/24"]
I tried to apply the patch #413 to fix the sudo wrapper but it did not solve the issue.
Let me know if you need more information.
Deleted user commented
Maybe more information are needed?
gregoryolsen commented
Hi chaica,
The only purpose for #413 is to resolve the correct lxc.lxcpath.
It does this by checking for lxc.lxcpath override in both:
- /etc/lxc/lxc.conf ; and
- /usr/local/etc/lxc/lxc.conf
If not found, then it uses "/var/lib/lxc" as if you had coded: lxc.lxcpath = /var/lib/lxc.
If you are having a problem with the lxcpath I'd recommend regenerating as follows:
vagrant lxc sudoers # I ran this from my id without sudo
That creates file /etc/sudoers.d/vagrant-lxc and regenerates /usr/local/bin/vagrant-lxc-wrapper.
If patch #413 is correctly applied /usr/local/bin/vagrant-lxc-wrapper will contain the following block of code:
81c81,112
< base = "/var/lib/lxc"
---
> def get_lxc_conf_value(search_key, file_name = "/etc/lxc/lxc.conf")
> found = "n"
> found_value = ""
> if File.file?(file_name) then
> IO.foreach(file_name) do |line|
> line.chomp!
> key, equals, value, rest = line.split(nil, 4)
> case key
> when /^([#;]|$)/; # ignore line
> when search_key; found = "y"
> end
> if found == "y" then
> found_value = value
> break
> end
> end
> end
> return found_value
> end
>
> ##
> # Resolve lxcpath
> # - Check /etc/lxc/lxc.conf
> # - Check /usr/local/etc/lxc/lxc.conf
> base = get_lxc_conf_value "lxc.lxcpath"
> if base == "" then
> base = get_lxc_conf_value "lxc.lxcpath", "/usr/local/etc/lxc/lxc.conf"
> if base == "" then
> base = "/var/lib/lxc"
> end
> end
> base.chomp!("/")
If your problem is actually due to lxcpath (what #413 is designed for) then
I'd expect it to immediately fail upon starting the container (i.e. after this line):
==> default: Starting container...
However in your case I see the container does in fact start, and gets
well past the problem #413 is designed to fix.
Therefore something else is going on, and while it may or may not be a problem with vagrant-lxc-wrapper, it couldn't have been caused by #413 because you got past that point.
In fact it appears something related to your private network configuration, but I can't say for sure.
I suggest you test again with DEBUG on like so:
$ VAGRANT_LOG=DEBUG vagrant up
And post the results. Perhaps then someone can assist you further.
Cheers
vladislavkononenko commented
Trying to resolve similar problem. I have installed vagrant and vagrant-lxc from Ubuntu 16.04 amd64 repo:
vk@vk-K61IC:/home/vk-unencrypted/mygits/trusty64php55$ lsb_release -a
No LSB modules are available.
Distributor ID: Ubuntu
Description: Ubuntu 16.04 LTS
Release: 16.04
Codename: xenial
vk@vk-K61IC:/home/vk-unencrypted/mygits/trusty64php55$ apt policy vagrant vagrant-lxc
vagrant:
Installed: 1.8.1+dfsg-1
Candidate: 1.8.1+dfsg-1
Version table:
*** 1.8.1+dfsg-1 500
500 http://ua.archive.ubuntu.com/ubuntu xenial/universe amd64 Packages
500 http://ua.archive.ubuntu.com/ubuntu xenial/universe i386 Packages
100 /var/lib/dpkg/status
vagrant-lxc:
Installed: 1.2.1-2
Candidate: 1.2.1-2
Version table:
*** 1.2.1-2 500
500 http://ua.archive.ubuntu.com/ubuntu xenial/universe amd64 Packages
500 http://ua.archive.ubuntu.com/ubuntu xenial/universe i386 Packages
100 /var/lib/dpkg/status
Vagrantfile:
# -*- mode: ruby -*-
# vi: set ft=ruby :
Vagrant.require_version ">= 1.5"
Vagrant.configure(2) do |config|
config.vm.box = "fgrehm/trusty64-lxc"
config.vm.network "private_network", ip: "192.168.33.144", lxc__bridge_name: "vlxcbr0"
config.vm.provider :lxc do |lxc|
lxc.container_name = 'trusty64php55'
lxc.customize 'cgroup.memory.limit_in_bytes', '1024M'
lxc.customize 'aa_profile', 'unconfined'
lxc.backingstore = 'none'
end
config.ssh.forward_agent = true
config.vm.provision "ansible" do |ansible|
ansible.playbook = "ansible/playbook.yml"
end
config.vm.synced_folder "./", "/vagrant", type: "nfs"
end
vagrant-lxc works until I did vagrant lxc sudoers.
Then I tried to do vagrant up:
vk@vk-K61IC:~/mygits/trusty64php55$ vagrant up
Bringing machine 'default' up with 'lxc' provider...
==> default: Checking if box 'fgrehm/trusty64-lxc' is up to date...
==> default: Starting container...
==> default: Waiting for machine to boot. This may take a few minutes...
default: SSH address: 10.0.3.238:22
default: SSH username: vagrant
default: SSH auth method: private key
==> default: Machine booted and ready!
==> default: Setting up private networks...
There was an error executing ["sudo", "/usr/local/bin/vagrant-lxc-wrapper", "/usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework", "vlxcbr0", "trusty64php55", "192.168.33.144/24"]
For more information on the failure, enable detailed logging by setting
the environment variable VAGRANT_LOG to DEBUG.
vk@vk-K61IC:~/mygits/trusty64php55$ sudo /usr/local/bin/vagrant-lxc-wrapper /usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework vlxcbr0 trusty64php55 192.168.33.144/24
Invalid arguments for command /usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework, provided args: ["vlxcbr0", "trusty64php55", "192.168.33.144/24"]
vk@vk-K61IC:~/mygits/trusty64php55$ echo $?
1
vk@vk-K61IC:~/mygits/trusty64php55$ sudo /usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework vlxcbr0 trusty64php55 192.168.33.144/24
[sudo] password for vk:
vk@vk-K61IC:~/mygits/trusty64php55$ echo $?
0
Looks like some problem in /usr/local/bin/vagrant-lxc-wrapper? Or maybe in /etc/sudoers.d/vagrant-lxc? (first sudo does not ask a password, second does)
vladislavkononenko commented
Here is result of execution VAGRANT_LOG=DEBUG vagrant up |& tee vagrant-up.log:
INFO global: Vagrant version: 1.8.1
INFO global: Ruby version: 2.3.1
INFO global: RubyGems version: 2.5.1
INFO global: VAGRANT_LOG="DEBUG"
INFO global: VAGRANT_INTERNAL_BUNDLERIZED="1"
INFO global: Plugins:
INFO global: - bundler = 1.11.2
INFO global: - vagrant-lxc = 1.2.1
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/provisioners/chef/plugin.rb
INFO manager: Registered plugin: chef
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/provisioners/ansible/plugin.rb
INFO manager: Registered plugin: ansible
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/provisioners/salt/plugin.rb
INFO manager: Registered plugin: salt
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/provisioners/docker/plugin.rb
INFO manager: Registered plugin: docker
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/provisioners/shell/plugin.rb
INFO manager: Registered plugin: shell
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/provisioners/cfengine/plugin.rb
INFO manager: Registered plugin: CFEngine Provisioner
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/provisioners/puppet/plugin.rb
INFO manager: Registered plugin: puppet
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/provisioners/file/plugin.rb
INFO manager: Registered plugin: file
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/synced_folders/nfs/plugin.rb
INFO manager: Registered plugin: NFS synced folders
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/synced_folders/smb/plugin.rb
INFO manager: Registered plugin: SMB synced folders
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/synced_folders/rsync/plugin.rb
INFO manager: Registered plugin: RSync synced folders
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/kernel_v2/plugin.rb
INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/communicators/winrm/plugin.rb
INFO manager: Registered plugin: winrm communicator
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/communicators/ssh/plugin.rb
INFO manager: Registered plugin: ssh communicator
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/kernel_v1/plugin.rb
INFO manager: Registered plugin: kernel
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/linux/plugin.rb
INFO manager: Registered plugin: Linux host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/gentoo/plugin.rb
INFO manager: Registered plugin: Gentoo host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/darwin/plugin.rb
INFO manager: Registered plugin: Mac OS X host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/windows/plugin.rb
INFO manager: Registered plugin: Windows host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/freebsd/plugin.rb
INFO manager: Registered plugin: FreeBSD host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/redhat/plugin.rb
INFO manager: Registered plugin: Red Hat Enterprise Linux host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/null/plugin.rb
INFO manager: Registered plugin: null host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/bsd/plugin.rb
INFO manager: Registered plugin: BSD host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/arch/plugin.rb
INFO manager: Registered plugin: Arch host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/suse/plugin.rb
INFO manager: Registered plugin: SUSE host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/hosts/slackware/plugin.rb
INFO manager: Registered plugin: Slackware host
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/resume/plugin.rb
INFO manager: Registered plugin: resume command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/global-status/plugin.rb
INFO manager: Registered plugin: global-status command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/destroy/plugin.rb
INFO manager: Registered plugin: destroy command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/ssh_config/plugin.rb
INFO manager: Registered plugin: ssh-config command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/provision/plugin.rb
INFO manager: Registered plugin: provision command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/plugin/plugin.rb
INFO manager: Registered plugin: plugin command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/box/plugin.rb
INFO manager: Registered plugin: box command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/suspend/plugin.rb
INFO manager: Registered plugin: suspend command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/init/plugin.rb
INFO manager: Registered plugin: init command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/ssh/plugin.rb
INFO manager: Registered plugin: ssh command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/reload/plugin.rb
INFO manager: Registered plugin: reload command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/package/plugin.rb
INFO manager: Registered plugin: package command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/status/plugin.rb
INFO manager: Registered plugin: status command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/port/plugin.rb
INFO manager: Registered plugin: port command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/provider/plugin.rb
INFO manager: Registered plugin: provider command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/snapshot/plugin.rb
INFO manager: Registered plugin: snapshot command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/list-commands/plugin.rb
INFO manager: Registered plugin: list-commands command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/help/plugin.rb
INFO manager: Registered plugin: help command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/up/plugin.rb
INFO manager: Registered plugin: up command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/rdp/plugin.rb
INFO manager: Registered plugin: rdp command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/login/plugin.rb
INFO manager: Registered plugin: vagrant-login
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/halt/plugin.rb
INFO manager: Registered plugin: halt command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/push/plugin.rb
INFO manager: Registered plugin: push command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/powershell/plugin.rb
INFO manager: Registered plugin: powershell command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/version/plugin.rb
INFO manager: Registered plugin: version command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/commands/cap/plugin.rb
INFO manager: Registered plugin: cap command
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/providers/docker/plugin.rb
INFO manager: Registered plugin: docker-provider
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/providers/virtualbox/plugin.rb
INFO manager: Registered plugin: VirtualBox provider
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/providers/hyperv/plugin.rb
INFO manager: Registered plugin: Hyper-V provider
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/nixos/plugin.rb
INFO manager: Registered plugin: NixOS guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/linux/plugin.rb
INFO manager: Registered plugin: Linux guest.
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/gentoo/plugin.rb
INFO manager: Registered plugin: Gentoo guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/darwin/plugin.rb
INFO manager: Registered plugin: Darwin guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/windows/plugin.rb
INFO manager: Registered plugin: Windows guest.
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/omnios/plugin.rb
INFO manager: Registered plugin: OmniOS guest.
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/fedora/plugin.rb
INFO manager: Registered plugin: Fedora guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/coreos/plugin.rb
INFO manager: Registered plugin: CoreOS guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/smartos/plugin.rb
INFO manager: Registered plugin: SmartOS guest.
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/freebsd/plugin.rb
INFO manager: Registered plugin: FreeBSD guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/redhat/plugin.rb
INFO manager: Registered plugin: Red Hat Enterprise Linux guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/solaris/plugin.rb
INFO manager: Registered plugin: Solaris guest.
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/photon/plugin.rb
INFO manager: Registered plugin: VMware Photon guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/pld/plugin.rb
INFO manager: Registered plugin: PLD Linux guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/mint/plugin.rb
INFO manager: Registered plugin: Mint guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/ubuntu/plugin.rb
INFO manager: Registered plugin: Ubuntu guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/debian/plugin.rb
INFO manager: Registered plugin: Debian guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/arch/plugin.rb
INFO manager: Registered plugin: Arch guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/netbsd/plugin.rb
INFO manager: Registered plugin: NetBSD guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/atomic/plugin.rb
INFO manager: Registered plugin: Atomic Host guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/suse/plugin.rb
INFO manager: Registered plugin: SUSE guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/slackware/plugin.rb
INFO manager: Registered plugin: Slackware guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/esxi/plugin.rb
INFO manager: Registered plugin: ESXi guest.
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/tinycore/plugin.rb
INFO manager: Registered plugin: TinyCore Linux guest.
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/solaris11/plugin.rb
INFO manager: Registered plugin: Solaris 11 guest.
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/openbsd/plugin.rb
INFO manager: Registered plugin: OpenBSD guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/guests/funtoo/plugin.rb
INFO manager: Registered plugin: Funtoo guest
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/pushes/heroku/plugin.rb
INFO manager: Registered plugin: heroku
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/pushes/ftp/plugin.rb
INFO manager: Registered plugin: ftp
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/pushes/noop/plugin.rb
INFO manager: Registered plugin: noop
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/pushes/atlas/plugin.rb
INFO manager: Registered plugin: atlas
DEBUG global: Loading core plugin: /usr/share/vagrant/plugins/pushes/local-exec/plugin.rb
INFO manager: Registered plugin: local-exec
INFO global: Loading plugins!
INFO manager: Registered plugin: vagrant-lxc
INFO vagrant: `vagrant` invoked: ["up"]
DEBUG vagrant: Creating Vagrant environment
INFO environment: Environment initialized (#<Vagrant::Environment:0x000000019396e8>)
INFO environment: - cwd: /home/vk-unencrypted/mygits/trusty64php55
INFO environment: Home path: /home/vk/.vagrant.d
INFO environment: Local data path: /home/vk-unencrypted/mygits/trusty64php55/.vagrant
DEBUG environment: Creating: /home/vk-unencrypted/mygits/trusty64php55/.vagrant
INFO environment: Running hook: environment_plugins_loaded
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: environment_plugins_loaded #<Vagrant::Action::Builder:0x000000021046e8>
INFO environment: Running hook: environment_load
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: environment_load #<Vagrant::Action::Builder:0x000000023e2900>
INFO cli: CLI: [] "up" []
DEBUG cli: Invoking command class: VagrantPlugins::CommandUp::Command []
DEBUG command: 'Up' each target VM...
INFO loader: Set :root = ["#<Pathname:/home/vk-unencrypted/mygits/trusty64php55/Vagrantfile>"]
DEBUG loader: Populating proc cache for #<Pathname:/home/vk-unencrypted/mygits/trusty64php55/Vagrantfile>
DEBUG loader: Load procs for pathname: /home/vk-unencrypted/mygits/trusty64php55/Vagrantfile
INFO root: Version requirements from Vagrantfile: [">= 1.5"]
INFO root: - Version requirements satisfied!
INFO loader: Loading configuration in order: [:home, :root]
DEBUG loader: Loading from: root (evaluating)
DEBUG provisioner: Provisioner defined:
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
INFO loader: Set "16963360_machine_default" = []
INFO loader: Loading configuration in order: [:home, :root, "16963360_machine_default"]
DEBUG loader: Loading from: root (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
INFO host: Autodetecting host type for [#<Vagrant::Environment: /home/vk-unencrypted/mygits/trusty64php55>]
DEBUG host: Trying: gentoo
DEBUG host: Trying: darwin
DEBUG host: Trying: freebsd
DEBUG host: Trying: redhat
DEBUG host: Trying: arch
DEBUG host: Trying: suse
DEBUG host: Trying: slackware
DEBUG host: Trying: linux
INFO host: Detected: linux!
DEBUG host: Searching for cap: provider_install_lxc
DEBUG host: Checking in: linux
DEBUG command: Getting target VMs for command. Arguments:
DEBUG command: -- names: ["default"]
DEBUG command: -- options: {:provider=>nil}
DEBUG command: Finding machine that match name: default
INFO command: Active machine found with name default. Using provider: lxc
INFO environment: Getting machine: default (lxc)
INFO environment: Uncached load of machine.
INFO loader: Set "16963360_machine_default" = []
INFO loader: Loading configuration in order: [:home, :root, "16963360_machine_default"]
DEBUG loader: Loading from: root (cache)
DEBUG loader: Configuration loaded successfully, finalizing and returning
DEBUG push: finalizing
INFO box_collection: Box found: fgrehm/trusty64-lxc (lxc)
INFO environment: Running hook: authenticate_box_url
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 2 hooks defined.
INFO runner: Running action: authenticate_box_url #<Vagrant::Action::Builder:0x00000002da3868>
INFO warden: Calling IN action: #<VagrantPlugins::LoginCommand::AddAuthentication:0x0000000393d520>
DEBUG client: No authentication token in environment or /home/vk/.vagrant.d/data/vagrant_login_token
INFO warden: Calling OUT action: #<VagrantPlugins::LoginCommand::AddAuthentication:0x0000000393d520>
INFO machine: Initializing machine: default
INFO machine: - Provider: Vagrant::LXC::Provider
INFO machine: - Box: #<Vagrant::Box:0x000000039b6e48>
INFO machine: - Data dir: /home/vk-unencrypted/mygits/trusty64php55/.vagrant/machines/default/lxc
DEBUG lxc: Found sudo wrapper : /usr/local/bin/vagrant-lxc-wrapper
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "which", "lxc-create"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
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: "trusty64php55"
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-ls"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: trusty64php55
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 31999
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-info", "--name", "trusty64php55"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: trusty64php55
State: STOPPED
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO interface: Machine: metadata ["provider", :lxc, {:target=>:default}]
INFO command: With machine: default (#<Vagrant::LXC::Provider:0x00000003ac2670 @logger=#<Log4r::Logger:0x00000003ac2620 @fullname="vagrant::provider::lxc", @outputters=[], @additive=true, @name="lxc", @path="vagrant::provider", @parent=#<Log4r::Logger:0x0000000223a8f0 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x000000021b6c58 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Thread::Mutex:0x000000021b6b90>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x000000021b31c0 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000223a7b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @machine=#<Vagrant::Machine: default (Vagrant::LXC::Provider)>, @shell=#<Vagrant::LXC::SudoWrapper:0x00000003af6cb8 @wrapper_path="/usr/local/bin/vagrant-lxc-wrapper", @logger=#<Log4r::Logger:0x00000003af6c68 @fullname="vagrant::lxc::sudo_wrapper", @outputters=[], @additive=true, @name="sudo_wrapper", @path="vagrant::lxc", @parent=#<Log4r::Logger:0x0000000223a8f0 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x000000021b6c58 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Thread::Mutex:0x000000021b6b90>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x000000021b31c0 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000223a7b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>>, @driver=#<Vagrant::LXC::Driver:0x00000003b6dcc8 @container_name="trusty64php55", @sudo_wrapper=#<Vagrant::LXC::SudoWrapper:0x00000003af6cb8 @wrapper_path="/usr/local/bin/vagrant-lxc-wrapper", @logger=#<Log4r::Logger:0x00000003af6c68 @fullname="vagrant::lxc::sudo_wrapper", @outputters=[], @additive=true, @name="sudo_wrapper", @path="vagrant::lxc", @parent=#<Log4r::Logger:0x0000000223a8f0 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x000000021b6c58 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Thread::Mutex:0x000000021b6b90>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x000000021b31c0 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000223a7b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>>, @cli=#<Vagrant::LXC::Driver::CLI:0x00000003b6dca0 @sudo_wrapper=#<Vagrant::LXC::SudoWrapper:0x00000003af6cb8 @wrapper_path="/usr/local/bin/vagrant-lxc-wrapper", @logger=#<Log4r::Logger:0x00000003af6c68 @fullname="vagrant::lxc::sudo_wrapper", @outputters=[], @additive=true, @name="sudo_wrapper", @path="vagrant::lxc", @parent=#<Log4r::Logger:0x0000000223a8f0 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x000000021b6c58 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Thread::Mutex:0x000000021b6b90>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x000000021b31c0 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000223a7b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>>, @name="trusty64php55", @logger=#<Log4r::Logger:0x00000003b6dc50 @fullname="vagrant::provider::lxc::container::cli", @outputters=[], @additive=true, @name="cli", @path="vagrant::provider::lxc::container", @parent=#<Log4r::Logger:0x00000003ac2620 @fullname="vagrant::provider::lxc", @outputters=[], @additive=true, @name="lxc", @path="vagrant::provider", @parent=#<Log4r::Logger:0x0000000223a8f0 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x000000021b6c58 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Thread::Mutex:0x000000021b6b90>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x000000021b31c0 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000223a7b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @level=1, @trace=false>>, @logger=#<Log4r::Logger:0x00000003ba9048 @fullname="vagrant::provider::lxc::driver", @outputters=[], @additive=true, @name="driver", @path="vagrant::provider::lxc", @parent=#<Log4r::Logger:0x00000003ac2620 @fullname="vagrant::provider::lxc", @outputters=[], @additive=true, @name="lxc", @path="vagrant::provider", @parent=#<Log4r::Logger:0x0000000223a8f0 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x000000021b6c58 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Thread::Mutex:0x000000021b6b90>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x000000021b31c0 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000223a7b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @level=1, @trace=false>, @customizations=[]>, @cap_logger=#<Log4r::Logger:0x00000003c2bf98 @fullname="vagrant::capability_host::vagrant::lxc::provider", @outputters=[], @additive=true, @name="provider", @path="vagrant::capability_host::vagrant::lxc", @parent=#<Log4r::Logger:0x0000000223a8f0 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x000000021b6c58 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Thread::Mutex:0x000000021b6b90>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x000000021b31c0 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000223a7b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @cap_host_chain=[[:lxc, #<#<Class:0x00000002189640>:0x00000003c58750>]], @cap_args=[#<Vagrant::Machine: default (Vagrant::LXC::Provider)>], @cap_caps={:docker=>#<Vagrant::Registry:0x000000021892d0 @items={:public_address=>#<Proc:0x0000000218ef00@/usr/share/vagrant/plugins/providers/docker/plugin.rb:54>, :proxy_machine=>#<Proc:0x0000000218ec80@/usr/share/vagrant/plugins/providers/docker/plugin.rb:59>}, @results_cache={}>, :virtualbox=>#<Vagrant::Registry:0x00000002189190 @items={:forwarded_ports=>#<Proc:0x000000021878e0@/usr/share/vagrant/plugins/providers/virtualbox/plugin.rb:27>, :nic_mac_addresses=>#<Proc:0x00000002187840@/usr/share/vagrant/plugins/providers/virtualbox/plugin.rb:32>, :public_address=>#<Proc:0x00000002187818@/usr/share/vagrant/plugins/providers/virtualbox/plugin.rb:37>, :snapshot_list=>#<Proc:0x000000021877f0@/usr/share/vagrant/plugins/providers/virtualbox/plugin.rb:42>}, @results_cache={}>, :hyperv=>#<Vagrant::Registry:0x00000002189028 @items={:public_address=>#<Proc:0x000000023c1ae8@/usr/share/vagrant/plugins/providers/hyperv/plugin.rb:25>}, @results_cache={}>, :lxc=>#<Vagrant::Registry:0x00000002188bc8 @items={:public_address=>#<Proc:0x00000001cf7438@/usr/lib/ruby/vendor_ruby/vagrant-lxc/plugin.rb:36>}, @results_cache={}>}>)
INFO interface: info: Bringing machine 'default' up with 'lxc' provider...
Bringing machine 'default' up with 'lxc' provider...
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-info", "--name", "trusty64php55"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: trusty64php55
State: STOPPED
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
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:0x00000003a4a698> up {:destroy_on_error=>true, :install_provider=>true, :parallel=>true, :provision_ignore_sentinel=>false, :provision_types=>nil}
INFO machine: Calling action: up on provider LXC (trusty64php55)
DEBUG environment: Attempting to acquire process-lock: machine-action-1334630dd2a55d163849c35f271b5648
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-1334630dd2a55d163849c35f271b5648
INFO interface: Machine: action ["up", "start", {:target=>:default}]
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: machine_action_up #<Vagrant::Action::Builder:0x00000003ee0478>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x007f7d7802eca0>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x007f7d7802ec78>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: machine_action_up #<Vagrant::Action::Builder:0x007f7d780e1af8>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::IsState:0x007f7d780eb5a8>
DEBUG is_state: Checking if machine state is 'not_created'
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-info", "--name", "trusty64php55"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: trusty64php55
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:0x007f7d780eb5a8>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: machine_action_up #<Vagrant::Action::Warden:0x007f7d781c2850>
INFO warden: Calling IN action: #<Proc:0x007f7d781d1580@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Proc:0x007f7d781c27b0@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x007f7d7802ec00>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::BoxCheckOutdated:0x007f7d7802ebd8>
INFO interface: output: Checking if box 'fgrehm/trusty64-lxc' is up to date...
INFO interface: output: ==> default: Checking if box 'fgrehm/trusty64-lxc' is up to date...
==> default: Checking if box 'fgrehm/trusty64-lxc' is up to date...
INFO downloader: Downloader starting download:
INFO downloader: -- Source: https://atlas.hashicorp.com/fgrehm/trusty64-lxc
INFO downloader: -- Destination: /tmp/vagrant-vk-10407/box20160716-10407-nohj05
INFO subprocess: Starting process: ["/usr/bin/curl", "-q", "--fail", "--location", "--max-redirs", "10", "--user-agent", "Vagrant/1.8.1 (+https://www.vagrantup.com; ruby2.3.1)", "-H", "Accept: application/json", "--output", "/tmp/vagrant-vk-10407/box20160716-10407-nohj05", "https://atlas.hashicorp.com/fgrehm/trusty64-lxc"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stderr: % Total % Received % Xferd Average Speed Time Time Time Current
Dload Upload Total Spent Left Speed
0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0
DEBUG subprocess: stderr:
0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0
DEBUG subprocess: stderr:
0 0 0 0 0 0 0 0 --:--:-- 0:00:01 --:--:-- 0
DEBUG subprocess: stderr:
100 119 100 119 0 0 81 0 0:00:01 0:00:01 --:--:-- 81
DEBUG subprocess: stderr:
100 1206 100 1206 0 0 722 0 0:00:01 0:00:01 --:--:-- 722
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 31998
DEBUG subprocess: Exit status: 0
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x007f7d78066588>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: machine_action_up #<Vagrant::Action::Builder:0x007f7d780207b8>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::IsState:0x007f7d78011dd0>
DEBUG is_state: Checking if machine state is 'running'
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-info", "--name", "trusty64php55"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: trusty64php55
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:0x007f7d78011dd0>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: machine_action_up #<Vagrant::Action::Warden:0x00000003ba9520>
INFO warden: Calling IN action: #<Proc:0x000000038b5ad0@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Provision:0x00000003ba9480>
INFO provision: Checking provisioner sentinel file...
INFO provision: Sentinel found! Not provisioning.
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::EnvSet:0x00000003b21508>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::HandleForwardedPortCollisions:0x00000003b214e0>
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 environment: Released process lock: fpcollision
DEBUG environment: Attempting to acquire process-lock: dotlock
INFO environment: Acquired process lock: dotlock
INFO environment: Released process lock: dotlock
INFO warden: Calling IN action: #<Vagrant::LXC::Action::PrepareNFSValidIds:0x00000003ac1108>
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-ls"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: trusty64php55
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO warden: Calling IN action: #<VagrantPlugins::SyncedFolderNFS::ActionCleanup:0x00000003a6bbb8>
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: ["trusty64php55"]
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/vk-unencrypted/mygits/trusty64php55>, #<Vagrant::UI::Prefixed:0x00000003a85b08 @logger=#<Log4r::Logger:0x00000003a85ab8 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x0000000223a8f0 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x000000021b6c58 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Thread::Mutex:0x000000021b6b90>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x000000021b31c0 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000223a7b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={}, @stdin=#<IO:<STDIN>>, @stdout=#<IO:<STDOUT>>, @stderr=#<IO:<STDERR>>, @prefix=:default, @ui=#<Vagrant::UI::Basic:0x00000001939260 @logger=#<Log4r::Logger:0x00000001939210 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x0000000223a8f0 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x000000021b6c58 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Thread::Mutex:0x000000021b6b90>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x000000021b31c0 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000223a7b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={:color=>:default}, @stdin=#<IO:<STDIN>>, @stdout=#<IO:<STDOUT>>, @stderr=#<IO:<STDERR>>, @lock=#<Thread::Mutex:0x00000001a624c0>>>, ["trusty64php55"]] (linux)
INFO linux: Pruning invalid NFS entries...
DEBUG linux: Valid ID: trusty64php55
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SyncedFolderCleanup:0x00000003a230e8>
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/vk-unencrypted/mygits/trusty64php55>] (linux)
INFO synced_folder_cleanup: Invoking synced folder cleanup for: nfs
INFO nfs: NFS pruning. Valid IDs: ["trusty64php55"]
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/vk-unencrypted/mygits/trusty64php55>, #<Vagrant::UI::Prefixed:0x00000003a85b08 @logger=#<Log4r::Logger:0x00000003a85ab8 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x0000000223a8f0 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x000000021b6c58 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Thread::Mutex:0x000000021b6b90>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x000000021b31c0 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000223a7b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={}, @stdin=#<IO:<STDIN>>, @stdout=#<IO:<STDOUT>>, @stderr=#<IO:<STDERR>>, @prefix=:default, @ui=#<Vagrant::UI::Basic:0x00000001939260 @logger=#<Log4r::Logger:0x00000001939210 @fullname="vagrant::ui::interface", @outputters=[], @additive=true, @name="interface", @path="vagrant::ui", @parent=#<Log4r::Logger:0x0000000223a8f0 @fullname="vagrant", @outputters=[#<Log4r::StderrOutputter:0x000000021b6c58 @mon_owner=nil, @mon_count=0, @mon_mutex=#<Thread::Mutex:0x000000021b6b90>, @name="stderr", @level=0, @formatter=#<Log4r::DefaultFormatter:0x000000021b31c0 @depth=7>, @out=#<IO:<STDERR>>>], @additive=true, @name="vagrant", @path="", @parent=#<Log4r::RootLogger:0x0000000223a7b0 @level=0, @outputters=[]>, @level=1, @trace=false>, @level=1, @trace=false>, @opts={:color=>:default}, @stdin=#<IO:<STDIN>>, @stdout=#<IO:<STDOUT>>, @stderr=#<IO:<STDERR>>, @lock=#<Thread::Mutex:0x00000001a624c0>>>, ["trusty64php55"]] (linux)
INFO linux: Pruning invalid NFS entries...
DEBUG linux: Valid ID: trusty64php55
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SyncedFolders:0x000000039edba0>
INFO synced_folders: SyncedFolders loading from cache: false
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/vk-unencrypted/mygits/trusty64php55>] (linux)
INFO synced_folders: Synced Folder Implementation: nfs
INFO synced_folders: - /vagrant: ./ => /vagrant
INFO synced_folders: Invoking synced folder prepare for: nfs
INFO warden: Calling IN action: #<Vagrant::LXC::Action::PrepareNFSSettings:0x000000039ac808>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::SetHostname:0x0000000393edd0>
INFO warden: Calling IN action: #<Vagrant::LXC::Action::WarnNetworks:0x0000000393eda8>
INFO warden: Calling IN action: #<Vagrant::LXC::Action::ForwardPorts:0x0000000393ed80>
INFO warden: Calling IN action: #<Vagrant::LXC::Action::PrivateNetworks:0x000000038f7ca0>
INFO warden: Calling IN action: #<Vagrant::LXC::Action::Boot:0x000000038f7c78>
INFO interface: info: Starting container...
INFO interface: info: ==> default: Starting container...
==> default: Starting container...
INFO driver: Starting container...
DEBUG driver: Prunning vagrant-lxc customizations
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "which", "lxc-version"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
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", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-create", "--version"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: 2.0.3
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-config", "lxc.lxcpath"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
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", "/usr/local/bin/vagrant-lxc-wrapper", "cat", "/var/lib/lxc/trusty64php55/config"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: # Template used to create this container: /usr/share/vagrant-plugins/vagrant-lxc/scripts/lxc-template
# Parameters passed to the template: --tarball /home/vk/.vagrant.d/boxes/fgrehm-VAGRANTSLASH-trusty64-lxc/1.2.0/lxc/rootfs.tar.gz --config /home/vk/.vagrant.d/boxes/fgrehm-VAGRANTSLASH-trusty64-lxc/1.2.0/lxc/lxc-config
# For additional config options, please look at lxc.container.conf(5)
# Uncomment the following line to support nesting containers:
#lxc.include = /usr/share/lxc/config/nesting.conf
# (Be aware this has security implications)
##############################################
# Container specific configuration (automatically set)
lxc.rootfs = /var/lib/lxc/trusty64php55/rootfs
lxc.rootfs.backend = dir
lxc.utsname = trusty64php55
##############################################
# Network configuration (automatically set)
lxc.network.type = veth
lxc.network.link = lxcbr0
lxc.network.flags = up
lxc.network.hwaddr = 00:16:3e:e5:e4:0e
##############################################
# vagrant-lxc base box specific configuration
# Default pivot location
lxc.pivotdir = lxc_putold
# Default mount entries
lxc.mount.entry = proc proc proc nodev,noexec,nosuid 0 0
lxc.mount.entry = sysfs sys sysfs defaults 0 0
# Default console settings
lxc.devttydir = lxc
lxc.tty = 4
lxc.pts = 1024
# Default capabilities
lxc.cap.drop = sys_module mac_admin mac_override sys_time
# When using LXC with apparmor, the container will be confined by default.
# If you wish for it to instead run unconfined, copy the following line
# (uncommented) to the container's configuration file.
#lxc.aa_profile = unconfined
# To support container nesting on an Ubuntu host while retaining most of
# apparmor's added security, use the following two lines instead.
#lxc.aa_profile = lxc-container-default-with-nesting
#lxc.hook.mount = /usr/share/lxc/hooks/mountcgroups
# Uncomment the following line to autodetect squid-deb-proxy configuration on the
# host and forward it to the guest at start time.
#lxc.hook.pre-start = /usr/share/lxc/hooks/squid-deb-proxy-client
# If you wish to allow mounting block filesystems, then use the following
# line instead, and make sure to grant access to the block device and/or loop
# devices below in lxc.cgroup.devices.allow.
#lxc.aa_profile = lxc-container-default-with-mounting
# Default cgroup limits
lxc.cgroup.devices.deny = a
## Allow any mknod (but not using the node)
lxc.cgroup.devices.allow = c *:* m
lxc.cgroup.devices.allow = b *:* m
## /dev/null and zero
lxc.cgroup.devices.allow = c 1:3 rwm
lxc.cgroup.devices.allow = c 1:5 rwm
## consoles
lxc.cgroup.devices.allow = c 5:0 rwm
lxc.cgroup.devices.allow = c 5:1 rwm
## /dev/{,u}random
lxc.cgroup.devices.allow = c 1:8 rwm
lxc.cgroup.devices.allow = c 1:9 rwm
## /dev/pts/*
lxc.cgroup.devices.allow = c 5:2 rwm
lxc.cgroup.devices.allow = c 136:* rwm
## rtc
lxc.cgroup.devices.allow = c 254:0 rm
## fuse
lxc.cgroup.devices.allow = c 10:229 rwm
## tun
lxc.cgroup.devices.allow = c 10:200 rwm
## full
lxc.cgroup.devices.allow = c 1:7 rwm
## hpet
lxc.cgroup.devices.allow = c 10:228 rwm
## kvm
lxc.cgroup.devices.allow = c 10:232 rwm
## To use loop devices, copy the following line to the container's
## configuration file (uncommented).
#lxc.cgroup.devices.allow = b 7:* rwm
##############################################
# vagrant-lxc container specific configuration
# VAGRANT-BEGIN
lxc.cgroup.memory.limit_in_bytes=1024M
lxc.aa_profile=unconfined
lxc.utsname=trusty64php55
lxc.mount.entry=/sys/fs/pstore sys/fs/pstore none bind,optional 0 0
lxc.mount.entry=tmpfs tmp tmpfs nodev,nosuid,size=2G 0 0
# VAGRANT-END
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "cp", "-f", "/tmp/lxc-config20160716-10407-1uqv6yn", "/var/lib/lxc/trusty64php55/config"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
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", "/usr/local/bin/vagrant-lxc-wrapper", "chown", "root:root", "/var/lib/lxc/trusty64php55/config"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 31999
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "cat", "/var/lib/lxc/trusty64php55/config"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: # Template used to create this container: /usr/share/vagrant-plugins/vagrant-lxc/scripts/lxc-template
# Parameters passed to the template: --tarball /home/vk/.vagrant.d/boxes/fgrehm-VAGRANTSLASH-trusty64-lxc/1.2.0/lxc/rootfs.tar.gz --config /home/vk/.vagrant.d/boxes/fgrehm-VAGRANTSLASH-trusty64-lxc/1.2.0/lxc/lxc-config
# For additional config options, please look at lxc.container.conf(5)
# Uncomment the following line to support nesting containers:
#lxc.include = /usr/share/lxc/config/nesting.conf
# (Be aware this has security implications)
##############################################
# Container specific configuration (automatically set)
lxc.rootfs = /var/lib/lxc/trusty64php55/rootfs
lxc.rootfs.backend = dir
lxc.utsname = trusty64php55
##############################################
# Network configuration (automatically set)
lxc.network.type = veth
lxc.network.link = lxcbr0
lxc.network.flags = up
lxc.network.hwaddr = 00:16:3e:e5:e4:0e
##############################################
# vagrant-lxc base box specific configuration
# Default pivot location
lxc.pivotdir = lxc_putold
# Default mount entries
lxc.mount.entry = proc proc proc nodev,noexec,nosuid 0 0
lxc.mount.entry = sysfs sys sysfs defaults 0 0
# Default console settings
lxc.devttydir = lxc
lxc.tty = 4
lxc.pts = 1024
# Default capabilities
lxc.cap.drop = sys_module mac_admin mac_override sys_time
# When using LXC with apparmor, the container will be confined by default.
# If you wish for it to instead run unconfined, copy the following line
# (uncommented) to the container's configuration file.
#lxc.aa_profile = unconfined
# To support container nesting on an Ubuntu host while retaining most of
# apparmor's added security, use the following two lines instead.
#lxc.aa_profile = lxc-container-default-with-nesting
#lxc.hook.mount = /usr/share/lxc/hooks/mountcgroups
# Uncomment the following line to autodetect squid-deb-proxy configuration on the
# host and forward it to the guest at start time.
#lxc.hook.pre-start = /usr/share/lxc/hooks/squid-deb-proxy-client
# If you wish to allow mounting block filesystems, then use the following
# line instead, and make sure to grant access to the block device and/or loop
# devices below in lxc.cgroup.devices.allow.
#lxc.aa_profile = lxc-container-default-with-mounting
# Default cgroup limits
lxc.cgroup.devices.deny = a
## Allow any mknod (but not using the node)
lxc.cgroup.devices.allow = c *:* m
lxc.cgroup.devices.allow = b *:* m
## /dev/null and zero
lxc.cgroup.devices.allow = c 1:3 rwm
lxc.cgroup.devices.allow = c 1:5 rwm
## consoles
lxc.cgroup.devices.allow = c 5:0 rwm
lxc.cgroup.devices.allow = c 5:1 rwm
## /dev/{,u}random
lxc.cgroup.devices.allow = c 1:8 rwm
lxc.cgroup.devices.allow = c 1:9 rwm
## /dev/pts/*
lxc.cgroup.devices.allow = c 5:2 rwm
lxc.cgroup.devices.allow = c 136:* rwm
## rtc
lxc.cgroup.devices.allow = c 254:0 rm
## fuse
lxc.cgroup.devices.allow = c 10:229 rwm
## tun
lxc.cgroup.devices.allow = c 10:200 rwm
## full
lxc.cgroup.devices.allow = c 1:7 rwm
## hpet
lxc.cgroup.devices.allow = c 10:228 rwm
## kvm
lxc.cgroup.devices.allow = c 10:232 rwm
## To use loop devices, copy the following line to the container's
## configuration file (uncommented).
#lxc.cgroup.devices.allow = b 7:* rwm
##############################################
# 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", "/usr/local/bin/vagrant-lxc-wrapper", "cp", "-f", "/tmp/lxc-config20160716-10407-t5wbzc", "/var/lib/lxc/trusty64php55/config"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
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", "/usr/local/bin/vagrant-lxc-wrapper", "chown", "root:root", "/var/lib/lxc/trusty64php55/config"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
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", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-start", "-d", "--name", "trusty64php55"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::WaitForCommunicator:0x000000038f7c50>
INFO interface: output: Waiting for machine to boot. This may take a few minutes...
INFO interface: output: ==> default: Waiting for machine to boot. This may take a few minutes...
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-info", "--name", "trusty64php55"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
==> default: Waiting for machine to boot. This may take a few minutes...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: trusty64php55
State: RUNNING
PID: 10573
CPU use: 0.26 seconds
BlkIO use: 32.00 KiB
Memory use: 2.34 MiB
KMem use: 0 bytes
Link: vethPS9FBH
TX bytes: 258 bytes
RX bytes: 180 bytes
Total bytes: 438 bytes
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-info", "--name", "trusty64php55"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: trusty64php55
DEBUG subprocess: stdout: State: RUNNING
DEBUG subprocess: stdout: PID: 10573
DEBUG subprocess: stdout: CPU use: 0.44 seconds
DEBUG subprocess: stdout: BlkIO use: 176.00 KiB
DEBUG subprocess: stdout: Memory use: 3.17 MiB
DEBUG subprocess: stdout: KMem use: 0 bytes
DEBUG subprocess: stdout: Link: vethPS9FBH
DEBUG subprocess: stdout: TX bytes: 258 bytes
DEBUG subprocess: stdout: RX bytes: 180 bytes
DEBUG subprocess: stdout: Total bytes: 438 bytes
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 31999
DEBUG subprocess: Exit status: 0
INFO machine: Calling action: ssh_ip on provider LXC (trusty64php55)
INFO interface: Machine: action ["ssh_ip", "start", {:target=>:default}]
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: machine_action_ssh_ip #<Vagrant::Action::Builder:0x007f7d780e9410>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x007f7d780e14e0>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: machine_action_ssh_ip #<Vagrant::Action::Builder:0x007f7d780258d0>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x007f7d78022608>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x007f7d78022608>
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-attach", "--name", "trusty64php55", "--", "/bin/true"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
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: 1 hooks defined.
INFO runner: Running action: machine_action_ssh_ip #<Vagrant::Action::Warden:0x00000003d70930>
INFO warden: Calling IN action: #<Proc:0x00000003b6d7a0@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::LXC::Action::FetchIpWithLxcAttach:0x00000003d708b8>
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-attach", "-h"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
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 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.
-L, --pty-log=FILE
Log pty output to FILE
-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", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-attach", "--name", "trusty64php55", "--namespaces", "'NETWORK|MOUNT'", "--", "/sbin/ip", "-4", "addr", "show", "scope", "global", "eth0"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
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", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-info", "--name", "trusty64php55"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: trusty64php55
DEBUG subprocess: stdout: State: RUNNING
DEBUG subprocess: stdout: PID: 10573
DEBUG subprocess: stdout: CPU use: 1.38 seconds
DEBUG subprocess: stdout: BlkIO use: 176.00 KiB
DEBUG subprocess: stdout: Memory use: 3.23 MiB
DEBUG subprocess: stdout: KMem use: 0 bytes
DEBUG subprocess: stdout: Link: vethPS9FBH
TX bytes: 418 bytes
RX bytes: 258 bytes
Total bytes: 676 bytes
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-info", "--name", "trusty64php55"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: trusty64php55
DEBUG subprocess: stdout: State: RUNNING
DEBUG subprocess: stdout: PID: 10573
DEBUG subprocess: stdout: IP: 10.0.3.238
DEBUG subprocess: stdout: CPU use: 3.15 seconds
BlkIO use: 472.00 KiB
DEBUG subprocess: stdout: Memory use: 25.16 MiB
DEBUG subprocess: stdout: KMem use: 0 bytes
Link: vethPS9FBH
TX bytes: 1.85 KiB
RX bytes: 3.09 KiB
Total bytes: 4.93 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 31999
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-info", "--name", "trusty64php55"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: trusty64php55
DEBUG subprocess: stdout: State: RUNNING
DEBUG subprocess: stdout: PID: 10573
DEBUG subprocess: stdout: IP: 10.0.3.238
DEBUG subprocess: stdout: CPU use: 3.98 seconds
DEBUG subprocess: stdout: BlkIO use: 472.00 KiB
DEBUG subprocess: stdout: Memory use: 23.40 MiB
DEBUG subprocess: stdout: KMem use: 0 bytes
DEBUG subprocess: stdout: Link: vethPS9FBH
TX bytes: 1.85 KiB
RX bytes: 3.28 KiB
Total bytes: 5.13 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-attach", "--name", "trusty64php55", "--namespaces", "'NETWORK|MOUNT'", "--", "/sbin/ip", "-4", "addr", "show", "scope", "global", "eth0"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: 8: eth0@if9: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc noqueue state UP group default qlen 1000
inet 10.0.3.238/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:0x00000003cf4a10>
INFO warden: Calling IN action: #<Proc:0x00000003c03598@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Proc:0x00000003c03598@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::LXC::Action::FetchIpFromDnsmasqLeases:0x00000003cf4a10>
INFO warden: Calling OUT action: #<Vagrant::LXC::Action::FetchIpWithLxcAttach:0x00000003d708b8>
INFO warden: Calling OUT action: #<Proc:0x00000003b6d7a0@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Call:0x007f7d780e14e0>
INFO interface: Machine: action ["ssh_ip", "end", {:target=>:default}]
DEBUG ssh: Checking key permissions: /home/vk-unencrypted/mygits/trusty64php55/.vagrant/machines/default/lxc/private_key
INFO interface: detail: SSH address: 10.0.3.238:22
INFO interface: detail: default: SSH address: 10.0.3.238:22
default: SSH address: 10.0.3.238:22
INFO interface: detail: SSH username: vagrant
INFO interface: detail: default: SSH username: vagrant
default: SSH username: vagrant
INFO interface: detail: SSH auth method: private key
INFO interface: detail: default: SSH auth method: private key
default: SSH auth method: private key
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-info", "--name", "trusty64php55"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: trusty64php55
DEBUG subprocess: stdout: State: RUNNING
DEBUG subprocess: stdout: PID: 10573
DEBUG subprocess: stdout: IP: 10.0.3.238
DEBUG subprocess: stdout: CPU use: 3.98 seconds
DEBUG subprocess: stdout: BlkIO use: 472.00 KiB
DEBUG subprocess: stdout: Memory use: 23.40 MiB
DEBUG subprocess: stdout: KMem use: 0 bytes
DEBUG subprocess: stdout: Link: vethPS9FBH
DEBUG subprocess: stdout: TX bytes: 1.93 KiB
RX bytes: 3.48 KiB
Total bytes: 5.41 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 (trusty64php55)
INFO interface: Machine: action ["ssh_ip", "start", {:target=>:default}]
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: machine_action_ssh_ip #<Vagrant::Action::Builder:0x000000037d3090>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x000000037cfdc8>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: machine_action_ssh_ip #<Vagrant::Action::Builder:0x00000003796280>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x0000000378f480>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x0000000378f480>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: machine_action_ssh_ip #<Vagrant::Action::Warden:0x0000000376cd68>
INFO warden: Calling IN action: #<Proc:0x000000036ffb28@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::LXC::Action::FetchIpWithLxcAttach:0x0000000376ccf0>
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-attach", "--name", "trusty64php55", "--namespaces", "'NETWORK|MOUNT'", "--", "/sbin/ip", "-4", "addr", "show", "scope", "global", "eth0"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: 8: eth0@if9: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc noqueue state UP group default qlen 1000
inet 10.0.3.238/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:0x0000000374bcf8>
INFO warden: Calling IN action: #<Proc:0x000000037278f8@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Proc:0x000000037278f8@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::LXC::Action::FetchIpFromDnsmasqLeases:0x0000000374bcf8>
INFO warden: Calling OUT action: #<Vagrant::LXC::Action::FetchIpWithLxcAttach:0x0000000376ccf0>
INFO warden: Calling OUT action: #<Proc:0x000000036ffb28@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Call:0x000000037cfdc8>
INFO interface: Machine: action ["ssh_ip", "end", {:target=>:default}]
DEBUG ssh: Checking key permissions: /home/vk-unencrypted/mygits/trusty64php55/.vagrant/machines/default/lxc/private_key
INFO ssh: Attempting SSH connection...
INFO ssh: Attempting to connect to SSH...
INFO ssh: - Host: 10.0.3.238
INFO ssh: - Port: 22
INFO ssh: - Username: vagrant
INFO ssh: - Password? false
INFO ssh: - Key Path: ["/home/vk-unencrypted/mygits/trusty64php55/.vagrant/machines/default/lxc/private_key"]
DEBUG ssh: == Net-SSH connection debug-level log START ==
DEBUG ssh: D, [2016-07-16T03:56:28.895393 #10407] DEBUG -- net.ssh.transport.session[1b566ec]: establishing connection to 10.0.3.238:22
D, [2016-07-16T03:56:28.896035 #10407] DEBUG -- net.ssh.transport.session[1b566ec]: connection established
I, [2016-07-16T03:56:28.896182 #10407] INFO -- net.ssh.transport.server_version[1b55e2c]: negotiating protocol version
D, [2016-07-16T03:56:28.904849 #10407] DEBUG -- net.ssh.transport.server_version[1b55e2c]: remote is `SSH-2.0-OpenSSH_6.6.1p1 Ubuntu-2ubuntu2'
D, [2016-07-16T03:56:28.904983 #10407] DEBUG -- net.ssh.transport.server_version[1b55e2c]: local is `SSH-2.0-Ruby/Net::SSH_3.0.1 x86_64-linux-gnu'
D, [2016-07-16T03:56:28.906169 #10407] DEBUG -- socket[1b5632c]: read 1648 bytes
D, [2016-07-16T03:56:28.906353 #10407] DEBUG -- socket[1b5632c]: received packet nr 0 type 20 len 1644
I, [2016-07-16T03:56:28.906452 #10407] INFO -- net.ssh.transport.algorithms[1b5506c]: got KEXINIT from server
I, [2016-07-16T03:56:28.906730 #10407] INFO -- net.ssh.transport.algorithms[1b5506c]: sending KEXINIT
D, [2016-07-16T03:56:28.906946 #10407] DEBUG -- socket[1b5632c]: queueing packet nr 0 type 20 len 1684
D, [2016-07-16T03:56:28.907068 #10407] DEBUG -- socket[1b5632c]: sent 1688 bytes
I, [2016-07-16T03:56:28.907101 #10407] INFO -- net.ssh.transport.algorithms[1b5506c]: negotiating algorithms
D, [2016-07-16T03:56:28.907301 #10407] DEBUG -- net.ssh.transport.algorithms[1b5506c]: 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, [2016-07-16T03:56:28.907333 #10407] DEBUG -- net.ssh.transport.algorithms[1b5506c]: exchanging keys
D, [2016-07-16T03:56:28.907577 #10407] DEBUG -- socket[1b5632c]: queueing packet nr 1 type 34 len 20
D, [2016-07-16T03:56:28.907651 #10407] DEBUG -- socket[1b5632c]: sent 24 bytes
D, [2016-07-16T03:56:28.910641 #10407] DEBUG -- socket[1b5632c]: read 152 bytes
D, [2016-07-16T03:56:28.910770 #10407] DEBUG -- socket[1b5632c]: received packet nr 1 type 31 len 148
D, [2016-07-16T03:56:28.913307 #10407] DEBUG -- socket[1b5632c]: queueing packet nr 2 type 32 len 140
D, [2016-07-16T03:56:28.913530 #10407] DEBUG -- socket[1b5632c]: sent 144 bytes
D, [2016-07-16T03:56:28.919464 #10407] DEBUG -- socket[1b5632c]: read 720 bytes
D, [2016-07-16T03:56:28.919627 #10407] DEBUG -- socket[1b5632c]: received packet nr 2 type 33 len 700
D, [2016-07-16T03:56:28.921887 #10407] DEBUG -- socket[1b5632c]: queueing packet nr 3 type 21 len 20
D, [2016-07-16T03:56:28.922072 #10407] DEBUG -- socket[1b5632c]: sent 24 bytes
D, [2016-07-16T03:56:28.922147 #10407] DEBUG -- socket[1b5632c]: received packet nr 3 type 21 len 12
D, [2016-07-16T03:56:28.922602 #10407] DEBUG -- net.ssh.authentication.session[1b441cc]: beginning authentication of `vagrant'
D, [2016-07-16T03:56:28.922731 #10407] DEBUG -- socket[1b5632c]: queueing packet nr 4 type 5 len 28
D, [2016-07-16T03:56:28.922790 #10407] DEBUG -- socket[1b5632c]: sent 52 bytes
D, [2016-07-16T03:56:28.961665 #10407] DEBUG -- socket[1b5632c]: read 52 bytes
D, [2016-07-16T03:56:28.961862 #10407] DEBUG -- socket[1b5632c]: received packet nr 4 type 6 len 28
D, [2016-07-16T03:56:28.962015 #10407] DEBUG -- net.ssh.authentication.session[1b441cc]: trying none
D, [2016-07-16T03:56:28.962181 #10407] DEBUG -- socket[1b5632c]: queueing packet nr 5 type 50 len 44
D, [2016-07-16T03:56:28.962268 #10407] DEBUG -- socket[1b5632c]: sent 68 bytes
D, [2016-07-16T03:56:28.963423 #10407] DEBUG -- socket[1b5632c]: read 68 bytes
D, [2016-07-16T03:56:28.963992 #10407] DEBUG -- socket[1b5632c]: received packet nr 5 type 51 len 44
D, [2016-07-16T03:56:28.964122 #10407] DEBUG -- net.ssh.authentication.session[1b441cc]: allowed methods: publickey,password
D, [2016-07-16T03:56:28.964227 #10407] DEBUG -- net.ssh.authentication.methods.none[1b41080]: none failed
D, [2016-07-16T03:56:28.964313 #10407] DEBUG -- net.ssh.authentication.session[1b441cc]: trying publickey
D, [2016-07-16T03:56:28.964683 #10407] DEBUG -- net.ssh.authentication.agent[1b3f924]: connecting to ssh-agent
D, [2016-07-16T03:56:28.964978 #10407] DEBUG -- net.ssh.authentication.agent[1b3f924]: sending agent request 1 len 48
D, [2016-07-16T03:56:28.965506 #10407] DEBUG -- net.ssh.authentication.agent[1b3f924]: received agent packet 2 len 5
D, [2016-07-16T03:56:28.965578 #10407] DEBUG -- net.ssh.authentication.agent[1b3f924]: sending agent request 11 len 0
D, [2016-07-16T03:56:28.966896 #10407] DEBUG -- net.ssh.authentication.agent[1b3f924]: received agent packet 12 len 302
D, [2016-07-16T03:56:28.967329 #10407] DEBUG -- net.ssh.authentication.methods.publickey[1b3fb7c]: trying publickey (12:b2:e5:13:0e:64:f2:7e:8e:89:7d:06:d8:e1:ce:8d)
D, [2016-07-16T03:56:28.967630 #10407] DEBUG -- socket[1b5632c]: queueing packet nr 6 type 50 len 348
D, [2016-07-16T03:56:28.967784 #10407] DEBUG -- socket[1b5632c]: sent 372 bytes
D, [2016-07-16T03:56:28.968444 #10407] DEBUG -- socket[1b5632c]: read 324 bytes
D, [2016-07-16T03:56:28.968604 #10407] DEBUG -- socket[1b5632c]: received packet nr 6 type 60 len 300
D, [2016-07-16T03:56:28.974389 #10407] DEBUG -- socket[1b5632c]: queueing packet nr 7 type 50 len 620
D, [2016-07-16T03:56:28.974519 #10407] DEBUG -- socket[1b5632c]: sent 644 bytes
D, [2016-07-16T03:56:28.975860 #10407] DEBUG -- socket[1b5632c]: read 36 bytes
D, [2016-07-16T03:56:28.976043 #10407] DEBUG -- socket[1b5632c]: received packet nr 7 type 52 len 12
D, [2016-07-16T03:56:28.976138 #10407] DEBUG -- net.ssh.authentication.methods.publickey[1b3fb7c]: publickey succeeded (12:b2:e5:13:0e:64:f2:7e:8e:89:7d:06:d8:e1:ce:8d)
DEBUG ssh: == Net-SSH connection debug-level log END ==
DEBUG ssh: Checking whether SSH is ready...
DEBUG ssh: Re-using SSH connection.
INFO ssh: SSH is ready!
DEBUG ssh: Re-using SSH connection.
INFO ssh: Execute: (sudo=false)
DEBUG ssh: Exit status: 0
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-info", "--name", "trusty64php55"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: Name: trusty64php55
DEBUG subprocess: stdout: State: RUNNING
DEBUG subprocess: stdout: PID: 10573
DEBUG subprocess: stdout: IP: 10.0.3.238
DEBUG subprocess: stdout: CPU use: 4.03 seconds
DEBUG subprocess: stdout: BlkIO use: 472.00 KiB
DEBUG subprocess: stdout: Memory use: 24.51 MiB
DEBUG subprocess: stdout: KMem use: 0 bytes
DEBUG subprocess: stdout: Link: vethPS9FBH
DEBUG subprocess: stdout: TX bytes: 7.42 KiB
RX bytes: 8.87 KiB
Total bytes: 16.29 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 (trusty64php55)
INFO interface: Machine: action ["ssh_ip", "start", {:target=>:default}]
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: machine_action_ssh_ip #<Vagrant::Action::Builder:0x0000000361f960>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::Call:0x0000000361c6c0>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: machine_action_ssh_ip #<Vagrant::Action::Builder:0x000000035adf40>
INFO warden: Calling IN action: #<Vagrant::Action::Builtin::ConfigValidate:0x000000035ab790>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::ConfigValidate:0x000000035ab790>
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: machine_action_ssh_ip #<Vagrant::Action::Warden:0x00000003576040>
INFO warden: Calling IN action: #<Proc:0x00000002e07458@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling IN action: #<Vagrant::LXC::Action::FetchIpWithLxcAttach:0x00000003575fc8>
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-attach", "--name", "trusty64php55", "--namespaces", "'NETWORK|MOUNT'", "--", "/sbin/ip", "-4", "addr", "show", "scope", "global", "eth0"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-info", "--name", "trusty64php55"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stdout: 8: eth0@if9: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc noqueue state UP group default qlen 1000
DEBUG subprocess: stdout: inet 10.0.3.238/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:0x00000002c402c8>
INFO warden: Calling IN action: #<Proc:0x00000002e932c8@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Proc:0x00000002e932c8@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::LXC::Action::FetchIpFromDnsmasqLeases:0x00000002c402c8>
INFO warden: Calling OUT action: #<Vagrant::LXC::Action::FetchIpWithLxcAttach:0x00000003575fc8>
INFO warden: Calling OUT action: #<Proc:0x00000002e07458@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::Call:0x0000000361c6c0>
INFO interface: Machine: action ["ssh_ip", "end", {:target=>:default}]
DEBUG ssh: Checking key permissions: /home/vk-unencrypted/mygits/trusty64php55/.vagrant/machines/default/lxc/private_key
DEBUG subprocess: stdout: Name: trusty64php55
DEBUG subprocess: stdout: State: RUNNING
DEBUG subprocess: stdout: PID: 10573
DEBUG subprocess: stdout: IP: 10.0.3.238
DEBUG subprocess: stdout: CPU use: 4.03 seconds
DEBUG subprocess: stdout: BlkIO use: 472.00 KiB
DEBUG subprocess: stdout: Memory use: 24.51 MiB
DEBUG subprocess: stdout: KMem use: 0 bytes
DEBUG subprocess: stdout: Link: vethPS9FBH
TX bytes: 7.42 KiB
RX bytes: 9.07 KiB
Total bytes: 16.48 KiB
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO interface: output: Machine booted and ready!
INFO interface: output: ==> default: Machine booted and ready!
==> default: Machine booted and ready!
INFO warden: Calling IN action: #<Proc:0x000000038f7c00@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Proc:0x000000038f7c00@/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:94 (lambda)>
INFO warden: Calling OUT action: #<Vagrant::Action::Builtin::WaitForCommunicator:0x000000038f7c50>
INFO warden: Calling OUT action: #<Vagrant::LXC::Action::Boot:0x000000038f7c78>
INFO interface: output: Setting up private networks...
INFO interface: output: ==> default: Setting up private networks...
==> default: Setting up private networks...
INFO driver: Configuring network interface for trusty64php55 using 192.168.33.144 and bridge vlxcbr0
INFO driver: Checking whether bridge vlxcbr0 exists
INFO driver: Creating the bridge vlxcbr0
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "brctl", "addbr", "vlxcbr0"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 32000
DEBUG subprocess: Exit status: 0
INFO driver: Checking whether the bridge vlxcbr0 has an IP
INFO driver: Adding 192.168.33.254 to the bridge vlxcbr0
INFO subprocess: Starting process: ["/usr/bin/sudo", "/usr/local/bin/vagrant-lxc-wrapper", "ip", "addr", "add", "192.168.33.254/24", "dev", "vlxcbr0"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
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", "/usr/local/bin/vagrant-lxc-wrapper", "ip", "link", "set", "vlxcbr0", "up"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
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", "/usr/local/bin/vagrant-lxc-wrapper", "/usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework", "vlxcbr0", "trusty64php55", "192.168.33.144/24"]
INFO subprocess: Vagrant not running in installer, restoring original environment...
DEBUG subprocess: Selecting on IO
DEBUG subprocess: stderr: Invalid arguments for command /usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework, provided args: ["vlxcbr0", "trusty64php55", "192.168.33.144/24"]
DEBUG subprocess: Waiting for process to exit. Remaining to timeout: 31999
DEBUG subprocess: Exit status: 1
ERROR warden: Error occurred: There was an error executing ["sudo", "/usr/local/bin/vagrant-lxc-wrapper", "/usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework", "vlxcbr0", "trusty64php55", "192.168.33.144/24"]
For more information on the failure, enable detailed logging by setting
the environment variable VAGRANT_LOG to DEBUG.
INFO warden: Beginning recovery process...
INFO warden: Calling recover: #<Vagrant::Action::Builtin::HandleForwardedPortCollisions:0x00000003b214e0>
INFO warden: Recovery complete.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
ERROR warden: Error occurred: There was an error executing ["sudo", "/usr/local/bin/vagrant-lxc-wrapper", "/usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework", "vlxcbr0", "trusty64php55", "192.168.33.144/24"]
For more information on the failure, enable detailed logging by setting
the environment variable VAGRANT_LOG to DEBUG.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
ERROR warden: Error occurred: There was an error executing ["sudo", "/usr/local/bin/vagrant-lxc-wrapper", "/usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework", "vlxcbr0", "trusty64php55", "192.168.33.144/24"]
For more information on the failure, enable detailed logging by setting
the environment variable VAGRANT_LOG to DEBUG.
INFO warden: Beginning recovery process...
INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x007f7d78066588>
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
INFO warden: Calling recover: #<Vagrant::Action::Builtin::Call:0x007f7d7802ec78>
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
INFO warden: Recovery complete.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
ERROR warden: Error occurred: There was an error executing ["sudo", "/usr/local/bin/vagrant-lxc-wrapper", "/usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework", "vlxcbr0", "trusty64php55", "192.168.33.144/24"]
For more information on the failure, enable detailed logging by setting
the environment variable VAGRANT_LOG to DEBUG.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
ERROR warden: Error occurred: There was an error executing ["sudo", "/usr/local/bin/vagrant-lxc-wrapper", "/usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework", "vlxcbr0", "trusty64php55", "192.168.33.144/24"]
For more information on the failure, enable detailed logging by setting
the environment variable VAGRANT_LOG to DEBUG.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
INFO warden: Beginning recovery process...
INFO warden: Recovery complete.
INFO environment: Released process lock: machine-action-1334630dd2a55d163849c35f271b5648
INFO environment: Running hook: environment_unload
INFO runner: Preparing hooks for middleware sequence...
INFO runner: 1 hooks defined.
INFO runner: Running action: environment_unload #<Vagrant::Action::Builder:0x007f7d7819b0c0>
ERROR vagrant: Vagrant experienced an error! Details:
ERROR vagrant: #<Vagrant::LXC::Errors::ExecuteError: There was an error executing ["sudo", "/usr/local/bin/vagrant-lxc-wrapper", "/usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework", "vlxcbr0", "trusty64php55", "192.168.33.144/24"]
For more information on the failure, enable detailed logging by setting
the environment variable VAGRANT_LOG to DEBUG.>
ERROR vagrant: There was an error executing ["sudo", "/usr/local/bin/vagrant-lxc-wrapper", "/usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework", "vlxcbr0", "trusty64php55", "192.168.33.144/24"]
For more information on the failure, enable detailed logging by setting
the environment variable VAGRANT_LOG to DEBUG.
ERROR vagrant: /usr/lib/ruby/vendor_ruby/vagrant-lxc/sudo_wrapper.rb:51:in `block in execute'
/usr/lib/ruby/vendor_ruby/vagrant/util/retryable.rb:17:in `retryable'
/usr/lib/ruby/vendor_ruby/vagrant-lxc/sudo_wrapper.rb:41:in `execute'
/usr/lib/ruby/vendor_ruby/vagrant-lxc/sudo_wrapper.rb:18:in `run'
/usr/lib/ruby/vendor_ruby/vagrant-lxc/driver.rb:172:in `configure_private_network'
/usr/lib/ruby/vendor_ruby/vagrant-lxc/action/private_networks.rb:34:in `block in configure_private_networks'
/usr/lib/ruby/vendor_ruby/vagrant-lxc/action/private_networks.rb:25:in `each'
/usr/lib/ruby/vendor_ruby/vagrant-lxc/action/private_networks.rb:25:in `find'
/usr/lib/ruby/vendor_ruby/vagrant-lxc/action/private_networks.rb:25:in `configure_private_networks'
/usr/lib/ruby/vendor_ruby/vagrant-lxc/action/private_networks.rb:14:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant-lxc/action/forward_ports.rb:29:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant-lxc/action/warn_networks.rb:14:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/builtin/set_hostname.rb:16:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant-lxc/action/prepare_nfs_settings.rb:15:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/builtin/synced_folders.rb:87:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/builtin/synced_folder_cleanup.rb:28:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/share/vagrant/plugins/synced_folders/nfs/action_cleanup.rb:25:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant-lxc/action/prepare_nfs_valid_ids.rb:14:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/builtin/handle_forwarded_port_collisions.rb:49:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/builtin/env_set.rb:19:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/builtin/provision.rb:80:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:95:in `block in finalize_action'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/builder.rb:116:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/runner.rb:66:in `block in run'
/usr/lib/ruby/vendor_ruby/vagrant/util/busy.rb:19:in `busy'
/usr/lib/ruby/vendor_ruby/vagrant/action/runner.rb:66:in `run'
/usr/lib/ruby/vendor_ruby/vagrant/action/builtin/call.rb:53:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/builtin/box_check_outdated.rb:78:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/builtin/config_validate.rb:25:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:95:in `block in finalize_action'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:95:in `block in finalize_action'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/builder.rb:116:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/runner.rb:66:in `block in run'
/usr/lib/ruby/vendor_ruby/vagrant/util/busy.rb:19:in `busy'
/usr/lib/ruby/vendor_ruby/vagrant/action/runner.rb:66:in `run'
/usr/lib/ruby/vendor_ruby/vagrant/action/builtin/call.rb:53:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/builtin/config_validate.rb:25:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/warden.rb:34:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/builder.rb:116:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/action/runner.rb:66:in `block in run'
/usr/lib/ruby/vendor_ruby/vagrant/util/busy.rb:19:in `busy'
/usr/lib/ruby/vendor_ruby/vagrant/action/runner.rb:66:in `run'
/usr/lib/ruby/vendor_ruby/vagrant/machine.rb:224:in `action_raw'
/usr/lib/ruby/vendor_ruby/vagrant/machine.rb:199:in `block in action'
/usr/lib/ruby/vendor_ruby/vagrant/environment.rb:527:in `lock'
/usr/lib/ruby/vendor_ruby/vagrant/machine.rb:185:in `call'
/usr/lib/ruby/vendor_ruby/vagrant/machine.rb:185:in `action'
/usr/lib/ruby/vendor_ruby/vagrant/batch_action.rb:82:in `block (2 levels) in run'
INFO interface: error: There was an error executing ["sudo", "/usr/local/bin/vagrant-lxc-wrapper", "/usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework", "vlxcbr0", "trusty64php55", "192.168.33.144/24"]
For more information on the failure, enable detailed logging by setting
the environment variable VAGRANT_LOG to DEBUG.
There was an error executing ["sudo", "/usr/local/bin/vagrant-lxc-wrapper", "/usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework", "vlxcbr0", "trusty64php55", "192.168.33.144/24"]
For more information on the failure, enable detailed logging by setting
the environment variable VAGRANT_LOG to DEBUG.
INFO interface: Machine: error-exit ["Vagrant::LXC::Errors::ExecuteError", "There was an error executing [\"sudo\", \"/usr/local/bin/vagrant-lxc-wrapper\", \"/usr/share/vagrant-plugins/vagrant-lxc/scripts/pipework\", \"vlxcbr0\", \"trusty64php55\", \"192.168.33.144/24\"]\n\nFor more information on the failure, enable detailed logging by setting\nthe environment variable VAGRANT_LOG to DEBUG."]
jcherniak commented
Did you ever find a solution here?
fkleon commented
This might have been a different cause, but I've run into a similar problem on a Ubuntu 16.04 host with Vagrant 1.9.1.
The pipework script now seems to be in a different location that is not whitelisted in the vagrant-lxc-wrapper.
As far as I can tell, it used to be at ~/.vagrant.d/gems/gems/vagrant-lxc-1.2.3/scipts/pipework, and now is at ~/.vagrant.d/gems/2.2.5/gems/vagrant-lxc-1.2.3/script/pipework (potentially due to a newer ruby version bundled with vagrant?).
You can manually fix the appropriate regular expression in /usr/local/bin/vagrant-lxc-wrapper:
Whitelist.add_regex %r{/home/<user>/.vagrant.d/gems/(?:\d+?\.\d+?\.\d+?/)?gems/vagrant-lxc.+/scripts/pipework}, '**'
jcherniak commented
That seemed to work. Thank you!
iamjvn commented
Thanks for the fix! I lost many hours due to this...
andreclaudino commented
I had the same error, but add
Whitelist.add_regex %r{/home/<user>/.vagrant.d/gems/(?:\d+?\.\d+?\.\d+?/)?gems/vagrant-lxc.+/scripts/pipework}, '**'to /usr/local/bin/vagrant-lxc-wrapper (before Whitelist.run!(ARGV)) worked for me.
This solved my issue, #444 too.
gnutix commented
I suddenly had this issue today, even though my vagrant+lxc project used to work just fine. I've switched the box it used though, so maybe that's the reason ?
$ vagrant up --provider=lxc
Bringing machine 'default' up with 'lxc' provider...
==> default: Importing base box 'drifter/bionic64-base'...
There was an error executing ["sudo", "/usr/local/bin/vagrant-lxc-wrapper", "lxc-create", "-B", "dir", "--template", "/home/gnutix/.vagrant.d/gems/2.4.4/gems/vagrant-lxc-1.4.3/scripts/lxc-template", "--name", "project_default_1560410031009_28575", "--", "--tarball", "/home/gnutix/.vagrant.d/boxes/drifter-VAGRANTSLASH-bionic64-base/0.0.2/lxc/rootfs.tar.gz", "--config", "/home/gnutix/.vagrant.d/boxes/drifter-VAGRANTSLASH-bionic64-base/0.0.2/lxc/lxc-config"]
Here's the complete log with debug : https://gist.github.com/gnutix/1e0ba70dcddbf5b5e5520d1c283ee6df
At first my /usr/local/bin/vagrant-lxc-wrapper file did not exist, and I had to run vagrant lxc sudoers to create it. Once it was created, it already contained the fix suggested above :
# ...
# - Private network script and commands
Whitelist.add '/sbin/ip', 'addr', 'add', /(\d+|\.)+\/24/, 'dev', /.+/
Whitelist.add '/sbin/ip', 'link', 'set', /.+/, /(up|down)/
Whitelist.add '/sbin/brctl', /(addbr|delbr)/, /.+/
Whitelist.add_regex %r{/home/gnutix/.vagrant.d/gems/(?:\d+?\.\d+?\.\d+?/)?gems/vagrant-lxc.+/scripts/pipework}, '**'
##
# Commands from driver/cli.rb
Whitelist.add '/usr/bin/lxc-version'
Whitelist.add '/usr/bin/lxc-ls'
Whitelist.add '/usr/bin/lxc-info', '--name', /.*/
Whitelist.add '/usr/bin/lxc-info', '--name', /.*/, '-iH'
Whitelist.add '/usr/bin/lxc-create', '-B', /.*/, '--template', /.*/, '--name', /.*/, '**'
Whitelist.add '/usr/bin/lxc-create', '--version'
Whitelist.add '/usr/bin/lxc-destroy', '--name', /.*/
Whitelist.add '/usr/bin/lxc-start', '-d', '--name', /.*/, '**'
Whitelist.add '/usr/bin/lxc-stop', '--name', /.*/
Whitelist.add '/usr/bin/lxc-shutdown', '--name', /.*/
Whitelist.add '/usr/bin/lxc-attach', '--name', /.*/, '**'
Whitelist.add '/usr/bin/lxc-attach', '-h'
Whitelist.add '/usr/bin/lxc-config', 'lxc.lxcpath'
Whitelist.add '/usr/bin/lxc-update-config', '-c', /.*/
##
# Commands from driver/action/remove_temporary_files.rb
Whitelist.add '/bin/rm', '-rf', %r{\A#{base}/.*/rootfs/tmp/.*}
# Watch out for stones
Whitelist.run!(ARGV)