Skip to content
This repository has been archived by the owner on Apr 12, 2021. It is now read-only.

conjure-up hangs 'Waiting for deployment to settle' on kubernetes #1377

Open
donbowman opened this issue Mar 22, 2018 · 42 comments
Open

conjure-up hangs 'Waiting for deployment to settle' on kubernetes #1377

donbowman opened this issue Mar 22, 2018 · 42 comments
Assignees

Comments

@donbowman
Copy link

donbowman commented Mar 22, 2018

Report

Ubuntu 18.04, using localhost/lxd. juju appears to work (e.g. I can setup 'ghost' and access it). But conjure-up just hangs as below (logs et al attached).
lxc is configured using local lvm as storage and local bridge for network.

It doesn't put anything interesting in the logs. It doesn't seem to even start a single container.
(the controller pre-exists, i bootstrapped it w/ juju, it works for e.g. seting up ghost).

$ conjure-up kubernetes-core localhost office
/snap/conjure-up/980/wrappers/conjure-up: line 9: warning: setlocale: LC_ALL: cannot change locale (en_US.UTF-8): No such file or directory
[info] Summoning kubernetes-core to localhost
[info] Creating Juju model conjure-kubernetes-core-8e8...
[info] Juju model created.
[info] Running Kubernetes Core step: 00_process-providertype before-deploy.
[info] Deploying Applications.
[info] Waiting for deployment to settle.

Please provide the output of the following commands

$ which juju
/snap/bin/juju
$ which conjure-up
/snap/bin/conjure-up
$ conjure-up --version
/snap/conjure-up/980/wrappers/conjure-up: line 9: warning: setlocale: LC_ALL: cannot change locale (en_US.UTF-8): No such file or directory
conjure-up 2.5.6
$ which lxc
/usr/bin/lxc
$ lxc config show
config:
  core.https_address: '[::]'
$ lxc version
3.0.0.beta5
$ cat /etc/lsb-release 
DISTRIB_ID=Ubuntu
DISTRIB_RELEASE=18.04
DISTRIB_CODENAME=bionic
DISTRIB_DESCRIPTION="Ubuntu Bionic Beaver (development branch)"
$ lxd --version
3.0.0.beta5

Please attach tarball of ~/.cache/conjure-up:

tar cvzf conjure-up.tar.gz ~/.cache/conjure-up

Sosreport

Please attach a sosreport:

sudo apt install sosreport
sosreport

The resulting output file can be attached to this issue.

What Spell was Selected?

kubernetes-core

What provider (aws, maas, localhost, etc)?

localhost

MAAS Users

Which version of MAAS?

Commands ran

Please outline what commands were run to install and execute conjure-up:

$ conjure-up kubernetes-core localhost office

conjure-up.log

sosreport-DBowman-20180322111548.tar.gz

conjure up is from snap

$ snap list
Name          Version                    Rev   Tracking   Developer  Notes
conjure-up    2.5.6-20180320.1903        980   edge       canonical  classic
core          16-2.31.2                  4206  stable     canonical  core
juju          2.4-beta1+develop-b8c92cc  3820  edge       canonical  classic
libreoffice   6.0.3.1                    57    candidate  canonical  -
skype         8.18.76.7                  21    insider    skype      classic
snappy-debug  0.31.7-snapd2.28.5         219   stable     canonical  devmode

Additional Information

conjure-up ghost localhost office

works so its presumably related to kubernetes-core

@glortho
Copy link

glortho commented Apr 24, 2018

I appear to get the same on Ubuntu 16.04 running conjure-up canonical-kubernetes localhost.

Though one time after ~20 minutes or so it erred with conjureup.utils.SudoError: The "Canonical Distribution of Kubernetes" step "Kubernetes Cluster Controller" requires sudo: passwordless sudo required. Presumably same as #1386.

When running via interface I saw this problem: #1134

Giving up for now.

@donbowman
Copy link
Author

i subsequently discovered if one has 'snap install juju' its broken, since it needs to use the juju included w/ conjure-up.

@adam-stokes
Copy link
Contributor

@donbowman I think the recent 2.3.6 snap juju had issues with downloading its agent. Normally, if snap juju is installed prior to conjure-up, conjure-up will use that instead. But, yes, juju and jujud are both packaged within conjure-up, so it wouldn't have been affected by the recent 2.3.6 and simplestreams issue.

@glortho
Copy link

glortho commented Apr 24, 2018

Yeah I used the included juju. I did:

$ snap install lxd
$ snap install conjure-up --classic
$ lxd init
$ # cmds to add my user to lxd group etc
$ conjure-up canonical-kubernetes localhost

Ubuntu 16.04
lxd 3.0.0
conjure-up 2.5.6
juju 2.3.3-xenial-amd64

@akevinbailey
Copy link

akevinbailey commented May 3, 2018

I am having the same issue as donbowman on the released version of Ubuntu 18.04. Has anyone figured how to make conjure-up work on localhost? I even enabled sudo with no password, but it still gets stuck at "Waiting for deployment to settle."

@sanjeevshar
Copy link

I am facing the same issue. I have tried Ubuntu 18.04 as well as 16.04. The master node just sits there for ever in 'Waiting for deployment to settle'.
The last message in log file is given below. I cannot figure out what it is waiting for.

2018-05-09 17:43:47,707 [DEBUG] conjure-up/canonical-kubernetes - events.py:53 - Received DeploymentComplete at conjureup/controllers/deploy/common.py:30 in task _wait_for_applications at conjureup/controllers/deploy/gui.py:82
2018-05-09 17:43:47,708 [INFO] conjure-up/canonical-kubernetes - common.py:36 - Waiting for deployment to settle.

@adam-stokes
Copy link
Contributor

What does juju status look like?

@sanjeevshar
Copy link

Model Controller Cloud/Region Version SLA
conjure-canonical-kubern-399 conjure-up-localhost-eb0 localhost/localhost 2.3.3 unsupported

App Version Status Scale Charm Store Rev OS Notes
easyrsa 3.0.1 active 1 easyrsa jujucharms 40 ubuntu
etcd 3.2.9 active 3 etcd jujucharms 80 ubuntu
flannel 0.9.1 active 4 flannel jujucharms 56 ubuntu
kubeapi-load-balancer 1.10.3 active 1 kubeapi-load-balancer jujucharms 58 ubuntu exposed
kubernetes-master 1.10.2 waiting 1 kubernetes-master jujucharms 104 ubuntu
kubernetes-worker 1.10.2 active 3 kubernetes-worker jujucharms 118 ubuntu exposed

Unit Workload Agent Machine Public address Ports Message
easyrsa/0* active idle 0 10.32.13.105 Certificate Authority connected.
etcd/0* active idle 1 10.32.13.57 2379/tcp Healthy with 3 known peers
etcd/1 active idle 2 10.32.13.48 2379/tcp Healthy with 3 known peers
etcd/2 active idle 3 10.32.13.241 2379/tcp Healthy with 3 known peers
kubeapi-load-balancer/0* active idle 4 10.32.13.124 443/tcp Loadbalancer ready.
kubernetes-master/0 waiting idle 5 10.32.13.21 6443/tcp Waiting for kube-system pods to start*
flannel/0* active idle 10.32.13.21 Flannel subnet 10.1.87.1/24
kubernetes-worker/0 active idle 6 10.32.13.94 80/tcp,443/tcp Kubernetes worker running.
flannel/3 active idle 10.32.13.94 Flannel subnet 10.1.64.1/24
kubernetes-worker/1* active idle 7 10.32.13.12 80/tcp,443/tcp Kubernetes worker running.
flannel/2 active idle 10.32.13.12 Flannel subnet 10.1.48.1/24
kubernetes-worker/2 active idle 8 10.32.13.53 80/tcp,443/tcp Kubernetes worker running.
flannel/1 active idle 10.32.13.53 Flannel subnet 10.1.38.1/24

Machine State DNS Inst id Series AZ Message
0 started 10.32.13.105 juju-09d9d4-0 xenial Running
1 started 10.32.13.57 juju-09d9d4-1 xenial Running
2 started 10.32.13.48 juju-09d9d4-2 xenial Running
3 started 10.32.13.241 juju-09d9d4-3 xenial Running
4 started 10.32.13.124 juju-09d9d4-4 xenial Running
5 started 10.32.13.21 juju-09d9d4-5 xenial Running
6 started 10.32.13.94 juju-09d9d4-6 xenial Running
7 started 10.32.13.12 juju-09d9d4-7 xenial Running
8 started 10.32.13.53 juju-09d9d4-8 xenial Running

Relation provider Requirer Interface Type Message
easyrsa:client etcd:certificates tls-certificates regular
easyrsa:client kubeapi-load-balancer:certificates tls-certificates regular
easyrsa:client kubernetes-master:certificates tls-certificates regular
easyrsa:client kubernetes-worker:certificates tls-certificates regular
etcd:cluster etcd:cluster etcd peer
etcd:db flannel:etcd etcd regular
etcd:db kubernetes-master:etcd etcd regular
kubeapi-load-balancer:loadbalancer kubernetes-master:loadbalancer public-address regular
kubeapi-load-balancer:website kubernetes-worker:kube-api-endpoint http regular
kubernetes-master:cni flannel:cni kubernetes-cni subordinate
kubernetes-master:kube-api-endpoint kubeapi-load-balancer:apiserver http regular
kubernetes-master:kube-control kubernetes-worker:kube-control kube-control regular
kubernetes-worker:cni flannel:cni kubernetes-cni subordinate

@adam-stokes
Copy link
Contributor

@Cynerva these issues seem to be around waiting for pods to start

@Cynerva
Copy link

Cynerva commented May 10, 2018

@sanjeevshar Can you run the cdk-field-agent script and attach the archive it creates? https://github.com/juju-solutions/cdk-field-agent

@sanjeevshar
Copy link

@Cynerva I am having trouble running the python script. It is constantly throwing error msg:
ERROR open /home/test/.local/share/juju/controllers.yaml: permission denied
Error checking action output. Ignoring.
ERROR open /home/test/.local/share/juju/controllers.yaml: permission denied
Error checking action output. Ignoring.

I looked for the file controllers.yaml. It is there but with only root has permission for it. Not sure how that happened.

test@dsib2041:~/.local/share/juju$ ls -l
total 52
-rw------- 1 test test 261 May 10 10:21 accounts.yaml
-rw------- 1 test test 4505 May 10 10:21 bootstrap-config.yaml
-rw------- 1 test test 486 May 8 09:46 clouds.yaml
-rw------- 1 root root 3573 May 10 13:10 controllers.yaml
drwx------ 2 test test 4096 May 10 13:10 cookies
-rw-rw-r-- 1 test test 428 May 8 09:46 credentials.yaml
drwx------ 2 test test 4096 May 8 11:38 lxd
-rw------- 1 test test 537 May 10 10:21 models.yaml
-rw------- 1 test test 8942 May 6 08:28 public-clouds.yaml
drwx------ 2 test test 4096 May 6 08:28 ssh

Conjure-up kubernetes has failed and last error msg in log file is:

2018-05-10 10:26:31,295 [INFO] conjure-up/canonical-kubernetes - common.py:36 - Waiting for deployment to settle.
2018-05-10 13:09:56,582 [ERROR] conjure-up/canonical-kubernetes - juju.py:741 - DEBUG:root:kubernetes-worker/0 juju agent status is executing since 2018-05-10 17:09:15Z
DEBUG:root:kubernetes-master/0 workload status is waiting since 2018-05-10 17:05:50Z
DEBUG:root:kubernetes-worker/0 juju agent status is executing since 2018-05-10 17:09:15Z
DEBUG:root:kubernetes-master/0 workload status is waiting since 2018-05-10 17:05:50Z
DEBUG:root:kubernetes-worker/0 juju agent status is executing since 2018-05-10 17:09:15Z
DEBUG:root:kubernetes-master/0 workload status is waiting since 2018-05-10 17:05:50Z
DEBUG:root:kubernetes-worker/0 juju agent status is executing since 2018-05-10 17:09:15Z
ERROR:root:ERROR open /home/test/.local/share/juju/controllers.yaml: permission denied

ERROR:root:juju status --format=json failed: 1
2018-05-10 13:09:56,591 [DEBUG] conjure-up/canonical-kubernetes - events.py:53 - Setting Error at conjureup/events.py:148
2018-05-10 13:09:56,592 [ERROR] conjure-up/canonical-kubernetes - events.py:160 - Unhandled exception in <Task finished coro=<DeployController._wait_for_applications() done, d efined at /snap/conjure-up/987/lib/python3.6/site-packages/conjureup/controllers/deploy/gui.py:81> exception=DeploymentFailure('Some applications failed to start successfully. ',)>

@Cynerva
Copy link

Cynerva commented May 10, 2018

Huh, that's weird. No idea what would cause the permissions on controllers.yaml to change like that. Can you try changing the owner back and running the cdk-field-agent script again?

sudo chown test:test /home/test/.local/share/juju/controllers.yaml

If the permission changes back to root:root again, let us know and we can try to figure out how to reproduce it. Thanks.

@sanjeevshar
Copy link

@Cynerva Tar file is too big (33MB) . I will clean out the system try again so that logs are not that big.

@sanjeevshar
Copy link

@Cynerva : Please find tarball of logs

results-2018-05-10-20-14-04.tar.gz

@Cynerva
Copy link

Cynerva commented May 11, 2018

Thanks @sanjeevshar. The relevant error comes from results-2018-05-10-20-14-04.tar.gz/debug/kubernetes-master/0/debug-20180511001210.tar.gz/kubectl/describe-pods:

Failed create pod sandbox: rpc error: code = Unknown desc = failed pulling image "k8s.gcr.io/pause-amd64:3.1": error pulling image configuration: Get https://storage.googleapis.com/us.artifacts.google-containers.appspot.com/containers/images/sha256:da86e6ba6ca197bf6bc5e9d900febd906b133eaa4750e6bed647b0fbe50ed43e: x509: certificate signed by unknown authority

All of the pods are hitting this fatal error, which is why kubernetes-master is stuck on Waiting for kube-system pods to start.

The error originates from dockerd on the kubernetes-worker units trying to reach storage.googleapis.com. All three kubernetes-worker units are hitting this error, so it seems to a problem with the environment.

@sanjeevshar Can you think of anything in your environment that could be intercepting docker's traffic to storage.googleapis.com? One thing that could help narrow this down is to try hitting that URL directly with curl, both on your host machine:

curl https://storage.googleapis.com/us.artifacts.google-containers.appspot.com/containers/images/sha256:da86e6ba6ca197bf6bc5e9d900febd906b133eaa4750e6bed647b0fbe50ed43e

and on one of the kubernetes-worker units:

juju ssh kubernetes-worker/0 curl https://storage.googleapis.com/us.artifacts.google-containers.appspot.com/containers/images/sha256:da86e6ba6ca197bf6bc5e9d900febd906b133eaa4750e6bed647b0fbe50ed43e

@adam-stokes adam-stokes changed the title Conjure-up hangs 'Waiting for deployment to settle' on kubernetes-core conjure-up hangs 'Waiting for deployment to settle' on kubernetes May 11, 2018
@sanjeevshar
Copy link

Thank you @Cynerva for taking time to look into it. It could be the corporate firewall. Let me check with IT and get back to you. I have a couple of questions though:

  1. Why is the script stuck and waiting for ever.
  2. Is there a workaround to get the required images outside the firewall and then copy them to server.

Thanks,

@sanjeevshar
Copy link

sanjeevshar commented May 11, 2018

curl with -k options seems to work but not otherwise

test@dsib2041:~$ curl https://storage.googleapis.com/us.artifacts.google-containers.appspot.com/containers/images/sha256:da86e6ba6ca197bf6bc5e9d900febd906b133eaa4750e6bed647b0fbe50ed43e
curl: (60) server certificate verification failed. CAfile: /etc/ssl/certs/ca-certificates.crt CRLfile: none
More details here: http://curl.haxx.se/docs/sslcerts.html

curl performs SSL certificate verification by default, using a "bundle"
 of Certificate Authority (CA) public keys (CA certs). If the default
 bundle file isn't adequate, you can specify an alternate file
 using the --cacert option.
If this HTTPS server uses a certificate signed by a CA represented in
 the bundle, the certificate verification probably failed due to a
 problem with the certificate (it might be expired, or the name might
 not match the domain name in the URL).
