Still waiting for the Kubernetes API: the server could not find the requested resource

[doyoungim999]

Version

$ openshift-install version
./openshift-install unreleased-master-2361-g6a0eb9136b57a8e2609554b0eaeed3a3534e8cab
built from commit 6a0eb9136b57a8e2609554b0eaeed3a3534e8cab
release image registry.svc.ci.openshift.org/origin/release:4.3

Platform:

What happened?

I run "./installer/bin/openshift-install --dir install-dir create cluster"
and can see the message:
INFO Obtaining RHCOS image file from 'https://releases-art-rhcos.svc.ci.openshift.org/art/storage/releases/rhcos-4.4/44.81.202001030903.0/x86_64/rhcos-44.81.202001030903.0-qemu.x86_64.qcow2.gz?sha256=a7931dc062f4dcd1f614e487bac8c53699d54c78c84caa7b528a52f6788b5504'
INFO The file was found in cache: /root/.cache/openshift-installer/image_cache/b9c268865d8b4d59ae24cb5ebdaee1b7. Reusing...
INFO Creating infrastructure resources...
INFO Waiting up to 30m0s for the Kubernetes API at https://api.mycluster.5u04.info:6443...

When I check with

racut:/# [ 1687.486884] ignition[1600]: GET https://api-int.mycluster.5u04.info:22623/config/master: attempt #269
[ 1687.494911] ignition[1600]: GET error: Get https://api-int.mycluster.5u04.info:22623/config/master: dial tcp: lookup api-int.mycluster.5u04.info on 192.168.126.1:53: dial udp 192.168.126.1:53: connect: network is unreachable
exit[ 1692.487719] ignition[1600]: GET https://api-int.mycluster.5u04.info:22623/config/master: attempt #270
[ 1692.495112] ignition[1600]: GET error: Get https://api-int.mycluster.5u04.info:22623/config/master: dial tcp: lookup api-int.mycluster.5u04.info on 192.168.126.1:53: dial udp 192.168.126.1:53: connect: network is unreachable
[ 1697.213307] dhclient[932]: DHCPREQUEST on ens3 to 192.168.126.1 port 67 (xid=0xe7408a54)
[ 1697.218104] dhclient[932]: send_packet: Network is unreachable
[ 1697.220282] dhclient[932]: send_packet: please consult README file regarding broadcast address.
[ 1697.223543] dhclient[932]: dhclient.c:3099: Failed to send 300 byte long packet over fallback interface.
[ 1697.489567] ignition[1600]: GET https://api-int.mycluster.5u04.info:22623/config/master: attempt #271
[ 1697.498060] ignition[1600]: GET error: Get https://api-int.mycluster.5u04.info:22623/config/master: dial tcp: lookup api-int.mycluster.5u04.info on 192.168.126.1:53: dial udp 192.168.126.1:53: connect: network is unreachable

#ping api.mycluster.5u04.info
PING api.mycluster.5u04.info (192.168.126.10) 56(84) bytes of data.
64 bytes from api.mycluster.5u04.info (192.168.126.10): icmp_seq=1 ttl=64 time=0.147 ms
64 bytes from api.mycluster.5u04.info (192.168.126.10): icmp_seq=2 ttl=64 time=0.328 ms

ping is fine in my baremetal machine but I see "unreachable" error from VM continuously.

What you expected to happen?

Unrechearble error is removed.

How to reproduce it (as minimally and precisely as possible)?

All the time.

$ your-commands-here

Anything else we need to know?

As a result:
INFO Obtaining RHCOS image file from 'https://releases-art-rhcos.svc.ci.openshift.org/art/storage/releases/rhcos-4.4/44.81.202001030903.0/x86_64/rhcos-44.81.202001030903.0-qemu.x86_64.qcow2.gz?sha256=a7931dc062f4dcd1f614e487bac8c53699d54c78c84caa7b528a52f6788b5504'
INFO The file was found in cache: /root/.cache/openshift-installer/image_cache/b9c268865d8b4d59ae24cb5ebdaee1b7. Reusing...
INFO Creating infrastructure resources...
INFO Waiting up to 30m0s for the Kubernetes API at https://api.mycluster.5u04.info:6443...
ERROR Attempted to gather ClusterOperator status after installation failure: listing ClusterOperator objects: the server could not find the requested resource (get clusteroperators.config.openshift.io)
INFO Pulling debug logs from the bootstrap machine
ERROR Attempted to gather debug logs after installation failure: failed to create SSH client, ensure the proper ssh key is in your keyring or specify with --key: dial tcp 185.53.177.30:22: connect: connection timed out
FATAL Bootstrap failed to complete: waiting for Kubernetes API: context deadline exceeded

References

• enter text here.

[pierreprinetti]

Please specify the platform type: aws, libvirt, openstack, baremetal, or none (UPI)

[doyoungim999]

Platform is libvirt

[abhinavdahiya]

can you include the .openshift_install.log ?

[doyoungim999]

Hi I attached the last part which is issues:
What is the ip for api and etcd ?
I added in /etc/hosts
192.168.126.10 api.ocp.5u04.info api-int.ocp.5u04.info
192.168.126.11 etcd.ocp.5u04.info

time="2020-01-28T22:05:25-06:00" level=debug msg="libvirt_domain.master[0]: Creation complete after 1s [id=4fe0f2ea-60f8-42bf-b5a0-10fba8b4c8de]"
time="2020-01-28T22:05:25-06:00" level=debug
time="2020-01-28T22:05:25-06:00" level=debug msg="Apply complete! Resources: 9 added, 0 changed, 0 destroyed."
time="2020-01-28T22:05:25-06:00" level=debug msg="OpenShift Installer unreleased-master-2447-g1cc6935753842458bb84504f8e247ddc86cb93c7-dirty"
time="2020-01-28T22:05:25-06:00" level=debug msg="Built from commit 1cc6935"
time="2020-01-28T22:05:25-06:00" level=info msg="Waiting up to 20m0s for the Kubernetes API at https://api.ocp.5u04.info:6443..."
time="2020-01-28T22:05:28-06:00" level=debug msg="Still waiting for the Kubernetes API: Get https://api.ocp.5u04.info:6443/version?timeout=32s: dial tcp 192.168.122.10:6443: connect: no route to host"
time="2020-01-28T22:06:14-06:00" level=debug msg="Still waiting for the Kubernetes API: Get https://api.ocp.5u04.info:6443/version?timeout=32s: dial tcp 192.168.122.10:6443: connect: no route to host"
time="2020-01-28T22:06:44-06:00" level=debug msg="Still waiting for the Kubernetes API: Get https://api.ocp.5u04.info:6443/version?timeout=32s: dial tcp 192.168.126.10:6443: connect: connection refused"
time="2020-01-28T22:07:14-06:00" level=debug msg="Still waiting for the Kubernetes API: Get https://api.ocp.5u04.info:6443/version?timeout=32s: dial tcp 192.168.126.10:6443: connect: connection refused"
time="2020-01-28T22:07:36-06:00" level=debug msg="Still waiting for the Kubernetes API: Get https://api.ocp.5u04.info:6443/version?timeout=32s: x509: certificate signed by unknown authority (possibly because of "crypto/rsa: verification error" while trying to verify candidate authority certificate "kube-apiserver-lb-signer")"
time="2020-01-28T22:08:06-06:00" level=debug msg="Still waiting for the Kubernetes API: Get https://api.ocp.5u04.info:6443/version?timeout=32s: x509: certificate signed by unknown authority (possibly because of "crypto/rsa: verification error" while trying to verify candidate authority certificate "kube-apiserver-lb-signer")"

