{"action":"created","issue":{"url":"https://api.github.com/repos/rancher/os/issues/507","labels_url":"https://api.github.com/repos/rancher/os/issues/507/labels{/name}","comments_url":"https://api.github.com/repos/rancher/os/issues/507/comments","events_url":"https://api.github.com/repos/rancher/os/issues/507/events","html_url":"https://github.com/rancher/os/issues/507","id":101501630,"number":507,"title":"GCE cloud-config metadata user-data script execution","user":{"login":"fentas","id":405182,"avatar_url":"https://avatars.githubusercontent.com/u/405182?v=3","gravatar_id":"","url":"https://api.github.com/users/fentas","html_url":"https://github.com/fentas","followers_url":"https://api.github.com/users/fentas/followers","following_url":"https://api.github.com/users/fentas/following{/other_user}","gists_url":"https://api.github.com/users/fentas/gists{/gist_id}","starred_url":"https://api.github.com/users/fentas/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/fentas/subscriptions","organizations_url":"https://api.github.com/users/fentas/orgs","repos_url":"https://api.github.com/users/fentas/repos","events_url":"https://api.github.com/users/fentas/events{/privacy}","received_events_url":"https://api.github.com/users/fentas/received_events","type":"User","site_admin":false},"labels":[],"state":"open","locked":false,"assignee":null,"milestone":null,"comments":2,"created_at":"2015-08-17T20:20:05Z","updated_at":"2015-08-21T08:04:06Z","closed_at":null,"body":"According to this http://docs.rancher.com/os/cloud-config/\r\nif I start a gce instance with `--metadata-from-file user-data=${__dirname}/bootstrap/startup` it should execute the content of startup script \"[...] If the file starts with #!<interpreter> (e.g., #!/bin/sh) [...]\".\r\n\r\nI don't know maybe I misunderstand, but it should execute this if the system boots up or `cloud-init` is somewhere triggered?\r\nIf so (on boot up), I have the feeling it's not implemented yet or something is not working.\r\n\r\nIf I execute the script manually on the booted system everything works like a charm.\r\nMaybe I miss something? Thanks!\r\n\r\n*I found this in `system-docker.log` relavant?*\r\n```log\r\ntime=\"2015-08-17T18:41:30.278849189Z\" level=info msg=\"GET /containers/cloud-init/json\" \r\ntime=\"2015-08-17T18:41:30.278945290Z\" level=error msg=\"Handler for GET /containers/{name:.*}/json returned error: no such id: cloud-init\" \r\ntime=\"2015-08-17T18:41:30.278963826Z\" level=error msg=\"HTTP Error\" err=\"no such id: cloud-init\" statusCode=404 \r\n```\r\n*also `/var/log/messages`*\r\n```log\r\nAug 17 18:58:20 rancher docker/79d874d96daf[1]: time=\"2015-08-17T18:58:20Z\" level=error msg=\"Unrecognized cloud-init\\n\" \r\nAug 17 18:58:20 rancher docker/79d874d96daf[1]: time=\"2015-08-17T18:58:20Z\" level=info msg=\"Merging /var/lib/rancher/conf/cloud-config.d/user_config.yml\" \r\nAug 17 18:58:20 rancher docker/79d874d96daf[1]: time=\"2015-08-17T18:58:20Z\" level=info msg=\"Writing to /var/lib/rancher/conf/cloud-config-processed.yml\" \r\nAug 17 18:58:22 rancher docker/61c3f45d3f11[1]: time=\"2015-08-17T18:58:22Z\" level=info msg=\"Merging cloud-config from meta-data and user-data\" \r\n```\r\n\r\nps. Somehow I have to execute a script on startup. Maybe other solution? I saw that there is a ticket on gce agent support. "},"comment":{"url":"https://api.github.com/repos/rancher/os/issues/comments/133321684","html_url":"https://github.com/rancher/os/issues/507#issuecomment-133321684","issue_url":"https://api.github.com/repos/rancher/os/issues/507","id":133321684,"user":{"login":"fentas","id":405182,"avatar_url":"https://avatars.githubusercontent.com/u/405182?v=3","gravatar_id":"","url":"https://api.github.com/users/fentas","html_url":"https://github.com/fentas","followers_url":"https://api.github.com/users/fentas/followers","following_url":"https://api.github.com/users/fentas/following{/other_user}","gists_url":"https://api.github.com/users/fentas/gists{/gist_id}","starred_url":"https://api.github.com/users/fentas/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/fentas/subscriptions","organizations_url":"https://api.github.com/users/fentas/orgs","repos_url":"https://api.github.com/users/fentas/repos","events_url":"https://api.github.com/users/fentas/events{/privacy}","received_events_url":"https://api.github.com/users/fentas/received_events","type":"User","site_admin":false},"created_at":"2015-08-21T08:04:06Z","updated_at":"2015-08-21T08:04:06Z","body":"Now after some trying there is another strange behavior.\r\nIf I define `user-data` on the first run, rancheros won't add ssh key. Therefor I couldn't log in.\r\nAfter I removed `user-data` I was able to. \r\n\r\nNow here is relavant data from `messages` if `user-data` is not defined..\r\n```log\r\nAug 21 07:43:29 rancher acpid: starting up with netlink and the input layer\r\nAug 21 07:43:29 rancher acpid: 1 rule loaded\r\nAug 21 07:43:29 rancher acpid: waiting for events: event logging is off\r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: + MOUNT_POINT=/media/config-2\r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: ++ cut -f1 -d:\r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: ++ grep -- 'LABEL=\"config-2\"'\r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: ++ blkid\r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: + CONFIG_DEV=\r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: + mkdir -p /media/config-2\r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: + '[' -e '' ']'\r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: + mount -t 9p -o trans=virtio,version=9p2000.L config-2 /media/config-2\r\nAug 21 07:43:29 rancher kernel: [    6.733624] 9p: Installing v9fs 9p2000 file system support\r\nAug 21 07:43:29 rancher kernel: [    6.734796] 9pnet: Could not find request transport: virtio\r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: + true\r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: + ros config get cloud_init\r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: datasources:\r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: - gce\r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: \r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: + cloud-init -save -network=false\r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: time=\"2015-08-21T07:43:29Z\" level=error msg=\"Unrecognized cloud-init\\n\" \r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: time=\"2015-08-21T07:43:29Z\" level=info msg=\"Merging /var/lib/rancher/conf/cloud-config.d/user_config.yml\" \r\nAug 21 07:43:29 rancher docker/4fc5be258b36[1]: time=\"2015-08-21T07:43:29Z\" level=info msg=\"Writing to /var/lib/rancher/conf/cloud-config-processed.yml\" \r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: time=\"2015-08-21T07:43:29Z\" level=error msg=\"addr add failed\" \r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: time=\"2015-08-21T07:43:29Z\" level=error msg=\"Failed to apply settings to lo : file exists\" \r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: time=\"2015-08-21T07:43:29Z\" level=info msg=\"Running DHCP on eth0\" \r\nAug 21 07:43:29 rancher dhcpcd[12]: version 6.6.7 starting\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: dhcpcd[12]: version 6.6.7 starting\r\nAug 21 07:43:29 rancher dhcpcd[12]: eth0: waiting for carrier\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: dhcpcd[12]: eth0: waiting for carrier\r\nAug 21 07:43:29 rancher dhcpcd[12]: eth0: carrier acquired\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: dhcpcd[12]: eth0: carrier acquired\r\nAug 21 07:43:29 rancher dhcpcd[12]: DUID 00:01:00:01:1d:69:93:21:42:01:0a:f0:b2:05\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: dhcpcd[12]: DUID 00:01:00:01:1d:69:93:21:42:01:0a:f0:b2:05\r\nAug 21 07:43:29 rancher dhcpcd[12]: eth0: IAID 0a:f0:b2:05\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: dhcpcd[12]: eth0: IAID 0a:f0:b2:05\r\nAug 21 07:43:29 rancher dhcpcd[12]: eth0: soliciting a DHCP lease\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: dhcpcd[12]: eth0: soliciting a DHCP lease\r\nAug 21 07:43:29 rancher dhcpcd[12]: eth0: offered 10.240.178.5 from 169.254.169.254\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: dhcpcd[12]: eth0: offered 10.240.178.5 from 169.254.169.254\r\nAug 21 07:43:29 rancher dhcpcd[12]: eth0: leased 10.240.178.5 for infinity\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: dhcpcd[12]: eth0: leased 10.240.178.5 for infinity\r\nAug 21 07:43:29 rancher dhcpcd[12]: eth0: adding route to 10.240.0.1/32\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: dhcpcd[12]: eth0: adding route to 10.240.0.1/32\r\nAug 21 07:43:29 rancher dhcpcd[12]: eth0: adding default route via 10.240.0.1\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: dhcpcd[12]: eth0: adding default route via 10.240.0.1\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: logger: invalid option -- i\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: BusyBox v1.23.1 (2015-06-25 12:59:11 UTC) multi-call binary.\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: \r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: Usage: logger [OPTIONS] [MESSAGE]\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: \r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: Write MESSAGE (or stdin) to syslog\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: \r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: #011-s#011Log to stderr as well as the system log\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: #011-t TAG#011Log using the specified tag (defaults to user name)\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: #011-p PRIO#011Priority (numeric or facility.level pair)\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: \r\nAug 21 07:43:29 rancher dhcpcd[12]: forked to background, child pid 43\r\nAug 21 07:43:29 rancher docker/2a323de4f219[1]: dhcpcd[12]: forked to background, child pid 43\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + TLS_PATH=/etc/docker/tls\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + CGROUPS='perf_event net_cls freezer devices blkio memory cpuacct cpu cpuset'\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mkdir -p /sys/fs/cgroup\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mount -t tmpfs none /sys/fs/cgroup\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + for i in '$CGROUPS'\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mkdir -p /sys/fs/cgroup/perf_event\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mount -t cgroup -o perf_event none /sys/fs/cgroup/perf_event\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + for i in '$CGROUPS'\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mkdir -p /sys/fs/cgroup/net_cls\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mount -t cgroup -o net_cls none /sys/fs/cgroup/net_cls\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + for i in '$CGROUPS'\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mkdir -p /sys/fs/cgroup/freezer\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mount -t cgroup -o freezer none /sys/fs/cgroup/freezer\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + for i in '$CGROUPS'\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mkdir -p /sys/fs/cgroup/devices\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mount -t cgroup -o devices none /sys/fs/cgroup/devices\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + for i in '$CGROUPS'\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mkdir -p /sys/fs/cgroup/blkio\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mount -t cgroup -o blkio none /sys/fs/cgroup/blkio\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + for i in '$CGROUPS'\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mkdir -p /sys/fs/cgroup/memory\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mount -t cgroup -o memory none /sys/fs/cgroup/memory\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + for i in '$CGROUPS'\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mkdir -p /sys/fs/cgroup/cpuacct\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mount -t cgroup -o cpuacct none /sys/fs/cgroup/cpuacct\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + for i in '$CGROUPS'\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mkdir -p /sys/fs/cgroup/cpu\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mount -t cgroup -o cpu none /sys/fs/cgroup/cpu\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + for i in '$CGROUPS'\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mkdir -p /sys/fs/cgroup/cpuset\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + mount -t cgroup -o cpuset none /sys/fs/cgroup/cpuset\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + grep -q br_netfilter\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + lsmod\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + rm -f /var/run/docker.pid\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: +++ sed 's/^-//'\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: +++ ros config get user_docker.args\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: ++ echo docker -d -s overlay -G docker -H unix:///var/run/docker.sock -D\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + ARGS='docker -d -s overlay -G docker -H unix:///var/run/docker.sock -D'\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: +++ sed 's/^-//'\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: +++ ros config get user_docker.extra_args\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: ++ echo\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: + ARGS='docker -d -s overlay -G docker -H unix:///var/run/docker.sock -D '\r\nAug 21 07:43:29 rancher docker/07d718a022d2[1]: ++ ros config get user_docker.tls\r\nAug 21 07:43:30 rancher docker/07d718a022d2[1]: + '[' '' = true ']'\r\nAug 21 07:43:30 rancher docker/07d718a022d2[1]: + '[' -e /var/lib/rancher/conf/docker ']'\r\nAug 21 07:43:30 rancher docker/07d718a022d2[1]: + exec docker -d -s overlay -G docker -H unix:///var/run/docker.sock -D\r\nAug 21 07:43:30 rancher docker/af2b6fe101b8[1]: mount: permission denied (are you root?)\r\nAug 21 07:43:30 rancher docker/af2b6fe101b8[1]: time=\"2015-08-21T07:43:30Z\" level=info msg=\"Docker is ready\" \r\nAug 21 07:43:30 rancher kernel: [    7.852535] IPv6: ADDRCONF(NETDEV_UP): veth12b673a: link is not ready\r\nAug 21 07:43:30 rancher kernel: [    7.853379] IPv6: ADDRCONF(NETDEV_CHANGE): veth12b673a: link becomes ready\r\nAug 21 07:43:30 rancher kernel: [    7.854360] device veth12b673a entered promiscuous mode\r\nAug 21 07:43:30 rancher kernel: [    7.855191] docker-sys: port 1(veth12b673a) entered forwarding state\r\nAug 21 07:43:30 rancher kernel: [    7.856305] docker-sys: port 1(veth12b673a) entered forwarding state\r\nAug 21 07:43:30 rancher kernel: [    7.873335] eth0: renamed from vethc501589\r\nAug 21 07:43:30 rancher docker/2bb4721029a6[1]: Preloading docker images from /mnt/preload...\r\nAug 21 07:43:30 rancher docker/2bb4721029a6[1]: Done.\r\nAug 21 07:43:30 rancher kernel: [    7.909393] vethc501589: renamed from eth0\r\nAug 21 07:43:30 rancher kernel: [    7.915843] docker-sys: port 1(veth12b673a) entered disabled state\r\nAug 21 07:43:30 rancher kernel: [    7.916790] device veth12b673a left promiscuous mode\r\nAug 21 07:43:30 rancher kernel: [    7.917459] docker-sys: port 1(veth12b673a) entered disabled state\r\nAug 21 07:43:30 rancher kernel: [    8.059697] 9pnet: Could not find request transport: virtio\r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: + MOUNT_POINT=/media/config-2\r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: ++ cut -f1 -d:\r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: ++ grep -- 'LABEL=\"config-2\"'\r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: ++ blkid\r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: + CONFIG_DEV=\r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: + mkdir -p /media/config-2\r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: + '[' -e '' ']'\r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: + mount -t 9p -o trans=virtio,version=9p2000.L config-2 /media/config-2\r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: + true\r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: + ros config get cloud_init\r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: datasources:\r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: - gce\r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: \r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: + cloud-init -save -network=true\r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: time=\"2015-08-21T07:43:30Z\" level=error msg=\"Could not retrieve user-data: metadata: GCE metadata \\\"instance/attributes/user-data\\\" not defined\" \r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: time=\"2015-08-21T07:43:30Z\" level=info msg=\"Checking availability of \\\"local-file\\\"\\n\" \r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: time=\"2015-08-21T07:43:30Z\" level=info msg=\"Fetching user-data from datasource local-file\" \r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: time=\"2015-08-21T07:43:30Z\" level=info msg=\"Fetching meta-data from datasource of type local-file\" \r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: time=\"2015-08-21T07:43:30Z\" level=info msg=\"Merging /var/lib/rancher/conf/cloud-config.d/user_config.yml\" \r\nAug 21 07:43:30 rancher docker/caa610fa96cf[1]: time=\"2015-08-21T07:43:30Z\" level=info msg=\"Writing to /var/lib/rancher/conf/cloud-config-processed.yml\" \r\nAug 21 07:43:32 rancher docker/ce8ffa7c10d3[1]: time=\"2015-08-21T07:43:31Z\" level=info msg=\"Merging cloud-config from meta-data and user-data\" \r\nAug 21 07:43:32 rancher sshd[922]: Server listening on 0.0.0.0 port 22.\r\nAug 21 07:43:32 rancher sshd[922]: Server listening on :: port 22.\r\n```\r\n\r\nNow I added `user-data` and restarted the instance. Here is the `message` if `user-data` is defined..\r\n\r\n```log\r\nAug 21 07:46:16 rancher docker/0a2a72677cd0[1]: + MOUNT_POINT=/media/config-2\r\nAug 21 07:46:16 rancher docker/0a2a72677cd0[1]: ++ cut -f1 -d:\r\nAug 21 07:46:16 rancher docker/0a2a72677cd0[1]: ++ grep -- 'LABEL=\"config-2\"'\r\nAug 21 07:46:16 rancher docker/0a2a72677cd0[1]: ++ blkid\r\nAug 21 07:46:16 rancher docker/0a2a72677cd0[1]: + CONFIG_DEV=\r\nAug 21 07:46:16 rancher docker/0a2a72677cd0[1]: + mkdir -p /media/config-2\r\nAug 21 07:46:16 rancher docker/0a2a72677cd0[1]: + '[' -e '' ']'\r\nAug 21 07:46:16 rancher docker/0a2a72677cd0[1]: + mount -t 9p -o trans=virtio,version=9p2000.L config-2 /media/config-2\r\nAug 21 07:46:17 rancher kernel: [    7.550755] 9p: Installing v9fs 9p2000 file system support\r\nAug 21 07:46:17 rancher kernel: [    7.552408] 9pnet: Could not find request transport: virtio\r\nAug 21 07:46:17 rancher docker/0a2a72677cd0[1]: + true\r\nAug 21 07:46:17 rancher docker/0a2a72677cd0[1]: + ros config get cloud_init\r\nAug 21 07:46:17 rancher docker/0a2a72677cd0[1]: datasources:\r\nAug 21 07:46:17 rancher docker/0a2a72677cd0[1]: - gce\r\nAug 21 07:46:17 rancher docker/0a2a72677cd0[1]: \r\nAug 21 07:46:17 rancher docker/0a2a72677cd0[1]: + cloud-init -save -network=false\r\nAug 21 07:46:17 rancher docker/0a2a72677cd0[1]: time=\"2015-08-21T07:46:17Z\" level=error msg=\"Unrecognized cloud-init\\n\" \r\nAug 21 07:46:17 rancher docker/0a2a72677cd0[1]: time=\"2015-08-21T07:46:17Z\" level=info msg=\"Merging /var/lib/rancher/conf/cloud-config.d/user_config.yml\" \r\nAug 21 07:46:17 rancher docker/0a2a72677cd0[1]: time=\"2015-08-21T07:46:17Z\" level=info msg=\"Writing to /var/lib/rancher/conf/cloud-config-processed.yml\" \r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: time=\"2015-08-21T07:46:17Z\" level=error msg=\"addr add failed\" \r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: time=\"2015-08-21T07:46:17Z\" level=error msg=\"Failed to apply settings to lo : file exists\" \r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: time=\"2015-08-21T07:46:17Z\" level=info msg=\"Running DHCP on eth0\" \r\nAug 21 07:46:17 rancher dhcpcd[12]: version 6.6.7 starting\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: dhcpcd[12]: version 6.6.7 starting\r\nAug 21 07:46:17 rancher dhcpcd[12]: eth0: waiting for carrier\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: dhcpcd[12]: eth0: waiting for carrier\r\nAug 21 07:46:17 rancher dhcpcd[12]: eth0: carrier acquired\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: dhcpcd[12]: eth0: carrier acquired\r\nAug 21 07:46:17 rancher dhcpcd[12]: DUID 00:01:00:01:1d:69:93:c9:42:01:0a:f0:b2:05\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: dhcpcd[12]: DUID 00:01:00:01:1d:69:93:c9:42:01:0a:f0:b2:05\r\nAug 21 07:46:17 rancher dhcpcd[12]: eth0: IAID 0a:f0:b2:05\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: dhcpcd[12]: eth0: IAID 0a:f0:b2:05\r\nAug 21 07:46:17 rancher dhcpcd[12]: eth0: soliciting a DHCP lease\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: dhcpcd[12]: eth0: soliciting a DHCP lease\r\nAug 21 07:46:17 rancher dhcpcd[12]: eth0: offered 10.240.178.5 from 169.254.169.254\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: dhcpcd[12]: eth0: offered 10.240.178.5 from 169.254.169.254\r\nAug 21 07:46:17 rancher dhcpcd[12]: eth0: leased 10.240.178.5 for infinity\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: dhcpcd[12]: eth0: leased 10.240.178.5 for infinity\r\nAug 21 07:46:17 rancher dhcpcd[12]: eth0: adding route to 10.240.0.1/32\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: dhcpcd[12]: eth0: adding route to 10.240.0.1/32\r\nAug 21 07:46:17 rancher dhcpcd[12]: eth0: adding default route via 10.240.0.1\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: dhcpcd[12]: eth0: adding default route via 10.240.0.1\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: logger: invalid option -- i\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: BusyBox v1.23.1 (2015-06-25 12:59:11 UTC) multi-call binary.\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: \r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: Usage: logger [OPTIONS] [MESSAGE]\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: \r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: Write MESSAGE (or stdin) to syslog\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: \r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: #011-s#011Log to stderr as well as the system log\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: #011-t TAG#011Log using the specified tag (defaults to user name)\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: #011-p PRIO#011Priority (numeric or facility.level pair)\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: \r\nAug 21 07:46:17 rancher dhcpcd[12]: forked to background, child pid 43\r\nAug 21 07:46:17 rancher docker/3e7feaec0a67[1]: dhcpcd[12]: forked to background, child pid 43\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + TLS_PATH=/etc/docker/tls\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + CGROUPS='perf_event net_cls freezer devices blkio memory cpuacct cpu cpuset'\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mkdir -p /sys/fs/cgroup\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mount -t tmpfs none /sys/fs/cgroup\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + for i in '$CGROUPS'\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mkdir -p /sys/fs/cgroup/perf_event\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mount -t cgroup -o perf_event none /sys/fs/cgroup/perf_event\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + for i in '$CGROUPS'\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mkdir -p /sys/fs/cgroup/net_cls\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mount -t cgroup -o net_cls none /sys/fs/cgroup/net_cls\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + for i in '$CGROUPS'\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mkdir -p /sys/fs/cgroup/freezer\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mount -t cgroup -o freezer none /sys/fs/cgroup/freezer\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + for i in '$CGROUPS'\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mkdir -p /sys/fs/cgroup/devices\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mount -t cgroup -o devices none /sys/fs/cgroup/devices\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + for i in '$CGROUPS'\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mkdir -p /sys/fs/cgroup/blkio\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mount -t cgroup -o blkio none /sys/fs/cgroup/blkio\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + for i in '$CGROUPS'\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mkdir -p /sys/fs/cgroup/memory\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mount -t cgroup -o memory none /sys/fs/cgroup/memory\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + for i in '$CGROUPS'\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mkdir -p /sys/fs/cgroup/cpuacct\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mount -t cgroup -o cpuacct none /sys/fs/cgroup/cpuacct\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + for i in '$CGROUPS'\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mkdir -p /sys/fs/cgroup/cpu\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mount -t cgroup -o cpu none /sys/fs/cgroup/cpu\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + for i in '$CGROUPS'\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mkdir -p /sys/fs/cgroup/cpuset\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + mount -t cgroup -o cpuset none /sys/fs/cgroup/cpuset\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + grep -q br_netfilter\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + lsmod\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + rm -f /var/run/docker.pid\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: +++ sed 's/^-//'\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: +++ ros config get user_docker.args\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: ++ echo docker -d -s overlay -G docker -H unix:///var/run/docker.sock -D\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + ARGS='docker -d -s overlay -G docker -H unix:///var/run/docker.sock -D'\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: +++ sed 's/^-//'\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: +++ ros config get user_docker.extra_args\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: ++ echo\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + ARGS='docker -d -s overlay -G docker -H unix:///var/run/docker.sock -D '\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: ++ ros config get user_docker.tls\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + '[' '' = true ']'\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + '[' -e /var/lib/rancher/conf/docker ']'\r\nAug 21 07:46:18 rancher docker/b1bcdbd326b2[1]: + exec docker -d -s overlay -G docker -H unix:///var/run/docker.sock -D\r\nAug 21 07:46:18 rancher docker/af4a73e192b1[1]: mount: permission denied (are you root?)\r\nAug 21 07:46:18 rancher docker/af4a73e192b1[1]: time=\"2015-08-21T07:46:18Z\" level=info msg=\"Docker is ready\" \r\nAug 21 07:46:18 rancher kernel: [    9.061363] IPv6: ADDRCONF(NETDEV_UP): veth7930879: link is not ready\r\nAug 21 07:46:18 rancher kernel: [    9.062667] IPv6: ADDRCONF(NETDEV_CHANGE): veth7930879: link becomes ready\r\nAug 21 07:46:18 rancher kernel: [    9.064023] device veth7930879 entered promiscuous mode\r\nAug 21 07:46:18 rancher kernel: [    9.064767] docker-sys: port 1(veth7930879) entered forwarding state\r\nAug 21 07:46:18 rancher kernel: [    9.065664] docker-sys: port 1(veth7930879) entered forwarding state\r\nAug 21 07:46:18 rancher kernel: [    9.083303] eth0: renamed from veth2e40a90\r\nAug 21 07:46:18 rancher docker/364ad9895215[1]: Preloading docker images from /mnt/preload...\r\nAug 21 07:46:18 rancher docker/364ad9895215[1]: Done.\r\nAug 21 07:46:18 rancher kernel: [    9.121395] veth2e40a90: renamed from eth0\r\nAug 21 07:46:18 rancher kernel: [    9.127753] docker-sys: port 1(veth7930879) entered disabled state\r\nAug 21 07:46:18 rancher kernel: [    9.128785] device veth7930879 left promiscuous mode\r\nAug 21 07:46:18 rancher kernel: [    9.129451] docker-sys: port 1(veth7930879) entered disabled state\r\nAug 21 07:46:18 rancher kernel: [    9.269155] 9pnet: Could not find request transport: virtio\r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: + MOUNT_POINT=/media/config-2\r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: ++ cut -f1 -d:\r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: ++ grep -- 'LABEL=\"config-2\"'\r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: ++ blkid\r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: + CONFIG_DEV=\r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: + mkdir -p /media/config-2\r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: + '[' -e '' ']'\r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: + mount -t 9p -o trans=virtio,version=9p2000.L config-2 /media/config-2\r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: + true\r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: + ros config get cloud_init\r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: datasources:\r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: - gce\r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: \r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: + cloud-init -save -network=true\r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: time=\"2015-08-21T07:46:18Z\" level=info msg=\"Found UserData Config\" \r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: time=\"2015-08-21T07:46:18Z\" level=error msg=\"Could not unmarshal data: yaml: line 6: did not find expected key\" \r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: time=\"2015-08-21T07:46:18Z\" level=error msg=\"Could not process userdata: yaml: line 6: did not find expected key\" \r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: time=\"2015-08-21T07:46:18Z\" level=error msg=\"Error: yaml: line 6: did not find expected key\" \r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: time=\"2015-08-21T07:46:18Z\" level=error msg=\"Could not retrieve GCE CloudConfig yaml: line 6: did not find expected key\" \r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: time=\"2015-08-21T07:46:18Z\" level=error msg=\"Unrecognized cloud-init\\n\" \r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: time=\"2015-08-21T07:46:18Z\" level=info msg=\"Merging /var/lib/rancher/conf/cloud-config.d/user_config.yml\" \r\nAug 21 07:46:18 rancher docker/01580db95b1c[1]: time=\"2015-08-21T07:46:18Z\" level=info msg=\"Writing to /var/lib/rancher/conf/cloud-config-processed.yml\" \r\nAug 21 07:46:20 rancher docker/30a8743e3c2a[1]: time=\"2015-08-21T07:46:20Z\" level=info msg=\"Merging cloud-config from meta-data and user-data\" \r\nAug 21 07:46:21 rancher sshd[755]: Server listening on 0.0.0.0 port 22.\r\nAug 21 07:46:21 rancher sshd[755]: Server listening on :: port 22.\r\n```\r\n\r\nHopefully this will help you. Thanks a lot."}}