If you'd like to turn off curl's verification of the certificate, use
 the -k (or --insecure) option.

test@dsib2041:~$ curl -k https://storage.googleapis.com/us.artifacts.google-containers.appspot.com/containers/images/sha256:da86e6ba6ca197bf6bc5e9d900febd906b133eaa4750e6bed647b0fbe50ed43e
{"architecture":"amd64","config":{"Hostname":"9db6cde0ad7d","Domainname":"","User":"","AttachStdin":false,"AttachStdout":false,"AttachStderr":false,"Tty":false,"OpenStdin":false,"StdinOnce":false,"Env":["PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin"],"Cmd":null,"Image":"sha256:74a6e7300b7ff8333ea656f25ad2b2265b2030c767f82b82caa1560fc79ba9e4","Volumes":null,"WorkingDir":"","Entrypoint":["/pause"],"OnBuild":null,"Labels":{}},"container":"6e8c19e19d01f14a61e759b48101b00ba4003a1296b24a37ee2a3acce1c81f10","container_config":{"Hostname":"9db6cde0ad7d","Domainname":"","User":"","AttachStdin":false,"AttachStdout":false,"AttachStderr":false,"Tty":false,"OpenStdin":false,"StdinOnce":false,"Env":["PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin"],"Cmd":["/bin/sh","-c","#(nop) ","ENTRYPOINT [\"/pause\"]"],"Image":"sha256:74a6e7300b7ff8333ea656f25ad2b2265b2030c767f82b82caa1560fc79ba9e4","Volumes":null,"WorkingDir":"","Entrypoint":["/pause"],"OnBuild":null,"Labels":{}},"created":"2017-12-20T21:30:49.042210931Z","docker_version":"1.12.6","history":[{"created":"2017-12-20T21:30:48.622313805Z","created_by":"/bin/sh -c #(nop)  ARG ARCH","empty_layer":true},{"created":"2017-12-20T21:30:48.882412845Z","created_by":"/bin/sh -c #(nop) ADD file:0ea44d6c15daeb79d82d9893fdb53f247597e526721d54e2f16a798c51dcfb81 in /pause "},{"created":"2017-12-20T21:30:49.042210931Z","created_by":"/bin/sh -c #(nop)  ENTRYPOINT [\"/pause\"]","empty_layer":true}],"os":"linux","rootfs":{"type":"layers","diff_ids":["sha256:e17133b79956ad6f69ae7f775badd1c11bad2fc64f0529cab863b9d12fbaa5c4"]}}test@dsib2041:~$

@adam-stokes
Copy link
Contributor

@sanjeevshar This seems to indicate an issue between your corporate firewall. Can you verify with IT on getting necessary access?

@Cynerva
Copy link

Cynerva commented May 11, 2018

@sanjeevshar

  1. Why is the script stuck and waiting for ever.

The conjure-up spell is waiting for all of the units to indicate that they're "ready" (workload status is "active" and agent status is "idle"). The kubernetes-master unit never enters an "active" state because the pods aren't coming up.

I realize this isn't a very good signal to you, the user; ideally, something would tell you, directly, that the deployment has failed. But the variety of underlying issues we've seen is so large that we can't really account for all of them. Some of those issues are temporary and resolve themselves, while others are permanent and require user intervention.

In short: kubernetes-master can't tell the difference between "the pods aren't up yet" and "the pods will never come up."

  1. Is there a workaround to get the required images outside the firewall and then copy them to server.

Yes, but it's not easy. You will need to host a docker registry, upload the images to it, and tweak several configurations to make Kubernetes use your registry instead of k8s.gcr.io. The best I can do is refer you to our Running CDK in a restricted environment document.

@Cynerva
Copy link

Cynerva commented May 11, 2018

@sanjeevshar Thanks for the curl output. The -k requirement is definitely unusual. Can you run the same curl command with -v and share the SSL output? For example, I see this when I run it:

$ curl -k -v https://storage.googleapis.com/us.artifacts.google-containers.appspot.com/containers/images/sha256:da86e6ba6ca197bf6bc5e9d900febd906b133eaa4750e6bed647b0fbe50ed43e
...
* SSL connection using TLS1.2 / ECDHE_RSA_AES_128_GCM_SHA256
* 	 server certificate verification SKIPPED
* 	 server certificate status verification SKIPPED
* 	 common name: *.storage.googleapis.com (matched)
* 	 server certificate expiration date OK
* 	 server certificate activation date OK
* 	 certificate public key: RSA
* 	 certificate version: #3
* 	 subject: C=US,ST=California,L=Mountain View,O=Google LLC,CN=*.storage.googleapis.com
* 	 start date: Tue, 24 Apr 2018 10:20:47 GMT
* 	 expire date: Tue, 17 Jul 2018 09:26:00 GMT
* 	 issuer: C=US,O=Google Trust Services,CN=Google Internet Authority G3
* 	 compression: NULL
...

@luisenrike
Copy link

Hi, I'm having the same problem. I did a juju ssh kubernetes-master/0 and then sudo pastebinit /var/log/juju/unit-kubernetes-master-0.log and this is what I got: http://paste.ubuntu.com/p/knsmzqvrqW/

2018-05-12 04:07:32 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-05-12 04:07:32 DEBUG update-status Traceback (most recent call last):
2018-05-12 04:07:32 DEBUG update-status   File "/snap/cdk-addons/396/apply", line 119, in <module>
2018-05-12 04:07:32 DEBUG update-status     main()
2018-05-12 04:07:32 DEBUG update-status   File "/snap/cdk-addons/396/apply", line 13, in main
2018-05-12 04:07:32 DEBUG update-status     render_templates()
2018-05-12 04:07:32 DEBUG update-status   File "/snap/cdk-addons/396/apply", line 19, in render_templates
2018-05-12 04:07:32 DEBUG update-status     node_count = get_node_count()
2018-05-12 04:07:32 DEBUG update-status   File "/snap/cdk-addons/396/apply", line 103, in get_node_count
2018-05-12 04:07:32 DEBUG update-status     output = kubectl("get", "nodes", "-o", "name")
2018-05-12 04:07:32 DEBUG update-status   File "/snap/cdk-addons/396/apply", line 99, in kubectl
2018-05-12 04:07:32 DEBUG update-status     return subprocess.check_output(cmd)
2018-05-12 04:07:32 DEBUG update-status   File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-05-12 04:07:32 DEBUG update-status     **kwargs).stdout
2018-05-12 04:07:32 DEBUG update-status   File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-05-12 04:07:32 DEBUG update-status     output=stdout, stderr=stderr)
2018-05-12 04:07:32 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/396/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-05-12 04:07:32 INFO juju-log Addons are not ready yet.
2018-05-12 04:07:35 INFO juju-log Invoking reactive handler: reactive/tls_client.py:36:store_server
2018-05-12 04:07:36 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:577:send_data
2018-05-12 04:07:37 INFO juju-log Invoking reactive handler: reactive/tls_client.py

@ghost
Copy link

ghost commented May 14, 2018

@Cynerva I'm having the same issue but it doesn't seem to be firewall related 'coz curl above worked. Master is 'waiting on pods to start'. Here is my juju status and the debug results from the cdk field agent.

Any help to point me in the right direction would be appreciated.
jujudebug.tar.gz

Model Controller Cloud/Region Version SLA
conjure-canonical-kubern-5c0 conjure-up-localhost-306 localhost/localhost 2.3.3 unsupported

App Version Status Scale Charm Store Rev OS Notes
canal 0.9.1/2.5.1 active 4 canal jujucharms 34 ubuntu
easyrsa 3.0.1 active 1 easyrsa jujucharms 40 ubuntu
etcd 3.2.9 active 3 etcd jujucharms 80 ubuntu
grafana active 1 grafana jujucharms 7 ubuntu exposed
kubeapi-load-balancer 1.10.3 active 1 kubeapi-load-balancer jujucharms 58 ubuntu exposed
kubernetes-master 1.10.2 waiting 1 kubernetes-master jujucharms 104 ubuntu
kubernetes-worker 1.10.2 active 3 kubernetes-worker jujucharms 118 ubuntu exposed
prometheus active 1 prometheus jujucharms 5 ubuntu
telegraf active 4 telegraf jujucharms 9 ubuntu

Unit Workload Agent Machine Public address Ports Message
easyrsa/0* active idle 0 10.24.95.211 Certificate Authority connected.
etcd/0* active idle 1 10.24.95.46 2379/tcp Healthy with 3 known peers
etcd/1 active idle 2 10.24.95.19 2379/tcp Healthy with 3 known peers
etcd/2 active idle 3 10.24.95.90 2379/tcp Healthy with 3 known peers
grafana/0* active idle 4 10.24.95.27 3000/tcp Started grafana-server
kubeapi-load-balancer/0* active idle 5 10.24.95.53 443/tcp Loadbalancer ready.
kubernetes-master/0* waiting idle 6 10.24.95.171 6443/tcp Waiting for kube-system pods to start
canal/0* active idle 10.24.95.171 Flannel subnet 10.2.37.1/24
telegraf/0* active idle 10.24.95.171 9103/tcp Monitoring kubernetes-master/0
kubernetes-worker/0 active idle 7 10.24.95.14 80/tcp,443/tcp Kubernetes worker running.
canal/1 active idle 10.24.95.14 Flannel subnet 10.2.45.1/24
telegraf/1 active idle 10.24.95.14 9103/tcp Monitoring kubernetes-worker/0
kubernetes-worker/1 active idle 8 10.24.95.240 80/tcp,443/tcp Kubernetes worker running.
canal/3 active idle 10.24.95.240 Flannel subnet 10.2.7.1/24
telegraf/3 active idle 10.24.95.240 9103/tcp Monitoring kubernetes-worker/1
kubernetes-worker/2* active idle 9 10.24.95.175 80/tcp,443/tcp Kubernetes worker running.
canal/2 active idle 10.24.95.175 Flannel subnet 10.2.46.1/24
telegraf/2 active idle 10.24.95.175 9103/tcp Monitoring kubernetes-worker/2
prometheus/0* active idle 10 10.24.95.176 9090/tcp,12321/tcp Ready

Machine State DNS Inst id Series AZ Message
0 started 10.24.95.211 juju-5b0357-0 xenial Running
1 started 10.24.95.46 juju-5b0357-1 xenial Running
2 started 10.24.95.19 juju-5b0357-2 xenial Running
3 started 10.24.95.90 juju-5b0357-3 xenial Running
4 started 10.24.95.27 juju-5b0357-4 xenial Running
5 started 10.24.95.53 juju-5b0357-5 xenial Running
6 started 10.24.95.171 juju-5b0357-6 xenial Running
7 started 10.24.95.14 juju-5b0357-7 xenial Running
8 started 10.24.95.240 juju-5b0357-8 xenial Running
9 started 10.24.95.175 juju-5b0357-9 xenial Running
10 started 10.24.95.176 juju-5b0357-10 xenial Running

Relation provider Requirer Interface Type Message
easyrsa:client etcd:certificates tls-certificates regular
easyrsa:client kubeapi-load-balancer:certificates tls-certificates regular
easyrsa:client kubernetes-master:certificates tls-certificates regular
easyrsa:client kubernetes-worker:certificates tls-certificates regular
etcd:cluster etcd:cluster etcd peer
etcd:db canal:etcd etcd regular
etcd:db kubernetes-master:etcd etcd regular
kubeapi-load-balancer:loadbalancer kubernetes-master:loadbalancer public-address regular
kubeapi-load-balancer:website kubernetes-worker:kube-api-endpoint http regular
kubernetes-master:cni canal:cni kubernetes-cni subordinate
kubernetes-master:juju-info telegraf:juju-info juju-info subordinate
kubernetes-master:kube-api-endpoint kubeapi-load-balancer:apiserver http regular
kubernetes-master:kube-control kubernetes-worker:kube-control kube-control regular
kubernetes-worker:cni canal:cni kubernetes-cni subordinate
kubernetes-worker:juju-info telegraf:juju-info juju-info subordinate
prometheus:grafana-source grafana:grafana-source grafana-source regular
telegraf:prometheus-client prometheus:target http regular

@adam-stokes
Copy link
Contributor

#1430

@Cynerva
Copy link

Cynerva commented May 14, 2018

@vadoverde Calico/canal doesn't work with the localhost/LXD cloud because it uses and requires privileged Docker containers, which do not work in LXD. I'm pretty sure that's the underlying cause of your issue.

In particular, I expect the calico-node service is failing to start. You should be able to confirm it with the following command (assuming I didn't mistype something):

juju ssh kubernetes-master/0 journalctl -o cat -u calico-node

Edit: Actually, I can confirm this from your attached debug log (thanks for that btw):

May 14 02:22:04 juju-5b0357-6 docker[17759]: /usr/bin/docker: Error response from daemon: linux runtime spec devices: lstat /dev/.lxc/proc/2207/fdinfo/16: no such file or directory.
May 14 02:22:04 juju-5b0357-6 systemd[1]: calico-node.service: Main process exited, code=exited, status=127/n/a
May 14 02:22:05 juju-5b0357-6 docker[17791]: calico-node
May 14 02:22:05 juju-5b0357-6 systemd[1]: calico-node.service: Unit entered failed state.
May 14 02:22:05 juju-5b0357-6 systemd[1]: calico-node.service: Failed with result 'exit-code'.

@battlemidget Is there a way for us to make it where calico/canal isn't selectable in the spell when deploying to localhost?

@Cynerva
Copy link

Cynerva commented May 14, 2018

@luisenrike Most likely the error you're seeing indicates that kube-apiserver isn't up. You can check by doing juju ssh kubernetes-master/0 and then journalctl -o cat -u snap.kube-apiserver.daemon. Output of that would help us diagnose this.

Alternatively, feel free to run cdk-field-agent and attach the archive it creates since that includes more comprehensive info that will help us get to the root of this quicker.

@adam-stokes
Copy link
Contributor

@battlemidget Is there a way for us to make it where calico/canal isn't selectable in the spell when deploying to localhost?

Yea ill look into that

@sanjeevshar
Copy link

@Cynerva : I finally got the firewall issue resolved but kubernetes-master/0 is still in "Waiting for kube-system pods to start".
Here are the contents of /var/log/juju/unit-kubernetes-master-0.log:
http://paste.ubuntu.com/p/xv9d2wHQXC/

2018-06-23 23:07:16 INFO juju-log kubedns not ready yet
2018-06-23 23:07:16 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:664:configure_cdk_addons
2018-06-23 23:07:16 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:07:16 DEBUG config-changed snap-update-ns failed with code 1
2018-06-23 23:07:16 ERROR juju-log Hook error:
Traceback (most recent call last):
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
bus.dispatch(restricted=restricted_mode)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
_invoke(other_handlers)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
handler.invoke()
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
self._action(*args)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 669, in configure_cdk_addons
gpuEnable = (get_version('kube-apiserver') >= (1, 9) and
File "lib/charms/kubernetes/common.py", line 36, in get_version
version_string = subprocess.check_output(cmd).decode('utf-8')
File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
**kwargs).stdout
File "/usr/lib/python3.5/subprocess.py", line 708, in run
output=stdout, stderr=stderr)
subprocess.CalledProcessError: Command '['kube-apiserver', '--version']' returned non-zero exit status 1