[doyoungim999]

I found It does not matter with my /etc/hosts file. domain url is looking for their ip.
api server is not started ?

time="2020-01-28T22:42:04-06:00" level=info msg="Waiting up to 20m0s for the Kubernetes API at https://api.mycluster.5u04.info:6443..."
time="2020-01-28T22:42:07-06:00" level=debug msg="Still waiting for the Kubernetes API: Get https://api.mycluster.5u04.info:6443/version?timeout=32s: dial tcp 192.168.126.10:6443: connect: no route to host"
time="2020-01-28T22:42:15-06:00" level=debug msg="Still waiting for the Kubernetes API: Get https://api.mycluster.5u04.info:6443/version?timeout=32s: dial tcp 192.168.126.10:6443: connect: connection refused"
time="2020-01-28T22:42:45-06:00" level=debug msg="Still waiting for the Kubernetes API: Get https://api.mycluster.5u04.info:6443/version?timeout=32s: dial tcp 192.168.126.10:6443: connect: connection refused"
time="2020-01-28T22:43:15-06:00" level=debug msg="Still waiting for the Kubernetes API: Get https://api.mycluster.5u04.info:6443/version?timeout=32s: dial tcp 192.168.126.10:6443: connect: connection refused"
time="2020-01-28T22:43:48-06:00" level=debug msg="Still waiting for the Kubernetes API: Get https://api.mycluster.5u04.info:6443/version?timeout=32s: dial tcp 192.168.126.10:6443: connect: connection refused"
time="2020-01-28T22:44:10-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T22:44:40-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"

[doyoungim999]

Hi
I am using libvirt.
from the issue#1909 ( #1909)
I can find out the same issue is resolved after he changed to
registry.svc.ci.openshift.org/origin/release:4.2.
i cannot find out in virtlib image. Where can I fix it ?

[doyoungim999]

