[BeforeEach] [Top Level]
/openshifttmp/openshift/build-rpm-release/tito/rpmbuild-originc2WI6G/BUILD/origin-3.6.0/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:51
[BeforeEach] [k8s.io] StatefulSet
/openshifttmp/openshift/build-rpm-release/tito/rpmbuild-originc2WI6G/BUILD/origin-3.6.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:119
STEP: Creating a kubernetes client
May 1 16:01:08.579: INFO: >>> kubeConfig: /tmp/cluster-admin.kubeconfig
STEP: Building a namespace api object
May 1 16:01:09.321: INFO: About to run a Kube e2e test, ensuring namespace is privileged
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] [k8s.io] StatefulSet
/openshifttmp/openshift/build-rpm-release/tito/rpmbuild-originc2WI6G/BUILD/origin-3.6.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/statefulset.go:63
[BeforeEach] [k8s.io] Basic StatefulSet functionality [StatefulSetBasic]
/openshifttmp/openshift/build-rpm-release/tito/rpmbuild-originc2WI6G/BUILD/origin-3.6.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/statefulset.go:84
STEP: Creating service test in namespace e2e-tests-statefulset-tg8v0
[It] Scaling down before scale up is finished should wait until current pod will be running and ready before it will be removed
/openshifttmp/openshift/build-rpm-release/tito/rpmbuild-originc2WI6G/BUILD/origin-3.6.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/statefulset.go:348
STEP: Creating stateful set ss in namespace e2e-tests-statefulset-tg8v0, and pausing scale operations after each pod
May 1 16:01:10.756: INFO: Found 0 stateful pods, waiting for 1
May 1 16:01:20.797: INFO: Waiting for pod ss-0 to enter Running - Ready=true, currently Running - Ready=true
STEP: Scaling up stateful set ss to 3 replicas and pausing after 2nd pod
May 1 16:01:20.949: INFO: Set annotation pod.alpha.kubernetes.io/initialized to true on pod ss-0
May 1 16:01:21.071: INFO: Found 1 stateful pods, waiting for 2
May 1 16:01:31.106: INFO: Waiting for pod ss-0 to enter Running - Ready=true, currently Running - Ready=true
May 1 16:01:31.106: INFO: Waiting for pod ss-1 to enter Running - Ready=true, currently Running - Ready=false
May 1 16:01:41.104: INFO: Waiting for pod ss-0 to enter Running - Ready=true, currently Running - Ready=true
May 1 16:01:41.104: INFO: Waiting for pod ss-1 to enter Running - Ready=true, currently Running - Ready=true
STEP: Before scale up finished setting 2nd pod to be not ready by breaking readiness probe
May 1 16:01:41.161: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.prtest-5a37c28-1547.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=e2e-tests-statefulset-tg8v0 ss-0 -- /bin/sh -c mv -v /usr/share/nginx/html/index.html /tmp/'
May 1 16:01:41.972: INFO: stderr: ""
May 1 16:01:41.972: INFO: stdout of mv -v /usr/share/nginx/html/index.html /tmp/ on ss-0: '/usr/share/nginx/html/index.html' -> '/tmp/index.html'
May 1 16:01:41.972: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.prtest-5a37c28-1547.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=e2e-tests-statefulset-tg8v0 ss-1 -- /bin/sh -c mv -v /usr/share/nginx/html/index.html /tmp/'
May 1 16:01:42.972: INFO: stderr: ""
May 1 16:01:42.972: INFO: stdout of mv -v /usr/share/nginx/html/index.html /tmp/ on ss-1: '/usr/share/nginx/html/index.html' -> '/tmp/index.html'
May 1 16:01:42.972: INFO: Waiting for statefulset status.replicas updated to 0
May 1 16:01:43.007: INFO: Waiting for stateful set status to become 0, currently 1
May 1 16:01:53.040: INFO: Waiting for stateful set status to become 0, currently 1
May 1 16:02:03.038: INFO: Waiting for stateful set status to become 0, currently 1
May 1 16:02:13.099: INFO: Waiting for pod ss-0 to enter Running - Ready=false, currently Running - Ready=false
May 1 16:02:13.099: INFO: Waiting for pod ss-1 to enter Running - Ready=false, currently Running - Ready=false
STEP: Continue scale operation after the 2nd pod, and scaling down to 1 replica
May 1 16:02:13.246: INFO: Set annotation pod.alpha.kubernetes.io/initialized to true on pod ss-1
STEP: Verifying that the 2nd pod wont be removed if it is not running and ready
May 1 16:02:13.412: INFO: Verifying statefulset ss doesn't scale past 2 for another 9.999999607s
May 1 16:02:14.449: INFO: Verifying statefulset ss doesn't scale past 2 for another 8.964100532s
May 1 16:02:15.485: INFO: Verifying statefulset ss doesn't scale past 2 for another 7.926710983s
May 1 16:02:16.545: INFO: Verifying statefulset ss doesn't scale past 2 for another 6.891205394s
May 1 16:02:17.580: INFO: Verifying statefulset ss doesn't scale past 2 for another 5.83135574s
May 1 16:02:18.613: INFO: Verifying statefulset ss doesn't scale past 2 for another 4.795806249s
May 1 16:02:19.653: INFO: Verifying statefulset ss doesn't scale past 2 for another 3.763546709s
May 1 16:02:20.693: INFO: Verifying statefulset ss doesn't scale past 2 for another 2.722839297s
May 1 16:02:21.728: INFO: Verifying statefulset ss doesn't scale past 2 for another 1.682724819s
May 1 16:02:22.761: INFO: Verifying statefulset ss doesn't scale past 2 for another 648.228771ms
STEP: Verifying the 2nd pod is removed only when it becomes running and ready
May 1 16:02:23.896: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.prtest-5a37c28-1547.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=e2e-tests-statefulset-tg8v0 ss-0 -- /bin/sh -c mv -v /tmp/index.html /usr/share/nginx/html/'
May 1 16:02:25.242: INFO: stderr: ""
May 1 16:02:25.242: INFO: stdout of mv -v /tmp/index.html /usr/share/nginx/html/ on ss-0: '/tmp/index.html' -> '/usr/share/nginx/html/index.html'
May 1 16:02:25.242: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.prtest-5a37c28-1547.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=e2e-tests-statefulset-tg8v0 ss-1 -- /bin/sh -c mv -v /tmp/index.html /usr/share/nginx/html/'
May 1 16:02:26.625: INFO: stderr: ""
May 1 16:02:26.625: INFO: stdout of mv -v /tmp/index.html /usr/share/nginx/html/ on ss-1: '/tmp/index.html' -> '/usr/share/nginx/html/index.html'
May 1 16:02:26.625: INFO: Keep waiting, received error from watch event: too old resource version: 20028 (20066)
[AfterEach] [k8s.io] Basic StatefulSet functionality [StatefulSetBasic]
/openshifttmp/openshift/build-rpm-release/tito/rpmbuild-originc2WI6G/BUILD/origin-3.6.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/statefulset.go:92
May 1 16:02:26.664: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.prtest-5a37c28-1547.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig describe po ss-0 --namespace=e2e-tests-statefulset-tg8v0'
May 1 16:02:27.200: INFO: stderr: ""
May 1 16:02:27.200: INFO:
Output of kubectl describe ss-0:
Name: ss-0
Namespace: e2e-tests-statefulset-tg8v0
Security Policy: anyuid
Node: ci-prtest-5a37c28-1547-ig-m-0w9g/10.128.0.3
Start Time: Mon, 01 May 2017 16:01:10 -0400
Labels: baz=blah
foo=bar
Annotations: kubernetes.io/created-by={"kind":"SerializedReference","apiVersion":"v1","reference":{"kind":"StatefulSet","namespace":"e2e-tests-statefulset-tg8v0","name":"ss","uid":"ec022229-2ea8-11e7-a83f-42010a80...
openshift.io/scc=anyuid
pod.alpha.kubernetes.io/initialized=true
pod.beta.kubernetes.io/hostname=ss-0
pod.beta.kubernetes.io/subdomain=test
Status: Running
IP: 172.16.6.20
Controllers: StatefulSet/ss
Containers:
nginx:
Container ID: docker://0896ed45724a1c40c894f6d7153e2f19a67afe0b30a55536570928b5c8f2b764
Image: gcr.io/google_containers/nginx-slim:0.7
Image ID: docker-pullable://gcr.io/google_containers/nginx-slim@sha256:dd4efd4c13bec2c6f3fe855deeab9524efe434505568421d4f31820485b3a795
Port:
State: Running
Started: Mon, 01 May 2017 16:01:12 -0400
Ready: False
Restart Count: 0
Readiness: http-get http://:80/index.html delay=0s timeout=1s period=10s #success=1 #failure=3
Environment: <none>
Mounts:
/var/run/secrets/kubernetes.io/serviceaccount from default-token-lzd87 (ro)
Conditions:
Type Status
Initialized True
Ready False
PodScheduled True
Volumes:
default-token-lzd87:
Type: Secret (a volume populated by a Secret)
SecretName: default-token-lzd87
Optional: false
QoS Class: BestEffort
Node-Selectors: <none>
Tolerations: <none>
Events:
FirstSeen LastSeen Count From SubObjectPath Type Reason Message
--------- -------- ----- ---- ------------- -------- ------ -------
1m 1m 1 default-scheduler Normal Scheduled Successfully assigned ss-0 to ci-prtest-5a37c28-1547-ig-m-0w9g
1m 1m 1 kubelet, ci-prtest-5a37c28-1547-ig-m-0w9g spec.containers{nginx} Normal Pulled Container image "gcr.io/google_containers/nginx-slim:0.7" already present on machine
1m 1m 1 kubelet, ci-prtest-5a37c28-1547-ig-m-0w9g spec.containers{nginx} Normal Created Created container with id 0896ed45724a1c40c894f6d7153e2f19a67afe0b30a55536570928b5c8f2b764
1m 1m 1 kubelet, ci-prtest-5a37c28-1547-ig-m-0w9g spec.containers{nginx} Normal Started Started container with id 0896ed45724a1c40c894f6d7153e2f19a67afe0b30a55536570928b5c8f2b764
May 1 16:02:27.200: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.prtest-5a37c28-1547.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig logs ss-0 --namespace=e2e-tests-statefulset-tg8v0 --tail=100'
May 1 16:02:27.702: INFO: stderr: ""
May 1 16:02:27.702: INFO:
Last 100 log lines of ss-0:
172.16.6.1 - - [01/May/2017:20:01:18 +0000] "GET /index.html HTTP/1.1" 200 612 "-" "Go-http-client/1.1"
172.16.6.1 - - [01/May/2017:20:01:20 +0000] "GET /index.html HTTP/1.1" 200 612 "-" "Go-http-client/1.1"
172.16.6.1 - - [01/May/2017:20:01:30 +0000] "GET /index.html HTTP/1.1" 200 612 "-" "Go-http-client/1.1"
172.16.6.1 - - [01/May/2017:20:01:40 +0000] "GET /index.html HTTP/1.1" 200 612 "-" "Go-http-client/1.1"
172.16.6.1 - - [01/May/2017:20:01:50 +0000] "GET /index.html HTTP/1.1" 404 169 "-" "Go-http-client/1.1"
2017/05/01 20:01:50 [error] 5#5: *5 open() "/usr/share/nginx/html/index.html" failed (2: No such file or directory), client: 172.16.6.1, server: localhost, request: "GET /index.html HTTP/1.1", host: "172.16.6.20:80"
2017/05/01 20:02:00 [error] 5#5: *6 open() "/usr/share/nginx/html/index.html" failed (2: No such file or directory), client: 172.16.6.1, server: localhost, request: "GET /index.html HTTP/1.1", host: "172.16.6.20:80"
172.16.6.1 - - [01/May/2017:20:02:00 +0000] "GET /index.html HTTP/1.1" 404 169 "-" "Go-http-client/1.1"
2017/05/01 20:02:10 [error] 5#5: *7 open() "/usr/share/nginx/html/index.html" failed (2: No such file or directory), client: 172.16.6.1, server: localhost, request: "GET /index.html HTTP/1.1", host: "172.16.6.20:80"
172.16.6.1 - - [01/May/2017:20:02:10 +0000] "GET /index.html HTTP/1.1" 404 169 "-" "Go-http-client/1.1"
2017/05/01 20:02:20 [error] 5#5: *8 open() "/usr/share/nginx/html/index.html" failed (2: No such file or directory), client: 172.16.6.1, server: localhost, request: "GET /index.html HTTP/1.1", host: "172.16.6.20:80"
172.16.6.1 - - [01/May/2017:20:02:20 +0000] "GET /index.html HTTP/1.1" 404 169 "-" "Go-http-client/1.1"
May 1 16:02:27.702: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.prtest-5a37c28-1547.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig describe po ss-1 --namespace=e2e-tests-statefulset-tg8v0'
May 1 16:02:28.263: INFO: stderr: ""
May 1 16:02:28.263: INFO:
Output of kubectl describe ss-1:
Name: ss-1
Namespace: e2e-tests-statefulset-tg8v0
Security Policy: anyuid
Node: ci-prtest-5a37c28-1547-ig-n-jr3j/10.128.0.5
Start Time: Mon, 01 May 2017 16:01:21 -0400
Labels: baz=blah
foo=bar
Annotations: kubernetes.io/created-by={"kind":"SerializedReference","apiVersion":"v1","reference":{"kind":"StatefulSet","namespace":"e2e-tests-statefulset-tg8v0","name":"ss","uid":"ec022229-2ea8-11e7-a83f-42010a80...
openshift.io/scc=anyuid
pod.alpha.kubernetes.io/initialized=true
pod.beta.kubernetes.io/hostname=ss-1
pod.beta.kubernetes.io/subdomain=test
Status: Running
IP: 172.16.4.77
Controllers: StatefulSet/ss
Containers:
nginx:
Container ID: docker://bfb0db7beb33bd48a5d1116152ffde509fdf553bc645df1f3ed3c015aa078610
Image: gcr.io/google_containers/nginx-slim:0.7
Image ID: docker-pullable://gcr.io/google_containers/nginx-slim@sha256:dd4efd4c13bec2c6f3fe855deeab9524efe434505568421d4f31820485b3a795
Port:
State: Running
Started: Mon, 01 May 2017 16:01:23 -0400
Ready: False
Restart Count: 0
Readiness: http-get http://:80/index.html delay=0s timeout=1s period=10s #success=1 #failure=3
Environment: <none>
Mounts:
/var/run/secrets/kubernetes.io/serviceaccount from default-token-lzd87 (ro)
Conditions:
Type Status
Initialized True
Ready False
PodScheduled True
Volumes:
default-token-lzd87:
Type: Secret (a volume populated by a Secret)
SecretName: default-token-lzd87
Optional: false
QoS Class: BestEffort
Node-Selectors: <none>
Tolerations: <none>
Events:
FirstSeen LastSeen Count From SubObjectPath Type Reason Message
--------- -------- ----- ---- ------------- -------- ------ -------
1m 1m 1 default-scheduler Normal Scheduled Successfully assigned ss-1 to ci-prtest-5a37c28-1547-ig-n-jr3j
1m 1m 1 kubelet, ci-prtest-5a37c28-1547-ig-n-jr3j spec.containers{nginx} Normal Pulled Container image "gcr.io/google_containers/nginx-slim:0.7" already present on machine
1m 1m 1 kubelet, ci-prtest-5a37c28-1547-ig-n-jr3j spec.containers{nginx} Normal Created Created container with id bfb0db7beb33bd48a5d1116152ffde509fdf553bc645df1f3ed3c015aa078610
1m 1m 1 kubelet, ci-prtest-5a37c28-1547-ig-n-jr3j spec.containers{nginx} Normal Started Started container with id bfb0db7beb33bd48a5d1116152ffde509fdf553bc645df1f3ed3c015aa078610
May 1 16:02:28.263: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.prtest-5a37c28-1547.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig logs ss-1 --namespace=e2e-tests-statefulset-tg8v0 --tail=100'
May 1 16:02:28.768: INFO: stderr: ""
May 1 16:02:28.768: INFO:
Last 100 log lines of ss-1:
172.16.4.1 - - [01/May/2017:20:01:31 +0000] "GET /index.html HTTP/1.1" 200 612 "-" "Go-http-client/1.1"
172.16.4.1 - - [01/May/2017:20:01:41 +0000] "GET /index.html HTTP/1.1" 200 612 "-" "Go-http-client/1.1"
2017/05/01 20:01:51 [error] 5#5: *3 open() "/usr/share/nginx/html/index.html" failed (2: No such file or directory), client: 172.16.4.1, server: localhost, request: "GET /index.html HTTP/1.1", host: "172.16.4.77:80"
172.16.4.1 - - [01/May/2017:20:01:51 +0000] "GET /index.html HTTP/1.1" 404 169 "-" "Go-http-client/1.1"
172.16.4.1 - - [01/May/2017:20:02:01 +0000] "GET /index.html HTTP/1.1" 404 169 "-" "Go-http-client/1.1"
2017/05/01 20:02:01 [error] 5#5: *4 open() "/usr/share/nginx/html/index.html" failed (2: No such file or directory), client: 172.16.4.1, server: localhost, request: "GET /index.html HTTP/1.1", host: "172.16.4.77:80"
2017/05/01 20:02:11 [error] 5#5: *5 open() "/usr/share/nginx/html/index.html" failed (2: No such file or directory), client: 172.16.4.1, server: localhost, request: "GET /index.html HTTP/1.1", host: "172.16.4.77:80"
172.16.4.1 - - [01/May/2017:20:02:11 +0000] "GET /index.html HTTP/1.1" 404 169 "-" "Go-http-client/1.1"
2017/05/01 20:02:21 [error] 5#5: *6 open() "/usr/share/nginx/html/index.html" failed (2: No such file or directory), client: 172.16.4.1, server: localhost, request: "GET /index.html HTTP/1.1", host: "172.16.4.77:80"
172.16.4.1 - - [01/May/2017:20:02:21 +0000] "GET /index.html HTTP/1.1" 404 169 "-" "Go-http-client/1.1"
May 1 16:02:28.768: INFO: Deleting all statefulset in ns e2e-tests-statefulset-tg8v0
May 1 16:02:28.803: INFO: Scaling statefulset ss to 0
May 1 16:02:58.954: INFO: Waiting for statefulset status.replicas updated to 0
May 1 16:02:58.999: INFO: Deleting statefulset ss
[AfterEach] [k8s.io] StatefulSet
/openshifttmp/openshift/build-rpm-release/tito/rpmbuild-originc2WI6G/BUILD/origin-3.6.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:120
STEP: Collecting events from namespace "e2e-tests-statefulset-tg8v0".
STEP: Found 15 events.
May 1 16:02:59.205: INFO: At 2017-05-01 16:01:10 -0400 EDT - event for ss: {statefulset } SuccessfulCreate: create Pod ss-0 in StatefulSet ss successful
May 1 16:02:59.205: INFO: At 2017-05-01 16:01:10 -0400 EDT - event for ss-0: {default-scheduler } Scheduled: Successfully assigned ss-0 to ci-prtest-5a37c28-1547-ig-m-0w9g
May 1 16:02:59.205: INFO: At 2017-05-01 16:01:12 -0400 EDT - event for ss-0: {kubelet ci-prtest-5a37c28-1547-ig-m-0w9g} Created: Created container with id 0896ed45724a1c40c894f6d7153e2f19a67afe0b30a55536570928b5c8f2b764
May 1 16:02:59.205: INFO: At 2017-05-01 16:01:12 -0400 EDT - event for ss-0: {kubelet ci-prtest-5a37c28-1547-ig-m-0w9g} Started: Started container with id 0896ed45724a1c40c894f6d7153e2f19a67afe0b30a55536570928b5c8f2b764
May 1 16:02:59.205: INFO: At 2017-05-01 16:01:12 -0400 EDT - event for ss-0: {kubelet ci-prtest-5a37c28-1547-ig-m-0w9g} Pulled: Container image "gcr.io/google_containers/nginx-slim:0.7" already present on machine
May 1 16:02:59.205: INFO: At 2017-05-01 16:01:21 -0400 EDT - event for ss: {statefulset } FailedCreate: create Pod ss-1 in StatefulSet ss failed error: The POST operation against Pod could not be completed at this time, please try again.
May 1 16:02:59.205: INFO: At 2017-05-01 16:01:21 -0400 EDT - event for ss: {statefulset } SuccessfulCreate: create Pod ss-1 in StatefulSet ss successful
May 1 16:02:59.205: INFO: At 2017-05-01 16:01:21 -0400 EDT - event for ss-1: {default-scheduler } Scheduled: Successfully assigned ss-1 to ci-prtest-5a37c28-1547-ig-n-jr3j
May 1 16:02:59.205: INFO: At 2017-05-01 16:01:22 -0400 EDT - event for ss-1: {kubelet ci-prtest-5a37c28-1547-ig-n-jr3j} Pulled: Container image "gcr.io/google_containers/nginx-slim:0.7" already present on machine
May 1 16:02:59.205: INFO: At 2017-05-01 16:01:22 -0400 EDT - event for ss-1: {kubelet ci-prtest-5a37c28-1547-ig-n-jr3j} Created: Created container with id bfb0db7beb33bd48a5d1116152ffde509fdf553bc645df1f3ed3c015aa078610
May 1 16:02:59.205: INFO: At 2017-05-01 16:01:23 -0400 EDT - event for ss-1: {kubelet ci-prtest-5a37c28-1547-ig-n-jr3j} Started: Started container with id bfb0db7beb33bd48a5d1116152ffde509fdf553bc645df1f3ed3c015aa078610
May 1 16:02:59.205: INFO: At 2017-05-01 16:02:31 -0400 EDT - event for ss: {statefulset } SuccessfulDelete: delete Pod ss-1 in StatefulSet ss successful
May 1 16:02:59.205: INFO: At 2017-05-01 16:02:31 -0400 EDT - event for ss-1: {kubelet ci-prtest-5a37c28-1547-ig-n-jr3j} Killing: Killing container with id docker://bfb0db7beb33bd48a5d1116152ffde509fdf553bc645df1f3ed3c015aa078610:Need to kill Pod
May 1 16:02:59.205: INFO: At 2017-05-01 16:02:45 -0400 EDT - event for ss: {statefulset } SuccessfulDelete: delete Pod ss-0 in StatefulSet ss successful
May 1 16:02:59.205: INFO: At 2017-05-01 16:02:45 -0400 EDT - event for ss-0: {kubelet ci-prtest-5a37c28-1547-ig-m-0w9g} Killing: Killing container with id docker://0896ed45724a1c40c894f6d7153e2f19a67afe0b30a55536570928b5c8f2b764:Need to kill Pod
May 1 16:02:59.356: INFO: POD NODE PHASE GRACE CONDITIONS
May 1 16:02:59.357: INFO: docker-registry-2-r9z9x ci-prtest-5a37c28-1547-ig-m-0w9g Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 15:56:09 -0400 EDT } {Ready True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 15:56:13 -0400 EDT } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 15:56:09 -0400 EDT }]
May 1 16:02:59.357: INFO: registry-console-1-9zwfd ci-prtest-5a37c28-1547-ig-n-0z3f Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 15:55:50 -0400 EDT } {Ready False 0001-01-01 00:00:00 +0000 UTC 2017-05-01 15:55:50 -0400 EDT ContainersNotReady containers with unready status: [registry-console]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 15:55:50 -0400 EDT }]
May 1 16:02:59.357: INFO: registry-console-1-deploy ci-prtest-5a37c28-1547-ig-n-80t9 Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 15:55:25 -0400 EDT } {Ready True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 15:55:50 -0400 EDT } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 15:55:25 -0400 EDT }]
May 1 16:02:59.357: INFO: router-1-dzlxw ci-prtest-5a37c28-1547-ig-m-0w9g Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 15:54:55 -0400 EDT } {Ready True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 15:55:15 -0400 EDT } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 15:54:55 -0400 EDT }]
May 1 16:02:59.357: INFO: pod-configmaps-06dccbbb-2ea9-11e7-8fcc-0e5d7c1ab0ac ci-prtest-5a37c28-1547-ig-n-jr3j Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:01:55 -0400 EDT } {Ready True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:01:58 -0400 EDT } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:01:55 -0400 EDT }]
May 1 16:02:59.357: INFO: liveness-exec ci-prtest-5a37c28-1547-ig-n-0z3f Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:00:49 -0400 EDT } {Ready True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:00:52 -0400 EDT } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:00:49 -0400 EDT }]
May 1 16:02:59.357: INFO: pod-projected-configmaps-22bafc0a-2ea9-11e7-a27f-0e5d7c1ab0ac ci-prtest-5a37c28-1547-ig-n-80t9 Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:42 -0400 EDT } {Ready True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:44 -0400 EDT } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:42 -0400 EDT }]
May 1 16:02:59.357: INFO: pod-secrets-08972f9f-2ea9-11e7-ac35-0e5d7c1ab0ac ci-prtest-5a37c28-1547-ig-n-80t9 Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:01:58 -0400 EDT } {Ready True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:00 -0400 EDT } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:01:58 -0400 EDT }]
May 1 16:02:59.357: INFO: pod1 ci-prtest-5a37c28-1547-ig-n-0z3f Running 30s [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:44 -0400 EDT } {Ready False 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:53 -0400 EDT ContainersNotReady containers with unready status: [pause]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:44 -0400 EDT }]
May 1 16:02:59.357: INFO: pod2 ci-prtest-5a37c28-1547-ig-n-80t9 Running 30s [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:48 -0400 EDT } {Ready False 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:54 -0400 EDT ContainersNotReady containers with unready status: [pause]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:48 -0400 EDT }]
May 1 16:02:59.357: INFO: ss-0 ci-prtest-5a37c28-1547-ig-m-0w9g Running [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:26 -0400 EDT } {Ready True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:31 -0400 EDT } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:26 -0400 EDT }]
May 1 16:02:59.357: INFO: brokendeployment-1-deploy ci-prtest-5a37c28-1547-ig-n-0z3f Failed [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:01:11 -0400 EDT } {Ready False 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:01:19 -0400 EDT ContainersNotReady containers with unready status: [deployment]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:01:11 -0400 EDT }]
May 1 16:02:59.357: INFO: brokendeployment-1-hook-pre ci-prtest-5a37c28-1547-ig-n-80t9 Failed [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:01:13 -0400 EDT } {Ready False 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:01:13 -0400 EDT ContainersNotReady containers with unready status: [lifecycle]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:01:13 -0400 EDT }]
May 1 16:02:59.357: INFO: history-limit-10-83bts ci-prtest-5a37c28-1547-ig-n-80t9 Running 30s [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:31 -0400 EDT } {Ready True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:33 -0400 EDT } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:31 -0400 EDT }]
May 1 16:02:59.357: INFO: history-limit-11-jj292 ci-prtest-5a37c28-1547-ig-n-0z3f Running 30s [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:44 -0400 EDT } {Ready True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:46 -0400 EDT } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:44 -0400 EDT }]
May 1 16:02:59.357: INFO: history-limit-9-kg84n ci-prtest-5a37c28-1547-ig-n-0z3f Running 30s [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:19 -0400 EDT } {Ready True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:21 -0400 EDT } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:19 -0400 EDT }]
May 1 16:02:59.357: INFO: deployment-simple-4-bbq36 ci-prtest-5a37c28-1547-ig-n-80t9 Running 30s [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:16 -0400 EDT } {Ready False 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:53 -0400 EDT ContainersNotReady containers with unready status: [myapp]} {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:16 -0400 EDT }]
May 1 16:02:59.357: INFO: deployment-simple-5-2szn9 ci-prtest-5a37c28-1547-ig-n-0z3f Running 30s [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:23 -0400 EDT } {Ready True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:32 -0400 EDT } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:23 -0400 EDT }]
May 1 16:02:59.357: INFO: deployment-simple-5-m9j08 ci-prtest-5a37c28-1547-ig-n-jr3j Running 30s [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:23 -0400 EDT } {Ready True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:33 -0400 EDT } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:23 -0400 EDT }]
May 1 16:02:59.357: INFO: docker-build-1-build ci-prtest-5a37c28-1547-ig-n-jr3j Succeeded [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:22 -0400 EDT PodCompleted } {Ready False 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:53 -0400 EDT PodCompleted } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:22 -0400 EDT }]
May 1 16:02:59.357: INFO: docker-build-pull-1-build ci-prtest-5a37c28-1547-ig-n-jr3j Succeeded [{Initialized True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:54 -0400 EDT PodCompleted } {Ready False 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:57 -0400 EDT PodCompleted } {PodScheduled True 0001-01-01 00:00:00 +0000 UTC 2017-05-01 16:02:54 -0400 EDT }]
May 1 16:02:59.357: INFO:
May 1 16:02:59.389: INFO:
Logging node info for node ci-prtest-5a37c28-1547-ig-m-0w9g
May 1 16:02:59.419: INFO: Node Info: &Node{ObjectMeta:k8s_io_apimachinery_pkg_apis_meta_v1.ObjectMeta{Name:ci-prtest-5a37c28-1547-ig-m-0w9g,GenerateName:,Namespace:,SelfLink:/api/v1/nodesci-prtest-5a37c28-1547-ig-m-0w9g,UID:bcf9abae-2ea7-11e7-a83f-42010a800003,ResourceVersion:23007,Generation:0,CreationTimestamp:2017-05-01 15:52:42 -0400 EDT,DeletionTimestamp:<nil>,DeletionGracePeriodSeconds:nil,Labels:map[string]string{beta.kubernetes.io/arch: amd64,beta.kubernetes.io/instance-type: n1-standard-2,beta.kubernetes.io/os: linux,failure-domain.beta.kubernetes.io/region: us-central1,failure-domain.beta.kubernetes.io/zone: us-central1-a,kubernetes.io/hostname: ci-prtest-5a37c28-1547-ig-m-0w9g,role: infra,subrole: master,},Annotations:map[string]string{volumes.kubernetes.io/controller-managed-attach-detach: true,},OwnerReferences:[],Finalizers:[],ClusterName:,},Spec:NodeSpec{PodCIDR:,ExternalID:9060657082862809118,ProviderID:gce://openshift-gce-devel-ci/us-central1-a/ci-prtest-5a37c28-1547-ig-m-0w9g,Unschedulable:false,Taints:[],},Status:NodeStatus{Capacity:ResourceList{cpu: {{2 0} {<nil>} 2 DecimalSI},memory: {{7674597376 0} {<nil>} 7494724Ki BinarySI},pods: {{20 0} {<nil>} 20 DecimalSI},},Allocatable:ResourceList{cpu: {{2 0} {<nil>} 2 DecimalSI},memory: {{7569739776 0} {<nil>} 7392324Ki BinarySI},pods: {{20 0} {<nil>} 20 DecimalSI},},Phase:,Conditions:[{NetworkUnavailable False 0001-01-01 00:00:00 +0000 UTC 2017-05-01 15:52:42 -0400 EDT RouteCreated openshift-sdn cleared kubelet-set NoRouteCreated} {OutOfDisk False 2017-05-01 16:02:55 -0400 EDT 2017-05-01 15:52:42 -0400 EDT KubeletHasSufficientDisk kubelet has sufficient disk space available} {MemoryPressure False 2017-05-01 16:02:55 -0400 EDT 2017-05-01 15:52:42 -0400 EDT KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2017-05-01 16:02:55 -0400 EDT 2017-05-01 15:52:42 -0400 EDT KubeletHasNoDiskPressure kubelet has no disk pressure} {Ready True 2017-05-01 16:02:55 -0400 EDT 2017-05-01 15:52:42 -0400 EDT KubeletReady kubelet is posting ready status}],Addresses:[{InternalIP 10.128.0.3} {ExternalIP 35.188.50.76} {Hostname ci-prtest-5a37c28-1547-ig-m-0w9g}],DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:02f1ddb1415c4feba9880b2b8c4c5925,SystemUUID:D900819E-9976-4C63-E2B5-17163502B5E2,BootID:455c81b8-0504-4ade-8014-6b20614103a4,KernelVersion:3.10.0-514.6.1.el7.x86_64,OSImage:Red Hat Enterprise Linux Server 7.3 (Maipo),ContainerRuntimeVersion:docker://1.12.5,KubeletVersion:v1.6.1+5115d708d7,KubeProxyVersion:v1.6.1+5115d708d7,OperatingSystem:linux,Architecture:amd64,},Images:[{[docker.io/openshift/origin-haproxy-router@sha256:8ea695c0608e086ede7786e3b9bf6ba81a2ed3d2952506dbaa3a6a385047bf76 docker.io/openshift/origin-haproxy-router:v3.6.0-alpha.1] 656266633} {[docker.io/openshift/origin-deployer@sha256:393b6ff0ceead1efaece9b2bb8b508b644cbd0c6afb8f2abb4bb6e4f540ccb65 docker.io/openshift/origin-deployer:v3.6.0-alpha.1] 635306029} {[docker.io/openshift/origin-docker-registry@sha256:ec8130ec4591925e1b8609e03a5641e6f2be62a4859f27f59f6267a415b6c01d docker.io/openshift/origin-docker-registry:v3.6.0-alpha.1] 429239546} {[gcr.io/google_containers/nginx-slim@sha256:8b4501fe0fe221df663c22e16539f399e89594552f400408303c42f3dd8d0e52 gcr.io/google_containers/nginx-slim:0.8] 110487599} {[gcr.io/google_containers/nginx-slim@sha256:dd4efd4c13bec2c6f3fe855deeab9524efe434505568421d4f31820485b3a795 gcr.io/google_containers/nginx-slim:0.7] 86864428} {[gcr.io/google_containers/hostexec@sha256:cab8d4e2526f8f767c64febe4ce9e0f0e58cd35fdff81b3aadba4dd041ba9f00 gcr.io/google_containers/hostexec:1.2] 13209617} {[gcr.io/google_containers/netexec@sha256:56c53846f44ea214e4aa5df37c9c50331f0b09e64a32cc7cf17c7e1808d38eef gcr.io/google_containers/netexec:1.7] 8016035} {[docker.io/openshift/origin-pod@sha256:478fd0553a9600014256dede2ad4afb0b620421f5e0353a667be3a94d06dc9b0 docker.io/openshift/origin-pod:v3.6.0-alpha.1] 1138998}],VolumesInUse:[],VolumesAttached:[],},}
May 1 16:02:59.420: INFO:
Logging kubelet events for node ci-prtest-5a37c28-1547-ig-m-0w9g
May 1 16:02:59.451: INFO:
Logging pods the kubelet thinks is on node ci-prtest-5a37c28-1547-ig-m-0w9g
May 1 16:02:59.522: INFO: ss-0 started at 2017-05-01 16:02:26 -0400 EDT (0+1 container statuses recorded)
May 1 16:02:59.522: INFO: Container nginx ready: true, restart count 0
May 1 16:02:59.522: INFO: router-1-dzlxw started at 2017-05-01 15:54:55 -0400 EDT (0+1 container statuses recorded)
May 1 16:02:59.522: INFO: Container router ready: true, restart count 0
May 1 16:02:59.522: INFO: docker-registry-2-r9z9x started at 2017-05-01 15:56:09 -0400 EDT (0+1 container statuses recorded)
May 1 16:02:59.522: INFO: Container registry ready: true, restart count 0
May 1 16:02:59.851: INFO:
Latency metrics for node ci-prtest-5a37c28-1547-ig-m-0w9g
May 1 16:02:59.851: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.9 Latency:2m0.000492s}
May 1 16:02:59.851: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.99 Latency:2m0.000492s}
May 1 16:02:59.851: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.5 Latency:2m0.000492s}
May 1 16:02:59.851: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.99 Latency:22.672682s}
May 1 16:02:59.851: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.9 Latency:11.727144s}
May 1 16:02:59.851: INFO:
Logging node info for node ci-prtest-5a37c28-1547-ig-n-0z3f
May 1 16:02:59.892: INFO: Node Info: &Node{ObjectMeta:k8s_io_apimachinery_pkg_apis_meta_v1.ObjectMeta{Name:ci-prtest-5a37c28-1547-ig-n-0z3f,GenerateName:,Namespace:,SelfLink:/api/v1/nodesci-prtest-5a37c28-1547-ig-n-0z3f,UID:bce38617-2ea7-11e7-a83f-42010a800003,ResourceVersion:22760,Generation:0,CreationTimestamp:2017-05-01 15:52:42 -0400 EDT,DeletionTimestamp:<nil>,DeletionGracePeriodSeconds:nil,Labels:map[string]string{beta.kubernetes.io/arch: amd64,beta.kubernetes.io/instance-type: n1-standard-2,beta.kubernetes.io/os: linux,failure-domain.beta.kubernetes.io/region: us-central1,failure-domain.beta.kubernetes.io/zone: us-central1-a,kubernetes.io/hostname: ci-prtest-5a37c28-1547-ig-n-0z3f,role: app,},Annotations:map[string]string{volumes.kubernetes.io/controller-managed-attach-detach: true,},OwnerReferences:[],Finalizers:[],ClusterName:,},Spec:NodeSpec{PodCIDR:,ExternalID:1735843865684488221,ProviderID:gce://openshift-gce-devel-ci/us-central1-a/ci-prtest-5a37c28-1547-ig-n-0z3f,Unschedulable:false,Taints:[],},Status:NodeStatus{Capacity:ResourceList{cpu: {{2 0} {<nil>} 2 DecimalSI},memory: {{7674597376 0} {<nil>} 7494724Ki BinarySI},pods: {{20 0} {<nil>} 20 DecimalSI},},Allocatable:ResourceList{cpu: {{2 0} {<nil>} 2 DecimalSI},memory: {{7569739776 0} {<nil>} 7392324Ki BinarySI},pods: {{20 0} {<nil>} 20 DecimalSI},},Phase:,Conditions:[{NetworkUnavailable False 0001-01-01 00:00:00 +0000 UTC 2017-05-01 15:52:42 -0400 EDT RouteCreated openshift-sdn cleared kubelet-set NoRouteCreated} {OutOfDisk False 2017-05-01 16:02:53 -0400 EDT 2017-05-01 15:52:42 -0400 EDT KubeletHasSufficientDisk kubelet has sufficient disk space available} {MemoryPressure False 2017-05-01 16:02:53 -0400 EDT 2017-05-01 15:52:42 -0400 EDT KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2017-05-01 16:02:53 -0400 EDT 2017-05-01 15:52:42 -0400 EDT KubeletHasNoDiskPressure kubelet has no disk pressure} {Ready True 2017-05-01 16:02:53 -0400 EDT 2017-05-01 15:52:42 -0400 EDT KubeletReady kubelet is posting ready status}],Addresses:[{InternalIP 10.128.0.2} {ExternalIP 35.188.49.3} {Hostname ci-prtest-5a37c28-1547-ig-n-0z3f}],DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:02f1ddb1415c4feba9880b2b8c4c5925,SystemUUID:6E840054-EE5B-D456-9B2D-90248D49C6CF,BootID:bc414e8f-afca-43fb-859e-1d9f16ce9758,KernelVersion:3.10.0-514.6.1.el7.x86_64,OSImage:Red Hat Enterprise Linux Server 7.3 (Maipo),ContainerRuntimeVersion:docker://1.12.5,KubeletVersion:v1.6.1+5115d708d7,KubeProxyVersion:v1.6.1+5115d708d7,OperatingSystem:linux,Architecture:amd64,},Images:[{[docker.io/openshift/origin-sti-builder@sha256:88f85945c4bffaf226fce4e14f7f30158bd7a2a0f70eebe134e26ae89360d458 docker.io/openshift/origin-sti-builder:v3.6.0-alpha.1] 635306029} {[docker.io/openshift/origin-deployer@sha256:393b6ff0ceead1efaece9b2bb8b508b644cbd0c6afb8f2abb4bb6e4f540ccb65 docker.io/openshift/origin-deployer:v3.6.0-alpha.1] 635306029} {[docker.io/centos/mongodb-32-centos7@sha256:7dd7da83b71c7fd53b96323fa581724d2b713a2410f5837500d1430fb67f9691] 567588133} {[docker.io/centos/ruby-23-centos7@sha256:462084074bae6465c9be294ea2b3d09d0f73181f7c19bbd94078128e19fdd01c] 460196654} {[docker.io/cockpit/kubernetes@sha256:0a7eb8a7796975fbabf2d73f049970e094a3f52834611fe21c0942fa573dd528 docker.io/cockpit/kubernetes:latest] 284703249} {[docker.io/centos@sha256:4eda692c08e0a065ae91d74e82fff4af3da307b4341ad61fa61771cc4659af60 docker.io/centos:centos7] 192481139} {[gcr.io/google_containers/hostexec@sha256:cab8d4e2526f8f767c64febe4ce9e0f0e58cd35fdff81b3aadba4dd041ba9f00 gcr.io/google_containers/hostexec:1.2] 13209617} {[gcr.io/google_containers/netexec@sha256:56c53846f44ea214e4aa5df37c9c50331f0b09e64a32cc7cf17c7e1808d38eef gcr.io/google_containers/netexec:1.7] 8016035} {[gcr.io/google_containers/serve_hostname@sha256:a49737ee84a3b94f0b977f32e60c5daf11f0b5636f1f7503a2981524f351c57a gcr.io/google_containers/serve_hostname:v1.4] 6222101} {[gcr.io/google_containers/porter@sha256:076acdada33f35b917c9eebe89eba95923601302beac57274985e418b70067e2 gcr.io/google_containers/porter:cd5cb5791ebaa8641955f0e8c2a9bed669b1eaab] 5010921} {[gcr.io/google_containers/fakegitserver@sha256:e974692bb4d422a4e9ea6ff9df85fa36f189010703400496fea44aac6589d0dd gcr.io/google_containers/fakegitserver:0.1] 5007469} {[gcr.io/google_containers/update-demo@sha256:89ac104fa7c43880d2324f377b79be95b0b2b3fb32e4bd03b8d1e6d91a41f009 gcr.io/google_containers/update-demo:nautilus] 4555533} {[gcr.io/google_containers/test-webserver@sha256:f804e8837490d1dfdb5002e073f715fd0a08115de74e5a4847ca952315739372 gcr.io/google_containers/test-webserver:e2e] 4534272} {[gcr.io/google_containers/eptest@sha256:bb088b26ed78613cce171420168db9a6c62a8dbea17d7be13077e7010bae162f gcr.io/google_containers/eptest:0.1] 2970692} {[gcr.io/google_containers/busybox@sha256:d8d3bc2c183ed2f9f10e7258f84971202325ee6011ba137112e01e30f206de67 gcr.io/google_containers/busybox:latest] 2433303} {[gcr.io/google_containers/mounttest@sha256:c4dcedb26013ab4231a2b2aaa4eebd5c2a44d5c597fa0613c9ff8bde4fb9fe02 gcr.io/google_containers/mounttest:0.7] 2052704} {[gcr.io/google_containers/portforwardtester@sha256:306879729d3eff635a11b89f3e62e440c9f2fe4dabdfb9ef02bc67f2275f67ab gcr.io/google_containers/portforwardtester:1.2] 1892642} {[gcr.io/google_containers/mounttest@sha256:bec3122ddcf8bd999e44e46e096659f31241d09f5236bc3dc212ea584ca06856 gcr.io/google_containers/mounttest:0.8] 1450761} {[gcr.io/google_containers/mounttest-user@sha256:5487c126b03abf4119a8f7950cd5f591f72dbe4ab15623f3387d3917e1268b4e gcr.io/google_containers/mounttest-user:0.5] 1450761} {[docker.io/openshift/origin-pod@sha256:25c8fd6a2f34411883e2d8818677fe74402acab81d8f56f7badd4d375ec3c2d9 docker.io/openshift/origin-pod:latest] 1143145} {[docker.io/openshift/origin-pod@sha256:478fd0553a9600014256dede2ad4afb0b620421f5e0353a667be3a94d06dc9b0 docker.io/openshift/origin-pod:v3.6.0-alpha.1] 1138998} {[gcr.io/google_containers/busybox@sha256:4bdd623e848417d96127e16037743f0cd8b528c026e9175e22a84f639eca58ff gcr.io/google_containers/busybox:1.24] 1113554} {[gcr.io/google_containers/pause-amd64@sha256:163ac025575b775d1c0f9bf0bdd0f086883171eb475b5068e7defa4ca9e76516 gcr.io/google_containers/pause-amd64:3.0] 746888}],VolumesInUse:[],VolumesAttached:[],},}
May 1 16:02:59.892: INFO:
Logging kubelet events for node ci-prtest-5a37c28-1547-ig-n-0z3f
May 1 16:02:59.951: INFO:
Logging pods the kubelet thinks is on node ci-prtest-5a37c28-1547-ig-n-0z3f
May 1 16:03:00.086: INFO: brokendeployment-1-deploy started at 2017-05-01 16:01:11 -0400 EDT (0+1 container statuses recorded)
May 1 16:03:00.086: INFO: Container deployment ready: false, restart count 0
May 1 16:03:00.086: INFO: deployment-simple-5-2szn9 started at 2017-05-01 16:02:23 -0400 EDT (0+1 container statuses recorded)
May 1 16:03:00.086: INFO: Container myapp ready: true, restart count 0
May 1 16:03:00.086: INFO: liveness-exec started at 2017-05-01 16:00:49 -0400 EDT (0+1 container statuses recorded)
May 1 16:03:00.086: INFO: Container liveness ready: true, restart count 0
May 1 16:03:00.086: INFO: history-limit-11-jj292 started at 2017-05-01 16:02:44 -0400 EDT (0+1 container statuses recorded)
May 1 16:03:00.086: INFO: Container myapp ready: true, restart count 0
May 1 16:03:00.086: INFO: history-limit-9-kg84n started at 2017-05-01 16:02:19 -0400 EDT (0+1 container statuses recorded)
May 1 16:03:00.086: INFO: Container myapp ready: true, restart count 0
May 1 16:03:00.086: INFO: pod1 started at 2017-05-01 16:02:44 -0400 EDT (0+1 container statuses recorded)
May 1 16:03:00.086: INFO: Container pause ready: false, restart count 0
May 1 16:03:00.086: INFO: registry-console-1-9zwfd started at 2017-05-01 15:55:50 -0400 EDT (0+1 container statuses recorded)
May 1 16:03:00.086: INFO: Container registry-console ready: false, restart count 4
May 1 16:03:00.294: INFO:
Latency metrics for node ci-prtest-5a37c28-1547-ig-n-0z3f
May 1 16:03:00.294: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.99 Latency:2m2.308331s}
May 1 16:03:00.294: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.9 Latency:2m1.434798s}
May 1 16:03:00.294: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.5 Latency:2m0.000619s}
May 1 16:03:00.294: INFO: {Operation:update Method:pod_worker_latency_microseconds Quantile:0.99 Latency:31.468433s}
May 1 16:03:00.294: INFO: {Operation:update Method:pod_worker_latency_microseconds Quantile:0.9 Latency:30.951553s}
May 1 16:03:00.294: INFO: {Operation:update Method:pod_worker_latency_microseconds Quantile:0.5 Latency:30.398282s}
May 1 16:03:00.294: INFO: {Operation:stop_container Method:docker_operations_latency_microseconds Quantile:0.99 Latency:30.264458s}
May 1 16:03:00.294: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.5 Latency:23.726883s}
May 1 16:03:00.294: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.9 Latency:23.726883s}
May 1 16:03:00.294: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.99 Latency:23.726883s}
May 1 16:03:00.294: INFO: {Operation:pull_image Method:docker_operations_latency_microseconds Quantile:0.99 Latency:22.793845s}
May 1 16:03:00.294: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.99 Latency:19.475056s}
May 1 16:03:00.294: INFO:
Logging node info for node ci-prtest-5a37c28-1547-ig-n-80t9
May 1 16:03:00.325: INFO: Node Info: &Node{ObjectMeta:k8s_io_apimachinery_pkg_apis_meta_v1.ObjectMeta{Name:ci-prtest-5a37c28-1547-ig-n-80t9,GenerateName:,Namespace:,SelfLink:/api/v1/nodesci-prtest-5a37c28-1547-ig-n-80t9,UID:bce30514-2ea7-11e7-a83f-42010a800003,ResourceVersion:22987,Generation:0,CreationTimestamp:2017-05-01 15:52:42 -0400 EDT,DeletionTimestamp:<nil>,DeletionGracePeriodSeconds:nil,Labels:map[string]string{beta.kubernetes.io/arch: amd64,beta.kubernetes.io/instance-type: n1-standard-2,beta.kubernetes.io/os: linux,failure-domain.beta.kubernetes.io/region: us-central1,failure-domain.beta.kubernetes.io/zone: us-central1-a,kubernetes.io/hostname: ci-prtest-5a37c28-1547-ig-n-80t9,role: app,},Annotations:map[string]string{volumes.kubernetes.io/controller-managed-attach-detach: true,},OwnerReferences:[],Finalizers:[],ClusterName:,},Spec:NodeSpec{PodCIDR:,ExternalID:1462462411245760541,ProviderID:gce://openshift-gce-devel-ci/us-central1-a/ci-prtest-5a37c28-1547-ig-n-80t9,Unschedulable:false,Taints:[],},Status:NodeStatus{Capacity:ResourceList{cpu: {{2 0} {<nil>} 2 DecimalSI},memory: {{7674597376 0} {<nil>} 7494724Ki BinarySI},pods: {{20 0} {<nil>} 20 DecimalSI},},Allocatable:ResourceList{cpu: {{2 0} {<nil>} 2 DecimalSI},memory: {{7569739776 0} {<nil>} 7392324Ki BinarySI},pods: {{20 0} {<nil>} 20 DecimalSI},},Phase:,Conditions:[{NetworkUnavailable False 0001-01-01 00:00:00 +0000 UTC 2017-05-01 15:52:42 -0400 EDT RouteCreated openshift-sdn cleared kubelet-set NoRouteCreated} {OutOfDisk False 2017-05-01 16:02:55 -0400 EDT 2017-05-01 15:52:42 -0400 EDT KubeletHasSufficientDisk kubelet has sufficient disk space available} {MemoryPressure False 2017-05-01 16:02:55 -0400 EDT 2017-05-01 15:52:42 -0400 EDT KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2017-05-01 16:02:55 -0400 EDT 2017-05-01 15:52:42 -0400 EDT KubeletHasNoDiskPressure kubelet has no disk pressure} {Ready True 2017-05-01 16:02:55 -0400 EDT 2017-05-01 15:52:42 -0400 EDT KubeletReady kubelet is posting ready status}],Addresses:[{InternalIP 10.128.0.4} {ExternalIP 35.188.58.181} {Hostname ci-prtest-5a37c28-1547-ig-n-80t9}],DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:02f1ddb1415c4feba9880b2b8c4c5925,SystemUUID:B16550BB-2488-235D-8630-80A4DC001943,BootID:2797de9a-f3ae-4e05-8466-4cbe56a95131,KernelVersion:3.10.0-514.6.1.el7.x86_64,OSImage:Red Hat Enterprise Linux Server 7.3 (Maipo),ContainerRuntimeVersion:docker://1.12.5,KubeletVersion:v1.6.1+5115d708d7,KubeProxyVersion:v1.6.1+5115d708d7,OperatingSystem:linux,Architecture:amd64,},Images:[{[docker.io/openshift/origin-sti-builder@sha256:88f85945c4bffaf226fce4e14f7f30158bd7a2a0f70eebe134e26ae89360d458 docker.io/openshift/origin-sti-builder:v3.6.0-alpha.1] 635306029} {[docker.io/openshift/origin-deployer@sha256:393b6ff0ceead1efaece9b2bb8b508b644cbd0c6afb8f2abb4bb6e4f540ccb65 docker.io/openshift/origin-deployer:v3.6.0-alpha.1] 635306029} {[172.30.203.215:5000/extended-test-new-app-d4g0b-stxwt/a234567890123456789012345678901234567890123456789012345678@sha256:72f83c5231b05e2c7e407e2aa8f5511448e7f162a808d895f240b32cf8832f58 172.30.203.215:5000/extended-test-new-app-d4g0b-stxwt/a234567890123456789012345678901234567890123456789012345678:latest] 460701635} {[docker.io/centos/ruby-22-centos7@sha256:a87678f6244843f72a729b4d5e89071de885b2e4e1da51d08f88d2f75ec50d70 docker.io/centos/ruby-22-centos7:latest] 445554732} {[docker.io/centos/nodejs-4-centos7@sha256:f56359b47e5418e9b517595061e1ca860f3b577d475a42a030771bf3c4af29d7] 445388546} {[gcr.io/google_containers/redis@sha256:f066bcf26497fbc55b9bf0769cb13a35c0afa2aa42e737cc46b7fb04b23a2f25 gcr.io/google_containers/redis:e2e] 419003740} {[docker.io/openshift/origin-base@sha256:a3c62659643c6425074810fd207d5fc5febab6b0e989127df11ea214176f36bb docker.io/openshift/origin-base:latest] 396689147} {[docker.io/centos@sha256:4eda692c08e0a065ae91d74e82fff4af3da307b4341ad61fa61771cc4659af60 docker.io/centos:centos7] 192481139} {[gcr.io/google_containers/jessie-dnsutils@sha256:2460d596912244b5f8973573f7150e7264b570015f4becc2d0096f0bd1d17e36 gcr.io/google_containers/jessie-dnsutils:e2e] 190148402} {[gcr.io/google_containers/nginx-slim@sha256:dd4efd4c13bec2c6f3fe855deeab9524efe434505568421d4f31820485b3a795 gcr.io/google_containers/nginx-slim:0.7] 86864428} {[gcr.io/google_containers/nettest@sha256:8af3a0e8b8ab906b0648dd575e8785e04c19113531f8ffbaab9e149aa1a60763 gcr.io/google_containers/nettest:1.7] 24051275} {[gcr.io/google_containers/hostexec@sha256:cab8d4e2526f8f767c64febe4ce9e0f0e58cd35fdff81b3aadba4dd041ba9f00 gcr.io/google_containers/hostexec:1.2] 13209617} {[gcr.io/google_containers/dnsutils@sha256:cd9182f6d74e616942db1cef6f25e1e54b49ba0330c2e19d3ec061f027666cc0 gcr.io/google_containers/dnsutils:e2e] 8897789} {[gcr.io/google_containers/netexec@sha256:56c53846f44ea214e4aa5df37c9c50331f0b09e64a32cc7cf17c7e1808d38eef gcr.io/google_containers/netexec:1.7] 8016035} {[gcr.io/google_containers/serve_hostname@sha256:a49737ee84a3b94f0b977f32e60c5daf11f0b5636f1f7503a2981524f351c57a gcr.io/google_containers/serve_hostname:v1.4] 6222101} {[gcr.io/google_containers/update-demo@sha256:89ac104fa7c43880d2324f377b79be95b0b2b3fb32e4bd03b8d1e6d91a41f009 gcr.io/google_containers/update-demo:nautilus] 4555533} {[gcr.io/google_containers/test-webserver@sha256:f804e8837490d1dfdb5002e073f715fd0a08115de74e5a4847ca952315739372 gcr.io/google_containers/test-webserver:e2e] 4534272} {[gcr.io/google_containers/portforwardtester@sha256:306879729d3eff635a11b89f3e62e440c9f2fe4dabdfb9ef02bc67f2275f67ab gcr.io/google_containers/portforwardtester:1.2] 1892642} {[gcr.io/google_containers/mounttest@sha256:bec3122ddcf8bd999e44e46e096659f31241d09f5236bc3dc212ea584ca06856 gcr.io/google_containers/mounttest:0.8] 1450761} {[gcr.io/google_containers/mounttest-user@sha256:5487c126b03abf4119a8f7950cd5f591f72dbe4ab15623f3387d3917e1268b4e gcr.io/google_containers/mounttest-user:0.5] 1450761} {[docker.io/openshift/origin-pod@sha256:478fd0553a9600014256dede2ad4afb0b620421f5e0353a667be3a94d06dc9b0 docker.io/openshift/origin-pod:v3.6.0-alpha.1] 1138998} {[gcr.io/google_containers/busybox@sha256:4bdd623e848417d96127e16037743f0cd8b528c026e9175e22a84f639eca58ff gcr.io/google_containers/busybox:1.24] 1113554} {[gcr.io/google_containers/pause-amd64@sha256:163ac025575b775d1c0f9bf0bdd0f086883171eb475b5068e7defa4ca9e76516 gcr.io/google_containers/pause-amd64:3.0] 746888}],VolumesInUse:[],VolumesAttached:[],},}
May 1 16:03:00.326: INFO:
Logging kubelet events for node ci-prtest-5a37c28-1547-ig-n-80t9
May 1 16:03:00.356: INFO:
Logging pods the kubelet thinks is on node ci-prtest-5a37c28-1547-ig-n-80t9
May 1 16:03:00.433: INFO: history-limit-10-83bts started at 2017-05-01 16:02:31 -0400 EDT (0+1 container statuses recorded)
May 1 16:03:00.433: INFO: Container myapp ready: true, restart count 0
May 1 16:03:00.433: INFO: pod-secrets-08972f9f-2ea9-11e7-ac35-0e5d7c1ab0ac started at 2017-05-01 16:01:58 -0400 EDT (0+3 container statuses recorded)
May 1 16:03:00.433: INFO: Container creates-volume-test ready: true, restart count 0
May 1 16:03:00.433: INFO: Container dels-volume-test ready: true, restart count 0
May 1 16:03:00.433: INFO: Container upds-volume-test ready: true, restart count 0
May 1 16:03:00.433: INFO: brokendeployment-1-hook-pre started at 2017-05-01 16:01:13 -0400 EDT (0+1 container statuses recorded)
May 1 16:03:00.433: INFO: Container lifecycle ready: false, restart count 0
May 1 16:03:00.433: INFO: registry-console-1-deploy started at 2017-05-01 15:55:25 -0400 EDT (0+1 container statuses recorded)
May 1 16:03:00.433: INFO: Container deployment ready: true, restart count 0
May 1 16:03:00.433: INFO: deployment-simple-4-bbq36 started at 2017-05-01 16:02:16 -0400 EDT (0+1 container statuses recorded)
May 1 16:03:00.433: INFO: Container myapp ready: false, restart count 0
May 1 16:03:00.433: INFO: pod2 started at 2017-05-01 16:02:48 -0400 EDT (0+1 container statuses recorded)
May 1 16:03:00.433: INFO: Container pause ready: false, restart count 0
May 1 16:03:00.433: INFO: pod-projected-configmaps-22bafc0a-2ea9-11e7-a27f-0e5d7c1ab0ac started at 2017-05-01 16:02:42 -0400 EDT (0+1 container statuses recorded)
May 1 16:03:00.433: INFO: Container projected-configmap-volume-test ready: true, restart count 0
May 1 16:03:00.693: INFO:
Latency metrics for node ci-prtest-5a37c28-1547-ig-n-80t9
May 1 16:03:00.693: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.99 Latency:2m1.492873s}
May 1 16:03:00.693: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.9 Latency:2m1.083298s}
May 1 16:03:00.693: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.5 Latency:2m0.000522s}
May 1 16:03:00.693: INFO: {Operation:update Method:pod_worker_latency_microseconds Quantile:0.99 Latency:31.666929s}
May 1 16:03:00.693: INFO: {Operation:update Method:pod_worker_latency_microseconds Quantile:0.9 Latency:30.963921s}
May 1 16:03:00.693: INFO: {Operation:update Method:pod_worker_latency_microseconds Quantile:0.5 Latency:30.351228s}
May 1 16:03:00.693: INFO: {Operation:stop_container Method:docker_operations_latency_microseconds Quantile:0.99 Latency:30.240499s}
May 1 16:03:00.693: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.99 Latency:20.153017s}
May 1 16:03:00.693: INFO: {Operation:pull_image Method:docker_operations_latency_microseconds Quantile:0.99 Latency:12.26715s}
May 1 16:03:00.693: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.5 Latency:10.720405s}
May 1 16:03:00.693: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.9 Latency:10.720405s}
May 1 16:03:00.693: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.99 Latency:10.720405s}
May 1 16:03:00.693: INFO:
Logging node info for node ci-prtest-5a37c28-1547-ig-n-jr3j
May 1 16:03:00.725: INFO: Node Info: &Node{ObjectMeta:k8s_io_apimachinery_pkg_apis_meta_v1.ObjectMeta{Name:ci-prtest-5a37c28-1547-ig-n-jr3j,GenerateName:,Namespace:,SelfLink:/api/v1/nodesci-prtest-5a37c28-1547-ig-n-jr3j,UID:bce5d165-2ea7-11e7-a83f-42010a800003,ResourceVersion:22715,Generation:0,CreationTimestamp:2017-05-01 15:52:42 -0400 EDT,DeletionTimestamp:<nil>,DeletionGracePeriodSeconds:nil,Labels:map[string]string{beta.kubernetes.io/arch: amd64,beta.kubernetes.io/instance-type: n1-standard-2,beta.kubernetes.io/os: linux,failure-domain.beta.kubernetes.io/region: us-central1,failure-domain.beta.kubernetes.io/zone: us-central1-a,kubernetes.io/hostname: ci-prtest-5a37c28-1547-ig-n-jr3j,role: app,},Annotations:map[string]string{volumes.kubernetes.io/controller-managed-attach-detach: true,},OwnerReferences:[],Finalizers:[],ClusterName:,},Spec:NodeSpec{PodCIDR:,ExternalID:4804212899023826973,ProviderID:gce://openshift-gce-devel-ci/us-central1-a/ci-prtest-5a37c28-1547-ig-n-jr3j,Unschedulable:false,Taints:[],},Status:NodeStatus{Capacity:ResourceList{cpu: {{2 0} {<nil>} 2 DecimalSI},memory: {{7674597376 0} {<nil>} 7494724Ki BinarySI},pods: {{20 0} {<nil>} 20 DecimalSI},},Allocatable:ResourceList{cpu: {{2 0} {<nil>} 2 DecimalSI},memory: {{7569739776 0} {<nil>} 7392324Ki BinarySI},pods: {{20 0} {<nil>} 20 DecimalSI},},Phase:,Conditions:[{NetworkUnavailable False 0001-01-01 00:00:00 +0000 UTC 2017-05-01 15:52:42 -0400 EDT RouteCreated openshift-sdn cleared kubelet-set NoRouteCreated} {OutOfDisk False 2017-05-01 16:02:52 -0400 EDT 2017-05-01 15:52:42 -0400 EDT KubeletHasSufficientDisk kubelet has sufficient disk space available} {MemoryPressure False 2017-05-01 16:02:52 -0400 EDT 2017-05-01 15:52:42 -0400 EDT KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2017-05-01 16:02:52 -0400 EDT 2017-05-01 15:52:42 -0400 EDT KubeletHasNoDiskPressure kubelet has no disk pressure} {Ready True 2017-05-01 16:02:52 -0400 EDT 2017-05-01 15:52:42 -0400 EDT KubeletReady kubelet is posting ready status}],Addresses:[{InternalIP 10.128.0.5} {ExternalIP 35.188.103.127} {Hostname ci-prtest-5a37c28-1547-ig-n-jr3j}],DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:02f1ddb1415c4feba9880b2b8c4c5925,SystemUUID:8B7F39D0-B900-8744-EA2A-3E80A2BD752F,BootID:0a0fd533-74ed-4dfd-922e-99e4bf3a60d1,KernelVersion:3.10.0-514.6.1.el7.x86_64,OSImage:Red Hat Enterprise Linux Server 7.3 (Maipo),ContainerRuntimeVersion:docker://1.12.5,KubeletVersion:v1.6.1+5115d708d7,KubeProxyVersion:v1.6.1+5115d708d7,OperatingSystem:linux,Architecture:amd64,},Images:[{[docker.io/openshift/origin-sti-builder@sha256:88f85945c4bffaf226fce4e14f7f30158bd7a2a0f70eebe134e26ae89360d458 docker.io/openshift/origin-sti-builder:v3.6.0-alpha.1] 635306029} {[docker.io/openshift/origin-docker-builder@sha256:a76c1a9da9d17f59ded0070ae70f65159b861348b856b8d5e1f0fdcf29f10085 docker.io/openshift/origin-docker-builder:v3.6.0-alpha.1] 635306029} {[docker.io/openshift/origin-deployer@sha256:393b6ff0ceead1efaece9b2bb8b508b644cbd0c6afb8f2abb4bb6e4f540ccb65 docker.io/openshift/origin-deployer:v3.6.0-alpha.1] 635306029} {[docker.io/centos/ruby-22-centos7@sha256:a87678f6244843f72a729b4d5e89071de885b2e4e1da51d08f88d2f75ec50d70 docker.io/centos/ruby-22-centos7:latest] 445554732} {[gcr.io/google_containers/redis@sha256:f066bcf26497fbc55b9bf0769cb13a35c0afa2aa42e737cc46b7fb04b23a2f25 gcr.io/google_containers/redis:e2e] 419003740} {[docker.io/openshift/origin-base@sha256:a3c62659643c6425074810fd207d5fc5febab6b0e989127df11ea214176f36bb docker.io/openshift/origin-base:latest] 396689147} {[docker.io/centos@sha256:4eda692c08e0a065ae91d74e82fff4af3da307b4341ad61fa61771cc4659af60 docker.io/centos:7 docker.io/centos:centos7] 192481139} {[gcr.io/google_containers/nginx-slim@sha256:dd4efd4c13bec2c6f3fe855deeab9524efe434505568421d4f31820485b3a795 gcr.io/google_containers/nginx-slim:0.7] 86864428} {[gcr.io/google_containers/hostexec@sha256:cab8d4e2526f8f767c64febe4ce9e0f0e58cd35fdff81b3aadba4dd041ba9f00 gcr.io/google_containers/hostexec:1.2] 13209617} {[gcr.io/google_containers/netexec@sha256:56c53846f44ea214e4aa5df37c9c50331f0b09e64a32cc7cf17c7e1808d38eef gcr.io/google_containers/netexec:1.7] 8016035} {[gcr.io/google_containers/serve_hostname@sha256:a49737ee84a3b94f0b977f32e60c5daf11f0b5636f1f7503a2981524f351c57a gcr.io/google_containers/serve_hostname:v1.4] 6222101} {[gcr.io/google_containers/update-demo@sha256:89ac104fa7c43880d2324f377b79be95b0b2b3fb32e4bd03b8d1e6d91a41f009 gcr.io/google_containers/update-demo:nautilus] 4555533} {[gcr.io/google_containers/test-webserver@sha256:f804e8837490d1dfdb5002e073f715fd0a08115de74e5a4847ca952315739372 gcr.io/google_containers/test-webserver:e2e] 4534272} {[gcr.io/google_containers/liveness@sha256:90994881062c7de7bb1761f2f3d020fe9aa3d332a90e00ebd3ca9dcc1ed74f1c gcr.io/google_containers/liveness:e2e] 4387474} {[gcr.io/google_containers/eptest@sha256:bb088b26ed78613cce171420168db9a6c62a8dbea17d7be13077e7010bae162f gcr.io/google_containers/eptest:0.1] 2970692} {[gcr.io/google_containers/mounttest@sha256:c4dcedb26013ab4231a2b2aaa4eebd5c2a44d5c597fa0613c9ff8bde4fb9fe02 gcr.io/google_containers/mounttest:0.7] 2052704} {[gcr.io/google_containers/mounttest@sha256:bec3122ddcf8bd999e44e46e096659f31241d09f5236bc3dc212ea584ca06856 gcr.io/google_containers/mounttest:0.8] 1450761} {[gcr.io/google_containers/mounttest-user@sha256:5487c126b03abf4119a8f7950cd5f591f72dbe4ab15623f3387d3917e1268b4e gcr.io/google_containers/mounttest-user:0.5] 1450761} {[docker.io/openshift/origin-pod@sha256:478fd0553a9600014256dede2ad4afb0b620421f5e0353a667be3a94d06dc9b0 docker.io/openshift/origin-pod:v3.6.0-alpha.1] 1138998} {[gcr.io/google_containers/busybox@sha256:4bdd623e848417d96127e16037743f0cd8b528c026e9175e22a84f639eca58ff gcr.io/google_containers/busybox:1.24] 1113554} {[gcr.io/google_containers/pause-amd64@sha256:163ac025575b775d1c0f9bf0bdd0f086883171eb475b5068e7defa4ca9e76516 gcr.io/google_containers/pause-amd64:3.0] 746888} {[gcr.io/google-containers/pause@sha256:9ce5316f9752b8347484ab0f6778573af15524124d52b93230b9a0dcc987e73e gcr.io/google-containers/pause:2.0] 350164}],VolumesInUse:[],VolumesAttached:[],},}
May 1 16:03:00.725: INFO:
Logging kubelet events for node ci-prtest-5a37c28-1547-ig-n-jr3j
May 1 16:03:00.756: INFO:
Logging pods the kubelet thinks is on node ci-prtest-5a37c28-1547-ig-n-jr3j
May 1 16:03:00.844: INFO: pod-configmaps-06dccbbb-2ea9-11e7-8fcc-0e5d7c1ab0ac started at 2017-05-01 16:01:55 -0400 EDT (0+3 container statuses recorded)
May 1 16:03:00.844: INFO: Container createcm-volume-test ready: true, restart count 0
May 1 16:03:00.844: INFO: Container delcm-volume-test ready: true, restart count 0
May 1 16:03:00.844: INFO: Container updcm-volume-test ready: true, restart count 0
May 1 16:03:00.844: INFO: deployment-simple-5-m9j08 started at 2017-05-01 16:02:23 -0400 EDT (0+1 container statuses recorded)
May 1 16:03:00.844: INFO: Container myapp ready: true, restart count 0
May 1 16:03:01.047: INFO:
Latency metrics for node ci-prtest-5a37c28-1547-ig-n-jr3j
May 1 16:03:01.047: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.99 Latency:2m4.478214s}
May 1 16:03:01.047: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.9 Latency:2m1.734977s}
May 1 16:03:01.047: INFO: {Operation:sync Method:pod_worker_latency_microseconds Quantile:0.5 Latency:2m0.001719s}
May 1 16:03:01.047: INFO: {Operation:update Method:pod_worker_latency_microseconds Quantile:0.99 Latency:32.024443s}
May 1 16:03:01.047: INFO: {Operation:update Method:pod_worker_latency_microseconds Quantile:0.9 Latency:30.549394s}
May 1 16:03:01.047: INFO: {Operation:stop_container Method:docker_operations_latency_microseconds Quantile:0.99 Latency:30.17005s}
May 1 16:03:01.047: INFO: {Operation: Method:pod_start_latency_microseconds Quantile:0.99 Latency:25.372078s}
May 1 16:03:01.047: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.9 Latency:23.323811s}
May 1 16:03:01.047: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.99 Latency:23.323811s}
May 1 16:03:01.047: INFO: {Operation:create Method:pod_worker_latency_microseconds Quantile:0.5 Latency:22.991392s}
May 1 16:03:01.047: INFO: {Operation:pull_image Method:docker_operations_latency_microseconds Quantile:0.99 Latency:22.791052s}
STEP: Dumping a list of prepulled images on each node
May 1 16:03:01.078: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-statefulset-tg8v0" for this suite.
May 1 16:03:12.966: INFO: namespace: e2e-tests-statefulset-tg8v0, resource: bindings, ignored listing per whitelist
Seen here:
Error
Full output
click to view