2018-06-23 23:07:16 DEBUG config-changed Traceback (most recent call last):
2018-06-23 23:07:16 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/hooks/config-changed", line 19, in
2018-06-23 23:07:16 DEBUG config-changed main()
2018-06-23 23:07:16 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
2018-06-23 23:07:16 DEBUG config-changed bus.dispatch(restricted=restricted_mode)
2018-06-23 23:07:16 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
2018-06-23 23:07:16 DEBUG config-changed _invoke(other_handlers)
2018-06-23 23:07:16 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
2018-06-23 23:07:16 DEBUG config-changed handler.invoke()
2018-06-23 23:07:16 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
2018-06-23 23:07:16 DEBUG config-changed self._action(*args)
2018-06-23 23:07:16 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 669, in configure_cdk_addons
2018-06-23 23:07:16 DEBUG config-changed gpuEnable = (get_version('kube-apiserver') >= (1, 9) and
2018-06-23 23:07:16 DEBUG config-changed File "lib/charms/kubernetes/common.py", line 36, in get_version
2018-06-23 23:07:16 DEBUG config-changed version_string = subprocess.check_output(cmd).decode('utf-8')
2018-06-23 23:07:16 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-23 23:07:16 DEBUG config-changed **kwargs).stdout
2018-06-23 23:07:16 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-23 23:07:16 DEBUG config-changed output=stdout, stderr=stderr)
2018-06-23 23:07:16 DEBUG config-changed subprocess.CalledProcessError: Command '['kube-apiserver', '--version']' returned non-zero exit status 1
2018-06-23 23:07:16 ERROR juju.worker.uniter.operation runhook.go:113 hook "config-changed" failed: exit status 1
2018-06-23 23:07:16 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:07:58 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:07:59 INFO juju-log Reactive main running for hook config-changed
2018-06-23 23:07:59 INFO juju-log Initializing Snap Layer
2018-06-23 23:07:59 DEBUG config-changed lxc
2018-06-23 23:07:59 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-23 23:07:59 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:501:etcd_data_change
2018-06-23 23:08:00 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:582:push_service_data
2018-06-23 23:08:01 INFO juju-log Invoking reactive handler: hooks/relations/gcp/requires.py:116:remove_ready:gcp
2018-06-23 23:08:01 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:527:send_cluster_dns_detail
2018-06-23 23:08:01 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:08:01 INFO juju-log kubedns not ready yet
2018-06-23 23:08:01 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:721:create_self_config
2018-06-23 23:08:01 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:08:01 ERROR juju-log Hook error:
Traceback (most recent call last):
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
bus.dispatch(restricted=restricted_mode)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
_invoke(other_handlers)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
handler.invoke()
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
self._action(*args)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 727, in create_self_config
build_kubeconfig(server)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1006, in build_kubeconfig
user='admin', password=client_pass)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1029, in create_kubeconfig
check_call(split(cmd.format(kubeconfig, cluster, server, ca)))
File "/usr/lib/python3.5/subprocess.py", line 581, in check_call
raise CalledProcessError(retcode, cmd)
subprocess.CalledProcessError: Command '['kubectl', 'config', '--kubeconfig=/home/ubuntu/config', 'set-cluster', 'juju-cluster', '--server=https://10.143.127.215:6443', '--certificate-authority=/root/cdk/ca.crt', '--embed-certs=true']' returned non-zero exit status 1

2018-06-23 23:08:01 DEBUG config-changed Traceback (most recent call last):
2018-06-23 23:08:01 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/hooks/config-changed", line 19, in
2018-06-23 23:08:01 DEBUG config-changed main()
2018-06-23 23:08:01 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
2018-06-23 23:08:01 DEBUG config-changed bus.dispatch(restricted=restricted_mode)
2018-06-23 23:08:01 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
2018-06-23 23:08:01 DEBUG config-changed _invoke(other_handlers)
2018-06-23 23:08:01 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
2018-06-23 23:08:01 DEBUG config-changed handler.invoke()
2018-06-23 23:08:01 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
2018-06-23 23:08:01 DEBUG config-changed self._action(*args)
2018-06-23 23:08:01 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 727, in create_self_config
2018-06-23 23:08:01 DEBUG config-changed build_kubeconfig(server)
2018-06-23 23:08:01 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1006, in build_kubeconfig
2018-06-23 23:08:01 DEBUG config-changed user='admin', password=client_pass)
2018-06-23 23:08:01 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1029, in create_kubeconfig
2018-06-23 23:08:01 DEBUG config-changed check_call(split(cmd.format(kubeconfig, cluster, server, ca)))
2018-06-23 23:08:01 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 581, in check_call
2018-06-23 23:08:01 DEBUG config-changed raise CalledProcessError(retcode, cmd)
2018-06-23 23:08:01 DEBUG config-changed subprocess.CalledProcessError: Command '['kubectl', 'config', '--kubeconfig=/home/ubuntu/config', 'set-cluster', 'juju-cluster', '--server=https://10.143.127.215:6443', '--certificate-authority=/root/cdk/ca.crt', '--embed-certs=true']' returned non-zero exit status 1
2018-06-23 23:08:01 ERROR juju.worker.uniter.operation runhook.go:113 hook "config-changed" failed: exit status 1
2018-06-23 23:08:01 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:09:24 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:09:25 INFO juju-log Reactive main running for hook config-changed
2018-06-23 23:09:26 INFO juju-log Initializing Snap Layer
2018-06-23 23:09:26 DEBUG config-changed lxc
2018-06-23 23:09:26 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-23 23:09:26 INFO juju-log Invoking reactive handler: reactive/tls_client.py:60:store_client
2018-06-23 23:09:26 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:604:send_data
2018-06-23 23:09:27 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:582:push_service_data
2018-06-23 23:09:28 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:543:create_service_configs
2018-06-23 23:09:28 INFO juju-log Invoking reactive handler: hooks/relations/gcp/requires.py:116:remove_ready:gcp
2018-06-23 23:09:28 INFO juju-log Invoking reactive handler: reactive/tls_client.py:36:store_server
2018-06-23 23:09:29 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:721:create_self_config
2018-06-23 23:09:29 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:09:29 ERROR juju-log Hook error:
Traceback (most recent call last):
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
bus.dispatch(restricted=restricted_mode)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
_invoke(other_handlers)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
handler.invoke()
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
self._action(*args)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 727, in create_self_config
build_kubeconfig(server)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1006, in build_kubeconfig
user='admin', password=client_pass)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1029, in create_kubeconfig
check_call(split(cmd.format(kubeconfig, cluster, server, ca)))
File "/usr/lib/python3.5/subprocess.py", line 581, in check_call
raise CalledProcessError(retcode, cmd)
subprocess.CalledProcessError: Command '['kubectl', 'config', '--kubeconfig=/home/ubuntu/config', 'set-cluster', 'juju-cluster', '--server=https://10.143.127.215:6443', '--certificate-authority=/root/cdk/ca.crt', '--embed-certs=true']' returned non-zero exit status 1

2018-06-23 23:09:29 DEBUG config-changed Traceback (most recent call last):
2018-06-23 23:09:29 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/hooks/config-changed", line 19, in
2018-06-23 23:09:29 DEBUG config-changed main()
2018-06-23 23:09:29 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
2018-06-23 23:09:29 DEBUG config-changed bus.dispatch(restricted=restricted_mode)
2018-06-23 23:09:29 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
2018-06-23 23:09:29 DEBUG config-changed _invoke(other_handlers)
2018-06-23 23:09:29 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
2018-06-23 23:09:29 DEBUG config-changed handler.invoke()
2018-06-23 23:09:29 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
2018-06-23 23:09:29 DEBUG config-changed self._action(*args)
2018-06-23 23:09:29 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 727, in create_self_config
2018-06-23 23:09:29 DEBUG config-changed build_kubeconfig(server)
2018-06-23 23:09:29 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1006, in build_kubeconfig
2018-06-23 23:09:29 DEBUG config-changed user='admin', password=client_pass)
2018-06-23 23:09:29 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1029, in create_kubeconfig
2018-06-23 23:09:29 DEBUG config-changed check_call(split(cmd.format(kubeconfig, cluster, server, ca)))
2018-06-23 23:09:29 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 581, in check_call
2018-06-23 23:09:29 DEBUG config-changed raise CalledProcessError(retcode, cmd)
2018-06-23 23:09:29 DEBUG config-changed subprocess.CalledProcessError: Command '['kubectl', 'config', '--kubeconfig=/home/ubuntu/config', 'set-cluster', 'juju-cluster', '--server=https://10.143.127.215:6443', '--certificate-authority=/root/cdk/ca.crt', '--embed-certs=true']' returned non-zero exit status 1
2018-06-23 23:09:29 ERROR juju.worker.uniter.operation runhook.go:113 hook "config-changed" failed: exit status 1
2018-06-23 23:09:29 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:11:31 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:12:08 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:12:08 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:12:08 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:12:08 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:12:08 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:12:08 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:12:08 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:12:08 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:12:09 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:12:09 INFO juju-log Reactive main running for hook config-changed
2018-06-23 23:12:10 INFO juju-log Initializing Snap Layer
2018-06-23 23:12:10 DEBUG config-changed lxc
2018-06-23 23:12:10 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-23 23:12:10 INFO juju-log Invoking reactive handler: reactive/tls_client.py:36:store_server
2018-06-23 23:12:11 INFO juju-log Invoking reactive handler: reactive/tls_client.py:15:store_ca
2018-06-23 23:12:11 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:527:send_cluster_dns_detail
2018-06-23 23:12:11 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:12:11 INFO juju-log kubedns not ready yet
2018-06-23 23:12:11 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:582:push_service_data
2018-06-23 23:12:12 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:389:set_app_version
2018-06-23 23:12:12 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:12:12 DEBUG config-changed snap-update-ns failed with code 1
2018-06-23 23:12:12 ERROR juju-log Hook error:
Traceback (most recent call last):
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
bus.dispatch(restricted=restricted_mode)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
_invoke(other_handlers)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
handler.invoke()
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
self._action(*args)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 392, in set_app_version
version = check_output(['kube-apiserver', '--version'])
File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
**kwargs).stdout
File "/usr/lib/python3.5/subprocess.py", line 708, in run
output=stdout, stderr=stderr)
subprocess.CalledProcessError: Command '['kube-apiserver', '--version']' returned non-zero exit status 1

2018-06-23 23:12:12 DEBUG config-changed Traceback (most recent call last):
2018-06-23 23:12:12 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/hooks/config-changed", line 19, in
2018-06-23 23:12:12 DEBUG config-changed main()
2018-06-23 23:12:12 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
2018-06-23 23:12:12 DEBUG config-changed bus.dispatch(restricted=restricted_mode)
2018-06-23 23:12:12 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
2018-06-23 23:12:12 DEBUG config-changed _invoke(other_handlers)
2018-06-23 23:12:12 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
2018-06-23 23:12:12 DEBUG config-changed handler.invoke()
2018-06-23 23:12:12 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
2018-06-23 23:12:12 DEBUG config-changed self._action(*args)
2018-06-23 23:12:12 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 392, in set_app_version
2018-06-23 23:12:12 DEBUG config-changed version = check_output(['kube-apiserver', '--version'])
2018-06-23 23:12:12 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-23 23:12:12 DEBUG config-changed **kwargs).stdout
2018-06-23 23:12:12 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-23 23:12:12 DEBUG config-changed output=stdout, stderr=stderr)
2018-06-23 23:12:12 DEBUG config-changed subprocess.CalledProcessError: Command '['kube-apiserver', '--version']' returned non-zero exit status 1
2018-06-23 23:12:12 ERROR juju.worker.uniter.operation runhook.go:113 hook "config-changed" failed: exit status 1
2018-06-23 23:12:12 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:16:23 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:16:40 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:16:40 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:16:40 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:16:40 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:16:40 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:16:40 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:16:40 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:16:40 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:17:12 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:17:13 INFO juju-log Reactive main running for hook config-changed
2018-06-23 23:17:13 INFO juju-log Initializing Snap Layer
2018-06-23 23:17:13 DEBUG config-changed lxc
2018-06-23 23:17:13 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-23 23:17:14 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:527:send_cluster_dns_detail
2018-06-23 23:17:14 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:17:14 INFO juju-log kubedns not ready yet
2018-06-23 23:17:14 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:721:create_self_config
2018-06-23 23:17:14 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:17:14 ERROR juju-log Hook error:
Traceback (most recent call last):
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
bus.dispatch(restricted=restricted_mode)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
_invoke(other_handlers)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
handler.invoke()
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
self._action(*args)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 727, in create_self_config
build_kubeconfig(server)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1006, in build_kubeconfig
user='admin', password=client_pass)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1029, in create_kubeconfig
check_call(split(cmd.format(kubeconfig, cluster, server, ca)))
File "/usr/lib/python3.5/subprocess.py", line 581, in check_call
raise CalledProcessError(retcode, cmd)
subprocess.CalledProcessError: Command '['kubectl', 'config', '--kubeconfig=/home/ubuntu/config', 'set-cluster', 'juju-cluster', '--server=https://10.143.127.215:6443', '--certificate-authority=/root/cdk/ca.crt', '--embed-certs=true']' returned non-zero exit status 1

2018-06-23 23:17:14 DEBUG config-changed Traceback (most recent call last):
2018-06-23 23:17:14 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/hooks/config-changed", line 19, in
2018-06-23 23:17:14 DEBUG config-changed main()
2018-06-23 23:17:14 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
2018-06-23 23:17:14 DEBUG config-changed bus.dispatch(restricted=restricted_mode)
2018-06-23 23:17:14 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
2018-06-23 23:17:14 DEBUG config-changed _invoke(other_handlers)
2018-06-23 23:17:14 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
2018-06-23 23:17:14 DEBUG config-changed handler.invoke()
2018-06-23 23:17:14 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
2018-06-23 23:17:14 DEBUG config-changed self._action(*args)
2018-06-23 23:17:14 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 727, in create_self_config
2018-06-23 23:17:14 DEBUG config-changed build_kubeconfig(server)
2018-06-23 23:17:14 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1006, in build_kubeconfig
2018-06-23 23:17:14 DEBUG config-changed user='admin', password=client_pass)
2018-06-23 23:17:14 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1029, in create_kubeconfig
2018-06-23 23:17:14 DEBUG config-changed check_call(split(cmd.format(kubeconfig, cluster, server, ca)))
2018-06-23 23:17:14 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 581, in check_call
2018-06-23 23:17:14 DEBUG config-changed raise CalledProcessError(retcode, cmd)
2018-06-23 23:17:14 DEBUG config-changed subprocess.CalledProcessError: Command '['kubectl', 'config', '--kubeconfig=/home/ubuntu/config', 'set-cluster', 'juju-cluster', '--server=https://10.143.127.215:6443', '--certificate-authority=/root/cdk/ca.crt', '--embed-certs=true']' returned non-zero exit status 1
2018-06-23 23:17:14 ERROR juju.worker.uniter.operation runhook.go:113 hook "config-changed" failed: exit status 1
2018-06-23 23:17:14 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:20:39 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:22:14 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:22:14 INFO juju-log Reactive main running for hook config-changed
2018-06-23 23:22:15 INFO juju-log Initializing Snap Layer
2018-06-23 23:22:15 DEBUG config-changed lxc
2018-06-23 23:22:15 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-23 23:22:15 INFO juju-log Invoking reactive handler: hooks/relations/gcp/requires.py:116:remove_ready:gcp
2018-06-23 23:22:15 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:604:send_data
2018-06-23 23:22:16 INFO juju-log Invoking reactive handler: hooks/relations/aws/requires.py:106:remove_ready:aws
2018-06-23 23:22:16 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:543:create_service_configs
2018-06-23 23:22:17 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:664:configure_cdk_addons
2018-06-23 23:22:17 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:22:17 DEBUG config-changed snap-update-ns failed with code 1
2018-06-23 23:22:17 ERROR juju-log Hook error:
Traceback (most recent call last):
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
bus.dispatch(restricted=restricted_mode)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
_invoke(other_handlers)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
handler.invoke()
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
self._action(*args)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 669, in configure_cdk_addons
gpuEnable = (get_version('kube-apiserver') >= (1, 9) and
File "lib/charms/kubernetes/common.py", line 36, in get_version
version_string = subprocess.check_output(cmd).decode('utf-8')
File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
**kwargs).stdout
File "/usr/lib/python3.5/subprocess.py", line 708, in run
output=stdout, stderr=stderr)
subprocess.CalledProcessError: Command '['kube-apiserver', '--version']' returned non-zero exit status 1