This is whole log:
time="2020-01-28T23:28:44-06:00" level=debug msg="OpenShift Installer unreleased-master-2447-g1cc6935753842458bb84504f8e247ddc86cb93c7-dirty"
time="2020-01-28T23:28:44-06:00" level=debug msg="Built from commit 1cc6935"
time="2020-01-28T23:28:44-06:00" level=debug msg="Fetching Master Machines..."
time="2020-01-28T23:28:44-06:00" level=debug msg="Loading Master Machines..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading SSH Key..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Base Domain..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Platform..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Cluster Name..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Base Domain..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Platform..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Pull Secret..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Platform..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Platform Credentials Check..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Image..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Master Ignition Config..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Loading Root CA..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Fetching Cluster ID..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Fetching SSH Key..."
time="2020-01-28T23:28:44-06:00" level=debug msg=" Generating SSH Key..."
time="2020-01-28T23:28:47-06:00" level=debug msg=" Fetching Base Domain..."
time="2020-01-28T23:28:47-06:00" level=debug msg=" Fetching Platform..."
time="2020-01-28T23:28:47-06:00" level=debug msg=" Generating Platform..."
time="2020-01-28T23:28:59-06:00" level=debug msg=" Generating Base Domain..."
time="2020-01-28T23:29:05-06:00" level=debug msg=" Fetching Cluster Name..."
time="2020-01-28T23:29:05-06:00" level=debug msg=" Fetching Base Domain..."
time="2020-01-28T23:29:05-06:00" level=debug msg=" Reusing previously-fetched Base Domain"
time="2020-01-28T23:29:05-06:00" level=debug msg=" Fetching Platform..."
time="2020-01-28T23:29:05-06:00" level=debug msg=" Reusing previously-fetched Platform"
time="2020-01-28T23:29:05-06:00" level=debug msg=" Generating Cluster Name..."
time="2020-01-28T23:29:08-06:00" level=debug msg=" Fetching Pull Secret..."
time="2020-01-28T23:29:08-06:00" level=debug msg=" Generating Pull Secret..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Platform..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Platform"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Cluster ID..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Platform Credentials Check..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Platform Credentials Check..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Image..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Image..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Master Ignition Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Root CA..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Root CA..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Master Ignition Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg="Generating Master Machines..."
time="2020-01-28T23:29:16-06:00" level=debug msg="Fetching Worker Machines..."
time="2020-01-28T23:29:16-06:00" level=debug msg="Loading Worker Machines..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Platform Credentials Check..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Image..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Worker Ignition Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Root CA..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Cluster ID..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Cluster ID"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Platform Credentials Check..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Platform Credentials Check"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Image..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Image"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Worker Ignition Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Root CA..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Root CA"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Worker Ignition Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg="Generating Worker Machines..."
time="2020-01-28T23:29:16-06:00" level=debug msg="Fetching Common Manifests..."
time="2020-01-28T23:29:16-06:00" level=debug msg="Loading Common Manifests..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Ingress Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading DNS Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Platform Credentials Check..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Infrastructure Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Cloud Provider Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Platform Credentials Check..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Additional Trust Bundle Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Network Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Network CRDs..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Proxy Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Network Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Scheduler Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Image Content Source Policy..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Root CA..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Certificate (etcd-signer)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Certificate (etcd-ca-bundle)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Certificate (etcd-signer)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Certificate (etcd-client)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Certificate (etcd-signer)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Certificate (etcd-metric-ca-bundle)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Certificate (etcd-metric-signer)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Certificate (etcd-metric-signer)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Certificate (etcd-metric-signer-client)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Certificate (etcd-metric-signer)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Certificate (mcs)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Root CA..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading CVOOverrides..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading EtcdCAConfigMap..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading EtcdClientSecret..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading EtcdHostServiceEndpoints..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading EtcdHostService..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading EtcdMetricClientSecret..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading EtcdMetricServingCAConfigMap..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading EtcdMetricSignerSecret..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading EtcdNamespace..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading EtcdService..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading EtcdSignerSecret..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading KubeCloudConfig..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading EtcdServingCAConfigMap..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading KubeSystemConfigmapRootCA..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading MachineConfigServerTLSSecret..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading OpenshiftConfigSecretPullSecret..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Loading OpenshiftMachineConfigOperator..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Cluster ID..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Cluster ID"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Ingress Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Ingress Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching DNS Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Cluster ID..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Cluster ID"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Platform Credentials Check..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Platform Credentials Check"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating DNS Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Infrastructure Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Cluster ID..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Cluster ID"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Cloud Provider Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Cluster ID..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Cluster ID"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Platform Credentials Check..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Platform Credentials Check"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Cloud Provider Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Additional Trust Bundle Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Additional Trust Bundle Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Infrastructure Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Network Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Network CRDs..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Network CRDs..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Network Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Proxy Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Network Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Network Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Proxy Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Scheduler Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Scheduler Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Image Content Source Policy..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Image Content Source Policy..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Root CA..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Root CA"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Certificate (etcd-signer)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Certificate (etcd-signer)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Certificate (etcd-ca-bundle)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Certificate (etcd-signer)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Certificate (etcd-signer)"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Certificate (etcd-ca-bundle)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Certificate (etcd-client)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Fetching Certificate (etcd-signer)..."
time="2020-01-28T23:29:16-06:00" level=debug msg=" Reusing previously-fetched Certificate (etcd-signer)"
time="2020-01-28T23:29:16-06:00" level=debug msg=" Generating Certificate (etcd-client)..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching Certificate (etcd-metric-ca-bundle)..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching Certificate (etcd-metric-signer)..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating Certificate (etcd-metric-signer)..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating Certificate (etcd-metric-ca-bundle)..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching Certificate (etcd-metric-signer)..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Reusing previously-fetched Certificate (etcd-metric-signer)"
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching Certificate (etcd-metric-signer-client)..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching Certificate (etcd-metric-signer)..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Reusing previously-fetched Certificate (etcd-metric-signer)"
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating Certificate (etcd-metric-signer-client)..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching Certificate (mcs)..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching Root CA..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Reusing previously-fetched Root CA"
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating Certificate (mcs)..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching CVOOverrides..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating CVOOverrides..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching EtcdCAConfigMap..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating EtcdCAConfigMap..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching EtcdClientSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating EtcdClientSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching EtcdHostServiceEndpoints..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating EtcdHostServiceEndpoints..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching EtcdHostService..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating EtcdHostService..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching EtcdMetricClientSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating EtcdMetricClientSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching EtcdMetricServingCAConfigMap..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating EtcdMetricServingCAConfigMap..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching EtcdMetricSignerSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating EtcdMetricSignerSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching EtcdNamespace..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating EtcdNamespace..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching EtcdService..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating EtcdService..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching EtcdSignerSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating EtcdSignerSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching KubeCloudConfig..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating KubeCloudConfig..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching EtcdServingCAConfigMap..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating EtcdServingCAConfigMap..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching KubeSystemConfigmapRootCA..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating KubeSystemConfigmapRootCA..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching MachineConfigServerTLSSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating MachineConfigServerTLSSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching OpenshiftConfigSecretPullSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating OpenshiftConfigSecretPullSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching OpenshiftMachineConfigOperator..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating OpenshiftMachineConfigOperator..."
time="2020-01-28T23:29:17-06:00" level=debug msg="Generating Common Manifests..."
time="2020-01-28T23:29:17-06:00" level=debug msg="Fetching Openshift Manifests..."
time="2020-01-28T23:29:17-06:00" level=debug msg="Loading Openshift Manifests..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Loading Kubeadmin Password..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Loading CloudCredsSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Loading KubeadminPasswordSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Loading RoleCloudCredsSecretReader..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Loading Private Cluster Outbound Service..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Loading Baremetal Config CR..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching Cluster ID..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Reusing previously-fetched Cluster ID"
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching Kubeadmin Password..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating Kubeadmin Password..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching CloudCredsSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating CloudCredsSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching KubeadminPasswordSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating KubeadminPasswordSecret..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching RoleCloudCredsSecretReader..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating RoleCloudCredsSecretReader..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching Private Cluster Outbound Service..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating Private Cluster Outbound Service..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Fetching Baremetal Config CR..."
time="2020-01-28T23:29:17-06:00" level=debug msg=" Generating Baremetal Config CR..."
time="2020-01-28T23:29:17-06:00" level=debug msg="Generating Openshift Manifests..."
time="2020-01-28T23:29:53-06:00" level=debug msg="OpenShift Installer unreleased-master-2447-g1cc6935753842458bb84504f8e247ddc86cb93c7-dirty"
time="2020-01-28T23:29:53-06:00" level=debug msg="Built from commit 1cc6935"
time="2020-01-28T23:29:53-06:00" level=debug msg="Fetching Terraform Variables..."
time="2020-01-28T23:29:53-06:00" level=debug msg="Loading Terraform Variables..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading SSH Key..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using SSH Key loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Base Domain..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Platform..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Platform loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Base Domain loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Cluster Name..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Base Domain..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Platform..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Cluster Name loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Pull Secret..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Pull Secret loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Platform..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Install Config loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Cluster ID loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Image..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Image loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading BootstrapImage..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Bootstrap Ignition Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Kubeconfig Admin Internal Client..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (admin-kubeconfig-client)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (admin-kubeconfig-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-complete-server-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-localhost-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-localhost-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-service-network-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-service-network-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-lb-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-lb-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Kubeconfig Kubelet..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-complete-server-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kubelet-client)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kubelet-bootstrap-kubeconfig-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Kubeconfig Admin Client (Loopback)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (admin-kubeconfig-client)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-localhost-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Master Machines..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Platform Credentials Check..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Platform Credentials Check loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Image..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Master Ignition Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Root CA..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Root CA loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Master Ignition Config loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Master Machines from both state file and target directory"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Master Machines loaded from target directory"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Worker Machines..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Platform Credentials Check..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Image..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Worker Ignition Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Root CA..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Worker Ignition Config loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Worker Machines from both state file and target directory"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Worker Machines loaded from target directory"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Common Manifests..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Ingress Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Ingress Config loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading DNS Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Platform Credentials Check..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using DNS Config loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Infrastructure Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Cloud Provider Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Platform Credentials Check..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Cloud Provider Config loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Additional Trust Bundle Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Additional Trust Bundle Config loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Infrastructure Config loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Network Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Network CRDs..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Network CRDs loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Network Config loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Proxy Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Network Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Proxy Config loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Scheduler Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Scheduler Config loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Image Content Source Policy..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Image Content Source Policy loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Root CA..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (etcd-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Certificate (etcd-signer) loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (etcd-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (etcd-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Certificate (etcd-ca-bundle) loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (etcd-client)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (etcd-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Certificate (etcd-client) loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (etcd-metric-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (etcd-metric-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Certificate (etcd-metric-signer) loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Certificate (etcd-metric-ca-bundle) loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (etcd-metric-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (etcd-metric-signer-client)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (etcd-metric-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Certificate (etcd-metric-signer-client) loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (mcs)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Root CA..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Certificate (mcs) loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading CVOOverrides..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using CVOOverrides loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading EtcdCAConfigMap..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using EtcdCAConfigMap loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading EtcdClientSecret..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using EtcdClientSecret loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading EtcdHostServiceEndpoints..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using EtcdHostServiceEndpoints loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading EtcdHostService..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using EtcdHostService loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading EtcdMetricClientSecret..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using EtcdMetricClientSecret loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading EtcdMetricServingCAConfigMap..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using EtcdMetricServingCAConfigMap loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading EtcdMetricSignerSecret..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using EtcdMetricSignerSecret loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading EtcdNamespace..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using EtcdNamespace loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading EtcdService..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using EtcdService loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading EtcdSignerSecret..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using EtcdSignerSecret loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading KubeCloudConfig..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using KubeCloudConfig loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading EtcdServingCAConfigMap..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using EtcdServingCAConfigMap loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading KubeSystemConfigmapRootCA..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using KubeSystemConfigmapRootCA loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading MachineConfigServerTLSSecret..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using MachineConfigServerTLSSecret loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading OpenshiftConfigSecretPullSecret..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using OpenshiftConfigSecretPullSecret loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading OpenshiftMachineConfigOperator..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using OpenshiftMachineConfigOperator loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Common Manifests from both state file and target directory"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Common Manifests loaded from target directory"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Openshift Manifests..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Kubeadmin Password..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Kubeadmin Password loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading CloudCredsSecret..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using CloudCredsSecret loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading KubeadminPasswordSecret..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using KubeadminPasswordSecret loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading RoleCloudCredsSecretReader..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using RoleCloudCredsSecretReader loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Private Cluster Outbound Service..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Private Cluster Outbound Service loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Baremetal Config CR..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Baremetal Config CR loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Openshift Manifests from both state file and target directory"
time="2020-01-28T23:29:53-06:00" level=debug msg=" On-disk Openshift Manifests matches asset in state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Using Openshift Manifests loaded from state file"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Proxy Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (admin-kubeconfig-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (admin-kubeconfig-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (aggregator)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (aggregator-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (aggregator-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (system:kube-apiserver-proxy)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (aggregator-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (aggregator-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (system:kube-apiserver-proxy)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (aggregator)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (etcd-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (etcd-metric-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (etcd-metric-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (etcd-metric-signer-client)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (etcd-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (etcd-client)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (journal-gatewayd)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Root CA..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-lb-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-external-lb-server)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-lb-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-internal-lb-server)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-lb-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-lb-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-localhost-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-localhost-server)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-localhost-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-localhost-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-service-network-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-service-network-server)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-service-network-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-service-network-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-complete-server-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-complete-client-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (admin-kubeconfig-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kubelet-client-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kubelet-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-control-plane-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-control-plane-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-lb-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-localhost-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-service-network-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-to-kubelet-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-to-kubelet-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kubelet-bootstrap-kubeconfig-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kubelet-bootstrap-kubeconfig-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-to-kubelet-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-to-kubelet-client)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-to-kubelet-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-apiserver-to-kubelet-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-control-plane-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-control-plane-kube-controller-manager-client)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-control-plane-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-control-plane-kube-scheduler-client)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-control-plane-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kube-control-plane-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kubelet-bootstrap-kubeconfig-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kubelet-client-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kubelet-client)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kubelet-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kubelet-serving-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (kubelet-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Certificate (mcs)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Root CA..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Key Pair (service-account.pub)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Release Image Pull Spec..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Image..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Master Ignition Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Master Machines..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Loading Worker Machines..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Fetching Cluster ID..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Reusing previously-fetched Cluster ID"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Fetching Image..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Reusing previously-fetched Image"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Fetching BootstrapImage..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Generating BootstrapImage..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Fetching Bootstrap Ignition Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:53-06:00" level=debug msg=" Fetching Kubeconfig Admin Internal Client..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Fetching Certificate (admin-kubeconfig-client)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Fetching Certificate (admin-kubeconfig-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Generating Certificate (admin-kubeconfig-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Generating Certificate (admin-kubeconfig-client)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-complete-server-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-localhost-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-localhost-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Generating Certificate (kube-apiserver-localhost-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Generating Certificate (kube-apiserver-localhost-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-service-network-ca-bundle)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-service-network-signer)..."
time="2020-01-28T23:29:53-06:00" level=debug msg=" Generating Certificate (kube-apiserver-service-network-signer)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Generating Certificate (kube-apiserver-service-network-ca-bundle)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-lb-ca-bundle)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-lb-signer)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Generating Certificate (kube-apiserver-lb-signer)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Generating Certificate (kube-apiserver-lb-ca-bundle)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Generating Certificate (kube-apiserver-complete-server-ca-bundle)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:54-06:00" level=debug msg=" Generating Kubeconfig Admin Internal Client..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Kubeconfig Kubelet..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-complete-server-ca-bundle)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-complete-server-ca-bundle)"
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Certificate (kubelet-client)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Certificate (kubelet-bootstrap-kubeconfig-signer)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Generating Certificate (kubelet-bootstrap-kubeconfig-signer)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Generating Certificate (kubelet-client)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:54-06:00" level=debug msg=" Generating Kubeconfig Kubelet..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Kubeconfig Admin Client (Loopback)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Certificate (admin-kubeconfig-client)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Reusing previously-fetched Certificate (admin-kubeconfig-client)"
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-localhost-ca-bundle)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-localhost-ca-bundle)"
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:54-06:00" level=debug msg=" Generating Kubeconfig Admin Client (Loopback)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Master Machines..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Reusing previously-fetched Master Machines"
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Worker Machines..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Reusing previously-fetched Worker Machines"
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Common Manifests..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Reusing previously-fetched Common Manifests"
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Openshift Manifests..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Reusing previously-fetched Openshift Manifests"
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Proxy Config..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Reusing previously-fetched Proxy Config"
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Certificate (admin-kubeconfig-ca-bundle)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Certificate (admin-kubeconfig-signer)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Reusing previously-fetched Certificate (admin-kubeconfig-signer)"
time="2020-01-28T23:29:54-06:00" level=debug msg=" Generating Certificate (admin-kubeconfig-ca-bundle)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Certificate (aggregator)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Generating Certificate (aggregator)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Certificate (aggregator-ca-bundle)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Fetching Certificate (aggregator-signer)..."
time="2020-01-28T23:29:54-06:00" level=debug msg=" Generating Certificate (aggregator-signer)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Generating Certificate (aggregator-ca-bundle)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (system:kube-apiserver-proxy)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (aggregator-signer)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (aggregator-signer)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Generating Certificate (system:kube-apiserver-proxy)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (aggregator-signer)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (aggregator-signer)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (system:kube-apiserver-proxy)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (aggregator)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (aggregator)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Generating Certificate (system:kube-apiserver-proxy)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (etcd-ca-bundle)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (etcd-ca-bundle)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (etcd-metric-ca-bundle)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (etcd-metric-ca-bundle)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (etcd-metric-signer)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (etcd-metric-signer)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (etcd-metric-signer-client)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (etcd-metric-signer-client)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (etcd-signer)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (etcd-signer)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (etcd-client)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (etcd-client)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (journal-gatewayd)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Root CA..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Root CA"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Generating Certificate (journal-gatewayd)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-lb-ca-bundle)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-lb-ca-bundle)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-external-lb-server)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-lb-signer)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-lb-signer)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Generating Certificate (kube-apiserver-external-lb-server)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-internal-lb-server)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-lb-signer)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-lb-signer)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Generating Certificate (kube-apiserver-internal-lb-server)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-lb-signer)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-lb-signer)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-localhost-ca-bundle)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-localhost-ca-bundle)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-localhost-server)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-localhost-signer)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-localhost-signer)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Generating Certificate (kube-apiserver-localhost-server)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-localhost-signer)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-localhost-signer)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-service-network-ca-bundle)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-service-network-ca-bundle)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-service-network-server)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-service-network-signer)..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-service-network-signer)"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:55-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:55-06:00" level=debug msg=" Generating Certificate (kube-apiserver-service-network-server)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-service-network-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-service-network-signer)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-complete-server-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-complete-server-ca-bundle)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-complete-client-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (admin-kubeconfig-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (admin-kubeconfig-ca-bundle)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kubelet-client-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kubelet-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Generating Certificate (kubelet-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Generating Certificate (kubelet-client-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-control-plane-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-control-plane-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Generating Certificate (kube-control-plane-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-lb-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-lb-signer)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-localhost-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-localhost-signer)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-service-network-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-service-network-signer)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Generating Certificate (kube-control-plane-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-to-kubelet-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-to-kubelet-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Generating Certificate (kube-apiserver-to-kubelet-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Generating Certificate (kube-apiserver-to-kubelet-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kubelet-bootstrap-kubeconfig-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kubelet-bootstrap-kubeconfig-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kubelet-bootstrap-kubeconfig-signer)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Generating Certificate (kubelet-bootstrap-kubeconfig-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Generating Certificate (kube-apiserver-complete-client-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-to-kubelet-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-to-kubelet-ca-bundle)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-to-kubelet-client)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-to-kubelet-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-to-kubelet-signer)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Generating Certificate (kube-apiserver-to-kubelet-client)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-to-kubelet-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-to-kubelet-signer)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-control-plane-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-control-plane-ca-bundle)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-control-plane-kube-controller-manager-client)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-control-plane-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-control-plane-signer)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Generating Certificate (kube-control-plane-kube-controller-manager-client)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-control-plane-kube-scheduler-client)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-control-plane-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-control-plane-signer)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Generating Certificate (kube-control-plane-kube-scheduler-client)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kube-control-plane-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-control-plane-signer)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kubelet-bootstrap-kubeconfig-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kubelet-bootstrap-kubeconfig-ca-bundle)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kubelet-client-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kubelet-client-ca-bundle)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kubelet-client)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kubelet-client)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kubelet-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kubelet-signer)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kubelet-serving-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (kubelet-signer)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (kubelet-signer)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Generating Certificate (kubelet-serving-ca-bundle)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Certificate (mcs)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Certificate (mcs)"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Root CA..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Root CA"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Key Pair (service-account.pub)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Generating Key Pair (service-account.pub)..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Release Image Pull Spec..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Generating Release Image Pull Spec..."
time="2020-01-28T23:29:56-06:00" level=debug msg="Using internal constant for release image registry.svc.ci.openshift.org/origin/release:4.3"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Image..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Image"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Generating Bootstrap Ignition Config..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Master Ignition Config..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Master Ignition Config"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Master Machines..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Master Machines"
time="2020-01-28T23:29:56-06:00" level=debug msg=" Fetching Worker Machines..."
time="2020-01-28T23:29:56-06:00" level=debug msg=" Reusing previously-fetched Worker Machines"
time="2020-01-28T23:29:56-06:00" level=debug msg="Generating Terraform Variables..."
time="2020-01-28T23:29:57-06:00" level=info msg="Obtaining RHCOS image file from 'https://releases-art-rhcos.svc.ci.openshift.org/art/storage/releases/rhcos-4.4/44.81.202001241431.0/x86_64/rhcos-44.81.202001241431.0-qemu.x86_64.qcow2.gz?sha256=56154a5c68e94879ff276e9ad6a7efc080cb0cb99b034f44e1bcd41a371f6878'"
time="2020-01-28T23:29:57-06:00" level=info msg="The file was found in cache: /root/.cache/openshift-installer/image_cache/893b690279d58ad4a014862439fd1b95. Reusing..."
time="2020-01-28T23:29:57-06:00" level=info msg="Consuming Worker Machines from target directory"
time="2020-01-28T23:29:57-06:00" level=debug msg="Purging asset "Worker Machines" from disk"
time="2020-01-28T23:29:57-06:00" level=info msg="Consuming Openshift Manifests from target directory"
time="2020-01-28T23:29:57-06:00" level=debug msg="Purging asset "Openshift Manifests" from disk"
time="2020-01-28T23:29:57-06:00" level=info msg="Consuming Master Machines from target directory"
time="2020-01-28T23:29:57-06:00" level=debug msg="Purging asset "Master Machines" from disk"
time="2020-01-28T23:29:57-06:00" level=info msg="Consuming Common Manifests from target directory"
time="2020-01-28T23:29:57-06:00" level=debug msg="Purging asset "Common Manifests" from disk"
time="2020-01-28T23:29:57-06:00" level=debug msg="Fetching Kubeconfig Admin Client..."
time="2020-01-28T23:29:57-06:00" level=debug msg="Loading Kubeconfig Admin Client..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Loading Certificate (admin-kubeconfig-client)..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Loading Certificate (kube-apiserver-complete-server-ca-bundle)..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Fetching Certificate (admin-kubeconfig-client)..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Reusing previously-fetched Certificate (admin-kubeconfig-client)"
time="2020-01-28T23:29:57-06:00" level=debug msg=" Fetching Certificate (kube-apiserver-complete-server-ca-bundle)..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Reusing previously-fetched Certificate (kube-apiserver-complete-server-ca-bundle)"
time="2020-01-28T23:29:57-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:57-06:00" level=debug msg="Generating Kubeconfig Admin Client..."
time="2020-01-28T23:29:57-06:00" level=debug msg="Fetching Kubeadmin Password..."
time="2020-01-28T23:29:57-06:00" level=debug msg="Reusing previously-fetched Kubeadmin Password"
time="2020-01-28T23:29:57-06:00" level=debug msg="Fetching Certificate (journal-gatewayd)..."
time="2020-01-28T23:29:57-06:00" level=debug msg="Reusing previously-fetched Certificate (journal-gatewayd)"
time="2020-01-28T23:29:57-06:00" level=debug msg="Fetching Metadata..."
time="2020-01-28T23:29:57-06:00" level=debug msg="Loading Metadata..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Fetching Cluster ID..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Reusing previously-fetched Cluster ID"
time="2020-01-28T23:29:57-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:57-06:00" level=debug msg="Generating Metadata..."
time="2020-01-28T23:29:57-06:00" level=debug msg="Fetching Cluster..."
time="2020-01-28T23:29:57-06:00" level=debug msg="Loading Cluster..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Loading Cluster ID..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Loading Install Config..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Loading Platform Credentials Check..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Loading Terraform Variables..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Loading Kubeadmin Password..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Fetching Cluster ID..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Reusing previously-fetched Cluster ID"
time="2020-01-28T23:29:57-06:00" level=debug msg=" Fetching Install Config..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Reusing previously-fetched Install Config"
time="2020-01-28T23:29:57-06:00" level=debug msg=" Fetching Platform Credentials Check..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Reusing previously-fetched Platform Credentials Check"
time="2020-01-28T23:29:57-06:00" level=debug msg=" Fetching Terraform Variables..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Reusing previously-fetched Terraform Variables"
time="2020-01-28T23:29:57-06:00" level=debug msg=" Fetching Kubeadmin Password..."
time="2020-01-28T23:29:57-06:00" level=debug msg=" Reusing previously-fetched Kubeadmin Password"
time="2020-01-28T23:29:57-06:00" level=debug msg="Generating Cluster..."
time="2020-01-28T23:29:57-06:00" level=info msg="Creating infrastructure resources..."
time="2020-01-28T23:29:57-06:00" level=debug msg="Symlinking plugin terraform-provider-openstack src: "/root/go/src/github.com/openshift/installer/bin/openshift-install" dst: "/tmp/openshift-install-647652407/plugins/terraform-provider-openstack""
time="2020-01-28T23:29:57-06:00" level=debug msg="Symlinking plugin terraform-provider-ovirt src: "/root/go/src/github.com/openshift/installer/bin/openshift-install" dst: "/tmp/openshift-install-647652407/plugins/terraform-provider-ovirt""
time="2020-01-28T23:29:57-06:00" level=debug msg="Symlinking plugin terraform-provider-libvirt src: "/root/go/src/github.com/openshift/installer/bin/openshift-install" dst: "/tmp/openshift-install-647652407/plugins/terraform-provider-libvirt""
time="2020-01-28T23:29:57-06:00" level=debug msg="Symlinking plugin terraform-provider-local src: "/root/go/src/github.com/openshift/installer/bin/openshift-install" dst: "/tmp/openshift-install-647652407/plugins/terraform-provider-local""
time="2020-01-28T23:29:57-06:00" level=debug msg="Symlinking plugin terraform-provider-random src: "/root/go/src/github.com/openshift/installer/bin/openshift-install" dst: "/tmp/openshift-install-647652407/plugins/terraform-provider-random""
time="2020-01-28T23:29:57-06:00" level=debug msg="Symlinking plugin terraform-provider-aws src: "/root/go/src/github.com/openshift/installer/bin/openshift-install" dst: "/tmp/openshift-install-647652407/plugins/terraform-provider-aws""
time="2020-01-28T23:29:57-06:00" level=debug msg="Symlinking plugin terraform-provider-azurerm src: "/root/go/src/github.com/openshift/installer/bin/openshift-install" dst: "/tmp/openshift-install-647652407/plugins/terraform-provider-azurerm""
time="2020-01-28T23:29:57-06:00" level=debug msg="Symlinking plugin terraform-provider-azureprivatedns src: "/root/go/src/github.com/openshift/installer/bin/openshift-install" dst: "/tmp/openshift-install-647652407/plugins/terraform-provider-azureprivatedns""
time="2020-01-28T23:29:57-06:00" level=debug msg="Symlinking plugin terraform-provider-google src: "/root/go/src/github.com/openshift/installer/bin/openshift-install" dst: "/tmp/openshift-install-647652407/plugins/terraform-provider-google""
time="2020-01-28T23:29:57-06:00" level=debug msg="Symlinking plugin terraform-provider-ignition src: "/root/go/src/github.com/openshift/installer/bin/openshift-install" dst: "/tmp/openshift-install-647652407/plugins/terraform-provider-ignition""
time="2020-01-28T23:29:57-06:00" level=debug msg="Symlinking plugin terraform-provider-vsphere src: "/root/go/src/github.com/openshift/installer/bin/openshift-install" dst: "/tmp/openshift-install-647652407/plugins/terraform-provider-vsphere""
time="2020-01-28T23:29:57-06:00" level=debug msg="Initializing modules..."
time="2020-01-28T23:29:57-06:00" level=debug msg="- bootstrap in ../../../../../tmp/openshift-install-647652407/bootstrap"
time="2020-01-28T23:29:57-06:00" level=debug msg="- volume in ../../../../../tmp/openshift-install-647652407/volume"
time="2020-01-28T23:29:57-06:00" level=debug
time="2020-01-28T23:29:57-06:00" level=debug msg="Initializing the backend..."
time="2020-01-28T23:29:57-06:00" level=debug
time="2020-01-28T23:29:57-06:00" level=debug msg="Initializing provider plugins..."
time="2020-01-28T23:29:57-06:00" level=debug
time="2020-01-28T23:29:57-06:00" level=debug msg="Terraform has been successfully initialized!"
time="2020-01-28T23:29:57-06:00" level=debug
time="2020-01-28T23:29:57-06:00" level=debug msg="You may now begin working with Terraform. Try running "terraform plan" to see"
time="2020-01-28T23:29:57-06:00" level=debug msg="any changes that are required for your infrastructure. All Terraform commands"
time="2020-01-28T23:29:57-06:00" level=debug msg="should now work."
time="2020-01-28T23:29:57-06:00" level=debug
time="2020-01-28T23:29:57-06:00" level=debug msg="If you ever set or change modules or backend configuration for Terraform,"
time="2020-01-28T23:29:57-06:00" level=debug msg="rerun this command to reinitialize your working directory. If you forget, other"
time="2020-01-28T23:29:57-06:00" level=debug msg="commands will detect it and remind you to do so if necessary."
time="2020-01-28T23:29:59-06:00" level=debug msg="data.libvirt_network_dns_host_template.masters_int[0]: Refreshing state..."
time="2020-01-28T23:29:59-06:00" level=debug msg="data.libvirt_network_dns_host_template.etcds[0]: Refreshing state..."
time="2020-01-28T23:29:59-06:00" level=debug msg="data.libvirt_network_dns_host_template.masters[0]: Refreshing state..."
time="2020-01-28T23:29:59-06:00" level=debug msg="data.libvirt_network_dns_host_template.bootstrap[0]: Refreshing state..."
time="2020-01-28T23:29:59-06:00" level=debug msg="data.libvirt_network_dns_srv_template.etcd_cluster[0]: Refreshing state..."
time="2020-01-28T23:29:59-06:00" level=debug msg="data.libvirt_network_dns_host_template.bootstrap_int[0]: Refreshing state..."
time="2020-01-28T23:29:59-06:00" level=debug msg="libvirt_pool.storage_pool: Creating..."
time="2020-01-28T23:29:59-06:00" level=debug msg="libvirt_network.net: Creating..."
time="2020-01-28T23:30:04-06:00" level=debug msg="libvirt_pool.storage_pool: Creation complete after 5s [id=7d66f383-20cc-4ef3-b25a-30a0f6fff4df]"
time="2020-01-28T23:30:04-06:00" level=debug msg="libvirt_ignition.master: Creating..."
time="2020-01-28T23:30:04-06:00" level=debug msg="module.volume.libvirt_volume.coreos_base: Creating..."
time="2020-01-28T23:30:04-06:00" level=debug msg="module.bootstrap.libvirt_ignition.bootstrap: Creating..."
time="2020-01-28T23:30:09-06:00" level=debug msg="libvirt_network.net: Still creating... [10s elapsed]"
time="2020-01-28T23:30:14-06:00" level=debug msg="libvirt_ignition.master: Still creating... [10s elapsed]"
time="2020-01-28T23:30:14-06:00" level=debug msg="module.volume.libvirt_volume.coreos_base: Still creating... [10s elapsed]"
time="2020-01-28T23:30:14-06:00" level=debug msg="module.bootstrap.libvirt_ignition.bootstrap: Still creating... [10s elapsed]"
time="2020-01-28T23:30:19-06:00" level=debug msg="libvirt_network.net: Still creating... [20s elapsed]"
time="2020-01-28T23:30:24-06:00" level=debug msg="libvirt_ignition.master: Still creating... [20s elapsed]"
time="2020-01-28T23:30:24-06:00" level=debug msg="module.volume.libvirt_volume.coreos_base: Still creating... [20s elapsed]"
time="2020-01-28T23:30:24-06:00" level=debug msg="module.bootstrap.libvirt_ignition.bootstrap: Still creating... [20s elapsed]"
time="2020-01-28T23:30:29-06:00" level=debug msg="libvirt_network.net: Still creating... [30s elapsed]"
time="2020-01-28T23:30:34-06:00" level=debug msg="libvirt_ignition.master: Still creating... [30s elapsed]"
time="2020-01-28T23:30:34-06:00" level=debug msg="module.volume.libvirt_volume.coreos_base: Still creating... [30s elapsed]"
time="2020-01-28T23:30:34-06:00" level=debug msg="module.bootstrap.libvirt_ignition.bootstrap: Still creating... [30s elapsed]"
time="2020-01-28T23:30:39-06:00" level=debug msg="libvirt_network.net: Still creating... [40s elapsed]"
time="2020-01-28T23:30:44-06:00" level=debug msg="libvirt_ignition.master: Still creating... [40s elapsed]"
time="2020-01-28T23:30:44-06:00" level=debug msg="module.volume.libvirt_volume.coreos_base: Still creating... [40s elapsed]"
time="2020-01-28T23:30:44-06:00" level=debug msg="module.bootstrap.libvirt_ignition.bootstrap: Still creating... [40s elapsed]"
time="2020-01-28T23:30:49-06:00" level=debug msg="libvirt_network.net: Still creating... [50s elapsed]"
time="2020-01-28T23:30:54-06:00" level=debug msg="libvirt_ignition.master: Still creating... [50s elapsed]"
time="2020-01-28T23:30:54-06:00" level=debug msg="module.volume.libvirt_volume.coreos_base: Still creating... [50s elapsed]"
time="2020-01-28T23:30:54-06:00" level=debug msg="module.bootstrap.libvirt_ignition.bootstrap: Still creating... [50s elapsed]"
time="2020-01-28T23:30:59-06:00" level=debug msg="libvirt_network.net: Still creating... [1m0s elapsed]"
time="2020-01-28T23:31:04-06:00" level=debug msg="libvirt_ignition.master: Still creating... [1m0s elapsed]"
time="2020-01-28T23:31:04-06:00" level=debug msg="module.volume.libvirt_volume.coreos_base: Still creating... [1m0s elapsed]"
time="2020-01-28T23:31:04-06:00" level=debug msg="module.bootstrap.libvirt_ignition.bootstrap: Still creating... [1m0s elapsed]"
time="2020-01-28T23:31:09-06:00" level=debug msg="libvirt_network.net: Still creating... [1m10s elapsed]"
time="2020-01-28T23:31:14-06:00" level=debug msg="libvirt_ignition.master: Still creating... [1m10s elapsed]"
time="2020-01-28T23:31:14-06:00" level=debug msg="module.volume.libvirt_volume.coreos_base: Still creating... [1m10s elapsed]"
time="2020-01-28T23:31:14-06:00" level=debug msg="module.bootstrap.libvirt_ignition.bootstrap: Still creating... [1m10s elapsed]"
time="2020-01-28T23:31:19-06:00" level=debug msg="libvirt_network.net: Still creating... [1m20s elapsed]"
time="2020-01-28T23:31:24-06:00" level=debug msg="libvirt_ignition.master: Still creating... [1m20s elapsed]"
time="2020-01-28T23:31:24-06:00" level=debug msg="module.volume.libvirt_volume.coreos_base: Still creating... [1m20s elapsed]"
time="2020-01-28T23:31:24-06:00" level=debug msg="module.bootstrap.libvirt_ignition.bootstrap: Still creating... [1m20s elapsed]"
time="2020-01-28T23:31:29-06:00" level=debug msg="libvirt_network.net: Still creating... [1m30s elapsed]"
time="2020-01-28T23:31:34-06:00" level=debug msg="libvirt_ignition.master: Still creating... [1m30s elapsed]"
time="2020-01-28T23:31:34-06:00" level=debug msg="module.volume.libvirt_volume.coreos_base: Still creating... [1m30s elapsed]"
time="2020-01-28T23:31:34-06:00" level=debug msg="module.bootstrap.libvirt_ignition.bootstrap: Still creating... [1m30s elapsed]"
time="2020-01-28T23:31:39-06:00" level=debug msg="libvirt_network.net: Still creating... [1m40s elapsed]"
time="2020-01-28T23:31:44-06:00" level=debug msg="libvirt_ignition.master: Still creating... [1m40s elapsed]"
time="2020-01-28T23:31:44-06:00" level=debug msg="module.volume.libvirt_volume.coreos_base: Still creating... [1m40s elapsed]"
time="2020-01-28T23:31:44-06:00" level=debug msg="module.bootstrap.libvirt_ignition.bootstrap: Still creating... [1m40s elapsed]"
time="2020-01-28T23:31:49-06:00" level=debug msg="libvirt_network.net: Still creating... [1m50s elapsed]"
time="2020-01-28T23:31:54-06:00" level=debug msg="libvirt_ignition.master: Still creating... [1m50s elapsed]"
time="2020-01-28T23:31:54-06:00" level=debug msg="module.volume.libvirt_volume.coreos_base: Still creating... [1m50s elapsed]"
time="2020-01-28T23:31:54-06:00" level=debug msg="module.bootstrap.libvirt_ignition.bootstrap: Still creating... [1m50s elapsed]"
time="2020-01-28T23:31:59-06:00" level=debug msg="libvirt_network.net: Still creating... [2m0s elapsed]"
time="2020-01-28T23:31:59-06:00" level=debug msg="libvirt_ignition.master: Creation complete after 1m55s [id=/var/lib/libvirt/openshift-images/mycluster-rtt9n/mycluster-rtt9n-master.ign;5e3118cf-a7fc-af05-fd43-dc6da674055e]"
time="2020-01-28T23:31:59-06:00" level=debug msg="libvirt_network.net: Creation complete after 2m0s [id=a6dd99a4-fef5-415d-a88c-339e34f8dd44]"
time="2020-01-28T23:32:01-06:00" level=debug msg="module.volume.libvirt_volume.coreos_base: Creation complete after 1m57s [id=/var/lib/libvirt/openshift-images/mycluster-rtt9n/mycluster-rtt9n-base]"
time="2020-01-28T23:32:01-06:00" level=debug msg="libvirt_volume.master[0]: Creating..."
time="2020-01-28T23:32:01-06:00" level=debug msg="module.bootstrap.libvirt_volume.bootstrap: Creating..."
time="2020-01-28T23:32:01-06:00" level=debug msg="module.bootstrap.libvirt_ignition.bootstrap: Creation complete after 1m57s [id=/var/lib/libvirt/openshift-images/mycluster-rtt9n/mycluster-rtt9n-bootstrap.ign;5e3118d1-265f-6b37-6738-5461a74c32fb]"
time="2020-01-28T23:32:02-06:00" level=debug msg="module.bootstrap.libvirt_volume.bootstrap: Creation complete after 0s [id=/var/lib/libvirt/openshift-images/mycluster-rtt9n/mycluster-rtt9n-bootstrap]"
time="2020-01-28T23:32:02-06:00" level=debug msg="module.bootstrap.libvirt_domain.bootstrap: Creating..."
time="2020-01-28T23:32:02-06:00" level=debug msg="libvirt_volume.master[0]: Creation complete after 0s [id=/var/lib/libvirt/openshift-images/mycluster-rtt9n/mycluster-rtt9n-master-0]"
time="2020-01-28T23:32:02-06:00" level=debug msg="libvirt_domain.master[0]: Creating..."
time="2020-01-28T23:32:02-06:00" level=debug msg="module.bootstrap.libvirt_domain.bootstrap: Creation complete after 1s [id=9c7cac0b-b397-419f-8bc2-7800e2814b62]"
time="2020-01-28T23:32:02-06:00" level=debug msg="libvirt_domain.master[0]: Creation complete after 1s [id=58d0fe33-07b3-4405-9ecd-e6e78623ac7c]"
time="2020-01-28T23:32:03-06:00" level=debug
time="2020-01-28T23:32:03-06:00" level=debug msg="Apply complete! Resources: 9 added, 0 changed, 0 destroyed."
time="2020-01-28T23:32:03-06:00" level=debug msg="OpenShift Installer unreleased-master-2447-g1cc6935753842458bb84504f8e247ddc86cb93c7-dirty"
time="2020-01-28T23:32:03-06:00" level=debug msg="Built from commit 1cc6935"
time="2020-01-28T23:32:03-06:00" level=info msg="Waiting up to 20m0s for the Kubernetes API at https://api.mycluster.5u04.info:6443..."
time="2020-01-28T23:32:06-06:00" level=debug msg="Still waiting for the Kubernetes API: Get https://api.mycluster.5u04.info:6443/version?timeout=32s: dial tcp 192.168.126.10:6443: connect: no route to host"
time="2020-01-28T23:32:14-06:00" level=debug msg="Still waiting for the Kubernetes API: Get https://api.mycluster.5u04.info:6443/version?timeout=32s: dial tcp 192.168.126.10:6443: connect: connection refused"
time="2020-01-28T23:32:44-06:00" level=debug msg="Still waiting for the Kubernetes API: Get https://api.mycluster.5u04.info:6443/version?timeout=32s: dial tcp 192.168.126.10:6443: connect: connection refused"
time="2020-01-28T23:33:14-06:00" level=debug msg="Still waiting for the Kubernetes API: Get https://api.mycluster.5u04.info:6443/version?timeout=32s: dial tcp 192.168.126.10:6443: connect: connection refused"
time="2020-01-28T23:33:47-06:00" level=debug msg="Still waiting for the Kubernetes API: Get https://api.mycluster.5u04.info:6443/version?timeout=32s: dial tcp 192.168.126.10:6443: connect: connection refused"
time="2020-01-28T23:34:11-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:34:41-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:35:11-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:35:41-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:36:11-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:36:41-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:37:11-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:37:42-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:38:12-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:38:42-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:39:12-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:39:42-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:40:12-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:40:42-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:41:12-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:41:42-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:42:12-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:42:42-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:43:12-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:43:42-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:44:12-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:44:42-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:45:12-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:45:42-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:46:12-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:46:42-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:47:12-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:47:42-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:48:12-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:48:42-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:49:12-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:49:42-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:50:12-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:50:42-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:51:12-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:51:42-06:00" level=debug msg="Still waiting for the Kubernetes API: the server could not find the requested resource"
time="2020-01-28T23:52:03-06:00" level=error msg="Attempted to gather ClusterOperator status after installation failure: listing ClusterOperator objects: the server could not find the requested resource (get clusteroperators.config.openshift.io)"
time="2020-01-28T23:52:03-06:00" level=debug msg="Fetching Install Config..."
time="2020-01-28T23:52:03-06:00" level=debug msg="Loading Install Config..."
time="2020-01-28T23:52:03-06:00" level=debug msg=" Loading SSH Key..."
time="2020-01-28T23:52:03-06:00" level=debug msg=" Using SSH Key loaded from state file"
time="2020-01-28T23:52:03-06:00" level=debug msg=" Loading Base Domain..."
time="2020-01-28T23:52:03-06:00" level=debug msg=" Loading Platform..."
time="2020-01-28T23:52:03-06:00" level=debug msg=" Using Platform loaded from state file"
time="2020-01-28T23:52:03-06:00" level=debug msg=" Using Base Domain loaded from state file"
time="2020-01-28T23:52:03-06:00" level=debug msg=" Loading Cluster Name..."
time="2020-01-28T23:52:03-06:00" level=debug msg=" Loading Base Domain..."
time="2020-01-28T23:52:03-06:00" level=debug msg=" Loading Platform..."
time="2020-01-28T23:52:03-06:00" level=debug msg=" Using Cluster Name loaded from state file"
time="2020-01-28T23:52:03-06:00" level=debug msg=" Loading Pull Secret..."
time="2020-01-28T23:52:03-06:00" level=debug msg=" Using Pull Secret loaded from state file"
time="2020-01-28T23:52:03-06:00" level=debug msg=" Loading Platform..."
time="2020-01-28T23:52:03-06:00" level=debug msg="Using Install Config loaded from state file"
time="2020-01-28T23:52:03-06:00" level=debug msg="Reusing previously-fetched Install Config"
time="2020-01-28T23:52:03-06:00" level=info msg="Pulling debug logs from the bootstrap machine"
time="2020-01-28T23:52:03-06:00" level=debug msg="Using SSH_AUTH_SOCK /tmp/ssh-g1Rg45kgR62d/agent.677 to connect to an existing agent"
time="2020-01-28T23:56:18-06:00" level=error msg="Attempted to gather debug logs after installation failure: failed to create SSH client, ensure the proper ssh key is in your keyring or specify with --key: dial tcp 165.160.15.20:22: connect: connection timed out"
time="2020-01-28T23:56:18-06:00" level=fatal msg="Bootstrap failed to complete: waiting for Kubernetes API: context deadline exceeded"

[abhinavdahiya]

/assign @zeenix @praveenkumar

It would be nice if you guys can take a look at this. Thanks!

[praveenkumar]

@doyoungim999 how did you build the installer, using master? Also have you also used image override to have a different release payload?

[thdevelthreesixtiy]

Same issue here.
Stuck with Installer for libvirt after machines for bootstrap and master have been startet.
openshift_install-fail-200210.log

Installer build from head yesterday. No overrides,