failed Error 400007: `cell_z1/0' is not running after update

[rahulkj]

I'm getting the error Error 400007: `cell_z1/0' is not running after update while deploying diego - 0.1447.0 with cf - v228 on bosh-lite

After getting into the cell_z1 job, I see the rep is failing to start. Any ideas on how I can resolve this issue?

[cf-gitbot]

We have created an issue in Pivotal Tracker to manage this. You can view the current status of your issue at: https://www.pivotaltracker.com/story/show/112263475.

[emalm]

Hi, @rahulkj,

What are the logs from the rep job? You should be able to find them on the cell VM in /var/vcap/sys/log/rep/rep.stdout.log.

Thanks,
Eric, CF Runtime Diego PM

[rahulkj]

@ematpl - Here are the last few lines from the rep log

{"timestamp":"1453565051.154300690","source":"rep","message":"rep.garden-healthcheck.healthcheck.create.starting","log_level":0,"data":{"session":"3.1.3"}}
{"timestamp":"1453565060.863429070","source":"rep","message":"rep.garden-healthcheck.healthcheck.create.failed","log_level":2,"data":{"attempt":0,"error":"container: start: exit status 2","session":"3.1.3"}}
{"timestamp":"1453565074.606655121","source":"rep","message":"rep.garden-healthcheck.healthcheck.create.failed","log_level":2,"data":{"attempt":1,"error":"container: start: exit status 2","session":"3.1.3"}}
{"timestamp":"1453565076.991024733","source":"rep","message":"rep.started","log_level":1,"data":{"cell-id":"cell_z1-0"}}
{"timestamp":"1453565076.991100073","source":"rep","message":"rep.exited","log_level":1,"data":{}}
{"timestamp":"1453565091.034866095","source":"rep","message":"rep.wait-for-garden.ping-garden","log_level":1,"data":{"initialTime:":"2016-01-23T16:04:51.034224546Z","session":"1","wait-time-ns:":639512}}
{"timestamp":"1453565091.036183596","source":"rep","message":"rep.wait-for-garden.ping-garden-success","log_level":1,"data":{"initialTime:":"2016-01-23T16:04:51.034224546Z","session":"1","wait-time-ns:":1957538}}
{"timestamp":"1453565091.036298990","source":"rep","message":"rep.executor-fetching-containers-to-destroy","log_level":1,"data":{}}
{"timestamp":"1453565091.037037373","source":"rep","message":"rep.executor-fetched-containers-to-destroy","log_level":1,"data":{"num-containers":0}}
{"timestamp":"1453565091.037880898","source":"rep","message":"rep.initial-capacity","log_level":1,"data":{"capacity":{"memory_mb":5969,"disk_mb":78244,"containers":250}}}
{"timestamp":"1453565091.150654316","source":"rep","message":"rep.garden-healthcheck.starting","log_level":1,"data":{"session":"3"}}
{"timestamp":"1453565091.150716066","source":"rep","message":"rep.garden-healthcheck.healthcheck.starting","log_level":0,"data":{"session":"3.1"}}
{"timestamp":"1453565091.150741339","source":"rep","message":"rep.garden-healthcheck.healthcheck.list.starting","log_level":0,"data":{"session":"3.1.1"}}
{"timestamp":"1453565091.151329041","source":"rep","message":"rep.garden-healthcheck.healthcheck.list.succeeded","log_level":0,"data":{"attempt":0,"session":"3.1.1"}}
{"timestamp":"1453565091.151350975","source":"rep","message":"rep.garden-healthcheck.healthcheck.list.finished","log_level":0,"data":{"session":"3.1.1"}}
{"timestamp":"1453565091.151360989","source":"rep","message":"rep.garden-healthcheck.healthcheck.initial-destroy.starting","log_level":0,"data":{"numContainers":0,"session":"3.1.2"}}
{"timestamp":"1453565091.151367188","source":"rep","message":"rep.garden-healthcheck.healthcheck.initial-destroy.succeeded","log_level":0,"data":{"session":"3.1.2"}}
{"timestamp":"1453565091.151371717","source":"rep","message":"rep.garden-healthcheck.healthcheck.initial-destroy.finished","log_level":0,"data":{"session":"3.1.2"}}
{"timestamp":"1453565091.151377916","source":"rep","message":"rep.garden-healthcheck.healthcheck.create.starting","log_level":0,"data":{"session":"3.1.3"}}
{"timestamp":"1453565100.418776989","source":"rep","message":"rep.garden-healthcheck.healthcheck.create.failed","log_level":2,"data":{"attempt":0,"error":"container: start: exit status 2","session":"3.1.3"}}
{"timestamp":"1453565115.859810114","source":"rep","message":"rep.garden-healthcheck.healthcheck.create.failed","log_level":2,"data":{"attempt":1,"error":"container: start: exit status 2","session":"3.1.3"}}
{"timestamp":"1453565116.684677124","source":"rep","message":"rep.started","log_level":1,"data":{"cell-id":"cell_z1-0"}}
{"timestamp":"1453565116.684719324","source":"rep","message":"rep.exited","log_level":1,"data":{}}

[emalm]

Hi, @rahulkj,

Thanks for the logs. From the rep.garden-healthcheck.healthcheck.create.failed lines, it looks like garden-linux is unable to create a container for the rep's initial garden health-check. Could you also provide logs from garden-linux? They'll be in /var/vcap/sys/log/garden/garden.stdout.log. It would be great to see them from the time window of the last cycle from these rep logs (say, timestamp 1453565091 through 1453565117).

Best,
Eric

[rahulkj]

Hi @ematpl,

Here are the logs