2018-06-23 23:22:17 DEBUG config-changed Traceback (most recent call last):
2018-06-23 23:22:17 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/hooks/config-changed", line 19, in
2018-06-23 23:22:17 DEBUG config-changed main()
2018-06-23 23:22:17 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
2018-06-23 23:22:17 DEBUG config-changed bus.dispatch(restricted=restricted_mode)
2018-06-23 23:22:17 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
2018-06-23 23:22:17 DEBUG config-changed _invoke(other_handlers)
2018-06-23 23:22:17 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
2018-06-23 23:22:17 DEBUG config-changed handler.invoke()
2018-06-23 23:22:17 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
2018-06-23 23:22:17 DEBUG config-changed self._action(*args)
2018-06-23 23:22:17 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 669, in configure_cdk_addons
2018-06-23 23:22:17 DEBUG config-changed gpuEnable = (get_version('kube-apiserver') >= (1, 9) and
2018-06-23 23:22:17 DEBUG config-changed File "lib/charms/kubernetes/common.py", line 36, in get_version
2018-06-23 23:22:17 DEBUG config-changed version_string = subprocess.check_output(cmd).decode('utf-8')
2018-06-23 23:22:17 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-23 23:22:17 DEBUG config-changed **kwargs).stdout
2018-06-23 23:22:17 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-23 23:22:17 DEBUG config-changed output=stdout, stderr=stderr)
2018-06-23 23:22:17 DEBUG config-changed subprocess.CalledProcessError: Command '['kube-apiserver', '--version']' returned non-zero exit status 1
2018-06-23 23:22:17 ERROR juju.worker.uniter.operation runhook.go:113 hook "config-changed" failed: exit status 1
2018-06-23 23:22:17 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:22:22 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:22:22 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:22:22 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:22:22 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:22:22 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:22:22 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:22:22 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:22:22 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:25:50 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:27:17 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:27:18 INFO juju-log Reactive main running for hook config-changed
2018-06-23 23:27:18 INFO juju-log Initializing Snap Layer
2018-06-23 23:27:18 DEBUG config-changed lxc
2018-06-23 23:27:18 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-23 23:27:18 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:527:send_cluster_dns_detail
2018-06-23 23:27:18 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:27:18 INFO juju-log kubedns not ready yet
2018-06-23 23:27:19 INFO juju-log Invoking reactive handler: hooks/relations/gcp/requires.py:116:remove_ready:gcp
2018-06-23 23:27:19 INFO juju-log Invoking reactive handler: reactive/tls_client.py:15:store_ca
2018-06-23 23:27:19 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:721:create_self_config
2018-06-23 23:27:19 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:27:19 ERROR juju-log Hook error:
Traceback (most recent call last):
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
bus.dispatch(restricted=restricted_mode)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
_invoke(other_handlers)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
handler.invoke()
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
self._action(*args)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 727, in create_self_config
build_kubeconfig(server)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1006, in build_kubeconfig
user='admin', password=client_pass)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1029, in create_kubeconfig
check_call(split(cmd.format(kubeconfig, cluster, server, ca)))
File "/usr/lib/python3.5/subprocess.py", line 581, in check_call
raise CalledProcessError(retcode, cmd)
subprocess.CalledProcessError: Command '['kubectl', 'config', '--kubeconfig=/home/ubuntu/config', 'set-cluster', 'juju-cluster', '--server=https://10.143.127.215:6443', '--certificate-authority=/root/cdk/ca.crt', '--embed-certs=true']' returned non-zero exit status 1

2018-06-23 23:27:19 DEBUG config-changed Traceback (most recent call last):
2018-06-23 23:27:19 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/hooks/config-changed", line 19, in
2018-06-23 23:27:19 DEBUG config-changed main()
2018-06-23 23:27:19 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
2018-06-23 23:27:19 DEBUG config-changed bus.dispatch(restricted=restricted_mode)
2018-06-23 23:27:19 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
2018-06-23 23:27:19 DEBUG config-changed _invoke(other_handlers)
2018-06-23 23:27:19 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
2018-06-23 23:27:19 DEBUG config-changed handler.invoke()
2018-06-23 23:27:19 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
2018-06-23 23:27:19 DEBUG config-changed self._action(*args)
2018-06-23 23:27:19 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 727, in create_self_config
2018-06-23 23:27:19 DEBUG config-changed build_kubeconfig(server)
2018-06-23 23:27:19 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1006, in build_kubeconfig
2018-06-23 23:27:19 DEBUG config-changed user='admin', password=client_pass)
2018-06-23 23:27:19 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1029, in create_kubeconfig
2018-06-23 23:27:19 DEBUG config-changed check_call(split(cmd.format(kubeconfig, cluster, server, ca)))
2018-06-23 23:27:19 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 581, in check_call
2018-06-23 23:27:19 DEBUG config-changed raise CalledProcessError(retcode, cmd)
2018-06-23 23:27:19 DEBUG config-changed subprocess.CalledProcessError: Command '['kubectl', 'config', '--kubeconfig=/home/ubuntu/config', 'set-cluster', 'juju-cluster', '--server=https://10.143.127.215:6443', '--certificate-authority=/root/cdk/ca.crt', '--embed-certs=true']' returned non-zero exit status 1
2018-06-23 23:27:19 ERROR juju.worker.uniter.operation runhook.go:113 hook "config-changed" failed: exit status 1
2018-06-23 23:27:19 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:28:22 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:28:22 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:28:22 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:28:22 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:28:22 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:28:22 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:28:22 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:28:22 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:30:26 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:32:19 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:32:20 INFO juju-log Reactive main running for hook config-changed
2018-06-23 23:32:20 INFO juju-log Initializing Snap Layer
2018-06-23 23:32:20 DEBUG config-changed lxc
2018-06-23 23:32:20 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-23 23:32:21 INFO juju-log Invoking reactive handler: hooks/relations/aws/requires.py:106:remove_ready:aws
2018-06-23 23:32:21 INFO juju-log Invoking reactive handler: reactive/tls_client.py:15:store_ca
2018-06-23 23:32:21 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:604:send_data
2018-06-23 23:32:22 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:389:set_app_version
2018-06-23 23:32:22 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:32:22 DEBUG config-changed snap-update-ns failed with code 1
2018-06-23 23:32:22 ERROR juju-log Hook error:
Traceback (most recent call last):
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
bus.dispatch(restricted=restricted_mode)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
_invoke(other_handlers)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
handler.invoke()
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
self._action(*args)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 392, in set_app_version
version = check_output(['kube-apiserver', '--version'])
File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
**kwargs).stdout
File "/usr/lib/python3.5/subprocess.py", line 708, in run
output=stdout, stderr=stderr)
subprocess.CalledProcessError: Command '['kube-apiserver', '--version']' returned non-zero exit status 1

2018-06-23 23:32:22 DEBUG config-changed Traceback (most recent call last):
2018-06-23 23:32:22 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/hooks/config-changed", line 19, in
2018-06-23 23:32:22 DEBUG config-changed main()
2018-06-23 23:32:22 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
2018-06-23 23:32:22 DEBUG config-changed bus.dispatch(restricted=restricted_mode)
2018-06-23 23:32:22 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
2018-06-23 23:32:22 DEBUG config-changed _invoke(other_handlers)
2018-06-23 23:32:22 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
2018-06-23 23:32:22 DEBUG config-changed handler.invoke()
2018-06-23 23:32:22 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
2018-06-23 23:32:22 DEBUG config-changed self._action(*args)
2018-06-23 23:32:22 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 392, in set_app_version
2018-06-23 23:32:22 DEBUG config-changed version = check_output(['kube-apiserver', '--version'])
2018-06-23 23:32:22 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-23 23:32:22 DEBUG config-changed **kwargs).stdout
2018-06-23 23:32:22 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-23 23:32:22 DEBUG config-changed output=stdout, stderr=stderr)
2018-06-23 23:32:22 DEBUG config-changed subprocess.CalledProcessError: Command '['kube-apiserver', '--version']' returned non-zero exit status 1
2018-06-23 23:32:22 ERROR juju.worker.uniter.operation runhook.go:113 hook "config-changed" failed: exit status 1
2018-06-23 23:32:22 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:33:13 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:33:13 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:33:13 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:33:13 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:33:13 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:33:13 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:33:13 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:33:13 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:34:43 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:37:21 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:37:21 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:37:21 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:37:21 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:37:21 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:37:21 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:37:21 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:37:21 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:37:22 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:37:23 INFO juju-log Reactive main running for hook config-changed
2018-06-23 23:37:23 INFO juju-log Initializing Snap Layer
2018-06-23 23:37:23 DEBUG config-changed lxc
2018-06-23 23:37:23 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-23 23:37:23 INFO juju-log Invoking reactive handler: hooks/relations/aws/requires.py:106:remove_ready:aws
2018-06-23 23:37:24 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:543:create_service_configs
2018-06-23 23:37:24 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:501:etcd_data_change
2018-06-23 23:37:24 INFO juju-log Invoking reactive handler: reactive/tls_client.py:36:store_server
2018-06-23 23:37:25 INFO juju-log Invoking reactive handler: reactive/tls_client.py:15:store_ca
2018-06-23 23:37:25 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:604:send_data
2018-06-23 23:37:26 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:527:send_cluster_dns_detail
2018-06-23 23:37:26 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:37:26 INFO juju-log kubedns not ready yet
2018-06-23 23:37:26 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:389:set_app_version
2018-06-23 23:37:26 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:37:26 DEBUG config-changed snap-update-ns failed with code 1
2018-06-23 23:37:26 ERROR juju-log Hook error:
Traceback (most recent call last):
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
bus.dispatch(restricted=restricted_mode)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
_invoke(other_handlers)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
handler.invoke()
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
self._action(*args)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 392, in set_app_version
version = check_output(['kube-apiserver', '--version'])
File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
**kwargs).stdout
File "/usr/lib/python3.5/subprocess.py", line 708, in run
output=stdout, stderr=stderr)
subprocess.CalledProcessError: Command '['kube-apiserver', '--version']' returned non-zero exit status 1

2018-06-23 23:37:26 DEBUG config-changed Traceback (most recent call last):
2018-06-23 23:37:26 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/hooks/config-changed", line 19, in
2018-06-23 23:37:26 DEBUG config-changed main()
2018-06-23 23:37:26 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
2018-06-23 23:37:26 DEBUG config-changed bus.dispatch(restricted=restricted_mode)
2018-06-23 23:37:26 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
2018-06-23 23:37:26 DEBUG config-changed _invoke(other_handlers)
2018-06-23 23:37:26 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
2018-06-23 23:37:26 DEBUG config-changed handler.invoke()
2018-06-23 23:37:26 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
2018-06-23 23:37:26 DEBUG config-changed self._action(*args)
2018-06-23 23:37:26 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 392, in set_app_version
2018-06-23 23:37:26 DEBUG config-changed version = check_output(['kube-apiserver', '--version'])
2018-06-23 23:37:26 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-23 23:37:26 DEBUG config-changed **kwargs).stdout
2018-06-23 23:37:26 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-23 23:37:26 DEBUG config-changed output=stdout, stderr=stderr)
2018-06-23 23:37:26 DEBUG config-changed subprocess.CalledProcessError: Command '['kube-apiserver', '--version']' returned non-zero exit status 1
2018-06-23 23:37:26 ERROR juju.worker.uniter.operation runhook.go:113 hook "config-changed" failed: exit status 1
2018-06-23 23:37:26 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:40:22 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:42:26 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:42:27 INFO juju-log Reactive main running for hook config-changed
2018-06-23 23:42:27 INFO juju-log Initializing Snap Layer
2018-06-23 23:42:28 DEBUG config-changed lxc
2018-06-23 23:42:28 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-23 23:42:28 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:527:send_cluster_dns_detail
2018-06-23 23:42:28 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:42:28 INFO juju-log kubedns not ready yet
2018-06-23 23:42:28 INFO juju-log Invoking reactive handler: hooks/relations/gcp/requires.py:116:remove_ready:gcp
2018-06-23 23:42:28 INFO juju-log Invoking reactive handler: reactive/tls_client.py:36:store_server
2018-06-23 23:42:29 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:604:send_data
2018-06-23 23:42:29 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:501:etcd_data_change
2018-06-23 23:42:30 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:664:configure_cdk_addons
2018-06-23 23:42:30 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:42:30 DEBUG config-changed snap-update-ns failed with code 1
2018-06-23 23:42:30 ERROR juju-log Hook error:
Traceback (most recent call last):
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
bus.dispatch(restricted=restricted_mode)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
_invoke(other_handlers)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
handler.invoke()
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
self._action(*args)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 669, in configure_cdk_addons
gpuEnable = (get_version('kube-apiserver') >= (1, 9) and
File "lib/charms/kubernetes/common.py", line 36, in get_version
version_string = subprocess.check_output(cmd).decode('utf-8')
File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
**kwargs).stdout
File "/usr/lib/python3.5/subprocess.py", line 708, in run
output=stdout, stderr=stderr)
subprocess.CalledProcessError: Command '['kube-apiserver', '--version']' returned non-zero exit status 1

2018-06-23 23:42:30 DEBUG config-changed Traceback (most recent call last):
2018-06-23 23:42:30 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/hooks/config-changed", line 19, in
2018-06-23 23:42:30 DEBUG config-changed main()
2018-06-23 23:42:30 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
2018-06-23 23:42:30 DEBUG config-changed bus.dispatch(restricted=restricted_mode)
2018-06-23 23:42:30 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
2018-06-23 23:42:30 DEBUG config-changed _invoke(other_handlers)
2018-06-23 23:42:30 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
2018-06-23 23:42:30 DEBUG config-changed handler.invoke()
2018-06-23 23:42:30 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
2018-06-23 23:42:30 DEBUG config-changed self._action(*args)
2018-06-23 23:42:30 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 669, in configure_cdk_addons
2018-06-23 23:42:30 DEBUG config-changed gpuEnable = (get_version('kube-apiserver') >= (1, 9) and
2018-06-23 23:42:30 DEBUG config-changed File "lib/charms/kubernetes/common.py", line 36, in get_version
2018-06-23 23:42:30 DEBUG config-changed version_string = subprocess.check_output(cmd).decode('utf-8')
2018-06-23 23:42:30 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-23 23:42:30 DEBUG config-changed **kwargs).stdout
2018-06-23 23:42:30 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-23 23:42:30 DEBUG config-changed output=stdout, stderr=stderr)
2018-06-23 23:42:30 DEBUG config-changed subprocess.CalledProcessError: Command '['kube-apiserver', '--version']' returned non-zero exit status 1
2018-06-23 23:42:30 ERROR juju.worker.uniter.operation runhook.go:113 hook "config-changed" failed: exit status 1
2018-06-23 23:42:30 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:42:55 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:42:55 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:42:55 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:42:55 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:42:55 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:42:55 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:42:55 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:42:55 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:45:03 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:45:15 ERROR juju.worker.metrics.sender sender.go:82 could not remove batch "70bb7c73-1f99-4948-8f43-507ad0c71cd0" from spool: remove /var/lib/juju/metricspool/70bb7c73-1f99-4948-8f43-507ad0c71cd0: no such file or directory
2018-06-23 23:47:30 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:47:31 INFO juju-log Reactive main running for hook config-changed
2018-06-23 23:47:31 INFO juju-log Initializing Snap Layer
2018-06-23 23:47:31 DEBUG config-changed lxc
2018-06-23 23:47:32 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-23 23:47:32 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:721:create_self_config
2018-06-23 23:47:32 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:47:32 ERROR juju-log Hook error:
Traceback (most recent call last):
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
bus.dispatch(restricted=restricted_mode)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
_invoke(other_handlers)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
handler.invoke()
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
self._action(*args)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 727, in create_self_config
build_kubeconfig(server)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1006, in build_kubeconfig
user='admin', password=client_pass)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1029, in create_kubeconfig
check_call(split(cmd.format(kubeconfig, cluster, server, ca)))
File "/usr/lib/python3.5/subprocess.py", line 581, in check_call
raise CalledProcessError(retcode, cmd)
subprocess.CalledProcessError: Command '['kubectl', 'config', '--kubeconfig=/home/ubuntu/config', 'set-cluster', 'juju-cluster', '--server=https://10.143.127.215:6443', '--certificate-authority=/root/cdk/ca.crt', '--embed-certs=true']' returned non-zero exit status 1

