1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
103
104
105
106
107
108
109
110
111 | $ juju bootstrap google google --config=agent-metadata-url=https://s3.amazonaws.com/24b99da448314945835933a0c8edffb3/tools/streams --debug --config=agent-stream=devel
12:47:36 INFO juju.cmd supercommand.go:63 running juju [2.0.0 gc go1.6]
12:47:36 DEBUG juju.cmd supercommand.go:64 args: []string{"juju", "bootstrap", "google", "google", "--config=agent-metadata-url=https://s3.amazonaws.com/24b99da448314945835933a0c8edffb3/tools/streams", "--debug", "--config=agent-stream=devel"}
12:47:36 DEBUG juju.cmd.juju.commands bootstrap.go:535 provider attrs: map[]
12:47:36 INFO cmd cmd.go:141 Adding contents of "/home/nate/.local/share/juju/ssh/juju_id_rsa.pub" to authorized-keys
12:47:36 INFO cmd cmd.go:141 Adding contents of "/home/nate/.ssh/id_rsa.pub" to authorized-keys
12:47:36 DEBUG juju.cmd.juju.commands bootstrap.go:598 preparing controller with config: map[authorized-keys:ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCi1H4C068yHMB6Yg5aTXvX1/oMIiYVQrTile96TZPU0TGzCKkXqBWnQzm7N8nZLbL7aiky/Vqvvopc/GrJPFDxtSG7UcsP4VptmB+B+1KE7WKK2UZuEWKKIjRBWjJzvoSnZfOsCX9WchNoMIqX3Q6pQium0MUvJqm0US7hNNQXMNIPsYc1DrDL8K9WceXu4HCJRsLKXIArHrM2Ej6RHmm97llyAO8Ro/Jlf7oFh+TMiUGkaI4LBfZIDamjQd+e39SelVdHoDWYuSKZjOHF14YrgRLkKX21a+1aKL6GYsWR9wbsKoutH0x9ZHkjX+CwKuCmmj7kOSGsBuuDVP19xcCb juju-client-key
ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCsa3WLBhQQf8ZJKsoiEZQTTK3Hc2Sw1fXG3cugWxZWbas3dqfdHX69/PJwO+KEmX+xk/LHu+uTXnxhaLSH0vm1C2JbVmAhamX0wuXlZxH779J6KjulRi4mWRq3lWwS0dIGgXxsXCpUG1S0WWnt6o4C/Yxt60KDiyimW5vNT41JkaPXiHHwT95bcS9O3gApH+moNUuYx/+5bvh2gUecI+k8vQeyGEbeEu0axWbtqTQRLrbFi+ssf+XBDjXdBkW0mcejV7EjOdkjo0EdL9DIuTQGwvPCkZZK20snlf1+TMaoYyhUf92ETlQrVB+X9Mz/jf/qcrSzIelsu0pjg8DniRmD nate.finch@gmail.com
type:gce name:controller uuid:438d18d3-0dfd-4f62-8e7b-ba3bc09f015d agent-metadata-url:https://s3.amazonaws.com/24b99da448314945835933a0c8edffb3/tools/streams agent-stream:devel]
12:47:37 INFO cmd cmd.go:129 Creating Juju controller "google" on google/us-east1
12:47:37 INFO juju.cmd.juju.commands bootstrap.go:741 combined bootstrap constraints:
12:47:37 DEBUG juju.environs.bootstrap bootstrap.go:185 model "controller" supports service/machine networks: false
12:47:37 DEBUG juju.environs.bootstrap bootstrap.go:187 network management by juju enabled: true
12:47:37 INFO cmd cmd.go:141 Loading image metadata
12:47:37 DEBUG juju.environs imagemetadata.go:112 obtained image datasource "default cloud images"
12:47:37 DEBUG juju.environs imagemetadata.go:112 obtained image datasource "default ubuntu cloud images"
12:47:37 DEBUG juju.environs.bootstrap bootstrap.go:557 constraints for image metadata lookup &{{{us-east1 https://www.googleapis.com} [precise saucy wily win2008r2 win2016 centos7 genericlinux raring xenial win2012hv win2016nano win81 win10 yakkety quantal trusty utopic vivid win2012r2 win2012 win8 win2012hvr2 win7] [amd64 i386 armhf arm64 ppc64el s390x] released}}
12:47:38 DEBUG juju.environs.bootstrap bootstrap.go:569 found 0 image metadata in default cloud images
12:47:39 DEBUG juju.environs.bootstrap bootstrap.go:569 found 6 image metadata in default ubuntu cloud images
12:47:39 DEBUG juju.environs.bootstrap bootstrap.go:573 found 6 image metadata from all image data sources
12:47:39 INFO cmd cmd.go:129 Looking for packaged Juju agent version 2.0.0 for amd64
12:47:39 INFO juju.environs.bootstrap tools.go:72 looking for bootstrap agent binaries: version=2.0.0
12:47:39 INFO juju.environs.tools tools.go:101 finding agent binaries in stream "devel"
12:47:39 INFO juju.environs.tools tools.go:103 reading agent binaries with major.minor version 2.0
12:47:39 INFO juju.environs.tools tools.go:111 filtering agent binaries by version: 2.0.0
12:47:39 INFO juju.environs.tools tools.go:117 filtering agent binaries by architecture: amd64
12:47:39 DEBUG juju.environs.tools urls.go:109 trying datasource "keystone catalog"
12:47:41 DEBUG juju.environs.simplestreams simplestreams.go:680 using default candidate for content id "com.ubuntu.juju:devel:tools" are {20161007 mirrors:1.0 content-download streams/v1/cpc-mirrors.sjson []}
12:47:43 INFO juju.environs.bootstrap tools.go:74 found 0 packaged agent binaries
12:47:43 INFO cmd cmd.go:129 No packaged binary found, preparing local Juju agent binary
12:47:43 DEBUG juju.environs.sync sync.go:302 Making agent binary tarball
12:47:43 DEBUG juju.environs.tools build.go:126 looking for: juju
12:47:43 DEBUG juju.environs.tools build.go:165 checking: /home/nate/bin/jujud
12:47:43 INFO juju.environs.tools build.go:171 Found agent binary to upload (/home/nate/bin/jujud)
12:47:43 INFO juju.environs.tools build.go:181 target: /tmp/juju-tools292376929/jujud
12:47:44 DEBUG juju.environs.tools build.go:254 forcing version to 2.0.0.1
12:47:44 DEBUG juju.environs.tools build.go:42 adding entry: &tar.Header{Name:"FORCE-VERSION", Mode:436, Uid:0, Gid:0, Size:7, ModTime:time.Time{sec:63611887664, nsec:128645563, loc:(*time.Location)(0x3acde60)}, Typeflag:0x30, Linkname:"", Uname:"ubuntu", Gname:"ubuntu", Devmajor:0, Devminor:0, AccessTime:time.Time{sec:63611887664, nsec:128645563, loc:(*time.Location)(0x3acde60)}, ChangeTime:time.Time{sec:63611887664, nsec:128645563, loc:(*time.Location)(0x3acde60)}, Xattrs:map[string]string(nil)}
12:47:44 DEBUG juju.environs.tools build.go:42 adding entry: &tar.Header{Name:"jujud", Mode:509, Uid:0, Gid:0, Size:108935408, ModTime:time.Time{sec:63611887664, nsec:100631526, loc:(*time.Location)(0x3acde60)}, Typeflag:0x30, Linkname:"", Uname:"ubuntu", Gname:"ubuntu", Devmajor:0, Devminor:0, AccessTime:time.Time{sec:63611887664, nsec:100631526, loc:(*time.Location)(0x3acde60)}, ChangeTime:time.Time{sec:63611887664, nsec:100631526, loc:(*time.Location)(0x3acde60)}, Xattrs:map[string]string(nil)}
12:47:52 INFO juju.environs.sync sync.go:333 using agent binary 2.0.0-xenial-amd64 aliased to 2.0.0.1-xenial-amd64 (22005kB)
12:47:52 INFO cmd cmd.go:141 Starting new instance for initial controller
12:47:53 INFO juju.provider.gce.gceapi raw.go:333 GCE operation "operation-1476290873244-53eadc115b960-d7318c63-5a740881", waiting...
12:48:15 INFO juju.provider.gce.gceapi raw.go:357 GCE operation "operation-1476290873244-53eadc115b960-d7318c63-5a740881" finished
Launching controller instance(s) on google/us-east1...
12:48:15 DEBUG juju.environs.instances image.go:64 instance constraints {region: us-east1, series: xenial, arches: [amd64], constraints: , storage: []}
12:48:15 DEBUG juju.environs.instances image.go:70 matching constraints {region: us-east1, series: xenial, arches: [amd64], constraints: , storage: []} against possible image metadata [{Id:ubuntu-1604-xenial-v20161011 Arch:amd64 VirtType:kvm}]
12:48:15 INFO juju.environs.instances image.go:106 find instance - using image with id: ubuntu-1604-xenial-v20161011
12:48:15 DEBUG juju.cloudconfig.instancecfg instancecfg.go:782 Setting numa ctl preference to false
12:48:15 DEBUG juju.service discovery.go:62 discovered init system "systemd" from series "xenial"
12:48:15 DEBUG juju.provider.gce environ_broker.go:189 GCE user data; 1536 bytes
12:48:16 INFO juju.provider.gce environ_availzones.go:114 found 3 zones: [{us-east1-b []} {us-east1-c []} {us-east1-d []}]
12:48:17 INFO juju.provider.gce.gceapi raw.go:333 GCE operation "operation-1476290896571-53eadc279aa78-8898cf23-816e67e0", waiting...
12:48:41 INFO juju.provider.gce.gceapi raw.go:357 GCE operation "operation-1476290896571-53eadc279aa78-8898cf23-816e67e0" finished
12:48:42 INFO juju.provider.gce environ_broker.go:53 started instance "juju-9f015d-0" in zone "us-east1-b"
- juju-9f015d-0 (arch=amd64 mem=1.7G cores=1)
12:48:42 INFO juju.environs.bootstrap bootstrap.go:587 newest version: 2.0.0.1
12:48:42 INFO juju.environs.bootstrap bootstrap.go:602 picked bootstrap tools version: 2.0.0.1
12:48:42 INFO juju.environs.bootstrap bootstrap.go:378 Installing Juju agent on bootstrap instance
12:48:43 INFO cmd cmd.go:129 Fetching Juju GUI 2.2.1
12:48:43 DEBUG juju.cloudconfig.instancecfg instancecfg.go:782 Setting numa ctl preference to false
Waiting for address
Attempting to connect to 104.196.61.231:22
Attempting to connect to 10.142.0.3:22
12:49:18 DEBUG juju.utils.ssh ssh.go:292 using OpenSSH ssh client
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
@ WARNING: REMOTE HOST IDENTIFICATION HAS CHANGED! @
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
IT IS POSSIBLE THAT SOMEONE IS DOING SOMETHING NASTY!
Someone could be eavesdropping on you right now (man-in-the-middle attack)!
It is also possible that a host key has just been changed.
The fingerprint for the ECDSA key sent by the remote host is
SHA256:uCtdRA5Z/WW7EHyl/V222pDEWkaCOrHRX70KdYquXFI.
Please contact your system administrator.
Add correct host key in /home/nate/.ssh/known_hosts to get rid of this message.
Offending ECDSA key in /home/nate/.ssh/known_hosts:567
remove with:
ssh-keygen -f "/home/nate/.ssh/known_hosts" -R 104.196.61.231
Keyboard-interactive authentication is disabled to avoid man-in-the-middle attacks.
Logging to /var/log/cloud-init-output.log on the bootstrap machine
Running apt-get update
Running apt-get upgrade
Installing curl, cpu-checker, bridge-utils, cloud-utils, tmux
Installing Juju machine agent
Starting Juju machine agent (service jujud-machine-0)
12:51:18 INFO cmd cmd.go:129 Bootstrap agent now started
12:51:18 DEBUG juju.juju api.go:258 API hostnames [10.142.0.3:17070 104.196.61.231:17070] - resolving hostnames
12:51:18 INFO juju.juju api.go:280 new API addresses to cache [10.142.0.3:17070 104.196.61.231:17070]
12:51:18 INFO cmd cmd.go:129 Contacting Juju controller at 10.142.0.3 to verify accessibility...
12:51:18 INFO juju.juju api.go:72 connecting to API addresses: [10.142.0.3:17070 104.196.61.231:17070]
12:51:18 INFO juju.api apiclient.go:519 dialing "wss://10.142.0.3:17070/model/622ecd82-1aaa-4038-84e4-b22e855368f3/api"
12:51:18 INFO juju.api apiclient.go:519 dialing "wss://104.196.61.231:17070/model/622ecd82-1aaa-4038-84e4-b22e855368f3/api"
12:51:20 INFO juju.api apiclient.go:519 dialing "wss://104.196.61.231:17070/model/622ecd82-1aaa-4038-84e4-b22e855368f3/api"
12:51:20 INFO juju.api apiclient.go:309 connection established to "wss://104.196.61.231:17070/model/622ecd82-1aaa-4038-84e4-b22e855368f3/api"
12:51:20 DEBUG juju.juju api.go:253 API hostnames changed from [10.142.0.3:17070 104.196.61.231:17070] to [104.196.61.231:17070 10.142.0.3:17070] - resolving hostnames
12:51:20 INFO juju.juju api.go:275 API addresses changed from [10.142.0.3:17070 104.196.61.231:17070] to [104.196.61.231:17070 10.142.0.3:17070]
12:51:20 DEBUG juju.api monitor.go:35 RPC connection died
12:51:20 INFO cmd cmd.go:141 Still waiting for API to become available: upgrade in progress (upgrade in progress)
12:51:20 INFO juju.juju api.go:72 connecting to API addresses: [104.196.61.231:17070 10.142.0.3:17070]
12:51:20 INFO juju.api apiclient.go:519 dialing "wss://104.196.61.231:17070/model/622ecd82-1aaa-4038-84e4-b22e855368f3/api"
12:51:20 INFO juju.api apiclient.go:519 dialing "wss://10.142.0.3:17070/model/622ecd82-1aaa-4038-84e4-b22e855368f3/api"
12:51:21 INFO juju.api apiclient.go:309 connection established to "wss://104.196.61.231:17070/model/622ecd82-1aaa-4038-84e4-b22e855368f3/api"
12:51:21 DEBUG juju.juju api.go:263 API hostnames unchanged - not resolving
12:51:21 INFO cmd cmd.go:141 Still waiting for API to become available: upgrade in progress (upgrade in progress)
12:51:21 INFO juju.juju api.go:72 connecting to API addresses: [104.196.61.231:17070 10.142.0.3:17070]
12:51:21 INFO juju.api apiclient.go:519 dialing "wss://104.196.61.231:17070/model/622ecd82-1aaa-4038-84e4-b22e855368f3/api"
12:51:21 INFO juju.api apiclient.go:519 dialing "wss://10.142.0.3:17070/model/622ecd82-1aaa-4038-84e4-b22e855368f3/api"
12:51:22 INFO juju.api apiclient.go:309 connection established to "wss://104.196.61.231:17070/model/622ecd82-1aaa-4038-84e4-b22e855368f3/api"
12:51:23 DEBUG juju.juju api.go:263 API hostnames unchanged - not resolving
12:51:23 INFO cmd cmd.go:129 Bootstrap complete, "google" controller now available.
12:51:23 INFO cmd cmd.go:129 Controller machines are in the "controller" model.
12:51:23 INFO cmd cmd.go:129 Initial model "default" added.
12:51:23 INFO cmd supercommand.go:465 command finished
|