{"timestamp":"1453565091.001223326","source":"garden-linux","message":"garden-linux.connection.open","log_level":0,"data":{"local_addr":{"IP":"127.0.0.1","Port":7777,"Zone":""},"remote_addr":{"IP":"127.0.0.1","Port":38382,"Zone":""},"session":"14"}}
{"timestamp":"1453565091.001392365","source":"garden-linux","message":"garden-linux.connection.closed","log_level":0,"data":{"local_addr":{"IP":"127.0.0.1","Port":7777,"Zone":""},"remote_addr":{"IP":"127.0.0.1","Port":38382,"Zone":""},"session":"14"}}
{"timestamp":"1453565091.035336018","source":"garden-linux","message":"garden-linux.connection.open","log_level":0,"data":{"local_addr":{"IP":"127.0.0.1","Port":7777,"Zone":""},"remote_addr":{"IP":"127.0.0.1","Port":38385,"Zone":""},"session":"14"}}
{"timestamp":"1453565091.036077023","source":"garden-linux","message":"garden-linux.connection.closed","log_level":0,"data":{"local_addr":{"IP":"127.0.0.1","Port":7777,"Zone":""},"remote_addr":{"IP":"127.0.0.1","Port":38385,"Zone":""},"session":"14"}}
{"timestamp":"1453565091.036507130","source":"garden-linux","message":"garden-linux.connection.open","log_level":0,"data":{"local_addr":{"IP":"127.0.0.1","Port":7777,"Zone":""},"remote_addr":{"IP":"127.0.0.1","Port":38386,"Zone":""},"session":"14"}}
{"timestamp":"1453565091.036736250","source":"garden-linux","message":"garden-linux.garden-server.list.started","log_level":0,"data":{"session":"13.9568"}}
{"timestamp":"1453565091.036759615","source":"garden-linux","message":"garden-linux.backend.containers.started","log_level":0,"data":{"session":"12.3192"}}
{"timestamp":"1453565091.036770582","source":"garden-linux","message":"garden-linux.backend.containers.ending","log_level":0,"data":{"handles":[],"session":"12.3192"}}
{"timestamp":"1453565091.036781073","source":"garden-linux","message":"garden-linux.garden-server.list.ending","log_level":0,"data":{"handles":[],"session":"13.9568"}}
{"timestamp":"1453565091.037054062","source":"garden-linux","message":"garden-linux.connection.closed","log_level":0,"data":{"local_addr":{"IP":"127.0.0.1","Port":7777,"Zone":""},"remote_addr":{"IP":"127.0.0.1","Port":38386,"Zone":""},"session":"14"}}
{"timestamp":"1453565091.037349463","source":"garden-linux","message":"garden-linux.connection.open","log_level":0,"data":{"local_addr":{"IP":"127.0.0.1","Port":7777,"Zone":""},"remote_addr":{"IP":"127.0.0.1","Port":38387,"Zone":""},"session":"14"}}
{"timestamp":"1453565091.037797689","source":"garden-linux","message":"garden-linux.connection.closed","log_level":0,"data":{"local_addr":{"IP":"127.0.0.1","Port":7777,"Zone":""},"remote_addr":{"IP":"127.0.0.1","Port":38387,"Zone":""},"session":"14"}}
{"timestamp":"1453565091.150958300","source":"garden-linux","message":"garden-linux.connection.open","log_level":0,"data":{"local_addr":{"IP":"127.0.0.1","Port":7777,"Zone":""},"remote_addr":{"IP":"127.0.0.1","Port":38388,"Zone":""},"session":"14"}}
{"timestamp":"1453565091.151088953","source":"garden-linux","message":"garden-linux.garden-server.list.started","log_level":0,"data":{"session":"13.9570"}}
{"timestamp":"1453565091.151104927","source":"garden-linux","message":"garden-linux.backend.containers.started","log_level":0,"data":{"session":"12.3193"}}
{"timestamp":"1453565091.151113987","source":"garden-linux","message":"garden-linux.backend.containers.ending","log_level":0,"data":{"handles":[],"session":"12.3193"}}
{"timestamp":"1453565091.151125669","source":"garden-linux","message":"garden-linux.garden-server.list.ending","log_level":0,"data":{"handles":[],"session":"13.9570"}}
{"timestamp":"1453565091.151366234","source":"garden-linux","message":"garden-linux.connection.closed","log_level":0,"data":{"local_addr":{"IP":"127.0.0.1","Port":7777,"Zone":""},"remote_addr":{"IP":"127.0.0.1","Port":38388,"Zone":""},"session":"14"}}
{"timestamp":"1453565091.151924849","source":"garden-linux","message":"garden-linux.connection.open","log_level":0,"data":{"local_addr":{"IP":"127.0.0.1","Port":7777,"Zone":""},"remote_addr":{"IP":"127.0.0.1","Port":38389,"Zone":""},"session":"14"}}
{"timestamp":"1453565091.152054548","source":"garden-linux","message":"garden-linux.garden-server.create.creating","log_level":0,"data":{"request":{"Handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","GraceTime":0,"RootFSPath":"/var/vcap/packages/rootfs_cflinuxfs2/rootfs","BindMounts":null,"Network":"","Privileged":false,"Limits":{"bandwidth_limits":{},"cpu_limits":{},"disk_limits":{},"memory_limits":{}}},"session":"13.9571"}}
{"timestamp":"1453565091.152090073","source":"garden-linux","message":"garden-linux.pool.acquire.creating","log_level":1,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6373"}}
{"timestamp":"1453565091.152128935","source":"garden-linux","message":"garden-linux.pool.acquire.subnet-pool.acquire.subnet-selecting","log_level":1,"data":{"allocated-subnets":null,"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6373.1.1"}}
{"timestamp":"1453565091.152143478","source":"garden-linux","message":"garden-linux.pool.acquire.subnet-pool.acquire.subnet-selected","log_level":1,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6373.1.1","subnet":"10.254.0.0/30"}}
{"timestamp":"1453565091.152154446","source":"garden-linux","message":"garden-linux.pool.acquire.subnet-pool.acquire.ip-selecting","log_level":1,"data":{"allocated-ips":null,"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6373.1.1"}}
{"timestamp":"1453565091.152168512","source":"garden-linux","message":"garden-linux.pool.acquire.subnet-pool.acquire.ip-selected","log_level":1,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","ip":"10.254.0.2","session":"11.6373.1.1"}}
{"timestamp":"1453565091.152179956","source":"garden-linux","message":"garden-linux.pool.acquire.subnet-pool.acquire.new-allocated","log_level":1,"data":{"allocated-ips":["10.254.0.2"],"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6373.1.1"}}
{"timestamp":"1453565091.152189255","source":"garden-linux","message":"garden-linux.pool.acquire.acquired-pool-resources","log_level":1,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6373"}}
{"timestamp":"1453565091.152246952","source":"garden-linux","message":"garden-linux.pool.acquire.provide-rootfs-starting","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6373"}}
{"timestamp":"1453565091.152760506","source":"garden-linux","message":"garden-linux.pool.acquire.setup-iptables-starting","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6373"}}
{"timestamp":"1453565091.152803898","source":"garden-linux","message":"garden-linux.auvlk9hu04s.filter.logging-chain.setup.started","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04s-log","name":"w--instance-auvlk9hu04s","session":"15944.1.1.1","useKernelLogging":true}}
{"timestamp":"1453565091.152826071","source":"garden-linux","message":"garden-linux.auvlk9hu04s.filter.logging-chain.teardown.started","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04s-log","name":"w--instance-auvlk9hu04s","session":"15944.1.1.2","useKernelLogging":true}}
{"timestamp":"1453565091.155135155","source":"garden-linux","message":"garden-linux.auvlk9hu04s.filter.logging-chain.teardown.flushed","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04s-log","name":"w--instance-auvlk9hu04s","session":"15944.1.1.2","useKernelLogging":true}}
{"timestamp":"1453565091.164780378","source":"garden-linux","message":"garden-linux.auvlk9hu04s.filter.logging-chain.teardown.ending","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04s-log","name":"w--instance-auvlk9hu04s","session":"15944.1.1.2","useKernelLogging":true}}
{"timestamp":"1453565091.164868355","source":"garden-linux","message":"garden-linux.auvlk9hu04s.filter.logging-chain.command.starting","log_level":0,"data":{"argv":["/sbin/iptables","-w","-N","w--instance-auvlk9hu04s-log"],"name":"w--instance-auvlk9hu04s","session":"15944.1.1.3","useKernelLogging":true}}
{"timestamp":"1453565091.175695419","source":"garden-linux","message":"garden-linux.auvlk9hu04s.filter.logging-chain.command.succeeded","log_level":0,"data":{"argv":["/sbin/iptables","-w","-N","w--instance-auvlk9hu04s-log"],"exit-status":0,"name":"w--instance-auvlk9hu04s","session":"15944.1.1.3","took":"10.819113ms","useKernelLogging":true}}
{"timestamp":"1453565091.175755739","source":"garden-linux","message":"garden-linux.auvlk9hu04s.filter.logging-chain.setup.created","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04s-log","name":"w--instance-auvlk9hu04s","session":"15944.1.1.1","useKernelLogging":true}}
{"timestamp":"1453565091.175786018","source":"garden-linux","message":"garden-linux.auvlk9hu04s.filter.logging-chain.command.starting","log_level":0,"data":{"argv":["/sbin/iptables","-w","-A","w--instance-auvlk9hu04s-log","-m","conntrack","--ctstate","NEW,UNTRACKED,INVALID","--protocol","tcp","--jump","LOG","--log-prefix","executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb"],"name":"w--instance-auvlk9hu04s","session":"15944.1.1.4","useKernelLogging":true}}
{"timestamp":"1453565091.179084301","source":"garden-linux","message":"garden-linux.auvlk9hu04s.filter.logging-chain.command.succeeded","log_level":0,"data":{"argv":["/sbin/iptables","-w","-A","w--instance-auvlk9hu04s-log","-m","conntrack","--ctstate","NEW,UNTRACKED,INVALID","--protocol","tcp","--jump","LOG","--log-prefix","executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb"],"exit-status":0,"name":"w--instance-auvlk9hu04s","session":"15944.1.1.4","took":"3.289267ms","useKernelLogging":true}}
{"timestamp":"1453565091.179155350","source":"garden-linux","message":"garden-linux.auvlk9hu04s.filter.logging-chain.setup.conntrack-set-up","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04s-log","name":"w--instance-auvlk9hu04s","session":"15944.1.1.1","useKernelLogging":true}}
{"timestamp":"1453565091.179185867","source":"garden-linux","message":"garden-linux.auvlk9hu04s.filter.logging-chain.command.starting","log_level":0,"data":{"argv":["/sbin/iptables","-w","-A","w--instance-auvlk9hu04s-log","--jump","RETURN"],"name":"w--instance-auvlk9hu04s","session":"15944.1.1.5","useKernelLogging":true}}
{"timestamp":"1453565091.182302713","source":"garden-linux","message":"garden-linux.auvlk9hu04s.filter.logging-chain.command.succeeded","log_level":0,"data":{"argv":["/sbin/iptables","-w","-A","w--instance-auvlk9hu04s-log","--jump","RETURN"],"exit-status":0,"name":"w--instance-auvlk9hu04s","session":"15944.1.1.5","took":"3.106676ms","useKernelLogging":true}}
{"timestamp":"1453565091.182373285","source":"garden-linux","message":"garden-linux.auvlk9hu04s.filter.logging-chain.setup.ending","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04s-log","name":"w--instance-auvlk9hu04s","session":"15944.1.1.1","useKernelLogging":true}}
{"timestamp":"1453565091.182401180","source":"garden-linux","message":"garden-linux.pool.acquire.setup-iptables-ended","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6373"}}
{"timestamp":"1453565098.732564688","source":"garden-linux","message":"garden-linux.namespace-rootfs.namespace","log_level":1,"data":{"path":"/var/vcap/data/garden/aufs_graph/aufs/diff/e75109980d42e294af84d4b9fdf398b2e282d89be6053b87caa72e3336d6ac38","session":"15945"}}
{"timestamp":"1453565099.169734240","source":"garden-linux","message":"garden-linux.namespace-rootfs.namespaced","log_level":1,"data":{"path":"/var/vcap/data/garden/aufs_graph/aufs/diff/e75109980d42e294af84d4b9fdf398b2e282d89be6053b87caa72e3336d6ac38","session":"15945"}}
{"timestamp":"1453565099.177868128","source":"garden-linux","message":"garden-linux.pool.acquire.provide-rootfs-ended","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6373"}}
{"timestamp":"1453565099.177905560","source":"garden-linux","message":"garden-linux.pool.acquire.setup-bridge-starting","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6373"}}
{"timestamp":"1453565099.178329706","source":"garden-linux","message":"garden-linux.pool.acquire.setup-bridge-ended","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6373"}}
{"timestamp":"1453565099.178375721","source":"garden-linux","message":"garden-linux.pool.acquire.create-script.command.starting","log_level":0,"data":{"argv":["/var/vcap/packages/garden-linux/src/github.com/cloudfoundry-incubator/garden-linux/linux_backend/bin/create.sh","/var/vcap/data/garden/depot/auvlk9hu04s"],"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6373.2.1"}}
{"timestamp":"1453565099.253748655","source":"garden-linux","message":"garden-linux.pool.acquire.create-script.command.succeeded","log_level":0,"data":{"argv":["/var/vcap/packages/garden-linux/src/github.com/cloudfoundry-incubator/garden-linux/linux_backend/bin/create.sh","/var/vcap/data/garden/depot/auvlk9hu04s"],"exit-status":0,"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6373.2.1","took":"75.346661ms"}}
{"timestamp":"1453565099.254050732","source":"garden-linux","message":"garden-linux.pool.acquire.created","log_level":1,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6373"}}
{"timestamp":"1453565099.254185915","source":"garden-linux","message":"garden-linux.container.start.starting","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"15947.1"}}
{"timestamp":"1453565099.254212856","source":"garden-linux","message":"garden-linux.container.start.iptables-setup-starting","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"15947.1"}}
{"timestamp":"1453565099.254543304","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -S w--forward 2\u003e /dev/null | grep \"\\-g w--instance-auvlk9hu04s\\b\" | sed -e \"s/-A/-D/\" | xargs --no-run-if-empty --max-lines=1 iptables --wait"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31861"}}
{"timestamp":"1453565099.260903835","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -S w--forward 2\u003e /dev/null | grep \"\\-g w--instance-auvlk9hu04s\\b\" | sed -e \"s/-A/-D/\" | xargs --no-run-if-empty --max-lines=1 iptables --wait"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":25841},"ProcessState":{}},"session":"8.31861"}}
{"timestamp":"1453565099.261028767","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -F w--instance-auvlk9hu04s 2\u003e /dev/null || true"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31862"}}
{"timestamp":"1453565099.263988733","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -F w--instance-auvlk9hu04s 2\u003e /dev/null || true"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":25846},"ProcessState":{}},"session":"8.31862"}}
{"timestamp":"1453565099.264111519","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -X w--instance-auvlk9hu04s 2\u003e /dev/null || true"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31863"}}
{"timestamp":"1453565099.266391754","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -X w--instance-auvlk9hu04s 2\u003e /dev/null || true"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":25848},"ProcessState":{}},"session":"8.31863"}}
{"timestamp":"1453565099.266527414","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -S w--prerouting 2\u003e /dev/null | grep \"\\-j w--instance-auvlk9hu04s\\b\" | sed -e \"s/-A/-D/\" | xargs --no-run-if-empty --max-lines=1 iptables --wait --table nat"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"9.19117"}}
{"timestamp":"1453565099.269323826","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -S w--prerouting 2\u003e /dev/null | grep \"\\-j w--instance-auvlk9hu04s\\b\" | sed -e \"s/-A/-D/\" | xargs --no-run-if-empty --max-lines=1 iptables --wait --table nat"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":25850},"ProcessState":{}},"session":"9.19117"}}
{"timestamp":"1453565099.269387245","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -F w--instance-auvlk9hu04s 2\u003e /dev/null || true"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"9.19118"}}
{"timestamp":"1453565099.271620035","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -F w--instance-auvlk9hu04s 2\u003e /dev/null || true"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":25855},"ProcessState":{}},"session":"9.19118"}}
{"timestamp":"1453565099.271694899","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -X w--instance-auvlk9hu04s 2\u003e /dev/null || true"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"9.19119"}}
{"timestamp":"1453565099.274056435","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -X w--instance-auvlk9hu04s 2\u003e /dev/null || true"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":25857},"ProcessState":{}},"session":"9.19119"}}
{"timestamp":"1453565099.274208784","source":"garden-linux","message":"garden-linux.iptables-manager-filter.setup.starting","log_level":0,"data":{"cmd":{"Path":"/sbin/iptables","Args":["iptables","--wait","-N","w--instance-auvlk9hu04s"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31864"}}
{"timestamp":"1453565099.275740147","source":"garden-linux","message":"garden-linux.iptables-manager-filter.setup.ended","log_level":0,"data":{"cmd":{"Path":"/sbin/iptables","Args":["iptables","--wait","-N","w--instance-auvlk9hu04s"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":25859},"ProcessState":{}},"session":"8.31864"}}
{"timestamp":"1453565099.275801182","source":"garden-linux","message":"garden-linux.iptables-manager-filter.setup.starting","log_level":0,"data":{"cmd":{"Path":"/sbin/iptables","Args":["iptables","--wait","-A","w--instance-auvlk9hu04s","-s","10.254.0.0/30","-d","10.254.0.0/30","-j","ACCEPT"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31865"}}
{"timestamp":"1453565099.278191328","source":"garden-linux","message":"garden-linux.iptables-manager-filter.setup.ended","log_level":0,"data":{"cmd":{"Path":"/sbin/iptables","Args":["iptables","--wait","-A","w--instance-auvlk9hu04s","-s","10.254.0.0/30","-d","10.254.0.0/30","-j","ACCEPT"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":25860},"ProcessState":{}},"session":"8.31865"}}
{"timestamp":"1453565099.278333426","source":"garden-linux","message":"garden-linux.iptables-manager-filter.setup.starting","log_level":0,"data":{"cmd":{"Path":"/sbin/iptables","Args":["iptables","--wait","-A","w--instance-auvlk9hu04s","--goto","w--default"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31866"}}
{"timestamp":"1453565099.280448914","source":"garden-linux","message":"garden-linux.iptables-manager-filter.setup.ended","log_level":0,"data":{"cmd":{"Path":"/sbin/iptables","Args":["iptables","--wait","-A","w--instance-auvlk9hu04s","--goto","w--default"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":25861},"ProcessState":{}},"session":"8.31866"}}
{"timestamp":"1453565099.280518055","source":"garden-linux","message":"garden-linux.iptables-manager-filter.setup.starting","log_level":0,"data":{"cmd":{"Path":"/sbin/iptables","Args":["iptables","--wait","-I","w--forward","2","--in-interface","wb-auvlk9hsou00","--source","10.254.0.2","--goto","w--instance-auvlk9hu04s"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31867"}}
{"timestamp":"1453565099.282120705","source":"garden-linux","message":"garden-linux.iptables-manager-filter.setup.ended","log_level":0,"data":{"cmd":{"Path":"/sbin/iptables","Args":["iptables","--wait","-I","w--forward","2","--in-interface","wb-auvlk9hsou00","--source","10.254.0.2","--goto","w--instance-auvlk9hu04s"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":25862},"ProcessState":{}},"session":"8.31867"}}
{"timestamp":"1453565099.288267851","source":"garden-linux","message":"garden-linux.container.start.iptables-setup-ended","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"15947.1"}}
{"timestamp":"1453565099.288320780","source":"garden-linux","message":"garden-linux.container.start.wshd-start-starting","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"15947.1"}}
{"timestamp":"1453565099.288345337","source":"garden-linux","message":"garden-linux.container.start.command.starting","log_level":0,"data":{"argv":["/var/vcap/data/garden/depot/auvlk9hu04s/start.sh"],"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"15947.1.1"}}
{"timestamp":"1453565099.377946615","source":"garden-linux","message":"garden-linux.container.start.command.failed","log_level":2,"data":{"argv":["/var/vcap/data/garden/depot/auvlk9hu04s/start.sh"],"error":"exit status 2","exit-status":2,"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"15947.1.1","stderr":"Failed to create container: containerizer: wait for container: error: containerizer: initializing the container: system: mount sysfs on /sys: operation not permitted","stdout":"","took":"89.582045ms"}}
{"timestamp":"1453565099.378065586","source":"garden-linux","message":"garden-linux.container.start.wshd-start-failed","log_level":2,"data":{"error":"exit status 2","handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"15947.1"}}
{"timestamp":"1453565099.378102064","source":"garden-linux","message":"garden-linux.pool.release.releasing","log_level":1,"data":{"id":"auvlk9hu04s","session":"11.6374"}}
{"timestamp":"1453565099.404597044","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -S w--forward 2\u003e /dev/null | grep \"\\-g w--instance-auvlk9hu04s\\b\" | sed -e \"s/-A/-D/\" | xargs --no-run-if-empty --max-lines=1 iptables --wait"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31868"}}
{"timestamp":"1453565099.409778118","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -S w--forward 2\u003e /dev/null | grep \"\\-g w--instance-auvlk9hu04s\\b\" | sed -e \"s/-A/-D/\" | xargs --no-run-if-empty --max-lines=1 iptables --wait"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":25937},"ProcessState":{}},"session":"8.31868"}}
{"timestamp":"1453565099.409868956","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -F w--instance-auvlk9hu04s 2\u003e /dev/null || true"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31869"}}
{"timestamp":"1453565099.412100077","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -F w--instance-auvlk9hu04s 2\u003e /dev/null || true"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":25943},"ProcessState":{}},"session":"8.31869"}}
{"timestamp":"1453565099.412171841","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -X w--instance-auvlk9hu04s 2\u003e /dev/null || true"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31870"}}
{"timestamp":"1453565099.414304256","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -X w--instance-auvlk9hu04s 2\u003e /dev/null || true"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":25945},"ProcessState":{}},"session":"8.31870"}}
{"timestamp":"1453565099.414427042","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -S w--prerouting 2\u003e /dev/null | grep \"\\-j w--instance-auvlk9hu04s\\b\" | sed -e \"s/-A/-D/\" | xargs --no-run-if-empty --max-lines=1 iptables --wait --table nat"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"9.19120"}}
{"timestamp":"1453565099.417842150","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -S w--prerouting 2\u003e /dev/null | grep \"\\-j w--instance-auvlk9hu04s\\b\" | sed -e \"s/-A/-D/\" | xargs --no-run-if-empty --max-lines=1 iptables --wait --table nat"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":25947},"ProcessState":{}},"session":"9.19120"}}
{"timestamp":"1453565099.417915821","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -F w--instance-auvlk9hu04s 2\u003e /dev/null || true"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"9.19121"}}
{"timestamp":"1453565099.420361280","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -F w--instance-auvlk9hu04s 2\u003e /dev/null || true"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":25953},"ProcessState":{}},"session":"9.19121"}}
{"timestamp":"1453565099.420442820","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -X w--instance-auvlk9hu04s 2\u003e /dev/null || true"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"9.19122"}}
{"timestamp":"1453565099.422716379","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -X w--instance-auvlk9hu04s 2\u003e /dev/null || true"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":25955},"ProcessState":{}},"session":"9.19122"}}
{"timestamp":"1453565099.422786474","source":"garden-linux","message":"garden-linux.pool.release.command.starting","log_level":0,"data":{"argv":["/var/vcap/packages/garden-linux/src/github.com/cloudfoundry-incubator/garden-linux/linux_backend/bin/destroy.sh","/var/vcap/data/garden/depot/auvlk9hu04s"],"id":"auvlk9hu04s","session":"11.6374.1"}}
{"timestamp":"1453565099.454937696","source":"garden-linux","message":"garden-linux.pool.release.command.succeeded","log_level":0,"data":{"argv":["/var/vcap/packages/garden-linux/src/github.com/cloudfoundry-incubator/garden-linux/linux_backend/bin/destroy.sh","/var/vcap/data/garden/depot/auvlk9hu04s"],"exit-status":0,"id":"auvlk9hu04s","session":"11.6374.1","took":"32.126871ms"}}
{"timestamp":"1453565099.455040455","source":"garden-linux","message":"garden-linux.oven-cleaner.remove.start","log_level":1,"data":{"ID":"auvlk9hu04s","session":"5.9555"}}
{"timestamp":"1453565099.505639076","source":"garden-linux","message":"garden-linux.oven-cleaner.remove.start","log_level":1,"data":{"ID":"e75109980d42e294af84d4b9fdf398b2e282d89be6053b87caa72e3336d6ac38","session":"5.9556"}}
{"timestamp":"1453565099.939369440","source":"garden-linux","message":"garden-linux.oven-cleaner.remove.start","log_level":1,"data":{"ID":"8fe877bb02a659dcbcaf652e7f80120cb632718441dc137d80a661f2e41837b6","session":"5.9557"}}
{"timestamp":"1453565100.413789034","source":"garden-linux","message":"garden-linux.auvlk9hu04s.filter.logging-chain.teardown.started","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04s-log","name":"w--instance-auvlk9hu04s","session":"15948.1.1.1","useKernelLogging":true}}
{"timestamp":"1453565100.416595936","source":"garden-linux","message":"garden-linux.auvlk9hu04s.filter.logging-chain.teardown.flushed","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04s-log","name":"w--instance-auvlk9hu04s","session":"15948.1.1.1","useKernelLogging":true}}
{"timestamp":"1453565100.418252230","source":"garden-linux","message":"garden-linux.auvlk9hu04s.filter.logging-chain.teardown.ending","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04s-log","name":"w--instance-auvlk9hu04s","session":"15948.1.1.1","useKernelLogging":true}}
{"timestamp":"1453565100.418311834","source":"garden-linux","message":"garden-linux.pool.release.subnet-pool.release.changing-allocated-subnets","log_level":1,"data":{"allocated-subnets":null,"id":"auvlk9hu04s","session":"11.6374.2.1"}}
{"timestamp":"1453565100.418329716","source":"garden-linux","message":"garden-linux.pool.release.released","log_level":1,"data":{"id":"auvlk9hu04s","session":"11.6374"}}
{"timestamp":"1453565100.418339729","source":"garden-linux","message":"garden-linux.garden-server.create.failed","log_level":2,"data":{"error":"container: start: exit status 2","request":{"Handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","GraceTime":0,"RootFSPath":"/var/vcap/packages/rootfs_cflinuxfs2/rootfs","BindMounts":null,"Network":"","Privileged":false,"Limits":{"bandwidth_limits":{},"cpu_limits":{},"disk_limits":{},"memory_limits":{}}},"session":"13.9571"}}
{"timestamp":"1453565100.418836594","source":"garden-linux","message":"garden-linux.connection.closed","log_level":0,"data":{"local_addr":{"IP":"127.0.0.1","Port":7777,"Zone":""},"remote_addr":{"IP":"127.0.0.1","Port":38389,"Zone":""},"session":"14"}}
{"timestamp":"1453565105.420773268","source":"garden-linux","message":"garden-linux.connection.open","log_level":0,"data":{"local_addr":{"IP":"127.0.0.1","Port":7777,"Zone":""},"remote_addr":{"IP":"127.0.0.1","Port":38535,"Zone":""},"session":"14"}}
{"timestamp":"1453565105.421188831","source":"garden-linux","message":"garden-linux.garden-server.create.creating","log_level":0,"data":{"request":{"Handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","GraceTime":0,"RootFSPath":"/var/vcap/packages/rootfs_cflinuxfs2/rootfs","BindMounts":null,"Network":"","Privileged":false,"Limits":{"bandwidth_limits":{},"cpu_limits":{},"disk_limits":{},"memory_limits":{}}},"session":"13.9572"}}
{"timestamp":"1453565105.421273947","source":"garden-linux","message":"garden-linux.pool.acquire.creating","log_level":1,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6375"}}
{"timestamp":"1453565105.421348810","source":"garden-linux","message":"garden-linux.pool.acquire.subnet-pool.acquire.subnet-selecting","log_level":1,"data":{"allocated-subnets":null,"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6375.1.1"}}
{"timestamp":"1453565105.421381712","source":"garden-linux","message":"garden-linux.pool.acquire.subnet-pool.acquire.subnet-selected","log_level":1,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6375.1.1","subnet":"10.254.0.0/30"}}
{"timestamp":"1453565105.421424866","source":"garden-linux","message":"garden-linux.pool.acquire.subnet-pool.acquire.ip-selecting","log_level":1,"data":{"allocated-ips":null,"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6375.1.1"}}
{"timestamp":"1453565105.421454668","source":"garden-linux","message":"garden-linux.pool.acquire.subnet-pool.acquire.ip-selected","log_level":1,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","ip":"10.254.0.2","session":"11.6375.1.1"}}
{"timestamp":"1453565105.421481371","source":"garden-linux","message":"garden-linux.pool.acquire.subnet-pool.acquire.new-allocated","log_level":1,"data":{"allocated-ips":["10.254.0.2"],"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6375.1.1"}}
{"timestamp":"1453565105.421509266","source":"garden-linux","message":"garden-linux.pool.acquire.acquired-pool-resources","log_level":1,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6375"}}
{"timestamp":"1453565105.421619892","source":"garden-linux","message":"garden-linux.pool.acquire.provide-rootfs-starting","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6375"}}
{"timestamp":"1453565105.422644138","source":"garden-linux","message":"garden-linux.pool.acquire.setup-iptables-starting","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6375"}}
{"timestamp":"1453565105.422727585","source":"garden-linux","message":"garden-linux.auvlk9hu04t.filter.logging-chain.setup.started","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04t-log","name":"w--instance-auvlk9hu04t","session":"15949.1.1.1","useKernelLogging":true}}
{"timestamp":"1453565105.422761202","source":"garden-linux","message":"garden-linux.auvlk9hu04t.filter.logging-chain.teardown.started","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04t-log","name":"w--instance-auvlk9hu04t","session":"15949.1.1.2","useKernelLogging":true}}
{"timestamp":"1453565105.425665140","source":"garden-linux","message":"garden-linux.auvlk9hu04t.filter.logging-chain.teardown.flushed","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04t-log","name":"w--instance-auvlk9hu04t","session":"15949.1.1.2","useKernelLogging":true}}
{"timestamp":"1453565105.428054333","source":"garden-linux","message":"garden-linux.auvlk9hu04t.filter.logging-chain.teardown.ending","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04t-log","name":"w--instance-auvlk9hu04t","session":"15949.1.1.2","useKernelLogging":true}}
{"timestamp":"1453565105.428145409","source":"garden-linux","message":"garden-linux.auvlk9hu04t.filter.logging-chain.command.starting","log_level":0,"data":{"argv":["/sbin/iptables","-w","-N","w--instance-auvlk9hu04t-log"],"name":"w--instance-auvlk9hu04t","session":"15949.1.1.3","useKernelLogging":true}}
{"timestamp":"1453565105.430149078","source":"garden-linux","message":"garden-linux.auvlk9hu04t.filter.logging-chain.command.succeeded","log_level":0,"data":{"argv":["/sbin/iptables","-w","-N","w--instance-auvlk9hu04t-log"],"exit-status":0,"name":"w--instance-auvlk9hu04t","session":"15949.1.1.3","took":"1.994684ms","useKernelLogging":true}}
{"timestamp":"1453565105.430197954","source":"garden-linux","message":"garden-linux.auvlk9hu04t.filter.logging-chain.setup.created","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04t-log","name":"w--instance-auvlk9hu04t","session":"15949.1.1.1","useKernelLogging":true}}
{"timestamp":"1453565105.430227041","source":"garden-linux","message":"garden-linux.auvlk9hu04t.filter.logging-chain.command.starting","log_level":0,"data":{"argv":["/sbin/iptables","-w","-A","w--instance-auvlk9hu04t-log","-m","conntrack","--ctstate","NEW,UNTRACKED,INVALID","--protocol","tcp","--jump","LOG","--log-prefix","executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb"],"name":"w--instance-auvlk9hu04t","session":"15949.1.1.4","useKernelLogging":true}}
{"timestamp":"1453565105.432931900","source":"garden-linux","message":"garden-linux.auvlk9hu04t.filter.logging-chain.command.succeeded","log_level":0,"data":{"argv":["/sbin/iptables","-w","-A","w--instance-auvlk9hu04t-log","-m","conntrack","--ctstate","NEW,UNTRACKED,INVALID","--protocol","tcp","--jump","LOG","--log-prefix","executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb"],"exit-status":0,"name":"w--instance-auvlk9hu04t","session":"15949.1.1.4","took":"2.697195ms","useKernelLogging":true}}
{"timestamp":"1453565105.432976246","source":"garden-linux","message":"garden-linux.auvlk9hu04t.filter.logging-chain.setup.conntrack-set-up","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04t-log","name":"w--instance-auvlk9hu04t","session":"15949.1.1.1","useKernelLogging":true}}
{"timestamp":"1453565105.432998180","source":"garden-linux","message":"garden-linux.auvlk9hu04t.filter.logging-chain.command.starting","log_level":0,"data":{"argv":["/sbin/iptables","-w","-A","w--instance-auvlk9hu04t-log","--jump","RETURN"],"name":"w--instance-auvlk9hu04t","session":"15949.1.1.5","useKernelLogging":true}}
{"timestamp":"1453565105.434492111","source":"garden-linux","message":"garden-linux.auvlk9hu04t.filter.logging-chain.command.succeeded","log_level":0,"data":{"argv":["/sbin/iptables","-w","-A","w--instance-auvlk9hu04t-log","--jump","RETURN"],"exit-status":0,"name":"w--instance-auvlk9hu04t","session":"15949.1.1.5","took":"1.48789ms","useKernelLogging":true}}
{"timestamp":"1453565105.434531927","source":"garden-linux","message":"garden-linux.auvlk9hu04t.filter.logging-chain.setup.ending","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04t-log","name":"w--instance-auvlk9hu04t","session":"15949.1.1.1","useKernelLogging":true}}
{"timestamp":"1453565105.434546947","source":"garden-linux","message":"garden-linux.pool.acquire.setup-iptables-ended","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6375"}}
{"timestamp":"1453565113.232231140","source":"garden-linux","message":"garden-linux.namespace-rootfs.namespace","log_level":1,"data":{"path":"/var/vcap/data/garden/aufs_graph/aufs/diff/e75109980d42e294af84d4b9fdf398b2e282d89be6053b87caa72e3336d6ac38","session":"15950"}}
{"timestamp":"1453565113.607661247","source":"garden-linux","message":"garden-linux.namespace-rootfs.namespaced","log_level":1,"data":{"path":"/var/vcap/data/garden/aufs_graph/aufs/diff/e75109980d42e294af84d4b9fdf398b2e282d89be6053b87caa72e3336d6ac38","session":"15950"}}
{"timestamp":"1453565113.614071608","source":"garden-linux","message":"garden-linux.pool.acquire.provide-rootfs-ended","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6375"}}
{"timestamp":"1453565113.614097357","source":"garden-linux","message":"garden-linux.pool.acquire.setup-bridge-starting","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6375"}}
{"timestamp":"1453565113.614589930","source":"garden-linux","message":"garden-linux.pool.acquire.setup-bridge-ended","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6375"}}
{"timestamp":"1453565113.614647627","source":"garden-linux","message":"garden-linux.pool.acquire.create-script.command.starting","log_level":0,"data":{"argv":["/var/vcap/packages/garden-linux/src/github.com/cloudfoundry-incubator/garden-linux/linux_backend/bin/create.sh","/var/vcap/data/garden/depot/auvlk9hu04t"],"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6375.2.1"}}
{"timestamp":"1453565114.456629276","source":"garden-linux","message":"garden-linux.pool.acquire.create-script.command.succeeded","log_level":0,"data":{"argv":["/var/vcap/packages/garden-linux/src/github.com/cloudfoundry-incubator/garden-linux/linux_backend/bin/create.sh","/var/vcap/data/garden/depot/auvlk9hu04t"],"exit-status":0,"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6375.2.1","took":"841.968413ms"}}
{"timestamp":"1453565114.528663397","source":"garden-linux","message":"garden-linux.pool.acquire.created","log_level":1,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"11.6375"}}
{"timestamp":"1453565114.529322386","source":"garden-linux","message":"garden-linux.container.start.starting","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"15952.1"}}
{"timestamp":"1453565114.529337645","source":"garden-linux","message":"garden-linux.container.start.iptables-setup-starting","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"15952.1"}}
{"timestamp":"1453565114.529423714","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -S w--forward 2\u003e /dev/null | grep \"\\-g w--instance-auvlk9hu04t\\b\" | sed -e \"s/-A/-D/\" | xargs --no-run-if-empty --max-lines=1 iptables --wait"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31871"}}
{"timestamp":"1453565114.593808174","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -S w--forward 2\u003e /dev/null | grep \"\\-g w--instance-auvlk9hu04t\\b\" | sed -e \"s/-A/-D/\" | xargs --no-run-if-empty --max-lines=1 iptables --wait"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":26131},"ProcessState":{}},"session":"8.31871"}}
{"timestamp":"1453565114.593944550","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -F w--instance-auvlk9hu04t 2\u003e /dev/null || true"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31872"}}
{"timestamp":"1453565114.597259283","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -F w--instance-auvlk9hu04t 2\u003e /dev/null || true"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":26136},"ProcessState":{}},"session":"8.31872"}}
{"timestamp":"1453565114.597345591","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -X w--instance-auvlk9hu04t 2\u003e /dev/null || true"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31873"}}
{"timestamp":"1453565114.600172043","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -X w--instance-auvlk9hu04t 2\u003e /dev/null || true"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":26139},"ProcessState":{}},"session":"8.31873"}}
{"timestamp":"1453565114.600463867","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -S w--prerouting 2\u003e /dev/null | grep \"\\-j w--instance-auvlk9hu04t\\b\" | sed -e \"s/-A/-D/\" | xargs --no-run-if-empty --max-lines=1 iptables --wait --table nat"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"9.19123"}}
{"timestamp":"1453565114.606023312","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -S w--prerouting 2\u003e /dev/null | grep \"\\-j w--instance-auvlk9hu04t\\b\" | sed -e \"s/-A/-D/\" | xargs --no-run-if-empty --max-lines=1 iptables --wait --table nat"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":26142},"ProcessState":{}},"session":"9.19123"}}
{"timestamp":"1453565114.606106997","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -F w--instance-auvlk9hu04t 2\u003e /dev/null || true"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"9.19124"}}
{"timestamp":"1453565114.608348846","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -F w--instance-auvlk9hu04t 2\u003e /dev/null || true"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":26147},"ProcessState":{}},"session":"9.19124"}}
{"timestamp":"1453565114.608433962","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -X w--instance-auvlk9hu04t 2\u003e /dev/null || true"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"9.19125"}}
{"timestamp":"1453565114.610433578","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -X w--instance-auvlk9hu04t 2\u003e /dev/null || true"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":26149},"ProcessState":{}},"session":"9.19125"}}
{"timestamp":"1453565114.610594273","source":"garden-linux","message":"garden-linux.iptables-manager-filter.setup.starting","log_level":0,"data":{"cmd":{"Path":"/sbin/iptables","Args":["iptables","--wait","-N","w--instance-auvlk9hu04t"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31874"}}
{"timestamp":"1453565114.612107515","source":"garden-linux","message":"garden-linux.iptables-manager-filter.setup.ended","log_level":0,"data":{"cmd":{"Path":"/sbin/iptables","Args":["iptables","--wait","-N","w--instance-auvlk9hu04t"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":26151},"ProcessState":{}},"session":"8.31874"}}
{"timestamp":"1453565114.612198830","source":"garden-linux","message":"garden-linux.iptables-manager-filter.setup.starting","log_level":0,"data":{"cmd":{"Path":"/sbin/iptables","Args":["iptables","--wait","-A","w--instance-auvlk9hu04t","-s","10.254.0.0/30","-d","10.254.0.0/30","-j","ACCEPT"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31875"}}
{"timestamp":"1453565114.665952921","source":"garden-linux","message":"garden-linux.iptables-manager-filter.setup.ended","log_level":0,"data":{"cmd":{"Path":"/sbin/iptables","Args":["iptables","--wait","-A","w--instance-auvlk9hu04t","-s","10.254.0.0/30","-d","10.254.0.0/30","-j","ACCEPT"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":26152},"ProcessState":{}},"session":"8.31875"}}
{"timestamp":"1453565114.666064501","source":"garden-linux","message":"garden-linux.iptables-manager-filter.setup.starting","log_level":0,"data":{"cmd":{"Path":"/sbin/iptables","Args":["iptables","--wait","-A","w--instance-auvlk9hu04t","--goto","w--default"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31876"}}
{"timestamp":"1453565114.670491219","source":"garden-linux","message":"garden-linux.iptables-manager-filter.setup.ended","log_level":0,"data":{"cmd":{"Path":"/sbin/iptables","Args":["iptables","--wait","-A","w--instance-auvlk9hu04t","--goto","w--default"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":26153},"ProcessState":{}},"session":"8.31876"}}
{"timestamp":"1453565114.670647383","source":"garden-linux","message":"garden-linux.iptables-manager-filter.setup.starting","log_level":0,"data":{"cmd":{"Path":"/sbin/iptables","Args":["iptables","--wait","-I","w--forward","2","--in-interface","wb-auvlk9hsou10","--source","10.254.0.2","--goto","w--instance-auvlk9hu04t"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31877"}}
{"timestamp":"1453565114.673767567","source":"garden-linux","message":"garden-linux.iptables-manager-filter.setup.ended","log_level":0,"data":{"cmd":{"Path":"/sbin/iptables","Args":["iptables","--wait","-I","w--forward","2","--in-interface","wb-auvlk9hsou10","--source","10.254.0.2","--goto","w--instance-auvlk9hu04t"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":26154},"ProcessState":{}},"session":"8.31877"}}
{"timestamp":"1453565114.718748093","source":"garden-linux","message":"garden-linux.container.start.iptables-setup-ended","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"15952.1"}}
{"timestamp":"1453565114.719040394","source":"garden-linux","message":"garden-linux.container.start.wshd-start-starting","log_level":0,"data":{"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"15952.1"}}
{"timestamp":"1453565114.719212770","source":"garden-linux","message":"garden-linux.container.start.command.starting","log_level":0,"data":{"argv":["/var/vcap/data/garden/depot/auvlk9hu04t/start.sh"],"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"15952.1.1"}}
{"timestamp":"1453565114.888637781","source":"garden-linux","message":"garden-linux.container.start.command.failed","log_level":2,"data":{"argv":["/var/vcap/data/garden/depot/auvlk9hu04t/start.sh"],"error":"exit status 2","exit-status":2,"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"15952.1.1","stderr":"Failed to create container: containerizer: wait for container: error: containerizer: initializing the container: system: mount sysfs on /sys: operation not permitted","stdout":"","took":"169.39678ms"}}
{"timestamp":"1453565114.888737679","source":"garden-linux","message":"garden-linux.container.start.wshd-start-failed","log_level":2,"data":{"error":"exit status 2","handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"15952.1"}}
{"timestamp":"1453565114.888762474","source":"garden-linux","message":"garden-linux.pool.release.releasing","log_level":1,"data":{"id":"auvlk9hu04t","session":"11.6376"}}
{"timestamp":"1453565114.904662371","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -S w--forward 2\u003e /dev/null | grep \"\\-g w--instance-auvlk9hu04t\\b\" | sed -e \"s/-A/-D/\" | xargs --no-run-if-empty --max-lines=1 iptables --wait"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31878"}}
{"timestamp":"1453565114.910378695","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -S w--forward 2\u003e /dev/null | grep \"\\-g w--instance-auvlk9hu04t\\b\" | sed -e \"s/-A/-D/\" | xargs --no-run-if-empty --max-lines=1 iptables --wait"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":26231},"ProcessState":{}},"session":"8.31878"}}
{"timestamp":"1453565114.910517454","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -F w--instance-auvlk9hu04t 2\u003e /dev/null || true"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31879"}}
{"timestamp":"1453565114.913362265","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -F w--instance-auvlk9hu04t 2\u003e /dev/null || true"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":26237},"ProcessState":{}},"session":"8.31879"}}
{"timestamp":"1453565114.913461447","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -X w--instance-auvlk9hu04t 2\u003e /dev/null || true"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"8.31880"}}
{"timestamp":"1453565114.916060925","source":"garden-linux","message":"garden-linux.iptables-manager-filter.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait -X w--instance-auvlk9hu04t 2\u003e /dev/null || true"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":26239},"ProcessState":{}},"session":"8.31880"}}
{"timestamp":"1453565114.916212797","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -S w--prerouting 2\u003e /dev/null | grep \"\\-j w--instance-auvlk9hu04t\\b\" | sed -e \"s/-A/-D/\" | xargs --no-run-if-empty --max-lines=1 iptables --wait --table nat"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"9.19126"}}
{"timestamp":"1453565114.923296690","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -S w--prerouting 2\u003e /dev/null | grep \"\\-j w--instance-auvlk9hu04t\\b\" | sed -e \"s/-A/-D/\" | xargs --no-run-if-empty --max-lines=1 iptables --wait --table nat"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":26241},"ProcessState":{}},"session":"9.19126"}}
{"timestamp":"1453565114.923381805","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -F w--instance-auvlk9hu04t 2\u003e /dev/null || true"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"9.19127"}}
{"timestamp":"1453565114.926027060","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -F w--instance-auvlk9hu04t 2\u003e /dev/null || true"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":26247},"ProcessState":{}},"session":"9.19127"}}
{"timestamp":"1453565114.926109552","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.starting","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -X w--instance-auvlk9hu04t 2\u003e /dev/null || true"],"Env":null,"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":null,"Process":null,"ProcessState":null},"session":"9.19128"}}
{"timestamp":"1453565114.928568125","source":"garden-linux","message":"garden-linux.iptables-manager-nat.teardown.ended","log_level":0,"data":{"cmd":{"Path":"/bin/sh","Args":["sh","-c","iptables --wait --table nat -X w--instance-auvlk9hu04t 2\u003e /dev/null || true"],"Env":["TMPDIR=/var/vcap/data/tmp","MONIT_DATE=Fri, 22 Jan 2016 22:20:38 +0000","MONIT_HOST=d101dd20-1f4d-4484-9058-dd812a6945d9","PATH=/var/vcap/jobs/garden/bin:/var/vcap/packages/shadow/sbin:/var/vcap/packages/shadow/sbin:/bin:/usr/bin:/sbin:/usr/sbin","PWD=/etc/sv/monit","MONIT_PROCESS_PID=0","MONIT_EVENT=Started","MONIT_PROCESS_MEMORY=0","SHLVL=0","MONIT_PROCESS_CPU_PERCENT=0","MONIT_SERVICE=garden","MONIT_PROCESS_CHILDREN=0","MONIT_DESCRIPTION=Started","GARDEN_CGROUP_PATH=/tmp/garden-/cgroup","GARDEN_IPTABLES_ALLOW_HOST_ACCESS=false","GARDEN_IPTABLES_FILTER_DEFAULT_CHAIN=w--default","GARDEN_IPTABLES_FILTER_FORWARD_CHAIN=w--forward","GARDEN_IPTABLES_FILTER_INPUT_CHAIN=w--input","GARDEN_IPTABLES_FILTER_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_INSTANCE_PREFIX=w--instance-","GARDEN_IPTABLES_NAT_POSTROUTING_CHAIN=w--postrouting","GARDEN_IPTABLES_NAT_PREROUTING_CHAIN=w--prerouting","GARDEN_NETWORK_INTERFACE_PREFIX=w","GARDEN_TAG="],"Dir":"","Stdin":null,"Stdout":null,"Stderr":{},"ExtraFiles":null,"SysProcAttr":{"Chroot":"","Credential":null,"Ptrace":false,"Setsid":false,"Setpgid":true,"Setctty":false,"Noctty":false,"Ctty":0,"Pdeathsig":0,"Cloneflags":0,"UidMappings":null,"GidMappings":null},"Process":{"Pid":26249},"ProcessState":{}},"session":"9.19128"}}
{"timestamp":"1453565114.928648949","source":"garden-linux","message":"garden-linux.pool.release.command.starting","log_level":0,"data":{"argv":["/var/vcap/packages/garden-linux/src/github.com/cloudfoundry-incubator/garden-linux/linux_backend/bin/destroy.sh","/var/vcap/data/garden/depot/auvlk9hu04t"],"id":"auvlk9hu04t","session":"11.6376.1"}}
{"timestamp":"1453565114.959408522","source":"garden-linux","message":"garden-linux.pool.release.command.succeeded","log_level":0,"data":{"argv":["/var/vcap/packages/garden-linux/src/github.com/cloudfoundry-incubator/garden-linux/linux_backend/bin/destroy.sh","/var/vcap/data/garden/depot/auvlk9hu04t"],"exit-status":0,"id":"auvlk9hu04t","session":"11.6376.1","took":"30.746217ms"}}
{"timestamp":"1453565114.959475756","source":"garden-linux","message":"garden-linux.oven-cleaner.remove.start","log_level":1,"data":{"ID":"auvlk9hu04t","session":"5.9558"}}
{"timestamp":"1453565114.990728855","source":"garden-linux","message":"garden-linux.oven-cleaner.remove.start","log_level":1,"data":{"ID":"e75109980d42e294af84d4b9fdf398b2e282d89be6053b87caa72e3336d6ac38","session":"5.9559"}}
{"timestamp":"1453565115.410831451","source":"garden-linux","message":"garden-linux.oven-cleaner.remove.start","log_level":1,"data":{"ID":"8fe877bb02a659dcbcaf652e7f80120cb632718441dc137d80a661f2e41837b6","session":"5.9560"}}
{"timestamp":"1453565115.855698109","source":"garden-linux","message":"garden-linux.auvlk9hu04t.filter.logging-chain.teardown.started","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04t-log","name":"w--instance-auvlk9hu04t","session":"15953.1.1.1","useKernelLogging":true}}
{"timestamp":"1453565115.857918501","source":"garden-linux","message":"garden-linux.auvlk9hu04t.filter.logging-chain.teardown.flushed","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04t-log","name":"w--instance-auvlk9hu04t","session":"15953.1.1.1","useKernelLogging":true}}
{"timestamp":"1453565115.859426022","source":"garden-linux","message":"garden-linux.auvlk9hu04t.filter.logging-chain.teardown.ending","log_level":0,"data":{"logChainName":"w--instance-auvlk9hu04t-log","name":"w--instance-auvlk9hu04t","session":"15953.1.1.1","useKernelLogging":true}}
{"timestamp":"1453565115.859480619","source":"garden-linux","message":"garden-linux.pool.release.subnet-pool.release.changing-allocated-subnets","log_level":1,"data":{"allocated-subnets":null,"id":"auvlk9hu04t","session":"11.6376.2.1"}}
{"timestamp":"1453565115.859493971","source":"garden-linux","message":"garden-linux.pool.release.released","log_level":1,"data":{"id":"auvlk9hu04t","session":"11.6376"}}
{"timestamp":"1453565115.859503508","source":"garden-linux","message":"garden-linux.garden-server.create.failed","log_level":2,"data":{"error":"container: start: exit status 2","request":{"Handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","GraceTime":0,"RootFSPath":"/var/vcap/packages/rootfs_cflinuxfs2/rootfs","BindMounts":null,"Network":"","Privileged":false,"Limits":{"bandwidth_limits":{},"cpu_limits":{},"disk_limits":{},"memory_limits":{}}},"session":"13.9572"}}
{"timestamp":"1453565115.859941959","source":"garden-linux","message":"garden-linux.connection.closed","log_level":0,"data":{"local_addr":{"IP":"127.0.0.1","Port":7777,"Zone":""},"remote_addr":{"IP":"127.0.0.1","Port":38535,"Zone":""},"session":"14"}}

[emalm]

Hi, @rahulkj,

This log line seems to be the source of the failure to create the container:

{"timestamp":"1453565114.888637781","source":"garden-linux","message":"garden-linux.container.start.command.failed","log_level":2,"data":{"argv":["/var/vcap/data/garden/depot/auvlk9hu04t/start.sh"],"error":"exit status 2","exit-status":2,"handle":"executor-healthcheck-ac98a18b-8dab-45d0-77e2-fb2453f733fb","session":"15952.1.1","stderr":"Failed to create container: containerizer: wait for container: error: containerizer: initializing the container: system: mount sysfs on /sys: operation not permitted","stdout":"","took":"169.39678ms"}}

From the mount sysfs on /sys: operation not permitted part, I believe this is related to garden-linux not finding the sysfs filesystem mounted on /sys, which is an issue with older BOSH-Lite boxes. What versions of the BOSH-Lite vagrant box and of garden-linux-release are you using? I would recommend upgrading to the latest of each (9000.90.0 and 0.331.0, respectively) and trying again. This may require you to destroy and re-provision the BOSH-Lite VM.

Best,
Eric

[rahulkj]

OK.. so i'm using the vagrant fusion which links to v388 (vagrant box version).. I'll try again with virtualbox
Garden linux version in use is v0.330.0

I'll give this a shot now.. Thanks

[rahulkj]

@ematpl - Thanks for the tip, the deployment worked fine with the latest bosh-lite release for virtualbox

[emalm]

Thanks, @rahulkj! I've changed the diego-release README in 39e48d2 to recommend a recent-enough BOSH-Lite image for garden-linux to run correctly.

Best,
Eric