2018-06-23 23:47:32 DEBUG config-changed Traceback (most recent call last):
2018-06-23 23:47:32 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/hooks/config-changed", line 19, in
2018-06-23 23:47:32 DEBUG config-changed main()
2018-06-23 23:47:32 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
2018-06-23 23:47:32 DEBUG config-changed bus.dispatch(restricted=restricted_mode)
2018-06-23 23:47:32 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
2018-06-23 23:47:32 DEBUG config-changed _invoke(other_handlers)
2018-06-23 23:47:32 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
2018-06-23 23:47:32 DEBUG config-changed handler.invoke()
2018-06-23 23:47:32 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
2018-06-23 23:47:32 DEBUG config-changed self._action(*args)
2018-06-23 23:47:32 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 727, in create_self_config
2018-06-23 23:47:32 DEBUG config-changed build_kubeconfig(server)
2018-06-23 23:47:32 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1006, in build_kubeconfig
2018-06-23 23:47:32 DEBUG config-changed user='admin', password=client_pass)
2018-06-23 23:47:32 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 1029, in create_kubeconfig
2018-06-23 23:47:32 DEBUG config-changed check_call(split(cmd.format(kubeconfig, cluster, server, ca)))
2018-06-23 23:47:32 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 581, in check_call
2018-06-23 23:47:32 DEBUG config-changed raise CalledProcessError(retcode, cmd)
2018-06-23 23:47:32 DEBUG config-changed subprocess.CalledProcessError: Command '['kubectl', 'config', '--kubeconfig=/home/ubuntu/config', 'set-cluster', 'juju-cluster', '--server=https://10.143.127.215:6443', '--certificate-authority=/root/cdk/ca.crt', '--embed-certs=true']' returned non-zero exit status 1
2018-06-23 23:47:32 ERROR juju.worker.uniter.operation runhook.go:113 hook "config-changed" failed: exit status 1
2018-06-23 23:47:32 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:48:50 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:48:50 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:48:50 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:48:50 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:48:50 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:48:50 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:48:50 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:48:50 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:50:17 ERROR juju.worker.metrics.sender sender.go:82 could not remove batch "f4841660-2a34-4e94-85f5-44b5f64e23b1" from spool: remove /var/lib/juju/metricspool/f4841660-2a34-4e94-85f5-44b5f64e23b1: no such file or directory
2018-06-23 23:51:01 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:52:32 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:52:35 INFO juju-log Reactive main running for hook config-changed
2018-06-23 23:52:36 INFO juju-log Initializing Snap Layer
2018-06-23 23:52:36 DEBUG config-changed lxc
2018-06-23 23:52:36 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-23 23:52:36 INFO juju-log Invoking reactive handler: reactive/tls_client.py:36:store_server
2018-06-23 23:52:36 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:501:etcd_data_change
2018-06-23 23:52:37 INFO juju-log Invoking reactive handler: reactive/tls_client.py:15:store_ca
2018-06-23 23:52:37 INFO juju-log Invoking reactive handler: reactive/tls_client.py:60:store_client
2018-06-23 23:52:37 INFO juju-log Invoking reactive handler: hooks/relations/gcp/requires.py:116:remove_ready:gcp
2018-06-23 23:52:37 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:543:create_service_configs
2018-06-23 23:52:38 INFO juju-log Invoking reactive handler: hooks/relations/aws/requires.py:106:remove_ready:aws
2018-06-23 23:52:38 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:664:configure_cdk_addons
2018-06-23 23:52:38 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:52:38 DEBUG config-changed snap-update-ns failed with code 1
2018-06-23 23:52:38 ERROR juju-log Hook error:
Traceback (most recent call last):
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
bus.dispatch(restricted=restricted_mode)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
_invoke(other_handlers)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
handler.invoke()
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
self._action(*args)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 669, in configure_cdk_addons
gpuEnable = (get_version('kube-apiserver') >= (1, 9) and
File "lib/charms/kubernetes/common.py", line 36, in get_version
version_string = subprocess.check_output(cmd).decode('utf-8')
File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
**kwargs).stdout
File "/usr/lib/python3.5/subprocess.py", line 708, in run
output=stdout, stderr=stderr)
subprocess.CalledProcessError: Command '['kube-apiserver', '--version']' returned non-zero exit status 1

2018-06-23 23:52:38 DEBUG config-changed Traceback (most recent call last):
2018-06-23 23:52:38 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/hooks/config-changed", line 19, in
2018-06-23 23:52:38 DEBUG config-changed main()
2018-06-23 23:52:38 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
2018-06-23 23:52:38 DEBUG config-changed bus.dispatch(restricted=restricted_mode)
2018-06-23 23:52:38 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
2018-06-23 23:52:38 DEBUG config-changed _invoke(other_handlers)
2018-06-23 23:52:38 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
2018-06-23 23:52:38 DEBUG config-changed handler.invoke()
2018-06-23 23:52:38 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
2018-06-23 23:52:38 DEBUG config-changed self._action(*args)
2018-06-23 23:52:38 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 669, in configure_cdk_addons
2018-06-23 23:52:38 DEBUG config-changed gpuEnable = (get_version('kube-apiserver') >= (1, 9) and
2018-06-23 23:52:38 DEBUG config-changed File "lib/charms/kubernetes/common.py", line 36, in get_version
2018-06-23 23:52:38 DEBUG config-changed version_string = subprocess.check_output(cmd).decode('utf-8')
2018-06-23 23:52:38 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-23 23:52:38 DEBUG config-changed **kwargs).stdout
2018-06-23 23:52:38 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-23 23:52:38 DEBUG config-changed output=stdout, stderr=stderr)
2018-06-23 23:52:38 DEBUG config-changed subprocess.CalledProcessError: Command '['kube-apiserver', '--version']' returned non-zero exit status 1
2018-06-23 23:52:38 ERROR juju.worker.uniter.operation runhook.go:113 hook "config-changed" failed: exit status 1
2018-06-23 23:52:38 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:53:41 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:53:41 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:53:41 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:53:41 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:53:41 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:53:41 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:53:41 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:53:41 DEBUG collect-metrics cannot change profile for the next exec call: No such file or directory
2018-06-23 23:55:42 ERROR juju.worker.metrics.sender sender.go:82 could not remove batch "4e48176e-9ffb-4f2a-8955-21c9bfe3abf2" from spool: remove /var/lib/juju/metricspool/4e48176e-9ffb-4f2a-8955-21c9bfe3abf2: no such file or directory
2018-06-23 23:56:09 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:57:38 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:57:42 INFO juju-log Reactive main running for hook config-changed
2018-06-23 23:57:42 INFO juju-log Initializing Snap Layer
2018-06-23 23:57:42 DEBUG config-changed lxc
2018-06-23 23:57:42 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-23 23:57:42 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:604:send_data
2018-06-23 23:57:43 INFO juju-log Invoking reactive handler: reactive/tls_client.py:15:store_ca
2018-06-23 23:57:43 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:721:create_self_config
2018-06-23 23:57:45 DEBUG config-changed Cluster "juju-cluster" set.
2018-06-23 23:57:45 DEBUG config-changed Property "users" unset.
2018-06-23 23:57:45 DEBUG config-changed User "admin" set.
2018-06-23 23:57:45 DEBUG config-changed Context "juju-context" modified.
2018-06-23 23:57:45 DEBUG config-changed Switched to context "juju-context".
2018-06-23 23:57:45 INFO juju-log Invoking reactive handler: hooks/relations/gcp/requires.py:116:remove_ready:gcp
2018-06-23 23:57:45 INFO juju-log Invoking reactive handler: hooks/relations/aws/requires.py:106:remove_ready:aws
2018-06-23 23:57:45 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:527:send_cluster_dns_detail
2018-06-23 23:57:45 DEBUG config-changed The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-23 23:57:45 INFO juju-log kubedns not ready yet
2018-06-23 23:57:45 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:664:configure_cdk_addons
2018-06-23 23:57:49 DEBUG config-changed error: cannot perform the following tasks:
2018-06-23 23:57:49 DEBUG config-changed - Run configure hook of "cdk-addons" snap (run hook "configure":
2018-06-23 23:57:49 DEBUG config-changed -----
2018-06-23 23:57:49 DEBUG config-changed cannot change profile for the next exec call: No such file or directory
2018-06-23 23:57:49 DEBUG config-changed snap-update-ns failed with code 1
2018-06-23 23:57:49 DEBUG config-changed -----)
2018-06-23 23:57:49 ERROR juju-log Hook error:
Traceback (most recent call last):
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
bus.dispatch(restricted=restricted_mode)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
_invoke(other_handlers)
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
handler.invoke()
File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
self._action(*args)
File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 684, in configure_cdk_addons
check_call(['snap', 'set', 'cdk-addons'] + args)
File "/usr/lib/python3.5/subprocess.py", line 581, in check_call
raise CalledProcessError(retcode, cmd)
subprocess.CalledProcessError: Command '['snap', 'set', 'cdk-addons', 'arch=amd64', 'dns-ip=10.152.183.10', 'dns-domain=cluster.local', 'enable-dashboard=true', 'enable-kube-dns=true', 'enable-metrics=true', 'enable-gpu=false']' returned non-zero exit status 1

2018-06-23 23:57:49 DEBUG config-changed Traceback (most recent call last):
2018-06-23 23:57:49 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/hooks/config-changed", line 19, in
2018-06-23 23:57:49 DEBUG config-changed main()
2018-06-23 23:57:49 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/init.py", line 72, in main
2018-06-23 23:57:49 DEBUG config-changed bus.dispatch(restricted=restricted_mode)
2018-06-23 23:57:49 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 382, in dispatch
2018-06-23 23:57:49 DEBUG config-changed _invoke(other_handlers)
2018-06-23 23:57:49 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 358, in _invoke
2018-06-23 23:57:49 DEBUG config-changed handler.invoke()
2018-06-23 23:57:49 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/.venv/lib/python3.5/site-packages/charms/reactive/bus.py", line 180, in invoke
2018-06-23 23:57:49 DEBUG config-changed self._action(*args)
2018-06-23 23:57:49 DEBUG config-changed File "/var/lib/juju/agents/unit-kubernetes-master-0/charm/reactive/kubernetes_master.py", line 684, in configure_cdk_addons
2018-06-23 23:57:49 DEBUG config-changed check_call(['snap', 'set', 'cdk-addons'] + args)
2018-06-23 23:57:49 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 581, in check_call
2018-06-23 23:57:49 DEBUG config-changed raise CalledProcessError(retcode, cmd)
2018-06-23 23:57:49 DEBUG config-changed subprocess.CalledProcessError: Command '['snap', 'set', 'cdk-addons', 'arch=amd64', 'dns-ip=10.152.183.10', 'dns-domain=cluster.local', 'enable-dashboard=true', 'enable-kube-dns=true', 'enable-metrics=true', 'enable-gpu=false']' returned non-zero exit status 1
2018-06-23 23:57:49 ERROR juju.worker.uniter.operation runhook.go:113 hook "config-changed" failed: exit status 1
2018-06-23 23:57:49 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-23 23:58:46 DEBUG collect-metrics error: the server doesn't have a resource type "rs"
2018-06-23 23:58:46 DEBUG collect-metrics error: the server doesn't have a resource type "rc"
2018-06-23 23:58:47 DEBUG collect-metrics The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-23 23:58:47 DEBUG collect-metrics error: the server doesn't have a resource type "svc"
2018-06-23 23:58:47 DEBUG collect-metrics error: the server doesn't have a resource type "pvc"
2018-06-23 23:58:47 DEBUG collect-metrics error: the server doesn't have a resource type "pv"
2018-06-23 23:58:47 DEBUG collect-metrics error: the server doesn't have a resource type "sa"
2018-06-23 23:58:47 DEBUG collect-metrics error: the server doesn't have a resource type "po"
2018-06-24 00:00:17 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-24 00:02:49 INFO juju.worker.uniter resolver.go:100 awaiting error resolution for "config-changed" hook
2018-06-24 00:02:49 INFO juju-log Reactive main running for hook config-changed
2018-06-24 00:02:50 INFO juju-log Initializing Snap Layer
2018-06-24 00:02:50 DEBUG config-changed lxc
2018-06-24 00:02:50 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-24 00:02:50 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:543:create_service_configs
2018-06-24 00:02:51 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:527:send_cluster_dns_detail
2018-06-24 00:02:51 DEBUG config-changed The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:02:51 INFO juju-log kubedns not ready yet
2018-06-24 00:02:51 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:664:configure_cdk_addons
2018-06-24 00:02:54 DEBUG config-changed The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:02:54 DEBUG config-changed Traceback (most recent call last):
2018-06-24 00:02:54 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:02:54 DEBUG config-changed main()
2018-06-24 00:02:54 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:02:54 DEBUG config-changed if render_templates():
2018-06-24 00:02:54 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:02:54 DEBUG config-changed node_count = get_node_count()
2018-06-24 00:02:54 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:02:54 DEBUG config-changed output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:02:54 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:02:54 DEBUG config-changed return subprocess.check_output(cmd)
2018-06-24 00:02:54 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:02:54 DEBUG config-changed **kwargs).stdout
2018-06-24 00:02:54 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:02:54 DEBUG config-changed output=stdout, stderr=stderr)
2018-06-24 00:02:54 DEBUG config-changed subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:02:54 INFO juju-log Addons are not ready yet.
2018-06-24 00:03:15 DEBUG config-changed The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:03:15 DEBUG config-changed Traceback (most recent call last):
2018-06-24 00:03:15 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:03:15 DEBUG config-changed main()
2018-06-24 00:03:15 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:03:15 DEBUG config-changed if render_templates():
2018-06-24 00:03:15 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:03:15 DEBUG config-changed node_count = get_node_count()
2018-06-24 00:03:15 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:03:15 DEBUG config-changed output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:03:15 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:03:15 DEBUG config-changed return subprocess.check_output(cmd)
2018-06-24 00:03:15 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:03:15 DEBUG config-changed **kwargs).stdout
2018-06-24 00:03:15 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:03:15 DEBUG config-changed output=stdout, stderr=stderr)
2018-06-24 00:03:15 DEBUG config-changed subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:03:15 INFO juju-log Addons are not ready yet.
2018-06-24 00:03:35 DEBUG config-changed The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:03:35 DEBUG config-changed Traceback (most recent call last):
2018-06-24 00:03:35 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:03:35 DEBUG config-changed main()
2018-06-24 00:03:35 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:03:35 DEBUG config-changed if render_templates():
2018-06-24 00:03:35 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:03:35 DEBUG config-changed node_count = get_node_count()
2018-06-24 00:03:35 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:03:35 DEBUG config-changed output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:03:35 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:03:35 DEBUG config-changed return subprocess.check_output(cmd)
2018-06-24 00:03:35 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:03:35 DEBUG config-changed **kwargs).stdout
2018-06-24 00:03:35 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:03:35 DEBUG config-changed output=stdout, stderr=stderr)
2018-06-24 00:03:35 DEBUG config-changed subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:03:35 INFO juju-log Addons are not ready yet.
2018-06-24 00:03:56 DEBUG config-changed The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:03:56 DEBUG config-changed Traceback (most recent call last):
2018-06-24 00:03:56 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:03:56 DEBUG config-changed main()
2018-06-24 00:03:56 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:03:56 DEBUG config-changed if render_templates():
2018-06-24 00:03:56 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:03:56 DEBUG config-changed node_count = get_node_count()
2018-06-24 00:03:56 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:03:56 DEBUG config-changed output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:03:56 DEBUG config-changed File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:03:56 DEBUG config-changed return subprocess.check_output(cmd)
2018-06-24 00:03:56 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:03:56 DEBUG config-changed **kwargs).stdout
2018-06-24 00:03:56 DEBUG config-changed File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:03:56 DEBUG config-changed output=stdout, stderr=stderr)
2018-06-24 00:03:56 DEBUG config-changed subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:03:56 INFO juju-log Addons are not ready yet.
2018-06-24 00:03:56 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:582:push_service_data
2018-06-24 00:03:57 INFO juju-log Invoking reactive handler: reactive/tls_client.py:36:store_server
2018-06-24 00:03:57 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:389:set_app_version
2018-06-24 00:03:57 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:604:send_data
2018-06-24 00:03:58 INFO juju-log Invoking reactive handler: reactive/tls_client.py:60:store_client
2018-06-24 00:03:58 INFO juju-log Invoking reactive handler: hooks/relations/gcp/requires.py:116:remove_ready:gcp
2018-06-24 00:03:58 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:721:create_self_config
2018-06-24 00:03:58 DEBUG config-changed Cluster "juju-cluster" set.
2018-06-24 00:03:58 DEBUG config-changed Property "users" unset.
2018-06-24 00:03:59 DEBUG config-changed User "admin" set.
2018-06-24 00:03:59 DEBUG config-changed Context "juju-context" modified.
2018-06-24 00:03:59 DEBUG config-changed Switched to context "juju-context".
2018-06-24 00:03:59 INFO juju-log Invoking reactive handler: hooks/relations/aws/requires.py:106:remove_ready:aws
2018-06-24 00:03:59 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:501:etcd_data_change
2018-06-24 00:03:59 INFO juju-log Invoking reactive handler: reactive/tls_client.py:15:store_ca
2018-06-24 00:03:59 DEBUG config-changed inactive
2018-06-24 00:03:59 DEBUG config-changed inactive
2018-06-24 00:03:59 DEBUG config-changed inactive
2018-06-24 00:03:59 INFO juju.worker.uniter.operation runhook.go:119 ran "config-changed" hook
2018-06-24 00:04:31 DEBUG collect-metrics error: the server doesn't have a resource type "rs"
2018-06-24 00:04:31 DEBUG collect-metrics error: the server doesn't have a resource type "rc"
2018-06-24 00:04:31 DEBUG collect-metrics error: the server doesn't have a resource type "pv"
2018-06-24 00:04:31 DEBUG collect-metrics The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:04:31 DEBUG collect-metrics error: the server doesn't have a resource type "svc"
2018-06-24 00:04:31 DEBUG collect-metrics error: the server doesn't have a resource type "sa"
2018-06-24 00:04:31 DEBUG collect-metrics error: the server doesn't have a resource type "pvc"
2018-06-24 00:04:31 DEBUG collect-metrics error: the server doesn't have a resource type "po"
2018-06-24 00:05:35 INFO juju-log Reactive main running for hook update-status
2018-06-24 00:05:36 INFO juju-log Initializing Snap Layer
2018-06-24 00:05:36 DEBUG update-status lxc
2018-06-24 00:05:36 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-24 00:05:36 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:582:push_service_data
2018-06-24 00:05:37 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:543:create_service_configs
2018-06-24 00:05:37 INFO juju-log Invoking reactive handler: reactive/tls_client.py:60:store_client
2018-06-24 00:05:37 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:604:send_data
2018-06-24 00:05:38 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:721:create_self_config
2018-06-24 00:05:38 DEBUG update-status Cluster "juju-cluster" set.
2018-06-24 00:05:38 DEBUG update-status Property "users" unset.
2018-06-24 00:05:38 DEBUG update-status User "admin" set.
2018-06-24 00:05:38 DEBUG update-status Context "juju-context" modified.
2018-06-24 00:05:39 DEBUG update-status Switched to context "juju-context".
2018-06-24 00:05:39 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:501:etcd_data_change
2018-06-24 00:05:39 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:389:set_app_version
2018-06-24 00:05:39 INFO juju-log Invoking reactive handler: reactive/tls_client.py:36:store_server
2018-06-24 00:05:40 INFO juju-log Invoking reactive handler: hooks/relations/gcp/requires.py:116:remove_ready:gcp
2018-06-24 00:05:40 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:664:configure_cdk_addons
2018-06-24 00:05:40 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:05:40 DEBUG update-status Traceback (most recent call last):
2018-06-24 00:05:40 DEBUG update-status File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:05:40 DEBUG update-status main()
2018-06-24 00:05:40 DEBUG update-status File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:05:40 DEBUG update-status if render_templates():
2018-06-24 00:05:40 DEBUG update-status File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:05:40 DEBUG update-status node_count = get_node_count()
2018-06-24 00:05:40 DEBUG update-status File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:05:40 DEBUG update-status output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:05:40 DEBUG update-status File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:05:40 DEBUG update-status return subprocess.check_output(cmd)
2018-06-24 00:05:40 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:05:40 DEBUG update-status **kwargs).stdout
2018-06-24 00:05:40 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:05:40 DEBUG update-status output=stdout, stderr=stderr)
2018-06-24 00:05:40 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:05:40 INFO juju-log Addons are not ready yet.
2018-06-24 00:06:01 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:06:01 DEBUG update-status Traceback (most recent call last):
2018-06-24 00:06:01 DEBUG update-status File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:06:01 DEBUG update-status main()
2018-06-24 00:06:01 DEBUG update-status File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:06:01 DEBUG update-status if render_templates():
2018-06-24 00:06:01 DEBUG update-status File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:06:01 DEBUG update-status node_count = get_node_count()
2018-06-24 00:06:01 DEBUG update-status File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:06:01 DEBUG update-status output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:06:01 DEBUG update-status File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:06:01 DEBUG update-status return subprocess.check_output(cmd)
2018-06-24 00:06:01 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:06:01 DEBUG update-status **kwargs).stdout
2018-06-24 00:06:01 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:06:01 DEBUG update-status output=stdout, stderr=stderr)
2018-06-24 00:06:01 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:06:01 INFO juju-log Addons are not ready yet.
2018-06-24 00:06:21 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:06:21 DEBUG update-status Traceback (most recent call last):
2018-06-24 00:06:21 DEBUG update-status File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:06:21 DEBUG update-status main()
2018-06-24 00:06:21 DEBUG update-status File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:06:21 DEBUG update-status if render_templates():
2018-06-24 00:06:21 DEBUG update-status File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:06:21 DEBUG update-status node_count = get_node_count()
2018-06-24 00:06:21 DEBUG update-status File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:06:21 DEBUG update-status output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:06:21 DEBUG update-status File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:06:21 DEBUG update-status return subprocess.check_output(cmd)
2018-06-24 00:06:21 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:06:21 DEBUG update-status **kwargs).stdout
2018-06-24 00:06:21 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:06:21 DEBUG update-status output=stdout, stderr=stderr)
2018-06-24 00:06:21 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:06:21 INFO juju-log Addons are not ready yet.
2018-06-24 00:06:42 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:06:42 DEBUG update-status Traceback (most recent call last):
2018-06-24 00:06:42 DEBUG update-status File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:06:42 DEBUG update-status main()
2018-06-24 00:06:42 DEBUG update-status File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:06:42 DEBUG update-status if render_templates():
2018-06-24 00:06:42 DEBUG update-status File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:06:42 DEBUG update-status node_count = get_node_count()
2018-06-24 00:06:42 DEBUG update-status File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:06:42 DEBUG update-status output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:06:42 DEBUG update-status File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:06:42 DEBUG update-status return subprocess.check_output(cmd)
2018-06-24 00:06:42 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:06:42 DEBUG update-status **kwargs).stdout
2018-06-24 00:06:42 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:06:42 DEBUG update-status output=stdout, stderr=stderr)
2018-06-24 00:06:42 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:06:42 INFO juju-log Addons are not ready yet.
2018-06-24 00:06:42 INFO juju-log Invoking reactive handler: hooks/relations/aws/requires.py:106:remove_ready:aws
2018-06-24 00:06:42 INFO juju-log Invoking reactive handler: reactive/tls_client.py:15:store_ca
2018-06-24 00:06:42 DEBUG update-status inactive
2018-06-24 00:06:42 DEBUG update-status inactive
2018-06-24 00:06:42 DEBUG update-status inactive
2018-06-24 00:06:42 INFO juju.worker.uniter.operation runhook.go:119 ran "update-status" hook
2018-06-24 00:10:11 INFO juju-log Reactive main running for hook update-status
2018-06-24 00:10:11 INFO juju-log Initializing Snap Layer
2018-06-24 00:10:11 DEBUG update-status lxc
2018-06-24 00:10:11 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-24 00:10:12 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:664:configure_cdk_addons
2018-06-24 00:10:12 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:10:12 DEBUG update-status Traceback (most recent call last):
2018-06-24 00:10:12 DEBUG update-status File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:10:12 DEBUG update-status main()
2018-06-24 00:10:12 DEBUG update-status File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:10:12 DEBUG update-status if render_templates():
2018-06-24 00:10:12 DEBUG update-status File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:10:12 DEBUG update-status node_count = get_node_count()
2018-06-24 00:10:12 DEBUG update-status File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:10:12 DEBUG update-status output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:10:12 DEBUG update-status File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:10:12 DEBUG update-status return subprocess.check_output(cmd)
2018-06-24 00:10:12 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:10:12 DEBUG update-status **kwargs).stdout
2018-06-24 00:10:12 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:10:12 DEBUG update-status output=stdout, stderr=stderr)
2018-06-24 00:10:12 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:10:12 INFO juju-log Addons are not ready yet.
2018-06-24 00:10:14 DEBUG collect-metrics The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:10:14 DEBUG collect-metrics error: the server doesn't have a resource type "sa"
2018-06-24 00:10:14 DEBUG collect-metrics error: the server doesn't have a resource type "rc"
2018-06-24 00:10:14 DEBUG collect-metrics error: the server doesn't have a resource type "rs"
2018-06-24 00:10:14 DEBUG collect-metrics error: the server doesn't have a resource type "pvc"
2018-06-24 00:10:15 DEBUG collect-metrics error: the server doesn't have a resource type "svc"
2018-06-24 00:10:15 DEBUG collect-metrics error: the server doesn't have a resource type "po"
2018-06-24 00:10:15 DEBUG collect-metrics error: the server doesn't have a resource type "pv"
2018-06-24 00:10:33 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:10:33 DEBUG update-status Traceback (most recent call last):
2018-06-24 00:10:33 DEBUG update-status File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:10:33 DEBUG update-status main()
2018-06-24 00:10:33 DEBUG update-status File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:10:33 DEBUG update-status if render_templates():
2018-06-24 00:10:33 DEBUG update-status File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:10:33 DEBUG update-status node_count = get_node_count()
2018-06-24 00:10:33 DEBUG update-status File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:10:33 DEBUG update-status output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:10:33 DEBUG update-status File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:10:33 DEBUG update-status return subprocess.check_output(cmd)
2018-06-24 00:10:33 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:10:33 DEBUG update-status **kwargs).stdout
2018-06-24 00:10:33 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:10:33 DEBUG update-status output=stdout, stderr=stderr)
2018-06-24 00:10:33 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:10:33 INFO juju-log Addons are not ready yet.
2018-06-24 00:10:53 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:10:53 DEBUG update-status Traceback (most recent call last):
2018-06-24 00:10:53 DEBUG update-status File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:10:53 DEBUG update-status main()
2018-06-24 00:10:53 DEBUG update-status File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:10:53 DEBUG update-status if render_templates():
2018-06-24 00:10:53 DEBUG update-status File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:10:53 DEBUG update-status node_count = get_node_count()
2018-06-24 00:10:53 DEBUG update-status File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:10:53 DEBUG update-status output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:10:53 DEBUG update-status File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:10:53 DEBUG update-status return subprocess.check_output(cmd)
2018-06-24 00:10:53 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:10:53 DEBUG update-status **kwargs).stdout
2018-06-24 00:10:53 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:10:53 DEBUG update-status output=stdout, stderr=stderr)
2018-06-24 00:10:53 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:10:53 INFO juju-log Addons are not ready yet.
2018-06-24 00:10:58 ERROR juju.worker.metrics.sender sender.go:82 could not remove batch "b1c2e581-7890-4342-84cc-e5cc9cac815c" from spool: remove /var/lib/juju/metricspool/b1c2e581-7890-4342-84cc-e5cc9cac815c: no such file or directory
2018-06-24 00:11:13 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:11:13 DEBUG update-status Traceback (most recent call last):
2018-06-24 00:11:13 DEBUG update-status File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:11:13 DEBUG update-status main()
2018-06-24 00:11:13 DEBUG update-status File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:11:13 DEBUG update-status if render_templates():
2018-06-24 00:11:13 DEBUG update-status File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:11:13 DEBUG update-status node_count = get_node_count()
2018-06-24 00:11:13 DEBUG update-status File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:11:13 DEBUG update-status output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:11:13 DEBUG update-status File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:11:13 DEBUG update-status return subprocess.check_output(cmd)
2018-06-24 00:11:13 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:11:13 DEBUG update-status **kwargs).stdout
2018-06-24 00:11:13 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:11:13 DEBUG update-status output=stdout, stderr=stderr)
2018-06-24 00:11:13 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:11:13 INFO juju-log Addons are not ready yet.
2018-06-24 00:11:14 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:604:send_data
2018-06-24 00:11:15 INFO juju-log Invoking reactive handler: reactive/tls_client.py:36:store_server
2018-06-24 00:11:15 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:543:create_service_configs
2018-06-24 00:11:15 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:721:create_self_config
2018-06-24 00:11:15 DEBUG update-status Cluster "juju-cluster" set.
2018-06-24 00:11:15 DEBUG update-status Property "users" unset.
2018-06-24 00:11:16 DEBUG update-status User "admin" set.
2018-06-24 00:11:16 DEBUG update-status Context "juju-context" modified.
2018-06-24 00:11:16 DEBUG update-status Switched to context "juju-context".
2018-06-24 00:11:16 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:501:etcd_data_change
2018-06-24 00:11:16 INFO juju-log Invoking reactive handler: reactive/tls_client.py:60:store_client
2018-06-24 00:11:17 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:582:push_service_data
2018-06-24 00:11:17 INFO juju-log Invoking reactive handler: reactive/tls_client.py:15:store_ca
2018-06-24 00:11:17 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:389:set_app_version
2018-06-24 00:11:18 INFO juju-log Invoking reactive handler: hooks/relations/gcp/requires.py:116:remove_ready:gcp
2018-06-24 00:11:18 INFO juju-log Invoking reactive handler: hooks/relations/aws/requires.py:106:remove_ready:aws
2018-06-24 00:11:18 DEBUG update-status inactive
2018-06-24 00:11:18 DEBUG update-status inactive
2018-06-24 00:11:18 DEBUG update-status inactive
2018-06-24 00:11:18 INFO juju.worker.uniter.operation runhook.go:119 ran "update-status" hook
2018-06-24 00:14:22 INFO juju-log Reactive main running for hook update-status
2018-06-24 00:14:22 INFO juju-log Initializing Snap Layer
2018-06-24 00:14:22 DEBUG update-status lxc
2018-06-24 00:14:22 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-24 00:14:22 INFO juju-log Invoking reactive handler: hooks/relations/aws/requires.py:106:remove_ready:aws
2018-06-24 00:14:23 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:389:set_app_version
2018-06-24 00:14:23 INFO juju-log Invoking reactive handler: reactive/tls_client.py:36:store_server
2018-06-24 00:14:23 INFO juju-log Invoking reactive handler: hooks/relations/gcp/requires.py:116:remove_ready:gcp
2018-06-24 00:14:23 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:721:create_self_config
2018-06-24 00:14:24 DEBUG update-status Cluster "juju-cluster" set.
2018-06-24 00:14:24 DEBUG update-status Property "users" unset.
2018-06-24 00:14:24 DEBUG update-status User "admin" set.
2018-06-24 00:14:24 DEBUG update-status Context "juju-context" modified.
2018-06-24 00:14:24 DEBUG update-status Switched to context "juju-context".
2018-06-24 00:14:24 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:582:push_service_data
2018-06-24 00:14:25 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:501:etcd_data_change
2018-06-24 00:14:25 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:543:create_service_configs
2018-06-24 00:14:26 INFO juju-log Invoking reactive handler: reactive/tls_client.py:15:store_ca
2018-06-24 00:14:26 INFO juju-log Invoking reactive handler: reactive/tls_client.py:60:store_client
2018-06-24 00:14:26 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:664:configure_cdk_addons
2018-06-24 00:14:27 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:14:27 DEBUG update-status Traceback (most recent call last):
2018-06-24 00:14:27 DEBUG update-status File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:14:27 DEBUG update-status main()
2018-06-24 00:14:27 DEBUG update-status File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:14:27 DEBUG update-status if render_templates():
2018-06-24 00:14:27 DEBUG update-status File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:14:27 DEBUG update-status node_count = get_node_count()
2018-06-24 00:14:27 DEBUG update-status File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:14:27 DEBUG update-status output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:14:27 DEBUG update-status File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:14:27 DEBUG update-status return subprocess.check_output(cmd)
2018-06-24 00:14:27 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:14:27 DEBUG update-status **kwargs).stdout
2018-06-24 00:14:27 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:14:27 DEBUG update-status output=stdout, stderr=stderr)
2018-06-24 00:14:27 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:14:27 INFO juju-log Addons are not ready yet.
2018-06-24 00:14:47 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:14:47 DEBUG update-status Traceback (most recent call last):
2018-06-24 00:14:47 DEBUG update-status File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:14:47 DEBUG update-status main()
2018-06-24 00:14:47 DEBUG update-status File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:14:47 DEBUG update-status if render_templates():
2018-06-24 00:14:47 DEBUG update-status File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:14:47 DEBUG update-status node_count = get_node_count()
2018-06-24 00:14:47 DEBUG update-status File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:14:47 DEBUG update-status output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:14:47 DEBUG update-status File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:14:47 DEBUG update-status return subprocess.check_output(cmd)
2018-06-24 00:14:47 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:14:47 DEBUG update-status **kwargs).stdout
2018-06-24 00:14:47 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:14:47 DEBUG update-status output=stdout, stderr=stderr)
2018-06-24 00:14:47 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:14:47 INFO juju-log Addons are not ready yet.
2018-06-24 00:15:02 DEBUG collect-metrics error: the server doesn't have a resource type "po"
2018-06-24 00:15:03 DEBUG collect-metrics The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:15:03 DEBUG collect-metrics error: the server doesn't have a resource type "rc"
2018-06-24 00:15:03 DEBUG collect-metrics error: the server doesn't have a resource type "pvc"
2018-06-24 00:15:03 DEBUG collect-metrics error: the server doesn't have a resource type "pv"
2018-06-24 00:15:03 DEBUG collect-metrics error: the server doesn't have a resource type "svc"
2018-06-24 00:15:03 DEBUG collect-metrics error: the server doesn't have a resource type "rs"
2018-06-24 00:15:03 DEBUG collect-metrics error: the server doesn't have a resource type "sa"
2018-06-24 00:15:06 ERROR juju.worker.metrics.sender sender.go:82 could not remove batch "2126527b-0818-4c57-83ea-b3ec7ad2c753" from spool: remove /var/lib/juju/metricspool/2126527b-0818-4c57-83ea-b3ec7ad2c753: no such file or directory
2018-06-24 00:15:07 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:15:07 DEBUG update-status Traceback (most recent call last):
2018-06-24 00:15:07 DEBUG update-status File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:15:07 DEBUG update-status main()
2018-06-24 00:15:07 DEBUG update-status File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:15:07 DEBUG update-status if render_templates():
2018-06-24 00:15:07 DEBUG update-status File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:15:07 DEBUG update-status node_count = get_node_count()
2018-06-24 00:15:07 DEBUG update-status File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:15:07 DEBUG update-status output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:15:07 DEBUG update-status File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:15:07 DEBUG update-status return subprocess.check_output(cmd)
2018-06-24 00:15:07 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:15:07 DEBUG update-status **kwargs).stdout
2018-06-24 00:15:07 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:15:07 DEBUG update-status output=stdout, stderr=stderr)
2018-06-24 00:15:07 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:15:07 INFO juju-log Addons are not ready yet.
2018-06-24 00:15:28 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:15:28 DEBUG update-status Traceback (most recent call last):
2018-06-24 00:15:28 DEBUG update-status File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:15:28 DEBUG update-status main()
2018-06-24 00:15:28 DEBUG update-status File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:15:28 DEBUG update-status if render_templates():
2018-06-24 00:15:28 DEBUG update-status File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:15:28 DEBUG update-status node_count = get_node_count()
2018-06-24 00:15:28 DEBUG update-status File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:15:28 DEBUG update-status output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:15:28 DEBUG update-status File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:15:28 DEBUG update-status return subprocess.check_output(cmd)
2018-06-24 00:15:28 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:15:28 DEBUG update-status **kwargs).stdout
2018-06-24 00:15:28 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:15:28 DEBUG update-status output=stdout, stderr=stderr)
2018-06-24 00:15:28 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:15:28 INFO juju-log Addons are not ready yet.
2018-06-24 00:15:28 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:604:send_data
2018-06-24 00:15:29 DEBUG update-status inactive
2018-06-24 00:15:29 DEBUG update-status inactive
2018-06-24 00:15:29 DEBUG update-status inactive
2018-06-24 00:15:29 INFO juju.worker.uniter.operation runhook.go:119 ran "update-status" hook
2018-06-24 00:19:39 INFO juju-log Reactive main running for hook update-status
2018-06-24 00:19:39 INFO juju-log Initializing Snap Layer
2018-06-24 00:19:39 DEBUG update-status lxc
2018-06-24 00:19:39 INFO juju-log Initializing Leadership Layer (is leader)
2018-06-24 00:19:39 INFO juju-log Invoking reactive handler: reactive/tls_client.py:15:store_ca
2018-06-24 00:19:40 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:389:set_app_version
2018-06-24 00:19:40 INFO juju-log Invoking reactive handler: hooks/relations/gcp/requires.py:116:remove_ready:gcp
2018-06-24 00:19:40 INFO juju-log Invoking reactive handler: reactive/tls_client.py:60:store_client
2018-06-24 00:19:41 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:721:create_self_config
2018-06-24 00:19:41 DEBUG update-status Cluster "juju-cluster" set.
2018-06-24 00:19:41 DEBUG update-status Property "users" unset.
2018-06-24 00:19:41 DEBUG update-status User "admin" set.
2018-06-24 00:19:41 DEBUG update-status Context "juju-context" modified.
2018-06-24 00:19:41 DEBUG update-status Switched to context "juju-context".
2018-06-24 00:19:41 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:543:create_service_configs
2018-06-24 00:19:42 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:664:configure_cdk_addons
2018-06-24 00:19:42 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:19:42 DEBUG update-status Traceback (most recent call last):
2018-06-24 00:19:42 DEBUG update-status File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:19:42 DEBUG update-status main()
2018-06-24 00:19:42 DEBUG update-status File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:19:42 DEBUG update-status if render_templates():
2018-06-24 00:19:42 DEBUG update-status File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:19:42 DEBUG update-status node_count = get_node_count()
2018-06-24 00:19:42 DEBUG update-status File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:19:42 DEBUG update-status output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:19:42 DEBUG update-status File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:19:42 DEBUG update-status return subprocess.check_output(cmd)
2018-06-24 00:19:42 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:19:42 DEBUG update-status **kwargs).stdout
2018-06-24 00:19:42 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:19:42 DEBUG update-status output=stdout, stderr=stderr)
2018-06-24 00:19:42 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:19:42 INFO juju-log Addons are not ready yet.
2018-06-24 00:19:45 DEBUG collect-metrics The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:19:45 DEBUG collect-metrics error: the server doesn't have a resource type "rs"
2018-06-24 00:19:45 DEBUG collect-metrics error: the server doesn't have a resource type "pv"
2018-06-24 00:19:45 DEBUG collect-metrics error: the server doesn't have a resource type "rc"
2018-06-24 00:19:45 DEBUG collect-metrics error: the server doesn't have a resource type "pvc"
2018-06-24 00:19:46 DEBUG collect-metrics error: the server doesn't have a resource type "svc"
2018-06-24 00:19:46 DEBUG collect-metrics error: the server doesn't have a resource type "po"
2018-06-24 00:19:46 DEBUG collect-metrics error: the server doesn't have a resource type "sa"
2018-06-24 00:20:03 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:20:03 DEBUG update-status Traceback (most recent call last):
2018-06-24 00:20:03 DEBUG update-status File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:20:03 DEBUG update-status main()
2018-06-24 00:20:03 DEBUG update-status File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:20:03 DEBUG update-status if render_templates():
2018-06-24 00:20:03 DEBUG update-status File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:20:03 DEBUG update-status node_count = get_node_count()
2018-06-24 00:20:03 DEBUG update-status File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:20:03 DEBUG update-status output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:20:03 DEBUG update-status File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:20:03 DEBUG update-status return subprocess.check_output(cmd)
2018-06-24 00:20:03 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:20:03 DEBUG update-status **kwargs).stdout
2018-06-24 00:20:03 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:20:03 DEBUG update-status output=stdout, stderr=stderr)
2018-06-24 00:20:03 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:20:03 INFO juju-log Addons are not ready yet.
2018-06-24 00:20:23 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:20:23 DEBUG update-status Traceback (most recent call last):
2018-06-24 00:20:23 DEBUG update-status File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:20:23 DEBUG update-status main()
2018-06-24 00:20:23 DEBUG update-status File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:20:23 DEBUG update-status if render_templates():
2018-06-24 00:20:23 DEBUG update-status File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:20:23 DEBUG update-status node_count = get_node_count()
2018-06-24 00:20:23 DEBUG update-status File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:20:23 DEBUG update-status output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:20:23 DEBUG update-status File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:20:23 DEBUG update-status return subprocess.check_output(cmd)
2018-06-24 00:20:23 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:20:23 DEBUG update-status **kwargs).stdout
2018-06-24 00:20:23 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:20:23 DEBUG update-status output=stdout, stderr=stderr)
2018-06-24 00:20:23 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:20:23 INFO juju-log Addons are not ready yet.
2018-06-24 00:20:43 DEBUG update-status The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:20:43 DEBUG update-status Traceback (most recent call last):
2018-06-24 00:20:43 DEBUG update-status File "/snap/cdk-addons/405/apply", line 145, in
2018-06-24 00:20:43 DEBUG update-status main()
2018-06-24 00:20:43 DEBUG update-status File "/snap/cdk-addons/405/apply", line 13, in main
2018-06-24 00:20:43 DEBUG update-status if render_templates():
2018-06-24 00:20:43 DEBUG update-status File "/snap/cdk-addons/405/apply", line 21, in render_templates
2018-06-24 00:20:43 DEBUG update-status node_count = get_node_count()
2018-06-24 00:20:43 DEBUG update-status File "/snap/cdk-addons/405/apply", line 129, in get_node_count
2018-06-24 00:20:43 DEBUG update-status output = kubectl("get", "nodes", "-o", "name")
2018-06-24 00:20:43 DEBUG update-status File "/snap/cdk-addons/405/apply", line 125, in kubectl
2018-06-24 00:20:43 DEBUG update-status return subprocess.check_output(cmd)
2018-06-24 00:20:43 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
2018-06-24 00:20:43 DEBUG update-status **kwargs).stdout
2018-06-24 00:20:43 DEBUG update-status File "/usr/lib/python3.5/subprocess.py", line 708, in run
2018-06-24 00:20:43 DEBUG update-status output=stdout, stderr=stderr)
2018-06-24 00:20:43 DEBUG update-status subprocess.CalledProcessError: Command '['/snap/cdk-addons/405/kubectl', 'get', 'nodes', '-o', 'name']' returned non-zero exit status 1
2018-06-24 00:20:43 INFO juju-log Addons are not ready yet.
2018-06-24 00:20:44 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:501:etcd_data_change
2018-06-24 00:20:44 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:582:push_service_data
2018-06-24 00:20:45 INFO juju-log Invoking reactive handler: reactive/kubernetes_master.py:604:send_data
2018-06-24 00:20:45 INFO juju-log Invoking reactive handler: hooks/relations/aws/requires.py:106:remove_ready:aws
2018-06-24 00:20:45 INFO juju-log Invoking reactive handler: reactive/tls_client.py:36:store_server
2018-06-24 00:20:46 DEBUG update-status inactive
2018-06-24 00:20:46 DEBUG update-status inactive
2018-06-24 00:20:46 DEBUG update-status inactive
2018-06-24 00:20:46 INFO juju.worker.uniter.operation runhook.go:119 ran "update-status" hook
2018-06-24 00:23:50 DEBUG collect-metrics error: the server doesn't have a resource type "rc"
2018-06-24 00:23:50 DEBUG collect-metrics The connection to the server localhost:8080 was refused - did you specify the right host or port?
2018-06-24 00:23:50 DEBUG collect-metrics error: the server doesn't have a resource type "svc"
2018-06-24 00:23:50 DEBUG collect-metrics error: the server doesn't have a resource type "pvc"
2018-06-24 00:23:50 DEBUG collect-metrics error: the server doesn't have a resource type "pv"
2018-06-24 00:23:50 DEBUG collect-metrics error: the server doesn't have a resource type "rs"
2018-06-24 00:23:50 DEBUG collect-metrics error: the server doesn't have a resource type "sa"
2018-06-24 00:23:51 DEBUG collect-metrics error: the server doesn't have a resource type "po"

@sanjeevshar
Copy link

Juju Status:

test@dsib0165:~$ juju status
Model Controller Cloud/Region Version SLA
conjure-kubernetes-core-3a6 conjure-up-localhost-329 localhost/localhost 2.3.8 unsupported

App Version Status Scale Charm Store Rev OS Notes
easyrsa 3.0.1 active 1 easyrsa jujucharms 44 ubuntu
etcd 3.2.9 active 1 etcd jujucharms 87 ubuntu
flannel 0.9.1 waiting 2 flannel jujucharms 59 ubuntu
kubernetes-master 1.10.4 blocked 1 kubernetes-master jujucharms 113 ubuntu exposed
kubernetes-worker 1.10.4 waiting 1 kubernetes-worker jujucharms 128 ubuntu exposed

Unit Workload Agent Machine Public address Ports Message
easyrsa/0* active idle 0/lxd/0 10.0.168.187 Certificate Authority connected.
etcd/0* active idle 0 10.143.127.215 2379/tcp Errored with 0 known peers
kubernetes-master/0* blocked idle 0 10.143.127.215 6443/tcp Stopped services: kube-apiserver,kube-controller-manager,kube-scheduler
flannel/0* waiting idle 10.143.127.215 Waiting for Flannel
kubernetes-worker/0* waiting idle 1 10.143.127.46 80/tcp,443/tcp Waiting for kubelet,kube-proxy to start.
flannel/1 waiting idle 10.143.127.46 Waiting for Flannel

Machine State DNS Inst id Series AZ Message
0 started 10.143.127.215 juju-200138-0 xenial Running
0/lxd/0 started 10.0.168.187 juju-200138-0-lxd-0 xenial Container started
1 started 10.143.127.46 juju-200138-1 xenial Running

@Cynerva
Copy link

Cynerva commented Jun 25, 2018

@sanjeevshar It looks like you're hitting this issue: #1448

You can try manually applying the workaround in this comment: #1448 (comment)

@sanjeevshar
Copy link

@Cynerva I do not understand how #1448 (comment) applies to this issue as there is no reboot involved here.
When should I issue the command juju run --all 'sudo apparmor_parser /var/lib/snapd/apparmor/profiles/*' as mentioned in the workaround.

@Cynerva
Copy link

Cynerva commented Jun 25, 2018

Oh, sorry @sanjeevshar. I was going based off the symptoms: the cannot change profile for the next exec call: No such file or directory error is identical to what we see in #1448, but you must be hitting it for different reasons.

You can try running that workaround after deployment, after the units are up and you start seeing the errors. I'm guessing it won't help in this case though, and I guess conjure-up will call it a failed deployment and bail out by then anyway.

Anyway, that error in the debug-log indicates this is related to snaps (the package format we use for etcd and kubernetes services), but we'll have to dig deeper to find the underlying cause.

@sanjeevshar can you think of anything noteworthy that's changed since your earlier deployments where you hit the firewall issue? New operating system or anything like that? I ask because those earlier deployments looked to be running the snap services just fine.

If you can run cdk-field-agent again and attach the archive, that would help a lot - we would be looking for snap related apparmor errors in there.

It would also help if you can run this after a failed deployment, before cleaning it up, and share the output:

juju run --all sudo aa-status

@sanjeevshar
Copy link

@Cynerva My master/0 is still in " Waiting for kube-system pods to start" state but here is output of juju run --all sudo aa-status

test@dsib0165:~$ juju run --all sudo aa-status

  • MachineId: "0"
    Stdout: "apparmor module is loaded.\n86 profiles are loaded.\n72 profiles are in
    enforce mode.\n /sbin/dhclient\n /snap/core/4830/usr/lib/snapd/snap-confine\n
    \ /snap/core/4830/usr/lib/snapd/snap-confine//mount-namespace-capture-helper\n
    \ /usr/bin/evince\n /usr/bin/evince-previewer\n /usr/bin/evince-previewer//sanitized_helper\n
    \ /usr/bin/evince-thumbnailer\n /usr/bin/evince-thumbnailer//sanitized_helper\n
    \ /usr/bin/evince//sanitized_helper\n /usr/lib/NetworkManager/nm-dhcp-client.action\n
    \ /usr/lib/NetworkManager/nm-dhcp-helper\n /usr/lib/connman/scripts/dhclient-script\n
    \ /usr/lib/cups/backend/cups-pdf\n /usr/lib/lightdm/lightdm-guest-session\n
    \ /usr/lib/lightdm/lightdm-guest-session//chromium\n /usr/lib/snapd/snap-confine\n
    \ /usr/lib/snapd/snap-confine//mount-namespace-capture-helper\n /usr/sbin/cups-browsed\n
    \ /usr/sbin/cupsd\n /usr/sbin/cupsd//third_party\n /usr/sbin/ippusbxd\n /usr/sbin/tcpdump\n
    \ :lxd-juju-362d18-0_:/sbin/dhclient\n :lxd-juju-362d18-0_:/usr/bin/lxc-start\n
    \ :lxd-juju-362d18-0_:/usr/lib/NetworkManager/nm-dhcp-client.action\n
    \ :lxd-juju-362d18-0_:/usr/lib/NetworkManager/nm-dhcp-helper\n
    \ :lxd-juju-362d18-0_:/usr/lib/connman/scripts/dhclient-script\n
    \ :lxd-juju-362d18-0_:/usr/lib/lxd/lxd-bridge-proxy\n
    \ :lxd-juju-362d18-0_:/usr/lib/snapd/snap-confine\n
    \ :lxd-juju-362d18-0_:/usr/lib/snapd/snap-confine//mount-namespace-capture-helper\n
    \ :lxd-juju-362d18-0_:/usr/sbin/tcpdump\n :lxd-juju-362d18-0_:lxc-container-default\n
    \ :lxd-juju-362d18-0_:lxc-container-default-cgns\n :lxd-juju-362d18-0_:lxc-container-default-with-mounting\n
    \ :lxd-juju-362d18-0_:lxc-container-default-with-nesting\n
    \ lxd-juju-362d18-0_</var/snap/lxd/common/lxd>\n lxd-juju-654e0d-0-lxd-0_</var/lib/lxd>\n
    \ lxd-juju-654e0d-0_</var/snap/lxd/common/lxd>\n lxd-juju-654e0d-1_</var/snap/lxd/common/lxd>\n
    \ snap-update-ns.cdk-addons\n snap-update-ns.conjure-up\n snap-update-ns.core\n
    \ snap-update-ns.etcd\n snap-update-ns.kube-apiserver\n snap-update-ns.kube-controller-manager\n
    \ snap-update-ns.kube-proxy\n snap-update-ns.kube-scheduler\n snap-update-ns.kubectl\n
    \ snap-update-ns.kubelet\n snap-update-ns.lxd\n snap.cdk-addons.apply\n snap.cdk-addons.hook.configure\n
    \ snap.core.hook.configure\n snap.kube-apiserver.daemon\n snap.kube-apiserver.hook.configure\n
    \ snap.kube-apiserver.kube-apiserver\n snap.kube-controller-manager.daemon\n
    \ snap.kube-controller-manager.hook.configure\n snap.kube-controller-manager.kube-controller-manager\n
    \ snap.kube-scheduler.daemon\n snap.kube-scheduler.hook.configure\n snap.kube-scheduler.kube-scheduler\n
    \ snap.lxd.benchmark\n snap.lxd.buginfo\n snap.lxd.check-kernel\n snap.lxd.daemon\n
    \ snap.lxd.hook.configure\n snap.lxd.lxc\n snap.lxd.lxd\n snap.lxd.migrate\n
    \ webbrowser-app\n webbrowser-app//oxide_helper\n14 profiles are in complain
    mode.\n snap.conjure-up.conjure-down\n snap.conjure-up.conjure-up\n snap.conjure-up.hook.configure\n
    \ snap.conjure-up.juju\n snap.conjure-up.juju-wait\n snap.etcd.etcd\n snap.etcd.etcdctl\n
    \ snap.kube-proxy.daemon\n snap.kube-proxy.hook.configure\n snap.kube-proxy.kube-proxy\n
    \ snap.kubectl.kubectl\n snap.kubelet.daemon\n snap.kubelet.hook.configure\n
    \ snap.kubelet.kubelet\n6 processes have profiles defined.\n5 processes are in
    enforce mode.\n /sbin/dhclient (3391) \n /sbin/dhclient (14704) \n snap.kube-apiserver.daemon
    (22611) \n snap.kube-controller-manager.daemon (23535) \n snap.kube-scheduler.daemon
    (24077) \n1 processes are in complain mode.\n snap.etcd.etcd (16277) \n0 processes
    are unconfined but have a profile defined.\n"
  • MachineId: 0/lxd/0
    ReturnCode: 4
    Stderr: |
    sudo: unable to resolve host juju-654e0d-0-lxd-0
    You do not have enough privilege to read the profile set.
    Stdout: |
    apparmor module is loaded.
  • MachineId: "1"
    Stdout: "apparmor module is loaded.\n86 profiles are loaded.\n72 profiles are in
    enforce mode.\n /sbin/dhclient\n /snap/core/4830/usr/lib/snapd/snap-confine\n
    \ /snap/core/4830/usr/lib/snapd/snap-confine//mount-namespace-capture-helper\n
    \ /usr/bin/evince\n /usr/bin/evince-previewer\n /usr/bin/evince-previewer//sanitized_helper\n
    \ /usr/bin/evince-thumbnailer\n /usr/bin/evince-thumbnailer//sanitized_helper\n
    \ /usr/bin/evince//sanitized_helper\n /usr/lib/NetworkManager/nm-dhcp-client.action\n
    \ /usr/lib/NetworkManager/nm-dhcp-helper\n /usr/lib/connman/scripts/dhclient-script\n
    \ /usr/lib/cups/backend/cups-pdf\n /usr/lib/lightdm/lightdm-guest-session\n
    \ /usr/lib/lightdm/lightdm-guest-session//chromium\n /usr/lib/snapd/snap-confine\n
    \ /usr/lib/snapd/snap-confine//mount-namespace-capture-helper\n /usr/sbin/cups-browsed\n
    \ /usr/sbin/cupsd\n /usr/sbin/cupsd//third_party\n /usr/sbin/ippusbxd\n /usr/sbin/tcpdump\n
    \ :lxd-juju-362d18-0_:/sbin/dhclient\n :lxd-juju-362d18-0_:/usr/bin/lxc-start\n
    \ :lxd-juju-362d18-0_:/usr/lib/NetworkManager/nm-dhcp-client.action\n
    \ :lxd-juju-362d18-0_:/usr/lib/NetworkManager/nm-dhcp-helper\n
    \ :lxd-juju-362d18-0_:/usr/lib/connman/scripts/dhclient-script\n
    \ :lxd-juju-362d18-0_:/usr/lib/lxd/lxd-bridge-proxy\n
    \ :lxd-juju-362d18-0_:/usr/lib/snapd/snap-confine\n
    \ :lxd-juju-362d18-0_:/usr/lib/snapd/snap-confine//mount-namespace-capture-helper\n
    \ :lxd-juju-362d18-0_:/usr/sbin/tcpdump\n :lxd-juju-362d18-0_:lxc-container-default\n
    \ :lxd-juju-362d18-0_:lxc-container-default-cgns\n :lxd-juju-362d18-0_:lxc-container-default-with-mounting\n
    \ :lxd-juju-362d18-0_:lxc-container-default-with-nesting\n
    \ lxd-juju-362d18-0_</var/snap/lxd/common/lxd>\n lxd-juju-654e0d-0-lxd-0_</var/lib/lxd>\n
    \ lxd-juju-654e0d-0_</var/snap/lxd/common/lxd>\n lxd-juju-654e0d-1_</var/snap/lxd/common/lxd>\n
    \ snap-update-ns.cdk-addons\n snap-update-ns.conjure-up\n snap-update-ns.core\n
    \ snap-update-ns.etcd\n snap-update-ns.kube-apiserver\n snap-update-ns.kube-controller-manager\n
    \ snap-update-ns.kube-proxy\n snap-update-ns.kube-scheduler\n snap-update-ns.kubectl\n
    \ snap-update-ns.kubelet\n snap-update-ns.lxd\n snap.cdk-addons.apply\n snap.cdk-addons.hook.configure\n
    \ snap.core.hook.configure\n snap.kube-apiserver.daemon\n snap.kube-apiserver.hook.configure\n
    \ snap.kube-apiserver.kube-apiserver\n snap.kube-controller-manager.daemon\n
    \ snap.kube-controller-manager.hook.configure\n snap.kube-controller-manager.kube-controller-manager\n
    \ snap.kube-scheduler.daemon\n snap.kube-scheduler.hook.configure\n snap.kube-scheduler.kube-scheduler\n
    \ snap.lxd.benchmark\n snap.lxd.buginfo\n snap.lxd.check-kernel\n snap.lxd.daemon\n
    \ snap.lxd.hook.configure\n snap.lxd.lxc\n snap.lxd.lxd\n snap.lxd.migrate\n
    \ webbrowser-app\n webbrowser-app//oxide_helper\n14 profiles are in complain
    mode.\n snap.conjure-up.conjure-down\n snap.conjure-up.conjure-up\n snap.conjure-up.hook.configure\n
    \ snap.conjure-up.juju\n snap.conjure-up.juju-wait\n snap.etcd.etcd\n snap.etcd.etcdctl\n
    \ snap.kube-proxy.daemon\n snap.kube-proxy.hook.configure\n snap.kube-proxy.kube-proxy\n
    \ snap.kubectl.kubectl\n snap.kubelet.daemon\n snap.kubelet.hook.configure\n
    \ snap.kubelet.kubelet\n3 processes have profiles defined.\n1 processes are in
    enforce mode.\n /sbin/dhclient (2586) \n2 processes are in complain mode.\n
    \ snap.kube-proxy.daemon (10583) \n snap.kubelet.daemon (10614) \n0 processes
    are unconfined but have a profile defined.\n"

@sanjeevshar
Copy link

cdk-field-agent output. Nothing has changed on my setup. I have even reinstalled Ubuntu 16.04 but no change in behavior.

results-2018-06-25-19-22-17.tar.gz

@Cynerva
Copy link

Cynerva commented Jun 26, 2018

Thanks @sanjeevshar. Sorry for the confusion - the debug-log and juju status that you pasted definitely showed a different error relating to snap profiles - must have been a fluke. The newly attached archive lines up more with what you've been saying.

Anyway, I still see this error:

failed pulling image "k8s.gcr.io/pause-amd64:3.1": error pulling image configuration: Get https://storage.googleapis.com/us.artifacts.google-containers.appspot.com/containers/images/sha256:da86e6ba6ca197bf6bc5e9d900febd906b133eaa4750e6bed647b0fbe50ed43e: x509: certificate signed by unknown authority"

which is the same error you were hitting before, so it looks to me like traffic to storage.googleapis.com is still being intercepted or proxied in some way.

Can you share the output of this command? I'd like to see what the verbose output says about the certificate:

curl -k -v https://storage.googleapis.com/us.artifacts.google-containers.appspot.com/containers/images/sha256:da86e6ba6ca197bf6bc5e9d900febd906b133eaa4750e6bed647b0fbe50ed43e

@sanjeevshar
Copy link

Thank you for your patience @Cynerva . Actually IT had given me a couple of certificates to install on the host which I did and that is how host is able to get to storage.googleapis.com:
test@dsib0165:~$ wget https://storage.googleapis.com/us.artifacts.google-containers.appspot.com/containers/images/sha256:da86e6ba6ca197bf6bc5e9d900febd9 06b133eaa4750e6bed647b0fbe50ed43e
--2018-06-26 09:31:55-- https://storage.googleapis.com/us.artifacts.google-containers.appspot.com/containers/images/sha256:da86e6ba6ca197bf6bc5e9d900fe bd906b133eaa4750e6bed647b0fbe50ed43e
Resolving storage.googleapis.com (storage.googleapis.com)... 172.217.11.16, 2607:f8b0:4006:81b::2010
Connecting to storage.googleapis.com (storage.googleapis.com)|172.217.11.16|:443... connected.
HTTP request sent, awaiting response... 200 OK
Length: 1609 (1.6K) [application/octet-stream]
Saving to: âsha256:da86e6ba6ca197bf6bc5e9d900febd906b133eaa4750e6bed647b0fbe50ed43eâ

sha256:da86e6ba6ca197bf6bc5e9d900febd 100%[=========================================================================>] 1.57K --.-KB/s in 0.01s

2018-06-26 09:31:55 (146 KB/s) - âsha256:da86e6ba6ca197bf6bc5e9d900febd906b133eaa4750e6bed647b0fbe50ed43eâ saved [1609/1609]

Unfortunately when I run the same command on worker or master nodes, it fails:
ubuntu@juju-654e0d-1:~$ wget https://storage.googleapis.com/us.artifacts.google-containers.appspot.com/containers/images/sha256:da86e6ba6ca197bf6bc5e9d900febd906b133eaa4750e6bed647b0fbe50ed43e
--2018-06-26 13:42:01-- https://storage.googleapis.com/us.artifacts.google-containers.appspot.com/containers/images/sha256:da86e6ba6ca197bf6bc5e9d900febd906b133eaa4750e6bed647b0fbe50ed43e
Resolving storage.googleapis.com (storage.googleapis.com)... 172.217.12.208, 2607:f8b0:4006:814::2010
Connecting to storage.googleapis.com (storage.googleapis.com)|172.217.12.208|:443... connected.
ERROR: cannot verify storage.googleapis.com's certificate, issued by âCN=EMC SSL Decryption Authority,OU=Global Security Organization,O=EMC Corporation,C=US,emailAddress=GSO_WebSec@emc.comâ:
Unable to locally verify the issuer's authority.

Is there a workaround for this? Now I have installed certificates on master/0 and worker/0 and they are able to get to storage.googleapis.com but how do restart/continue the installation using same containers.

@adam-stokes
Copy link
Contributor

@sanjeevshar If you wanted to clone our spells repo and add the certificate stuff in a before-wait hook, you could then deploy from that altered spell. Soon as I get some time I'll provide you with an example spell on what it would look like

@sanjeevshar
Copy link

@battlemidget Whenever you get time please post an example. It kind of sounds strange that the cluster deployment in on private cloud environment requires node to directly to storage.googleapis.com to fetch files. I think modes should get everything from the host that it needs for installation.
I deployed Pivtoal container services (PKS) in the same environment with VSphere as cloud provider and had no issues.

@adam-stokes
Copy link
Contributor

@sanjeevshar I believe we're working on an offline deployment that may solve this issue, @Cynerva is this correct?

@sanjeevshar Please see the following docs for summoning spells from local directory:

https://docs.conjure-up.io/devel/en/usage#github-and-bitbucket

You'll want to git clone https://github.com/conjure-up/spells as well.

@Cynerva
Copy link

Cynerva commented Jul 16, 2018

I believe we're working on an offline deployment that may solve this issue, @Cynerva is this correct?

The best we have is a documented path for installing CDK in a network-restricted environment, without conjure-up: https://github.com/juju-solutions/bundle-canonical-kubernetes/wiki/Running-CDK-in-a-restricted-environment

Those instructions include setting up your own docker registry with the necessary images, and configuring Kubernetes to use it. That would take the direct calls to storage.googleapis.com out of the picture.

So it's doable, but not straightforward. I don't think that's going to change any time soon.

@dswersky
Copy link

I just had this same problem with identical symptoms. I provisioned a 4CPU/16GB AWS EC2 instance and ran conjure-up to install Kubernetes. The process hung with the master nodes waiting to start. The CloudWatch monitor showed the CPUs pegged at 85% the entire time, until I killed the lxc containers.

I killed that instance, and started an 8CPU/32GB instance. As expected, the process went MUCH faster. The deployment was successful. CPU utilization peaked briefly at 76%, then fell to about 28% after the deployment completed. Last I checked, the CPUs are sitting at 8% utilization with the cluster running.

Of course my experience is likely just one of many potential causes for the symptoms reported in this thread.

@mukundmr
Copy link

This problem seems to go away if you choose "dir" as the storage mechanism for lxd when you do "lxd init". This is as per documentation at conjureup.io

@sparse91
Copy link

This problem can be solved by lots of CPU cores and Memory. I started by 4CPU/4GB then Increasing to 32CPU/16GB solved it and made installation so fast.

Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Projects
None yet
Development

No branches or pull requests

10 participants