SuccessConsole Output

Skipping 6,585 KB.. Full Log
Nov 10 13:04:18.566: INFO: 	Container alert-buffer ready: true, restart count 0
Nov 10 13:04:18.566: INFO: 	Container alertmanager ready: true, restart count 0
Nov 10 13:04:18.566: INFO: 	Container alerts-proxy ready: true, restart count 0
Nov 10 13:04:18.566: INFO: 	Container prom-proxy ready: true, restart count 0
Nov 10 13:04:18.566: INFO: 	Container prometheus ready: true, restart count 0
Nov 10 13:04:18.566: INFO: registry-console-1-zts8s from default started at 2017-11-10 12:29:21 +0000 UTC (1 container statuses recorded)
Nov 10 13:04:18.566: INFO: 	Container registry-console ready: true, restart count 0
Nov 10 13:04:18.566: INFO: router-2-wmdk9 from default started at 2017-11-10 12:29:49 +0000 UTC (1 container statuses recorded)
Nov 10 13:04:18.566: INFO: 	Container router ready: true, restart count 0
[It] validates that NodeSelector is respected if matching [Conformance] [Suite:openshift/conformance/serial] [Suite:k8s]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/predicates.go:296
STEP: Trying to launch a pod without a label to get a node which can launch it.
STEP: Explicitly delete pod here to free the resource it takes.
STEP: Trying to apply a random label on the found node.
STEP: verifying the node has the label kubernetes.io/e2e-abbce682-c617-11e7-b8d4-0e640c284dde 42
STEP: Trying to relaunch the pod, now with labels.
STEP: removing the label kubernetes.io/e2e-abbce682-c617-11e7-b8d4-0e640c284dde off the node ip-172-18-11-139.ec2.internal
STEP: verifying the node doesn't have the label kubernetes.io/e2e-abbce682-c617-11e7-b8d4-0e640c284dde
[AfterEach] [k8s.io] SchedulerPredicates [Serial]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:131
Nov 10 13:04:26.631: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-sched-pred-8jr2k" for this suite.
Nov 10 13:04:48.765: INFO: namespace: e2e-tests-sched-pred-8jr2k, resource: bindings, ignored listing per whitelist
Nov 10 13:04:48.765: INFO: namespace e2e-tests-sched-pred-8jr2k deletion completed in 22.131472562s
[AfterEach] [k8s.io] SchedulerPredicates [Serial]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/predicates.go:73
I1110 13:04:48.765518    1335 request.go:785] Error in request: resource name may not be empty

• [SLOW TEST:30.335 seconds]
[k8s.io] SchedulerPredicates [Serial]
/tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:620
  validates that NodeSelector is respected if matching [Conformance] [Suite:openshift/conformance/serial] [Suite:k8s]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/predicates.go:296
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[k8s.io] SchedulerPredicates [Serial] 
  validates that NodeSelector is respected if not matching [Conformance] [Suite:openshift/conformance/serial] [Suite:k8s]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/predicates.go:242
[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [k8s.io] SchedulerPredicates [Serial]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:130
STEP: Creating a kubernetes client
Nov 10 13:04:48.766: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Nov 10 13:04:48.844: 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] SchedulerPredicates [Serial]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/predicates.go:109
Nov 10 13:04:48.894: INFO: Waiting up to 1m0s for all (but 0) nodes to be ready
Nov 10 13:04:48.898: INFO: Waiting for terminating namespaces to be deleted...
Nov 10 13:04:48.902: INFO: 
Logging pods the kubelet thinks is on node ip-172-18-11-139.ec2.internal before test
Nov 10 13:04:48.908: INFO: prometheus-0 from kube-system started at 2017-11-10 12:33:09 +0000 UTC (5 container statuses recorded)
Nov 10 13:04:48.908: INFO: 	Container alert-buffer ready: true, restart count 0
Nov 10 13:04:48.908: INFO: 	Container alertmanager ready: true, restart count 0
Nov 10 13:04:48.908: INFO: 	Container alerts-proxy ready: true, restart count 0
Nov 10 13:04:48.908: INFO: 	Container prom-proxy ready: true, restart count 0
Nov 10 13:04:48.908: INFO: 	Container prometheus ready: true, restart count 0
Nov 10 13:04:48.908: INFO: registry-console-1-zts8s from default started at 2017-11-10 12:29:21 +0000 UTC (1 container statuses recorded)
Nov 10 13:04:48.908: INFO: 	Container registry-console ready: true, restart count 0
Nov 10 13:04:48.908: INFO: router-2-wmdk9 from default started at 2017-11-10 12:29:49 +0000 UTC (1 container statuses recorded)
Nov 10 13:04:48.908: INFO: 	Container router ready: true, restart count 0
Nov 10 13:04:48.908: INFO: docker-registry-2-snxhf from default started at 2017-11-10 12:29:32 +0000 UTC (1 container statuses recorded)
Nov 10 13:04:48.908: INFO: 	Container registry ready: true, restart count 0
[It] validates that NodeSelector is respected if not matching [Conformance] [Suite:openshift/conformance/serial] [Suite:k8s]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/predicates.go:242
STEP: Trying to schedule Pod with nonempty NodeSelector.
I1110 13:04:48.911417    1335 reflector.go:213] Starting reflector *v1.Event (0s) from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/events.go:135
I1110 13:04:48.911462    1335 reflector.go:251] Listing and watching *v1.Event from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/events.go:135
STEP: Considering event: 
Type = [Warning], Name = [restricted-pod.14f5bb3598b14eef], Reason = [FailedScheduling], Message = [0/1 nodes are available: 1 MatchNodeSelector.]
[AfterEach] [k8s.io] SchedulerPredicates [Serial]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:131
Nov 10 13:04:49.927: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-sched-pred-9vqf6" for this suite.
Nov 10 13:05:11.982: INFO: namespace: e2e-tests-sched-pred-9vqf6, resource: bindings, ignored listing per whitelist
Nov 10 13:05:12.053: INFO: namespace e2e-tests-sched-pred-9vqf6 deletion completed in 22.121631358s
[AfterEach] [k8s.io] SchedulerPredicates [Serial]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/predicates.go:73
I1110 13:05:12.053495    1335 request.go:785] Error in request: resource name may not be empty

• [SLOW TEST:23.287 seconds]
[k8s.io] SchedulerPredicates [Serial]
/tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:620
  validates that NodeSelector is respected if not matching [Conformance] [Suite:openshift/conformance/serial] [Suite:k8s]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/predicates.go:242
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[k8s.io] Namespaces [Serial] 
  should delete fast enough (90 percent of 100 namespaces in 150 seconds) [Suite:openshift/conformance/serial] [Suite:k8s]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/namespace.go:271
[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [k8s.io] Namespaces [Serial]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:130
STEP: Creating a kubernetes client
Nov 10 13:05:12.057: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
STEP: Waiting for a default service account to be provisioned in namespace
[It] should delete fast enough (90 percent of 100 namespaces in 150 seconds) [Suite:openshift/conformance/serial] [Suite:k8s]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/namespace.go:271
STEP: Creating testing namespaces
I1110 13:05:12.209376    1335 request.go:641] Throttling request took 62.734265ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:12.259899    1335 request.go:641] Throttling request took 113.242436ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:12.312212    1335 request.go:641] Throttling request took 165.51477ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:12.361211    1335 request.go:641] Throttling request took 214.502847ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:12.409385    1335 request.go:641] Throttling request took 262.640913ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:12.460229    1335 request.go:641] Throttling request took 313.487481ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:12.511224    1335 request.go:641] Throttling request took 364.444068ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:12.561225    1335 request.go:641] Throttling request took 414.440208ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:12.609557    1335 request.go:641] Throttling request took 462.735425ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:12.659371    1335 request.go:641] Throttling request took 512.543551ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:12.714196    1335 request.go:641] Throttling request took 567.3301ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:12.760843    1335 request.go:641] Throttling request took 613.971845ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:12.809378    1335 request.go:641] Throttling request took 662.468996ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:12.859381    1335 request.go:641] Throttling request took 712.462771ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:12.909594    1335 request.go:641] Throttling request took 762.649848ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:12.960197    1335 request.go:641] Throttling request took 813.235193ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.011287    1335 request.go:641] Throttling request took 864.305142ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.060234    1335 request.go:641] Throttling request took 913.227299ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.109358    1335 request.go:641] Throttling request took 962.337867ms, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.160220    1335 request.go:641] Throttling request took 1.013174741s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.209396    1335 request.go:641] Throttling request took 1.062301081s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.260229    1335 request.go:641] Throttling request took 1.113093096s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.310020    1335 request.go:641] Throttling request took 1.162826269s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.359402    1335 request.go:641] Throttling request took 1.212156092s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.410230    1335 request.go:641] Throttling request took 1.26295007s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.459592    1335 request.go:641] Throttling request took 1.312275137s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.511196    1335 request.go:641] Throttling request took 1.363833259s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.562214    1335 request.go:641] Throttling request took 1.414806563s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.609806    1335 request.go:641] Throttling request took 1.462352661s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.659665    1335 request.go:641] Throttling request took 1.512168879s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.711216    1335 request.go:641] Throttling request took 1.563677834s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.760191    1335 request.go:641] Throttling request took 1.612608669s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.810226    1335 request.go:641] Throttling request took 1.662604672s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.862203    1335 request.go:641] Throttling request took 1.714534252s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.911171    1335 request.go:641] Throttling request took 1.763442696s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:13.962237    1335 request.go:641] Throttling request took 1.814481018s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:14.010934    1335 request.go:641] Throttling request took 1.863134633s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:14.060228    1335 request.go:641] Throttling request took 1.912386217s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:14.109385    1335 request.go:641] Throttling request took 1.96150342s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:14.159881    1335 request.go:641] Throttling request took 2.011959405s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:14.211218    1335 request.go:641] Throttling request took 2.063258116s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:14.261404    1335 request.go:641] Throttling request took 2.11339773s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:14.310429    1335 request.go:641] Throttling request took 2.162379168s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:14.359507    1335 request.go:641] Throttling request took 2.211415038s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:14.409612    1335 request.go:641] Throttling request took 2.261473282s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:14.459357    1335 request.go:641] Throttling request took 2.311162065s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:14.510261    1335 request.go:641] Throttling request took 2.362010602s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:14.560255    1335 request.go:641] Throttling request took 2.411956001s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
I1110 13:05:14.614203    1335 request.go:641] Throttling request took 2.465866853s, request: POST:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces
STEP: Waiting 10 seconds
STEP: Deleting namespaces
Nov 10 13:05:25.832: INFO: namespace : e2e-tests-nslifetest-99-h2w6h api call to delete is complete 
Nov 10 13:05:25.834: INFO: namespace : e2e-tests-nslifetest-32-c9vd6 api call to delete is complete 
Nov 10 13:05:25.838: INFO: namespace : e2e-tests-nslifetest-33-qg7vp api call to delete is complete 
Nov 10 13:05:25.849: INFO: namespace : e2e-tests-nslifetest-2-8g9gw api call to delete is complete 
Nov 10 13:05:25.849: INFO: namespace : e2e-tests-nslifetest-17-ct982 api call to delete is complete 
Nov 10 13:05:25.851: INFO: namespace : e2e-tests-nslifetest-49-brljg api call to delete is complete 
Nov 10 13:05:25.851: INFO: namespace : e2e-tests-nslifetest-10-96wbd api call to delete is complete 
Nov 10 13:05:25.851: INFO: namespace : e2e-tests-nslifetest-20-cgrk8 api call to delete is complete 
Nov 10 13:05:25.851: INFO: namespace : e2e-tests-nslifetest-45-6mm6h api call to delete is complete 
Nov 10 13:05:25.851: INFO: namespace : e2e-tests-nslifetest-11-drwc5 api call to delete is complete 
Nov 10 13:05:25.851: INFO: namespace : e2e-tests-nslifetest-24-444mh api call to delete is complete 
Nov 10 13:05:25.851: INFO: namespace : e2e-tests-nslifetest-26-xcp7z api call to delete is complete 
Nov 10 13:05:25.851: INFO: namespace : e2e-tests-nslifetest-36-lj4vm api call to delete is complete 
I1110 13:05:25.863226    1335 request.go:641] Throttling request took 52.697606ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-78-cvbc7
Nov 10 13:05:25.863: INFO: namespace : e2e-tests-nslifetest-30-qjwrn api call to delete is complete 
Nov 10 13:05:25.863: INFO: namespace : e2e-tests-nslifetest-38-65hrq api call to delete is complete 
Nov 10 13:05:25.863: INFO: namespace : e2e-tests-nslifetest-13-hrpl4 api call to delete is complete 
Nov 10 13:05:25.863: INFO: namespace : e2e-tests-nslifetest-31-zwnpx api call to delete is complete 
Nov 10 13:05:25.863: INFO: namespace : e2e-tests-nslifetest-28-7j8dt api call to delete is complete 
Nov 10 13:05:25.863: INFO: namespace : e2e-tests-nslifetest-4-fv89b api call to delete is complete 
Nov 10 13:05:25.863: INFO: namespace : e2e-tests-nslifetest-12-77kjq api call to delete is complete 
Nov 10 13:05:25.863: INFO: namespace : e2e-tests-nslifetest-22-vpls6 api call to delete is complete 
Nov 10 13:05:25.863: INFO: namespace : e2e-tests-nslifetest-23-dvtkg api call to delete is complete 
Nov 10 13:05:25.863: INFO: namespace : e2e-tests-nslifetest-19-gwkbj api call to delete is complete 
Nov 10 13:05:25.863: INFO: namespace : e2e-tests-nslifetest-37-9drzq api call to delete is complete 
Nov 10 13:05:25.863: INFO: namespace : e2e-tests-nslifetest-39-nptn5 api call to delete is complete 
Nov 10 13:05:25.865: INFO: namespace : e2e-tests-nslifetest-75-xb5mk api call to delete is complete 
Nov 10 13:05:25.865: INFO: namespace : e2e-tests-nslifetest-43-6nz4m api call to delete is complete 
Nov 10 13:05:25.865: INFO: namespace : e2e-tests-nslifetest-14-dd4m6 api call to delete is complete 
Nov 10 13:05:25.865: INFO: namespace : e2e-tests-nslifetest-42-p4pwk api call to delete is complete 
Nov 10 13:05:25.865: INFO: namespace : e2e-tests-nslifetest-1-8qnbd api call to delete is complete 
Nov 10 13:05:25.866: INFO: namespace : e2e-tests-nslifetest-3-xhwxn api call to delete is complete 
Nov 10 13:05:25.866: INFO: namespace : e2e-tests-nslifetest-35-xgb6f api call to delete is complete 
Nov 10 13:05:25.866: INFO: namespace : e2e-tests-nslifetest-5-27252 api call to delete is complete 
Nov 10 13:05:25.866: INFO: namespace : e2e-tests-nslifetest-27-g86fg api call to delete is complete 
Nov 10 13:05:25.866: INFO: namespace : e2e-tests-nslifetest-44-6gcdt api call to delete is complete 
Nov 10 13:05:25.866: INFO: namespace : e2e-tests-nslifetest-15-rftpg api call to delete is complete 
Nov 10 13:05:25.866: INFO: namespace : e2e-tests-nslifetest-29-mwbn8 api call to delete is complete 
Nov 10 13:05:25.866: INFO: namespace : e2e-tests-nslifetest-41-2x4w9 api call to delete is complete 
Nov 10 13:05:25.872: INFO: namespace : e2e-tests-nslifetest-40-k4ftn api call to delete is complete 
Nov 10 13:05:25.872: INFO: namespace : e2e-tests-nslifetest-18-8c6ck api call to delete is complete 
Nov 10 13:05:25.872: INFO: namespace : e2e-tests-nslifetest-47-r69ld api call to delete is complete 
Nov 10 13:05:25.872: INFO: namespace : e2e-tests-nslifetest-77-jr5hq api call to delete is complete 
Nov 10 13:05:25.872: INFO: namespace : e2e-tests-nslifetest-25-277p4 api call to delete is complete 
Nov 10 13:05:25.872: INFO: namespace : e2e-tests-nslifetest-48-dpt7m api call to delete is complete 
Nov 10 13:05:25.872: INFO: namespace : e2e-tests-nslifetest-16-77q56 api call to delete is complete 
Nov 10 13:05:25.872: INFO: namespace : e2e-tests-nslifetest-46-rd7sw api call to delete is complete 
Nov 10 13:05:25.872: INFO: namespace : e2e-tests-nslifetest-0-7kjqj api call to delete is complete 
Nov 10 13:05:25.872: INFO: namespace : e2e-tests-nslifetest-21-9d42t api call to delete is complete 
Nov 10 13:05:25.876: INFO: namespace : e2e-tests-nslifetest-76-jxskc api call to delete is complete 
Nov 10 13:05:25.876: INFO: namespace : e2e-tests-nslifetest-34-hn8fm api call to delete is complete 
Nov 10 13:05:25.881: INFO: namespace : e2e-tests-nslifetest-78-cvbc7 api call to delete is complete 
I1110 13:05:25.909682    1335 request.go:641] Throttling request took 99.130914ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-79-5l6d5
Nov 10 13:05:25.914: INFO: namespace : e2e-tests-nslifetest-79-5l6d5 api call to delete is complete 
I1110 13:05:25.959380    1335 request.go:641] Throttling request took 148.813979ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-8-rp8ph
Nov 10 13:05:25.963: INFO: namespace : e2e-tests-nslifetest-8-rp8ph api call to delete is complete 
I1110 13:05:26.009361    1335 request.go:641] Throttling request took 198.782526ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-80-c7d2x
Nov 10 13:05:26.012: INFO: namespace : e2e-tests-nslifetest-80-c7d2x api call to delete is complete 
I1110 13:05:26.059378    1335 request.go:641] Throttling request took 248.790443ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-81-txszd
Nov 10 13:05:26.063: INFO: namespace : e2e-tests-nslifetest-81-txszd api call to delete is complete 
I1110 13:05:26.109295    1335 request.go:641] Throttling request took 298.6927ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-82-kb5zp
Nov 10 13:05:26.112: INFO: namespace : e2e-tests-nslifetest-82-kb5zp api call to delete is complete 
I1110 13:05:26.159358    1335 request.go:641] Throttling request took 348.746801ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-83-dk82f
Nov 10 13:05:26.162: INFO: namespace : e2e-tests-nslifetest-83-dk82f api call to delete is complete 
I1110 13:05:26.209364    1335 request.go:641] Throttling request took 398.737828ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-50-ck6xr
Nov 10 13:05:26.212: INFO: namespace : e2e-tests-nslifetest-50-ck6xr api call to delete is complete 
I1110 13:05:26.259371    1335 request.go:641] Throttling request took 448.736147ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-84-lggfj
Nov 10 13:05:26.262: INFO: namespace : e2e-tests-nslifetest-84-lggfj api call to delete is complete 
I1110 13:05:26.309377    1335 request.go:641] Throttling request took 498.742309ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-51-lbv72
Nov 10 13:05:26.312: INFO: namespace : e2e-tests-nslifetest-51-lbv72 api call to delete is complete 
I1110 13:05:26.359373    1335 request.go:641] Throttling request took 548.726374ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-85-jjggn
Nov 10 13:05:26.362: INFO: namespace : e2e-tests-nslifetest-85-jjggn api call to delete is complete 
I1110 13:05:26.409421    1335 request.go:641] Throttling request took 598.771222ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-52-x4lp9
Nov 10 13:05:26.412: INFO: namespace : e2e-tests-nslifetest-52-x4lp9 api call to delete is complete 
I1110 13:05:26.459351    1335 request.go:641] Throttling request took 648.703342ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-86-44xpl
Nov 10 13:05:26.463: INFO: namespace : e2e-tests-nslifetest-86-44xpl api call to delete is complete 
I1110 13:05:26.509374    1335 request.go:641] Throttling request took 698.721827ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-53-hgqrj
Nov 10 13:05:26.512: INFO: namespace : e2e-tests-nslifetest-53-hgqrj api call to delete is complete 
I1110 13:05:26.559360    1335 request.go:641] Throttling request took 748.700218ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-87-k2nmg
Nov 10 13:05:26.562: INFO: namespace : e2e-tests-nslifetest-87-k2nmg api call to delete is complete 
I1110 13:05:26.609347    1335 request.go:641] Throttling request took 798.680488ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-54-wnwsg
Nov 10 13:05:26.612: INFO: namespace : e2e-tests-nslifetest-54-wnwsg api call to delete is complete 
I1110 13:05:26.659396    1335 request.go:641] Throttling request took 848.721826ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-88-mjz8f
Nov 10 13:05:26.663: INFO: namespace : e2e-tests-nslifetest-88-mjz8f api call to delete is complete 
I1110 13:05:26.709353    1335 request.go:641] Throttling request took 898.66616ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-55-6h72s
Nov 10 13:05:26.712: INFO: namespace : e2e-tests-nslifetest-55-6h72s api call to delete is complete 
I1110 13:05:26.759310    1335 request.go:641] Throttling request took 948.624539ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-89-lvz59
Nov 10 13:05:26.762: INFO: namespace : e2e-tests-nslifetest-89-lvz59 api call to delete is complete 
I1110 13:05:26.809359    1335 request.go:641] Throttling request took 998.661739ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-56-llbht
Nov 10 13:05:26.812: INFO: namespace : e2e-tests-nslifetest-56-llbht api call to delete is complete 
I1110 13:05:26.859459    1335 request.go:641] Throttling request took 1.048754005s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-9-q7ts9
Nov 10 13:05:26.862: INFO: namespace : e2e-tests-nslifetest-9-q7ts9 api call to delete is complete 
I1110 13:05:26.909350    1335 request.go:641] Throttling request took 1.098641367s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-57-6hbtg
Nov 10 13:05:26.915: INFO: namespace : e2e-tests-nslifetest-57-6hbtg api call to delete is complete 
I1110 13:05:26.959357    1335 request.go:641] Throttling request took 1.148641631s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-90-6clmr
Nov 10 13:05:26.962: INFO: namespace : e2e-tests-nslifetest-90-6clmr api call to delete is complete 
I1110 13:05:27.009351    1335 request.go:641] Throttling request took 1.198629247s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-58-wksrw
Nov 10 13:05:27.013: INFO: namespace : e2e-tests-nslifetest-58-wksrw api call to delete is complete 
I1110 13:05:27.059388    1335 request.go:641] Throttling request took 1.248648789s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-91-qls9x
Nov 10 13:05:27.063: INFO: namespace : e2e-tests-nslifetest-91-qls9x api call to delete is complete 
I1110 13:05:27.109358    1335 request.go:641] Throttling request took 1.29861343s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-59-fpsxn
Nov 10 13:05:27.112: INFO: namespace : e2e-tests-nslifetest-59-fpsxn api call to delete is complete 
I1110 13:05:27.159402    1335 request.go:641] Throttling request took 1.348644091s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-92-x4s6z
Nov 10 13:05:27.162: INFO: namespace : e2e-tests-nslifetest-92-x4s6z api call to delete is complete 
I1110 13:05:27.209346    1335 request.go:641] Throttling request took 1.398601813s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-6-m2btn
Nov 10 13:05:27.212: INFO: namespace : e2e-tests-nslifetest-6-m2btn api call to delete is complete 
I1110 13:05:27.259375    1335 request.go:641] Throttling request took 1.448624345s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-93-v8bzz
Nov 10 13:05:27.262: INFO: namespace : e2e-tests-nslifetest-93-v8bzz api call to delete is complete 
I1110 13:05:27.309372    1335 request.go:641] Throttling request took 1.498617757s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-60-s7d9t
Nov 10 13:05:27.312: INFO: namespace : e2e-tests-nslifetest-60-s7d9t api call to delete is complete 
I1110 13:05:27.359377    1335 request.go:641] Throttling request took 1.548610141s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-94-xc57b
Nov 10 13:05:27.362: INFO: namespace : e2e-tests-nslifetest-94-xc57b api call to delete is complete 
I1110 13:05:27.409364    1335 request.go:641] Throttling request took 1.598588992s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-61-rfhv2
Nov 10 13:05:27.412: INFO: namespace : e2e-tests-nslifetest-61-rfhv2 api call to delete is complete 
I1110 13:05:27.459381    1335 request.go:641] Throttling request took 1.648599756s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-95-ftb59
Nov 10 13:05:27.464: INFO: namespace : e2e-tests-nslifetest-95-ftb59 api call to delete is complete 
I1110 13:05:27.509355    1335 request.go:641] Throttling request took 1.698575503s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-62-cbhb4
Nov 10 13:05:27.512: INFO: namespace : e2e-tests-nslifetest-62-cbhb4 api call to delete is complete 
I1110 13:05:27.559375    1335 request.go:641] Throttling request took 1.748581797s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-63-v8t25
Nov 10 13:05:27.562: INFO: namespace : e2e-tests-nslifetest-63-v8t25 api call to delete is complete 
I1110 13:05:27.609345    1335 request.go:641] Throttling request took 1.798550939s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-96-lqqt2
Nov 10 13:05:27.612: INFO: namespace : e2e-tests-nslifetest-96-lqqt2 api call to delete is complete 
I1110 13:05:27.659379    1335 request.go:641] Throttling request took 1.848566887s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-97-xxgbb
Nov 10 13:05:27.662: INFO: namespace : e2e-tests-nslifetest-97-xxgbb api call to delete is complete 
I1110 13:05:27.709349    1335 request.go:641] Throttling request took 1.898539054s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-64-gf6bf
Nov 10 13:05:27.712: INFO: namespace : e2e-tests-nslifetest-64-gf6bf api call to delete is complete 
I1110 13:05:27.759371    1335 request.go:641] Throttling request took 1.948546717s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-98-nlbts
Nov 10 13:05:27.762: INFO: namespace : e2e-tests-nslifetest-98-nlbts api call to delete is complete 
I1110 13:05:27.809356    1335 request.go:641] Throttling request took 1.998529958s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-65-pg9np
Nov 10 13:05:27.812: INFO: namespace : e2e-tests-nslifetest-65-pg9np api call to delete is complete 
I1110 13:05:27.859384    1335 request.go:641] Throttling request took 2.04855051s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-66-snnw9
Nov 10 13:05:27.862: INFO: namespace : e2e-tests-nslifetest-66-snnw9 api call to delete is complete 
I1110 13:05:27.909417    1335 request.go:641] Throttling request took 2.098558031s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-67-6nj29
Nov 10 13:05:27.912: INFO: namespace : e2e-tests-nslifetest-67-6nj29 api call to delete is complete 
I1110 13:05:27.959398    1335 request.go:641] Throttling request took 2.148526561s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-68-czvln
Nov 10 13:05:27.962: INFO: namespace : e2e-tests-nslifetest-68-czvln api call to delete is complete 
I1110 13:05:28.009358    1335 request.go:641] Throttling request took 2.198476662s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-69-gk24h
Nov 10 13:05:28.013: INFO: namespace : e2e-tests-nslifetest-69-gk24h api call to delete is complete 
I1110 13:05:28.059365    1335 request.go:641] Throttling request took 2.248476854s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-7-ghbjv
Nov 10 13:05:28.062: INFO: namespace : e2e-tests-nslifetest-7-ghbjv api call to delete is complete 
I1110 13:05:28.109387    1335 request.go:641] Throttling request took 2.298487018s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-70-kt7lp
Nov 10 13:05:28.113: INFO: namespace : e2e-tests-nslifetest-70-kt7lp api call to delete is complete 
I1110 13:05:28.159357    1335 request.go:641] Throttling request took 2.34844442s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-71-8l5hf
Nov 10 13:05:28.162: INFO: namespace : e2e-tests-nslifetest-71-8l5hf api call to delete is complete 
I1110 13:05:28.209403    1335 request.go:641] Throttling request took 2.398476012s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-72-9ml24
Nov 10 13:05:28.214: INFO: namespace : e2e-tests-nslifetest-72-9ml24 api call to delete is complete 
I1110 13:05:28.259376    1335 request.go:641] Throttling request took 2.448442369s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-73-gc6xd
Nov 10 13:05:28.262: INFO: namespace : e2e-tests-nslifetest-73-gc6xd api call to delete is complete 
I1110 13:05:28.309378    1335 request.go:641] Throttling request took 2.498433626s, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-74-lw78b
Nov 10 13:05:28.312: INFO: namespace : e2e-tests-nslifetest-74-lw78b api call to delete is complete 
STEP: Waiting for namespaces to vanish
Nov 10 13:05:30.317: INFO: Remaining namespaces : 100
Nov 10 13:05:32.321: INFO: Remaining namespaces : 90
Nov 10 13:05:36.124: INFO: Remaining namespaces : 90
Nov 10 13:05:36.348: INFO: Remaining namespaces : 90
Nov 10 13:05:41.168: INFO: Remaining namespaces : 81
Nov 10 13:05:42.329: INFO: Remaining namespaces : 80
Nov 10 13:05:46.181: INFO: Remaining namespaces : 76
Nov 10 13:05:46.328: INFO: Remaining namespaces : 74
Nov 10 13:05:51.328: INFO: Remaining namespaces : 70
Nov 10 13:05:52.321: INFO: Remaining namespaces : 60
Nov 10 13:05:56.055: INFO: Remaining namespaces : 60
Nov 10 13:05:56.348: INFO: Remaining namespaces : 60
Nov 10 13:06:00.940: INFO: Remaining namespaces : 50
Nov 10 13:06:06.004: INFO: Remaining namespaces : 40
Nov 10 13:06:06.328: INFO: Remaining namespaces : 40
Nov 10 13:06:11.178: INFO: Remaining namespaces : 30
Nov 10 13:06:12.324: INFO: Remaining namespaces : 29
Nov 10 13:06:16.192: INFO: Remaining namespaces : 23
Nov 10 13:06:16.323: INFO: Remaining namespaces : 20
Nov 10 13:06:21.451: INFO: Remaining namespaces : 17
[AfterEach] [k8s.io] Namespaces [Serial]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:131
Nov 10 13:06:22.327: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-namespaces-6zbpv" for this suite.
Nov 10 13:06:28.459: INFO: namespace: e2e-tests-namespaces-6zbpv, resource: bindings, ignored listing per whitelist
Nov 10 13:06:28.522: INFO: namespace e2e-tests-namespaces-6zbpv deletion completed in 6.163507505s
STEP: Destroying namespace "e2e-tests-nslifetest-0-7kjqj" for this suite.
Nov 10 13:06:28.524: INFO: Namespace e2e-tests-nslifetest-0-7kjqj was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-2-8g9gw" for this suite.
Nov 10 13:06:28.526: INFO: Namespace e2e-tests-nslifetest-2-8g9gw was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-1-8qnbd" for this suite.
Nov 10 13:06:28.528: INFO: Namespace e2e-tests-nslifetest-1-8qnbd was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-51-lbv72" for this suite.
Nov 10 13:06:28.530: INFO: Namespace e2e-tests-nslifetest-51-lbv72 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-54-wnwsg" for this suite.
Nov 10 13:06:28.532: INFO: Namespace e2e-tests-nslifetest-54-wnwsg was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-52-x4lp9" for this suite.
Nov 10 13:06:28.534: INFO: Namespace e2e-tests-nslifetest-52-x4lp9 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-50-ck6xr" for this suite.
Nov 10 13:06:28.535: INFO: Namespace e2e-tests-nslifetest-50-ck6xr was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-3-xhwxn" for this suite.
Nov 10 13:06:28.537: INFO: Namespace e2e-tests-nslifetest-3-xhwxn was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-11-drwc5" for this suite.
Nov 10 13:06:28.539: INFO: Namespace e2e-tests-nslifetest-11-drwc5 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-4-fv89b" for this suite.
Nov 10 13:06:28.541: INFO: Namespace e2e-tests-nslifetest-4-fv89b was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-53-hgqrj" for this suite.
Nov 10 13:06:28.543: INFO: Namespace e2e-tests-nslifetest-53-hgqrj was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-9-q7ts9" for this suite.
Nov 10 13:06:28.545: INFO: Namespace e2e-tests-nslifetest-9-q7ts9 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-60-s7d9t" for this suite.
Nov 10 13:06:28.546: INFO: Namespace e2e-tests-nslifetest-60-s7d9t was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-55-6h72s" for this suite.
Nov 10 13:06:28.548: INFO: Namespace e2e-tests-nslifetest-55-6h72s was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-10-96wbd" for this suite.
Nov 10 13:06:28.550: INFO: Namespace e2e-tests-nslifetest-10-96wbd was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-61-rfhv2" for this suite.
Nov 10 13:06:28.552: INFO: Namespace e2e-tests-nslifetest-61-rfhv2 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-5-27252" for this suite.
Nov 10 13:06:28.554: INFO: Namespace e2e-tests-nslifetest-5-27252 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-15-rftpg" for this suite.
Nov 10 13:06:28.556: INFO: Namespace e2e-tests-nslifetest-15-rftpg was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-7-ghbjv" for this suite.
Nov 10 13:06:28.558: INFO: Namespace e2e-tests-nslifetest-7-ghbjv was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-6-m2btn" for this suite.
Nov 10 13:06:28.560: INFO: Namespace e2e-tests-nslifetest-6-m2btn was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-12-77kjq" for this suite.
Nov 10 13:06:28.562: INFO: Namespace e2e-tests-nslifetest-12-77kjq was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-56-llbht" for this suite.
Nov 10 13:06:28.611: INFO: Namespace e2e-tests-nslifetest-56-llbht was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-62-cbhb4" for this suite.
Nov 10 13:06:28.660: INFO: Namespace e2e-tests-nslifetest-62-cbhb4 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-8-rp8ph" for this suite.
Nov 10 13:06:28.711: INFO: Namespace e2e-tests-nslifetest-8-rp8ph was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-58-wksrw" for this suite.
Nov 10 13:06:28.761: INFO: Namespace e2e-tests-nslifetest-58-wksrw was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-99-h2w6h" for this suite.
Nov 10 13:06:28.811: INFO: Namespace e2e-tests-nslifetest-99-h2w6h was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-59-fpsxn" for this suite.
Nov 10 13:06:28.861: INFO: Namespace e2e-tests-nslifetest-59-fpsxn was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-57-6hbtg" for this suite.
Nov 10 13:06:28.910: INFO: Namespace e2e-tests-nslifetest-57-6hbtg was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-63-v8t25" for this suite.
Nov 10 13:06:28.960: INFO: Namespace e2e-tests-nslifetest-63-v8t25 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-65-pg9np" for this suite.
Nov 10 13:06:29.011: INFO: Namespace e2e-tests-nslifetest-65-pg9np was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-64-gf6bf" for this suite.
Nov 10 13:06:29.061: INFO: Namespace e2e-tests-nslifetest-64-gf6bf was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-14-dd4m6" for this suite.
Nov 10 13:06:29.111: INFO: Namespace e2e-tests-nslifetest-14-dd4m6 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-16-77q56" for this suite.
Nov 10 13:06:29.161: INFO: Namespace e2e-tests-nslifetest-16-77q56 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-33-qg7vp" for this suite.
Nov 10 13:06:29.210: INFO: Namespace e2e-tests-nslifetest-33-qg7vp was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-34-hn8fm" for this suite.
Nov 10 13:06:29.261: INFO: Namespace e2e-tests-nslifetest-34-hn8fm was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-13-hrpl4" for this suite.
Nov 10 13:06:29.311: INFO: Namespace e2e-tests-nslifetest-13-hrpl4 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-67-6nj29" for this suite.
Nov 10 13:06:29.361: INFO: Namespace e2e-tests-nslifetest-67-6nj29 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-36-lj4vm" for this suite.
I1110 13:06:29.411271    1335 request.go:641] Throttling request took 50.194706ms, request: DELETE:https://ip-172-18-11-139.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-36-lj4vm
Nov 10 13:06:29.413: INFO: Namespace e2e-tests-nslifetest-36-lj4vm was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-35-xgb6f" for this suite.
Nov 10 13:06:29.461: INFO: Namespace e2e-tests-nslifetest-35-xgb6f was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-17-ct982" for this suite.
Nov 10 13:06:29.511: INFO: Namespace e2e-tests-nslifetest-17-ct982 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-68-czvln" for this suite.
Nov 10 13:06:29.561: INFO: Namespace e2e-tests-nslifetest-68-czvln was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-37-9drzq" for this suite.
Nov 10 13:06:29.611: INFO: Namespace e2e-tests-nslifetest-37-9drzq was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-18-8c6ck" for this suite.
Nov 10 13:06:29.661: INFO: Namespace e2e-tests-nslifetest-18-8c6ck was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-69-gk24h" for this suite.
Nov 10 13:06:29.710: INFO: Namespace e2e-tests-nslifetest-69-gk24h was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-19-gwkbj" for this suite.
Nov 10 13:06:29.760: INFO: Namespace e2e-tests-nslifetest-19-gwkbj was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-39-nptn5" for this suite.
Nov 10 13:06:29.811: INFO: Namespace e2e-tests-nslifetest-39-nptn5 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-66-snnw9" for this suite.
Nov 10 13:06:29.861: INFO: Namespace e2e-tests-nslifetest-66-snnw9 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-38-65hrq" for this suite.
Nov 10 13:06:29.911: INFO: Namespace e2e-tests-nslifetest-38-65hrq was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-20-cgrk8" for this suite.
Nov 10 13:06:29.961: INFO: Namespace e2e-tests-nslifetest-20-cgrk8 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-40-k4ftn" for this suite.
Nov 10 13:06:30.011: INFO: Namespace e2e-tests-nslifetest-40-k4ftn was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-70-kt7lp" for this suite.
Nov 10 13:06:30.061: INFO: Namespace e2e-tests-nslifetest-70-kt7lp was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-21-9d42t" for this suite.
Nov 10 13:06:30.111: INFO: Namespace e2e-tests-nslifetest-21-9d42t was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-72-9ml24" for this suite.
Nov 10 13:06:30.161: INFO: Namespace e2e-tests-nslifetest-72-9ml24 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-42-p4pwk" for this suite.
Nov 10 13:06:30.210: INFO: Namespace e2e-tests-nslifetest-42-p4pwk was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-41-2x4w9" for this suite.
Nov 10 13:06:30.260: INFO: Namespace e2e-tests-nslifetest-41-2x4w9 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-71-8l5hf" for this suite.
Nov 10 13:06:30.311: INFO: Namespace e2e-tests-nslifetest-71-8l5hf was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-73-gc6xd" for this suite.
Nov 10 13:06:30.361: INFO: Namespace e2e-tests-nslifetest-73-gc6xd was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-43-6nz4m" for this suite.
Nov 10 13:06:30.411: INFO: Namespace e2e-tests-nslifetest-43-6nz4m was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-44-6gcdt" for this suite.
Nov 10 13:06:30.461: INFO: Namespace e2e-tests-nslifetest-44-6gcdt was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-74-lw78b" for this suite.
Nov 10 13:06:30.511: INFO: Namespace e2e-tests-nslifetest-74-lw78b was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-75-xb5mk" for this suite.
Nov 10 13:06:30.561: INFO: Namespace e2e-tests-nslifetest-75-xb5mk was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-45-6mm6h" for this suite.
Nov 10 13:06:30.611: INFO: Namespace e2e-tests-nslifetest-45-6mm6h was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-76-jxskc" for this suite.
Nov 10 13:06:30.661: INFO: Namespace e2e-tests-nslifetest-76-jxskc was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-46-rd7sw" for this suite.
Nov 10 13:06:30.711: INFO: Namespace e2e-tests-nslifetest-46-rd7sw was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-77-jr5hq" for this suite.
Nov 10 13:06:30.761: INFO: Namespace e2e-tests-nslifetest-77-jr5hq was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-47-r69ld" for this suite.
Nov 10 13:06:30.811: INFO: Namespace e2e-tests-nslifetest-47-r69ld was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-78-cvbc7" for this suite.
Nov 10 13:06:30.860: INFO: Namespace e2e-tests-nslifetest-78-cvbc7 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-48-dpt7m" for this suite.
Nov 10 13:06:30.910: INFO: Namespace e2e-tests-nslifetest-48-dpt7m was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-79-5l6d5" for this suite.
Nov 10 13:06:30.961: INFO: Namespace e2e-tests-nslifetest-79-5l6d5 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-80-c7d2x" for this suite.
Nov 10 13:06:31.011: INFO: Namespace e2e-tests-nslifetest-80-c7d2x was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-49-brljg" for this suite.
Nov 10 13:06:31.060: INFO: Namespace e2e-tests-nslifetest-49-brljg was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-81-txszd" for this suite.
Nov 10 13:06:31.111: INFO: Namespace e2e-tests-nslifetest-81-txszd was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-82-kb5zp" for this suite.
Nov 10 13:06:31.160: INFO: Namespace e2e-tests-nslifetest-82-kb5zp was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-83-dk82f" for this suite.
Nov 10 13:06:31.210: INFO: Namespace e2e-tests-nslifetest-83-dk82f was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-84-lggfj" for this suite.
Nov 10 13:06:31.260: INFO: Namespace e2e-tests-nslifetest-84-lggfj was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-85-jjggn" for this suite.
Nov 10 13:06:31.310: INFO: Namespace e2e-tests-nslifetest-85-jjggn was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-86-44xpl" for this suite.
Nov 10 13:06:31.361: INFO: Namespace e2e-tests-nslifetest-86-44xpl was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-87-k2nmg" for this suite.
Nov 10 13:06:31.411: INFO: Namespace e2e-tests-nslifetest-87-k2nmg was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-89-lvz59" for this suite.
Nov 10 13:06:31.461: INFO: Namespace e2e-tests-nslifetest-89-lvz59 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-88-mjz8f" for this suite.
Nov 10 13:06:31.511: INFO: Namespace e2e-tests-nslifetest-88-mjz8f was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-90-6clmr" for this suite.
Nov 10 13:06:31.561: INFO: Namespace e2e-tests-nslifetest-90-6clmr was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-91-qls9x" for this suite.
Nov 10 13:06:31.611: INFO: Namespace e2e-tests-nslifetest-91-qls9x was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-92-x4s6z" for this suite.
Nov 10 13:06:31.661: INFO: Namespace e2e-tests-nslifetest-92-x4s6z was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-93-v8bzz" for this suite.
Nov 10 13:06:31.711: INFO: Namespace e2e-tests-nslifetest-93-v8bzz was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-94-xc57b" for this suite.
Nov 10 13:06:31.761: INFO: Namespace e2e-tests-nslifetest-94-xc57b was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-95-ftb59" for this suite.
Nov 10 13:06:31.810: INFO: Namespace e2e-tests-nslifetest-95-ftb59 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-96-lqqt2" for this suite.
Nov 10 13:06:31.861: INFO: Namespace e2e-tests-nslifetest-96-lqqt2 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-97-xxgbb" for this suite.
Nov 10 13:06:31.911: INFO: Namespace e2e-tests-nslifetest-97-xxgbb was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-98-nlbts" for this suite.
Nov 10 13:06:31.961: INFO: Namespace e2e-tests-nslifetest-98-nlbts was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-27-g86fg" for this suite.
Nov 10 13:06:32.011: INFO: Namespace e2e-tests-nslifetest-27-g86fg was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-22-vpls6" for this suite.
Nov 10 13:06:32.061: INFO: Namespace e2e-tests-nslifetest-22-vpls6 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-23-dvtkg" for this suite.
Nov 10 13:06:32.110: INFO: Namespace e2e-tests-nslifetest-23-dvtkg was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-24-444mh" for this suite.
Nov 10 13:06:32.160: INFO: Namespace e2e-tests-nslifetest-24-444mh was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-25-277p4" for this suite.
Nov 10 13:06:32.211: INFO: Namespace e2e-tests-nslifetest-25-277p4 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-26-xcp7z" for this suite.
Nov 10 13:06:32.261: INFO: Namespace e2e-tests-nslifetest-26-xcp7z was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-30-qjwrn" for this suite.
Nov 10 13:06:32.311: INFO: Namespace e2e-tests-nslifetest-30-qjwrn was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-28-7j8dt" for this suite.
Nov 10 13:06:32.362: INFO: Namespace e2e-tests-nslifetest-28-7j8dt was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-29-mwbn8" for this suite.
Nov 10 13:06:32.411: INFO: Namespace e2e-tests-nslifetest-29-mwbn8 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-31-zwnpx" for this suite.
Nov 10 13:06:32.461: INFO: Namespace e2e-tests-nslifetest-31-zwnpx was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-32-c9vd6" for this suite.
Nov 10 13:06:32.510: INFO: Namespace e2e-tests-nslifetest-32-c9vd6 was already deleted

• [SLOW TEST:80.453 seconds]
[k8s.io] Namespaces [Serial]
/tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:620
  should delete fast enough (90 percent of 100 namespaces in 150 seconds) [Suite:openshift/conformance/serial] [Suite:k8s]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/namespace.go:271
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[k8s.io] kubelet [k8s.io] Clean up pods on node 
  kubelet should be able to delete 10 pods per node in 1m0s. [Serial] [Suite:openshift/conformance/serial] [Suite:k8s]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/kubelet.go:394
[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [k8s.io] kubelet
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:130
STEP: Creating a kubernetes client
Nov 10 13:06:32.512: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Nov 10 13:06:32.580: 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] kubelet
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/kubelet.go:296
[BeforeEach] [k8s.io] Clean up pods on node
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/kubelet.go:340
[It] kubelet should be able to delete 10 pods per node in 1m0s. [Serial] [Suite:openshift/conformance/serial] [Suite:k8s]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/kubelet.go:394
STEP: Creating a RC of 10 pods and wait until all pods of this RC are running
STEP: creating replication controller cleanup10-f9406bca-c617-11e7-b8d4-0e640c284dde in namespace e2e-tests-kubelet-sx6gm
I1110 13:06:32.644346    1335 runners.go:176] Created replication controller with name: cleanup10-f9406bca-c617-11e7-b8d4-0e640c284dde, namespace: e2e-tests-kubelet-sx6gm, replica count: 10
I1110 13:06:32.644409    1335 reflector.go:213] Starting reflector *v1.Pod (0s) from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/utils/pod_store.go:53
I1110 13:06:32.644438    1335 reflector.go:251] Listing and watching *v1.Pod from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/utils/pod_store.go:53
I1110 13:06:42.644621    1335 runners.go:176] cleanup10-f9406bca-c617-11e7-b8d4-0e640c284dde Pods: 10 out of 10 created, 0 running, 10 pending, 0 waiting, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I1110 13:06:52.644838    1335 runners.go:176] cleanup10-f9406bca-c617-11e7-b8d4-0e640c284dde Pods: 10 out of 10 created, 10 running, 0 pending, 0 waiting, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
Nov 10 13:06:53.645: INFO: Checking pods on node ip-172-18-11-139.ec2.internal via /runningpods endpoint
Nov 10 13:06:53.653: INFO: Resource usage on node "ip-172-18-11-139.ec2.internal":
container cpu(cores) memory_working_set(MB) memory_rss(MB)
"runtime" 0.225      266.51                 258.27
"kubelet" 0.236      255.33                 254.77
"/"       0.941      8115.91                1762.47

STEP: Deleting the RC
STEP: deleting { ReplicationController} cleanup10-f9406bca-c617-11e7-b8d4-0e640c284dde in namespace e2e-tests-kubelet-sx6gm
I1110 13:06:53.655762    1335 reflector.go:213] Starting reflector *v1.Pod (0s) from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/utils/pod_store.go:53
I1110 13:06:53.655811    1335 reflector.go:251] Listing and watching *v1.Pod from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/utils/pod_store.go:53
Nov 10 13:06:53.840: INFO: Deleting { ReplicationController} cleanup10-f9406bca-c617-11e7-b8d4-0e640c284dde took: 84.857595ms
Nov 10 13:06:53.840: INFO: Terminating { ReplicationController} cleanup10-f9406bca-c617-11e7-b8d4-0e640c284dde pods took: 39.719µs
Nov 10 13:07:07.541: INFO: Garbage collecting { ReplicationController} cleanup10-f9406bca-c617-11e7-b8d4-0e640c284dde pods took: 13.785046556s
Nov 10 13:07:08.541: INFO: Checking pods on node ip-172-18-11-139.ec2.internal via /runningpods endpoint
Nov 10 13:07:08.548: INFO: Deleting 10 pods on 1 nodes completed in 1.007454308s after the RC was deleted
Nov 10 13:07:08.548: INFO: CPU usage of containers on node "ip-172-18-11-139.ec2.internal"
:container 5th%  20th% 50th% 70th% 90th% 95th% 99th%
"/"       0.000 0.000 0.941 0.941 0.941 0.941 0.941
"runtime" 0.000 0.000 0.107 0.107 0.107 0.107 0.107
"kubelet" 0.000 0.000 0.164 0.164 0.164 0.164 0.164

[AfterEach] [k8s.io] Clean up pods on node
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/kubelet.go:348
[AfterEach] [k8s.io] kubelet
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:131
Nov 10 13:07:08.553: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-kubelet-sx6gm" for this suite.
Nov 10 13:07:14.676: INFO: namespace: e2e-tests-kubelet-sx6gm, resource: bindings, ignored listing per whitelist
Nov 10 13:07:14.680: INFO: namespace e2e-tests-kubelet-sx6gm deletion completed in 6.123853231s

• [SLOW TEST:42.169 seconds]
[k8s.io] kubelet
/tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:620
  [k8s.io] Clean up pods on node
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:620
    kubelet should be able to delete 10 pods per node in 1m0s. [Serial] [Suite:openshift/conformance/serial] [Suite:k8s]
    /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/kubelet.go:394
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[k8s.io] Service endpoints latency 
  should not be very high [Conformance] [Serial] [Suite:openshift/conformance/serial] [Suite:k8s]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/service_latency.go:117
[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [k8s.io] Service endpoints latency
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:130
STEP: Creating a kubernetes client
Nov 10 13:07:14.681: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Nov 10 13:07:14.771: INFO: About to run a Kube e2e test, ensuring namespace is privileged
STEP: Waiting for a default service account to be provisioned in namespace
[It] should not be very high [Conformance] [Serial] [Suite:openshift/conformance/serial] [Suite:k8s]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/service_latency.go:117
STEP: creating replication controller svc-latency-rc in namespace e2e-tests-svc-latency-zcsh9
I1110 13:07:14.831985    1335 runners.go:176] Created replication controller with name: svc-latency-rc, namespace: e2e-tests-svc-latency-zcsh9, replica count: 1
I1110 13:07:14.832056    1335 reflector.go:213] Starting reflector *v1.Pod (0s) from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/utils/pod_store.go:53
I1110 13:07:14.832084    1335 reflector.go:251] Listing and watching *v1.Pod from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/utils/pod_store.go:53
I1110 13:07:15.832266    1335 runners.go:176] svc-latency-rc Pods: 1 out of 1 created, 0 running, 1 pending, 0 waiting, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I1110 13:07:16.832493    1335 runners.go:176] svc-latency-rc Pods: 1 out of 1 created, 0 running, 1 pending, 0 waiting, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I1110 13:07:17.832720    1335 runners.go:176] svc-latency-rc Pods: 1 out of 1 created, 1 running, 0 pending, 0 waiting, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I1110 13:07:17.833028    1335 reflector.go:213] Starting reflector *v1.Endpoints (0s) from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/service_latency.go:310
I1110 13:07:17.833057    1335 reflector.go:251] Listing and watching *v1.Endpoints from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/service_latency.go:310
Nov 10 13:07:17.938: INFO: Created: latency-svc-47xgp
Nov 10 13:07:17.950: INFO: Got endpoints: latency-svc-47xgp [17.120098ms]
Nov 10 13:07:17.963: INFO: Created: latency-svc-7m2gz
Nov 10 13:07:17.967: INFO: Created: latency-svc-ntml7
Nov 10 13:07:17.969: INFO: Got endpoints: latency-svc-7m2gz [18.936602ms]
Nov 10 13:07:17.977: INFO: Got endpoints: latency-svc-ntml7 [26.315764ms]
Nov 10 13:07:17.979: INFO: Created: latency-svc-sj4dk
Nov 10 13:07:17.985: INFO: Created: latency-svc-kfns8
Nov 10 13:07:17.994: INFO: Created: latency-svc-g6cg7
Nov 10 13:07:17.994: INFO: Got endpoints: latency-svc-kfns8 [44.153497ms]
Nov 10 13:07:17.994: INFO: Got endpoints: latency-svc-sj4dk [44.437407ms]
Nov 10 13:07:18.003: INFO: Got endpoints: latency-svc-g6cg7 [52.776227ms]
Nov 10 13:07:18.003: INFO: Created: latency-svc-2tgcw
Nov 10 13:07:18.008: INFO: Got endpoints: latency-svc-2tgcw [57.686362ms]
Nov 10 13:07:18.013: INFO: Created: latency-svc-4kl2l
Nov 10 13:07:18.017: INFO: Created: latency-svc-wcscm
Nov 10 13:07:18.019: INFO: Got endpoints: latency-svc-4kl2l [68.62704ms]
Nov 10 13:07:18.022: INFO: Created: latency-svc-9tbs4
Nov 10 13:07:18.022: INFO: Got endpoints: latency-svc-wcscm [71.925477ms]
Nov 10 13:07:18.027: INFO: Created: latency-svc-8tstm
Nov 10 13:07:18.027: INFO: Got endpoints: latency-svc-9tbs4 [76.914767ms]
Nov 10 13:07:18.033: INFO: Got endpoints: latency-svc-8tstm [82.568107ms]
Nov 10 13:07:18.033: INFO: Created: latency-svc-55zwr
Nov 10 13:07:18.039: INFO: Got endpoints: latency-svc-55zwr [88.785429ms]
Nov 10 13:07:18.039: INFO: Created: latency-svc-fv74v
Nov 10 13:07:18.040: INFO: Got endpoints: latency-svc-fv74v [89.56226ms]
Nov 10 13:07:18.044: INFO: Created: latency-svc-7bpwn
Nov 10 13:07:18.048: INFO: Got endpoints: latency-svc-7bpwn [97.725482ms]
Nov 10 13:07:18.051: INFO: Created: latency-svc-nj65n
Nov 10 13:07:18.053: INFO: Got endpoints: latency-svc-nj65n [102.556596ms]
Nov 10 13:07:18.056: INFO: Created: latency-svc-t4ptm
Nov 10 13:07:18.063: INFO: Created: latency-svc-dtcfq
Nov 10 13:07:18.063: INFO: Got endpoints: latency-svc-dtcfq [93.700026ms]
Nov 10 13:07:18.063: INFO: Got endpoints: latency-svc-t4ptm [112.709676ms]
Nov 10 13:07:18.070: INFO: Created: latency-svc-gdxjd
Nov 10 13:07:18.071: INFO: Created: latency-svc-xksng
Nov 10 13:07:18.075: INFO: Got endpoints: latency-svc-gdxjd [98.107035ms]
Nov 10 13:07:18.080: INFO: Created: latency-svc-224m7
Nov 10 13:07:18.082: INFO: Got endpoints: latency-svc-xksng [87.463149ms]
Nov 10 13:07:18.084: INFO: Created: latency-svc-kbcvs
Nov 10 13:07:18.088: INFO: Got endpoints: latency-svc-224m7 [93.808586ms]
Nov 10 13:07:18.091: INFO: Created: latency-svc-fsddl
Nov 10 13:07:18.095: INFO: Created: latency-svc-hr4ct
Nov 10 13:07:18.098: INFO: Created: latency-svc-jr956
Nov 10 13:07:18.100: INFO: Got endpoints: latency-svc-kbcvs [96.691112ms]
Nov 10 13:07:18.102: INFO: Got endpoints: latency-svc-jr956 [80.563371ms]
Nov 10 13:07:18.103: INFO: Got endpoints: latency-svc-fsddl [94.845388ms]
Nov 10 13:07:18.103: INFO: Got endpoints: latency-svc-hr4ct [84.056365ms]
Nov 10 13:07:18.110: INFO: Created: latency-svc-g5rg9
Nov 10 13:07:18.110: INFO: Created: latency-svc-jqq62
Nov 10 13:07:18.120: INFO: Created: latency-svc-lhqcn
Nov 10 13:07:18.120: INFO: Got endpoints: latency-svc-jqq62 [92.976307ms]
Nov 10 13:07:18.120: INFO: Created: latency-svc-jkvpg
Nov 10 13:07:18.120: INFO: Created: latency-svc-7fc94
Nov 10 13:07:18.120: INFO: Got endpoints: latency-svc-lhqcn [81.487105ms]
Nov 10 13:07:18.121: INFO: Got endpoints: latency-svc-g5rg9 [87.665918ms]
Nov 10 13:07:18.122: INFO: Got endpoints: latency-svc-jkvpg [82.573792ms]
Nov 10 13:07:18.127: INFO: Got endpoints: latency-svc-7fc94 [78.846682ms]
Nov 10 13:07:18.130: INFO: Created: latency-svc-m4mmw
Nov 10 13:07:18.139: INFO: Got endpoints: latency-svc-m4mmw [86.055493ms]
Nov 10 13:07:18.139: INFO: Created: latency-svc-lfrfn
Nov 10 13:07:18.145: INFO: Got endpoints: latency-svc-lfrfn [82.037787ms]
Nov 10 13:07:18.145: INFO: Created: latency-svc-9t6wz
Nov 10 13:07:18.152: INFO: Got endpoints: latency-svc-9t6wz [88.633891ms]
Nov 10 13:07:18.152: INFO: Created: latency-svc-szvlb
Nov 10 13:07:18.156: INFO: Got endpoints: latency-svc-szvlb [81.054602ms]
Nov 10 13:07:18.156: INFO: Created: latency-svc-dmgvj
Nov 10 13:07:18.158: INFO: Created: latency-svc-952nr
Nov 10 13:07:18.159: INFO: Created: latency-svc-522bb
Nov 10 13:07:18.163: INFO: Got endpoints: latency-svc-952nr [81.070087ms]
Nov 10 13:07:18.165: INFO: Got endpoints: latency-svc-dmgvj [76.986866ms]
Nov 10 13:07:18.173: INFO: Created: latency-svc-nf524
Nov 10 13:07:18.173: INFO: Created: latency-svc-7g2g9
Nov 10 13:07:18.178: INFO: Created: latency-svc-drtk8
Nov 10 13:07:18.183: INFO: Created: latency-svc-68bqk
Nov 10 13:07:18.183: INFO: Created: latency-svc-w9rhk
Nov 10 13:07:18.192: INFO: Created: latency-svc-qt94l
Nov 10 13:07:18.192: INFO: Created: latency-svc-cmv9p
Nov 10 13:07:18.195: INFO: Created: latency-svc-q94bn
Nov 10 13:07:18.199: INFO: Created: latency-svc-jhfwq
Nov 10 13:07:18.207: INFO: Created: latency-svc-2r87j
Nov 10 13:07:18.212: INFO: Got endpoints: latency-svc-522bb [112.360128ms]
Nov 10 13:07:18.217: INFO: Created: latency-svc-glz2k
Nov 10 13:07:18.225: INFO: Created: latency-svc-sz4z5
Nov 10 13:07:18.225: INFO: Created: latency-svc-fdxkf
Nov 10 13:07:18.227: INFO: Created: latency-svc-r8hf8
Nov 10 13:07:18.238: INFO: Created: latency-svc-lqk9t
Nov 10 13:07:18.255: INFO: Got endpoints: latency-svc-7g2g9 [152.161407ms]
Nov 10 13:07:18.262: INFO: Created: latency-svc-4s6zw
Nov 10 13:07:18.312: INFO: Got endpoints: latency-svc-nf524 [208.787358ms]
Nov 10 13:07:18.318: INFO: Created: latency-svc-wjnct
Nov 10 13:07:18.363: INFO: Got endpoints: latency-svc-drtk8 [260.435848ms]
Nov 10 13:07:18.370: INFO: Created: latency-svc-l5r74
Nov 10 13:07:18.409: INFO: Got endpoints: latency-svc-68bqk [288.94154ms]
Nov 10 13:07:18.426: INFO: Created: latency-svc-96tpv
Nov 10 13:07:18.455: INFO: Got endpoints: latency-svc-w9rhk [334.51678ms]
Nov 10 13:07:18.470: INFO: Created: latency-svc-nqx7c
Nov 10 13:07:18.506: INFO: Got endpoints: latency-svc-cmv9p [385.113875ms]
Nov 10 13:07:18.515: INFO: Created: latency-svc-w6z9z
Nov 10 13:07:18.565: INFO: Got endpoints: latency-svc-qt94l [442.351036ms]
Nov 10 13:07:18.572: INFO: Created: latency-svc-h8qg9
Nov 10 13:07:18.603: INFO: Got endpoints: latency-svc-q94bn [476.578115ms]
Nov 10 13:07:18.607: INFO: Created: latency-svc-n56sp
Nov 10 13:07:18.654: INFO: Got endpoints: latency-svc-jhfwq [514.818527ms]
Nov 10 13:07:18.660: INFO: Created: latency-svc-gp99m
Nov 10 13:07:18.707: INFO: Got endpoints: latency-svc-2r87j [561.974685ms]
Nov 10 13:07:18.713: INFO: Created: latency-svc-fmh5j
Nov 10 13:07:18.759: INFO: Got endpoints: latency-svc-glz2k [607.109539ms]
Nov 10 13:07:18.764: INFO: Created: latency-svc-wvws2
Nov 10 13:07:18.807: INFO: Got endpoints: latency-svc-fdxkf [651.327412ms]
Nov 10 13:07:18.812: INFO: Created: latency-svc-69w9r
Nov 10 13:07:18.858: INFO: Got endpoints: latency-svc-sz4z5 [694.831585ms]
Nov 10 13:07:18.863: INFO: Created: latency-svc-hmwfr
Nov 10 13:07:18.908: INFO: Got endpoints: latency-svc-r8hf8 [743.042506ms]
Nov 10 13:07:18.923: INFO: Created: latency-svc-hzn5x
Nov 10 13:07:18.960: INFO: Got endpoints: latency-svc-lqk9t [748.569354ms]
Nov 10 13:07:18.967: INFO: Created: latency-svc-whbsd
Nov 10 13:07:19.008: INFO: Got endpoints: latency-svc-4s6zw [752.989107ms]
Nov 10 13:07:19.014: INFO: Created: latency-svc-2lgft
Nov 10 13:07:19.058: INFO: Got endpoints: latency-svc-wjnct [746.138516ms]
Nov 10 13:07:19.065: INFO: Created: latency-svc-95dds
Nov 10 13:07:19.105: INFO: Got endpoints: latency-svc-l5r74 [741.923488ms]
Nov 10 13:07:19.111: INFO: Created: latency-svc-d95bv
Nov 10 13:07:19.155: INFO: Got endpoints: latency-svc-96tpv [746.169166ms]
Nov 10 13:07:19.163: INFO: Created: latency-svc-vlf2d
Nov 10 13:07:19.205: INFO: Got endpoints: latency-svc-nqx7c [749.822883ms]
Nov 10 13:07:19.214: INFO: Created: latency-svc-hrdsw
Nov 10 13:07:19.259: INFO: Got endpoints: latency-svc-w6z9z [753.014956ms]
Nov 10 13:07:19.265: INFO: Created: latency-svc-8p4zp
Nov 10 13:07:19.306: INFO: Got endpoints: latency-svc-h8qg9 [741.23676ms]
Nov 10 13:07:19.311: INFO: Created: latency-svc-pvb5c
Nov 10 13:07:19.355: INFO: Got endpoints: latency-svc-n56sp [751.670148ms]
Nov 10 13:07:19.360: INFO: Created: latency-svc-dc659
Nov 10 13:07:19.405: INFO: Got endpoints: latency-svc-gp99m [751.081846ms]
Nov 10 13:07:19.410: INFO: Created: latency-svc-4xvq8
Nov 10 13:07:19.459: INFO: Got endpoints: latency-svc-fmh5j [751.982395ms]
Nov 10 13:07:19.473: INFO: Created: latency-svc-qtbfl
Nov 10 13:07:19.505: INFO: Got endpoints: latency-svc-wvws2 [745.916361ms]
Nov 10 13:07:19.515: INFO: Created: latency-svc-c5nkr
Nov 10 13:07:19.554: INFO: Got endpoints: latency-svc-69w9r [746.880878ms]
Nov 10 13:07:19.559: INFO: Created: latency-svc-v5j9d
Nov 10 13:07:19.607: INFO: Got endpoints: latency-svc-hmwfr [749.417282ms]
Nov 10 13:07:19.618: INFO: Created: latency-svc-4xmdx
Nov 10 13:07:19.659: INFO: Got endpoints: latency-svc-hzn5x [751.446528ms]
Nov 10 13:07:19.667: INFO: Created: latency-svc-4xlzb
Nov 10 13:07:19.705: INFO: Got endpoints: latency-svc-whbsd [744.15921ms]
Nov 10 13:07:19.710: INFO: Created: latency-svc-xsl8h
Nov 10 13:07:19.757: INFO: Got endpoints: latency-svc-2lgft [748.999096ms]
Nov 10 13:07:19.766: INFO: Created: latency-svc-tpx8s
Nov 10 13:07:19.808: INFO: Got endpoints: latency-svc-95dds [749.948864ms]
Nov 10 13:07:19.817: INFO: Created: latency-svc-nzng8
Nov 10 13:07:19.854: INFO: Got endpoints: latency-svc-d95bv [749.328733ms]
Nov 10 13:07:19.860: INFO: Created: latency-svc-zvs5g
Nov 10 13:07:19.906: INFO: Got endpoints: latency-svc-vlf2d [750.486881ms]
Nov 10 13:07:19.918: INFO: Created: latency-svc-97ggj
Nov 10 13:07:19.956: INFO: Got endpoints: latency-svc-hrdsw [751.306301ms]
Nov 10 13:07:19.963: INFO: Created: latency-svc-r5nfj
Nov 10 13:07:20.009: INFO: Got endpoints: latency-svc-8p4zp [750.265379ms]
Nov 10 13:07:20.018: INFO: Created: latency-svc-v6zmd
Nov 10 13:07:20.074: INFO: Got endpoints: latency-svc-pvb5c [767.845654ms]
Nov 10 13:07:20.082: INFO: Created: latency-svc-9gmr4
Nov 10 13:07:20.109: INFO: Got endpoints: latency-svc-dc659 [753.659244ms]
Nov 10 13:07:20.118: INFO: Created: latency-svc-xdghb
Nov 10 13:07:20.156: INFO: Got endpoints: latency-svc-4xvq8 [751.021798ms]
Nov 10 13:07:20.163: INFO: Created: latency-svc-mh5hm
Nov 10 13:07:20.205: INFO: Got endpoints: latency-svc-qtbfl [746.462229ms]
Nov 10 13:07:20.214: INFO: Created: latency-svc-v8jcr
Nov 10 13:07:20.260: INFO: Got endpoints: latency-svc-c5nkr [754.996644ms]
Nov 10 13:07:20.269: INFO: Created: latency-svc-szf5k
Nov 10 13:07:20.306: INFO: Got endpoints: latency-svc-v5j9d [752.148561ms]
Nov 10 13:07:20.318: INFO: Created: latency-svc-2tjpb
Nov 10 13:07:20.358: INFO: Got endpoints: latency-svc-4xmdx [750.694589ms]
Nov 10 13:07:20.364: INFO: Created: latency-svc-6n6tg
Nov 10 13:07:20.408: INFO: Got endpoints: latency-svc-4xlzb [748.478766ms]
Nov 10 13:07:20.417: INFO: Created: latency-svc-xz5r4
Nov 10 13:07:20.461: INFO: Got endpoints: latency-svc-xsl8h [756.162858ms]
Nov 10 13:07:20.470: INFO: Created: latency-svc-6lfmp
Nov 10 13:07:20.506: INFO: Got endpoints: latency-svc-tpx8s [749.492648ms]
Nov 10 13:07:20.514: INFO: Created: latency-svc-lhbvx
Nov 10 13:07:20.555: INFO: Got endpoints: latency-svc-nzng8 [746.646318ms]
Nov 10 13:07:20.560: INFO: Created: latency-svc-l7p62
Nov 10 13:07:20.606: INFO: Got endpoints: latency-svc-zvs5g [751.609841ms]
Nov 10 13:07:20.611: INFO: Created: latency-svc-bj6vs
Nov 10 13:07:20.653: INFO: Got endpoints: latency-svc-97ggj [747.838694ms]
Nov 10 13:07:20.661: INFO: Created: latency-svc-hm67s
Nov 10 13:07:20.708: INFO: Got endpoints: latency-svc-r5nfj [752.361401ms]
Nov 10 13:07:20.715: INFO: Created: latency-svc-mlnhl
Nov 10 13:07:20.756: INFO: Got endpoints: latency-svc-v6zmd [747.330897ms]
Nov 10 13:07:20.762: INFO: Created: latency-svc-f5bks
Nov 10 13:07:20.808: INFO: Got endpoints: latency-svc-9gmr4 [734.423407ms]
Nov 10 13:07:20.818: INFO: Created: latency-svc-mrndd
Nov 10 13:07:20.854: INFO: Got endpoints: latency-svc-xdghb [745.649187ms]
Nov 10 13:07:20.859: INFO: Created: latency-svc-rzkhv
Nov 10 13:07:20.907: INFO: Got endpoints: latency-svc-mh5hm [751.397041ms]
Nov 10 13:07:20.915: INFO: Created: latency-svc-ckvq8
Nov 10 13:07:20.954: INFO: Got endpoints: latency-svc-v8jcr [749.144466ms]
Nov 10 13:07:20.960: INFO: Created: latency-svc-b2vpv
Nov 10 13:07:21.006: INFO: Got endpoints: latency-svc-szf5k [745.908696ms]
Nov 10 13:07:21.013: INFO: Created: latency-svc-jrvjv
Nov 10 13:07:21.056: INFO: Got endpoints: latency-svc-2tjpb [749.249424ms]
Nov 10 13:07:21.064: INFO: Created: latency-svc-jn6nj
Nov 10 13:07:21.107: INFO: Got endpoints: latency-svc-6n6tg [749.627737ms]
Nov 10 13:07:21.112: INFO: Created: latency-svc-w6wsb
Nov 10 13:07:21.155: INFO: Got endpoints: latency-svc-xz5r4 [746.888239ms]
Nov 10 13:07:21.161: INFO: Created: latency-svc-425n7
Nov 10 13:07:21.209: INFO: Got endpoints: latency-svc-6lfmp [747.860645ms]
Nov 10 13:07:21.217: INFO: Created: latency-svc-n494b
Nov 10 13:07:21.258: INFO: Got endpoints: latency-svc-lhbvx [751.368181ms]
Nov 10 13:07:21.264: INFO: Created: latency-svc-b2z6p
Nov 10 13:07:21.303: INFO: Got endpoints: latency-svc-l7p62 [748.718705ms]
Nov 10 13:07:21.308: INFO: Created: latency-svc-zkc6q
Nov 10 13:07:21.355: INFO: Got endpoints: latency-svc-bj6vs [748.70568ms]
Nov 10 13:07:21.362: INFO: Created: latency-svc-swdkg
Nov 10 13:07:21.406: INFO: Got endpoints: latency-svc-hm67s [752.400623ms]
Nov 10 13:07:21.418: INFO: Created: latency-svc-wtswq
Nov 10 13:07:21.460: INFO: Got endpoints: latency-svc-mlnhl [751.548477ms]
Nov 10 13:07:21.465: INFO: Created: latency-svc-mgvtd
Nov 10 13:07:21.505: INFO: Got endpoints: latency-svc-f5bks [748.453435ms]
Nov 10 13:07:21.511: INFO: Created: latency-svc-jwtm9
Nov 10 13:07:21.555: INFO: Got endpoints: latency-svc-mrndd [746.375718ms]
Nov 10 13:07:21.561: INFO: Created: latency-svc-zp2x4
Nov 10 13:07:21.605: INFO: Got endpoints: latency-svc-rzkhv [750.33639ms]
Nov 10 13:07:21.611: INFO: Created: latency-svc-p2pdb
Nov 10 13:07:21.663: INFO: Got endpoints: latency-svc-ckvq8 [755.524017ms]
Nov 10 13:07:21.670: INFO: Created: latency-svc-47j2s
Nov 10 13:07:21.704: INFO: Got endpoints: latency-svc-b2vpv [749.30394ms]
Nov 10 13:07:21.711: INFO: Created: latency-svc-t7l62
Nov 10 13:07:21.757: INFO: Got endpoints: latency-svc-jrvjv [751.123692ms]
Nov 10 13:07:21.763: INFO: Created: latency-svc-sxrpk
Nov 10 13:07:21.804: INFO: Got endpoints: latency-svc-jn6nj [748.546849ms]
Nov 10 13:07:21.814: INFO: Created: latency-svc-kll4t
Nov 10 13:07:21.859: INFO: Got endpoints: latency-svc-w6wsb [751.203504ms]
Nov 10 13:07:21.869: INFO: Created: latency-svc-8jvnb
Nov 10 13:07:21.904: INFO: Got endpoints: latency-svc-425n7 [749.093373ms]
Nov 10 13:07:21.918: INFO: Created: latency-svc-sjq26
Nov 10 13:07:21.960: INFO: Got endpoints: latency-svc-n494b [751.044019ms]
Nov 10 13:07:21.967: INFO: Created: latency-svc-bfgdv
Nov 10 13:07:22.005: INFO: Got endpoints: latency-svc-b2z6p [747.073806ms]
Nov 10 13:07:22.013: INFO: Created: latency-svc-rzx8j
Nov 10 13:07:22.055: INFO: Got endpoints: latency-svc-zkc6q [751.573268ms]
Nov 10 13:07:22.062: INFO: Created: latency-svc-gwrln
Nov 10 13:07:22.107: INFO: Got endpoints: latency-svc-swdkg [752.579915ms]
Nov 10 13:07:22.115: INFO: Created: latency-svc-fwfwx
Nov 10 13:07:22.156: INFO: Got endpoints: latency-svc-wtswq [750.196768ms]
Nov 10 13:07:22.167: INFO: Created: latency-svc-7xftw
Nov 10 13:07:22.208: INFO: Got endpoints: latency-svc-mgvtd [747.492703ms]
Nov 10 13:07:22.216: INFO: Created: latency-svc-97ksg
Nov 10 13:07:22.256: INFO: Got endpoints: latency-svc-jwtm9 [750.908663ms]
Nov 10 13:07:22.262: INFO: Created: latency-svc-hlgwr
Nov 10 13:07:22.310: INFO: Got endpoints: latency-svc-zp2x4 [755.050279ms]
Nov 10 13:07:22.319: INFO: Created: latency-svc-snbvn
Nov 10 13:07:22.360: INFO: Got endpoints: latency-svc-p2pdb [755.618116ms]
Nov 10 13:07:22.370: INFO: Created: latency-svc-qd7nf
Nov 10 13:07:22.406: INFO: Got endpoints: latency-svc-47j2s [743.342399ms]
Nov 10 13:07:22.413: INFO: Created: latency-svc-4v722
Nov 10 13:07:22.456: INFO: Got endpoints: latency-svc-t7l62 [751.990913ms]
Nov 10 13:07:22.462: INFO: Created: latency-svc-tqgcp
Nov 10 13:07:22.512: INFO: Got endpoints: latency-svc-sxrpk [754.840161ms]
Nov 10 13:07:22.517: INFO: Created: latency-svc-67jv4
Nov 10 13:07:22.554: INFO: Got endpoints: latency-svc-kll4t [749.640175ms]
Nov 10 13:07:22.561: INFO: Created: latency-svc-bcr2s
Nov 10 13:07:22.606: INFO: Got endpoints: latency-svc-8jvnb [747.020904ms]
Nov 10 13:07:22.613: INFO: Created: latency-svc-9hkfj
Nov 10 13:07:22.657: INFO: Got endpoints: latency-svc-sjq26 [752.927515ms]
Nov 10 13:07:22.663: INFO: Created: latency-svc-fdz22
Nov 10 13:07:22.705: INFO: Got endpoints: latency-svc-bfgdv [744.901103ms]
Nov 10 13:07:22.711: INFO: Created: latency-svc-d6v98
Nov 10 13:07:22.756: INFO: Got endpoints: latency-svc-rzx8j [750.856564ms]
Nov 10 13:07:22.767: INFO: Created: latency-svc-qgzrq
Nov 10 13:07:22.805: INFO: Got endpoints: latency-svc-gwrln [750.490297ms]
Nov 10 13:07:22.812: INFO: Created: latency-svc-pswpq
Nov 10 13:07:22.856: INFO: Got endpoints: latency-svc-fwfwx [748.601406ms]
Nov 10 13:07:22.862: INFO: Created: latency-svc-b8k5f
Nov 10 13:07:22.905: INFO: Got endpoints: latency-svc-7xftw [748.92302ms]
Nov 10 13:07:22.912: INFO: Created: latency-svc-nl2lh
Nov 10 13:07:22.956: INFO: Got endpoints: latency-svc-97ksg [748.117067ms]
Nov 10 13:07:22.965: INFO: Created: latency-svc-tkdpv
Nov 10 13:07:23.008: INFO: Got endpoints: latency-svc-hlgwr [751.693747ms]
Nov 10 13:07:23.014: INFO: Created: latency-svc-4n7bs
Nov 10 13:07:23.055: INFO: Got endpoints: latency-svc-snbvn [745.008242ms]
Nov 10 13:07:23.067: INFO: Created: latency-svc-smpkw
Nov 10 13:07:23.112: INFO: Got endpoints: latency-svc-qd7nf [751.341803ms]
Nov 10 13:07:23.118: INFO: Created: latency-svc-7bn6v
Nov 10 13:07:23.154: INFO: Got endpoints: latency-svc-4v722 [747.794381ms]
Nov 10 13:07:23.159: INFO: Created: latency-svc-67pvp
Nov 10 13:07:23.205: INFO: Got endpoints: latency-svc-tqgcp [748.653965ms]
Nov 10 13:07:23.215: INFO: Created: latency-svc-w4svc
Nov 10 13:07:23.256: INFO: Got endpoints: latency-svc-67jv4 [744.054695ms]
Nov 10 13:07:23.262: INFO: Created: latency-svc-wnh47
Nov 10 13:07:23.307: INFO: Got endpoints: latency-svc-bcr2s [752.651057ms]
Nov 10 13:07:23.321: INFO: Created: latency-svc-jb6z9
Nov 10 13:07:23.358: INFO: Got endpoints: latency-svc-9hkfj [752.032546ms]
Nov 10 13:07:23.368: INFO: Created: latency-svc-llhnj
Nov 10 13:07:23.405: INFO: Got endpoints: latency-svc-fdz22 [747.704906ms]
Nov 10 13:07:23.410: INFO: Created: latency-svc-rqhfq
Nov 10 13:07:23.459: INFO: Got endpoints: latency-svc-d6v98 [754.479308ms]
Nov 10 13:07:23.467: INFO: Created: latency-svc-b9s9d
Nov 10 13:07:23.506: INFO: Got endpoints: latency-svc-qgzrq [750.176824ms]
Nov 10 13:07:23.513: INFO: Created: latency-svc-9bfd5
Nov 10 13:07:23.559: INFO: Got endpoints: latency-svc-pswpq [753.454267ms]
Nov 10 13:07:23.568: INFO: Created: latency-svc-qdb8q
Nov 10 13:07:23.605: INFO: Got endpoints: latency-svc-b8k5f [749.434763ms]
Nov 10 13:07:23.614: INFO: Created: latency-svc-mq2g6
Nov 10 13:07:23.655: INFO: Got endpoints: latency-svc-nl2lh [750.21945ms]
Nov 10 13:07:23.664: INFO: Created: latency-svc-knlpj
Nov 10 13:07:23.706: INFO: Got endpoints: latency-svc-tkdpv [750.334516ms]
Nov 10 13:07:23.712: INFO: Created: latency-svc-tqxxn
Nov 10 13:07:23.755: INFO: Got endpoints: latency-svc-4n7bs [746.964636ms]
Nov 10 13:07:23.762: INFO: Created: latency-svc-sm587
Nov 10 13:07:23.806: INFO: Got endpoints: latency-svc-smpkw [750.906548ms]
Nov 10 13:07:23.820: INFO: Created: latency-svc-fzrrt
Nov 10 13:07:23.855: INFO: Got endpoints: latency-svc-7bn6v [743.23004ms]
Nov 10 13:07:23.864: INFO: Created: latency-svc-4smc4
Nov 10 13:07:23.904: INFO: Got endpoints: latency-svc-67pvp [749.744055ms]
Nov 10 13:07:23.913: INFO: Created: latency-svc-dqnkm
Nov 10 13:07:23.954: INFO: Got endpoints: latency-svc-w4svc [749.354609ms]
Nov 10 13:07:23.961: INFO: Created: latency-svc-xwjtx
Nov 10 13:07:24.005: INFO: Got endpoints: latency-svc-wnh47 [748.990504ms]
Nov 10 13:07:24.011: INFO: Created: latency-svc-j8v9k
Nov 10 13:07:24.057: INFO: Got endpoints: latency-svc-jb6z9 [750.03699ms]
Nov 10 13:07:24.061: INFO: Created: latency-svc-7btxm
Nov 10 13:07:24.108: INFO: Got endpoints: latency-svc-llhnj [750.229578ms]
Nov 10 13:07:24.114: INFO: Created: latency-svc-nld45
Nov 10 13:07:24.155: INFO: Got endpoints: latency-svc-rqhfq [750.815572ms]
Nov 10 13:07:24.162: INFO: Created: latency-svc-8rssj
Nov 10 13:07:24.207: INFO: Got endpoints: latency-svc-b9s9d [747.231415ms]
Nov 10 13:07:24.213: INFO: Created: latency-svc-29g46
Nov 10 13:07:24.255: INFO: Got endpoints: latency-svc-9bfd5 [748.743084ms]
Nov 10 13:07:24.259: INFO: Created: latency-svc-72v6z
Nov 10 13:07:24.312: INFO: Got endpoints: latency-svc-qdb8q [752.844451ms]
Nov 10 13:07:24.317: INFO: Created: latency-svc-btpmq
Nov 10 13:07:24.354: INFO: Got endpoints: latency-svc-mq2g6 [748.836485ms]
Nov 10 13:07:24.366: INFO: Created: latency-svc-c8mxn
Nov 10 13:07:24.423: INFO: Got endpoints: latency-svc-knlpj [768.001092ms]
Nov 10 13:07:24.429: INFO: Created: latency-svc-x2lgs
Nov 10 13:07:24.454: INFO: Got endpoints: latency-svc-tqxxn [747.799444ms]
Nov 10 13:07:24.460: INFO: Created: latency-svc-hds4n
Nov 10 13:07:24.508: INFO: Got endpoints: latency-svc-sm587 [753.531489ms]
Nov 10 13:07:24.513: INFO: Created: latency-svc-vhv4b
Nov 10 13:07:24.557: INFO: Got endpoints: latency-svc-fzrrt [750.957567ms]
Nov 10 13:07:24.563: INFO: Created: latency-svc-9wxth
Nov 10 13:07:24.605: INFO: Got endpoints: latency-svc-4smc4 [749.462069ms]
Nov 10 13:07:24.612: INFO: Created: latency-svc-vh8j7
Nov 10 13:07:24.655: INFO: Got endpoints: latency-svc-dqnkm [751.350617ms]
Nov 10 13:07:24.660: INFO: Created: latency-svc-g75l9
Nov 10 13:07:24.707: INFO: Got endpoints: latency-svc-xwjtx [752.856269ms]
Nov 10 13:07:24.715: INFO: Created: latency-svc-hdrj5
Nov 10 13:07:24.755: INFO: Got endpoints: latency-svc-j8v9k [750.592798ms]
Nov 10 13:07:24.760: INFO: Created: latency-svc-b7jbg
Nov 10 13:07:24.805: INFO: Got endpoints: latency-svc-7btxm [748.676465ms]
Nov 10 13:07:24.813: INFO: Created: latency-svc-k6d8l
Nov 10 13:07:24.863: INFO: Got endpoints: latency-svc-nld45 [754.402488ms]
Nov 10 13:07:24.873: INFO: Created: latency-svc-b7gr5
Nov 10 13:07:24.905: INFO: Got endpoints: latency-svc-8rssj [749.135414ms]
Nov 10 13:07:24.913: INFO: Created: latency-svc-tdtvf
Nov 10 13:07:24.954: INFO: Got endpoints: latency-svc-29g46 [747.367253ms]
Nov 10 13:07:24.959: INFO: Created: latency-svc-2svvp
Nov 10 13:07:25.009: INFO: Got endpoints: latency-svc-72v6z [753.964368ms]
Nov 10 13:07:25.018: INFO: Created: latency-svc-kq7l6
Nov 10 13:07:25.057: INFO: Got endpoints: latency-svc-btpmq [745.364069ms]
Nov 10 13:07:25.063: INFO: Created: latency-svc-xzppq
Nov 10 13:07:25.107: INFO: Got endpoints: latency-svc-c8mxn [752.533432ms]
Nov 10 13:07:25.118: INFO: Created: latency-svc-j726b
Nov 10 13:07:25.157: INFO: Got endpoints: latency-svc-x2lgs [733.294153ms]
Nov 10 13:07:25.170: INFO: Created: latency-svc-c66hh
Nov 10 13:07:25.205: INFO: Got endpoints: latency-svc-hds4n [751.032317ms]
Nov 10 13:07:25.212: INFO: Created: latency-svc-dctqc
Nov 10 13:07:25.255: INFO: Got endpoints: latency-svc-vhv4b [746.428785ms]
Nov 10 13:07:25.262: INFO: Created: latency-svc-9n86m
Nov 10 13:07:25.307: INFO: Got endpoints: latency-svc-9wxth [750.406207ms]
Nov 10 13:07:25.315: INFO: Created: latency-svc-b2nmd
Nov 10 13:07:25.358: INFO: Got endpoints: latency-svc-vh8j7 [753.1216ms]
Nov 10 13:07:25.365: INFO: Created: latency-svc-mbgkv
Nov 10 13:07:25.404: INFO: Got endpoints: latency-svc-g75l9 [748.534784ms]
Nov 10 13:07:25.412: INFO: Created: latency-svc-9zr8n
Nov 10 13:07:25.454: INFO: Got endpoints: latency-svc-hdrj5 [746.667418ms]
Nov 10 13:07:25.465: INFO: Created: latency-svc-7j2j8
Nov 10 13:07:25.505: INFO: Got endpoints: latency-svc-b7jbg [749.873241ms]
Nov 10 13:07:25.513: INFO: Created: latency-svc-7dw7b
Nov 10 13:07:25.559: INFO: Got endpoints: latency-svc-k6d8l [753.180093ms]
Nov 10 13:07:25.564: INFO: Created: latency-svc-54mqr
Nov 10 13:07:25.603: INFO: Got endpoints: latency-svc-b7gr5 [740.339398ms]
Nov 10 13:07:25.611: INFO: Created: latency-svc-v4gw2
Nov 10 13:07:25.654: INFO: Got endpoints: latency-svc-tdtvf [748.817904ms]
Nov 10 13:07:25.661: INFO: Created: latency-svc-wghxx
Nov 10 13:07:25.704: INFO: Got endpoints: latency-svc-2svvp [749.692869ms]
Nov 10 13:07:25.717: INFO: Created: latency-svc-5hgn4
Nov 10 13:07:25.754: INFO: Got endpoints: latency-svc-kq7l6 [745.053146ms]
Nov 10 13:07:25.807: INFO: Got endpoints: latency-svc-xzppq [750.156105ms]
Nov 10 13:07:25.855: INFO: Got endpoints: latency-svc-j726b [747.965863ms]
Nov 10 13:07:25.908: INFO: Got endpoints: latency-svc-c66hh [751.15068ms]
Nov 10 13:07:25.956: INFO: Got endpoints: latency-svc-dctqc [751.254565ms]
Nov 10 13:07:26.007: INFO: Got endpoints: latency-svc-9n86m [752.035784ms]
Nov 10 13:07:26.055: INFO: Got endpoints: latency-svc-b2nmd [748.176208ms]
Nov 10 13:07:26.107: INFO: Got endpoints: latency-svc-mbgkv [748.777429ms]
Nov 10 13:07:26.156: INFO: Got endpoints: latency-svc-9zr8n [752.017716ms]
Nov 10 13:07:26.205: INFO: Got endpoints: latency-svc-7j2j8 [751.232908ms]
Nov 10 13:07:26.254: INFO: Got endpoints: latency-svc-7dw7b [749.083696ms]
Nov 10 13:07:26.305: INFO: Got endpoints: latency-svc-54mqr [746.022233ms]
Nov 10 13:07:26.358: INFO: Got endpoints: latency-svc-v4gw2 [754.82318ms]
Nov 10 13:07:26.406: INFO: Got endpoints: latency-svc-wghxx [752.280312ms]
Nov 10 13:07:26.456: INFO: Got endpoints: latency-svc-5hgn4 [752.1089ms]
Nov 10 13:07:26.456: INFO: Latencies: [18.936602ms 26.315764ms 44.153497ms 44.437407ms 52.776227ms 57.686362ms 68.62704ms 71.925477ms 76.914767ms 76.986866ms 78.846682ms 80.563371ms 81.054602ms 81.070087ms 81.487105ms 82.037787ms 82.568107ms 82.573792ms 84.056365ms 86.055493ms 87.463149ms 87.665918ms 88.633891ms 88.785429ms 89.56226ms 92.976307ms 93.700026ms 93.808586ms 94.845388ms 96.691112ms 97.725482ms 98.107035ms 102.556596ms 112.360128ms 112.709676ms 152.161407ms 208.787358ms 260.435848ms 288.94154ms 334.51678ms 385.113875ms 442.351036ms 476.578115ms 514.818527ms 561.974685ms 607.109539ms 651.327412ms 694.831585ms 733.294153ms 734.423407ms 740.339398ms 741.23676ms 741.923488ms 743.042506ms 743.23004ms 743.342399ms 744.054695ms 744.15921ms 744.901103ms 745.008242ms 745.053146ms 745.364069ms 745.649187ms 745.908696ms 745.916361ms 746.022233ms 746.138516ms 746.169166ms 746.375718ms 746.428785ms 746.462229ms 746.646318ms 746.667418ms 746.880878ms 746.888239ms 746.964636ms 747.020904ms 747.073806ms 747.231415ms 747.330897ms 747.367253ms 747.492703ms 747.704906ms 747.794381ms 747.799444ms 747.838694ms 747.860645ms 747.965863ms 748.117067ms 748.176208ms 748.453435ms 748.478766ms 748.534784ms 748.546849ms 748.569354ms 748.601406ms 748.653965ms 748.676465ms 748.70568ms 748.718705ms 748.743084ms 748.777429ms 748.817904ms 748.836485ms 748.92302ms 748.990504ms 748.999096ms 749.083696ms 749.093373ms 749.135414ms 749.144466ms 749.249424ms 749.30394ms 749.328733ms 749.354609ms 749.417282ms 749.434763ms 749.462069ms 749.492648ms 749.627737ms 749.640175ms 749.692869ms 749.744055ms 749.822883ms 749.873241ms 749.948864ms 750.03699ms 750.156105ms 750.176824ms 750.196768ms 750.21945ms 750.229578ms 750.265379ms 750.334516ms 750.33639ms 750.406207ms 750.486881ms 750.490297ms 750.592798ms 750.694589ms 750.815572ms 750.856564ms 750.906548ms 750.908663ms 750.957567ms 751.021798ms 751.032317ms 751.044019ms 751.081846ms 751.123692ms 751.15068ms 751.203504ms 751.232908ms 751.254565ms 751.306301ms 751.341803ms 751.350617ms 751.368181ms 751.397041ms 751.446528ms 751.548477ms 751.573268ms 751.609841ms 751.670148ms 751.693747ms 751.982395ms 751.990913ms 752.017716ms 752.032546ms 752.035784ms 752.1089ms 752.148561ms 752.280312ms 752.361401ms 752.400623ms 752.533432ms 752.579915ms 752.651057ms 752.844451ms 752.856269ms 752.927515ms 752.989107ms 753.014956ms 753.1216ms 753.180093ms 753.454267ms 753.531489ms 753.659244ms 753.964368ms 754.402488ms 754.479308ms 754.82318ms 754.840161ms 754.996644ms 755.050279ms 755.524017ms 755.618116ms 756.162858ms 767.845654ms 768.001092ms]
Nov 10 13:07:26.456: INFO: 50 %ile: 748.743084ms
Nov 10 13:07:26.456: INFO: 90 %ile: 752.927515ms
Nov 10 13:07:26.456: INFO: 99 %ile: 767.845654ms
Nov 10 13:07:26.456: INFO: Total sample count: 200
[AfterEach] [k8s.io] Service endpoints latency
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:131
Nov 10 13:07:26.456: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-svc-latency-zcsh9" for this suite.
Nov 10 13:07:48.543: INFO: namespace: e2e-tests-svc-latency-zcsh9, resource: bindings, ignored listing per whitelist
Nov 10 13:07:48.608: INFO: namespace e2e-tests-svc-latency-zcsh9 deletion completed in 22.14970161s

• [SLOW TEST:33.927 seconds]
[k8s.io] Service endpoints latency
/tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:620
  should not be very high [Conformance] [Serial] [Suite:openshift/conformance/serial] [Suite:k8s]
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/service_latency.go:117
------------------------------
SSSSSSSSSSSSS
------------------------------
[Conformance][Feature:ImageRegistry][registry][migration][Serial] manifest migration from etcd to registry storage 
  registry can get access to manifest [local] [Suite:openshift/conformance/serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/registry/registry.go:149
[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [Conformance][Feature:ImageRegistry][registry][migration][Serial] manifest migration from etcd to registry storage
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:130
STEP: Creating a kubernetes client
Nov 10 13:07:48.609: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Nov 10 13:07:48.688: INFO: configPath is now "/tmp/extended-test-registry-migration-f5hth-npcfz-user.kubeconfig"
Nov 10 13:07:48.688: INFO: The user is now "extended-test-registry-migration-f5hth-npcfz-user"
Nov 10 13:07:48.688: INFO: Creating project "extended-test-registry-migration-f5hth-npcfz"
Nov 10 13:07:48.742: INFO: Waiting on permissions in project "extended-test-registry-migration-f5hth-npcfz" ...
STEP: Waiting for a default service account to be provisioned in namespace
[JustBeforeEach] [Conformance][Feature:ImageRegistry][registry][migration][Serial] manifest migration from etcd to registry storage
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/registry/registry.go:46
Nov 10 13:07:48.791: INFO: Running 'oc env --config=/etc/origin/master/admin.kubeconfig --namespace=default dc/docker-registry --list'
[It] registry can get access to manifest [local] [Suite:openshift/conformance/serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/registry/registry.go:149
STEP: set up policy for registry to have anonymous access to images
Nov 10 13:07:49.061: INFO: Running 'oc policy --config=/tmp/extended-test-registry-migration-f5hth-npcfz-user.kubeconfig --namespace=extended-test-registry-migration-f5hth-npcfz add-role-to-user registry-viewer system:anonymous'
role "registry-viewer" added: "system:anonymous"
STEP: pushing image...
Step 1 : FROM scratch
 ---> 
Step 2 : COPY data1 /data1
 ---> cb5043b8c066
Removing intermediate container 68aa8ad04177
Successfully built cb5043b8c066
Nov 10 13:07:50.512: INFO: Running 'oc whoami --config=/tmp/extended-test-registry-migration-f5hth-npcfz-user.kubeconfig --namespace=extended-test-registry-migration-f5hth-npcfz -t'
The push refers to a repository [172.30.247.126:5000/extended-test-registry-migration-f5hth-npcfz/app]
Preparing
Pushing [====================>                              ]    512 B/1.28 kB
Pushing
Pushing [==================================================>] 1.792 kB
Pushing
Pushing [==================================================>] 3.072 kB
Pushing
Pushing [==================================================>] 3.072 kB
Pushing
Pushed
latest: digest: sha256:a8f08f24085df649e5d07783a6048a6c7f629515855bccc5471f31eb4adb9006 size: 525
STEP: checking that the image doesn't have the manifest...
STEP: getting image manifest from docker-registry...
Nov 10 13:07:51.058: INFO: Running 'oc whoami --config=/tmp/extended-test-registry-migration-f5hth-npcfz-user.kubeconfig --namespace=extended-test-registry-migration-f5hth-npcfz -t'
pinged registry at https://172.30.247.126:5000, got api versions: [registry/2.0]
STEP: restoring manifest...
STEP: checking that the manifest is present in the image...
STEP: getting image manifest from docker-registry one more time...
Nov 10 13:07:51.345: INFO: Running 'oc whoami --config=/tmp/extended-test-registry-migration-f5hth-npcfz-user.kubeconfig --namespace=extended-test-registry-migration-f5hth-npcfz -t'
pinged registry at https://172.30.247.126:5000, got api versions: [registry/2.0]
STEP: waiting until image is updated...
STEP: checking that the manifest was removed from the image...
STEP: getting image manifest from docker-registry to check if it's available...
Nov 10 13:07:51.811: INFO: Running 'oc whoami --config=/tmp/extended-test-registry-migration-f5hth-npcfz-user.kubeconfig --namespace=extended-test-registry-migration-f5hth-npcfz -t'
pinged registry at https://172.30.247.126:5000, got api versions: [registry/2.0]
STEP: pulling image...
STEP: get secret list err <nil> 
STEP: secret name builder-dockercfg-jcmfh 
STEP: docker cfg token json {"172.30.247.126:5000":{"username":"serviceaccount","password":"eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJleHRlbmRlZC10ZXN0LXJlZ2lzdHJ5LW1pZ3JhdGlvbi1mNWh0aC1ucGNmeiIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJidWlsZGVyLXRva2VuLW50eDdwIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6ImJ1aWxkZXIiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIyNjk4MjQwNi1jNjE4LTExZTctODQ0OC0wZTY0MGMyODRkZGUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6ZXh0ZW5kZWQtdGVzdC1yZWdpc3RyeS1taWdyYXRpb24tZjVodGgtbnBjZno6YnVpbGRlciJ9.NMpfPCMUlhLOeNCrhCd55D5ulPol-QJftRDpAOjgK3zViWTRdXIZZmFiDTQ7AOGBYCkHWj0Wn0LxO34oNydhEgo9Hi2KyMTOjz1m2_I2zZvdWTiYGKLHTVbz_AU7neHEN9sshVc0SipZwW8z0twucNkKBCWooPfpwlHf9HiN_n3D7f902cZMlnxas8HS5YTtEwg3mBw9fGd-pmVelOowP16h72Nn4mmNVgiYtuYLuq5G6xT4_b94bkSvmiW2fdmZkvJf0dkz6abSuxw7jnDV1gtnoohSk5NtMBLoKMePtSJ05jPqkD_vXNzLJUknsZA0ujSkr5zuI2pyK-InBm3KYA","email":"serviceaccount@example.org","auth":"c2VydmljZWFjY291bnQ6ZXlKaGJHY2lPaUpTVXpJMU5pSXNJblI1Y0NJNklrcFhWQ0o5LmV5SnBjM01pT2lKcmRXSmxjbTVsZEdWekwzTmxjblpwWTJWaFkyTnZkVzUwSWl3aWEzVmlaWEp1WlhSbGN5NXBieTl6WlhKMmFXTmxZV05qYjNWdWRDOXVZVzFsYzNCaFkyVWlPaUpsZUhSbGJtUmxaQzEwWlhOMExYSmxaMmx6ZEhKNUxXMXBaM0poZEdsdmJpMW1OV2gwYUMxdWNHTm1laUlzSW10MVltVnlibVYwWlhNdWFXOHZjMlZ5ZG1salpXRmpZMjkxYm5RdmMyVmpjbVYwTG01aGJXVWlPaUppZFdsc1pHVnlMWFJ2YTJWdUxXNTBlRGR3SWl3aWEzVmlaWEp1WlhSbGN5NXBieTl6WlhKMmFXTmxZV05qYjNWdWRDOXpaWEoyYVdObExXRmpZMjkxYm5RdWJtRnRaU0k2SW1KMWFXeGtaWElpTENKcmRXSmxjbTVsZEdWekxtbHZMM05sY25acFkyVmhZMk52ZFc1MEwzTmxjblpwWTJVdFlXTmpiM1Z1ZEM1MWFXUWlPaUl5TmprNE1qUXdOaTFqTmpFNExURXhaVGN0T0RRME9DMHdaVFkwTUdNeU9EUmtaR1VpTENKemRXSWlPaUp6ZVhOMFpXMDZjMlZ5ZG1salpXRmpZMjkxYm5RNlpYaDBaVzVrWldRdGRHVnpkQzF5WldkcGMzUnllUzF0YVdkeVlYUnBiMjR0WmpWb2RHZ3RibkJqWm5vNlluVnBiR1JsY2lKOS5OTXBmUENNVWxoTE9lTkNyaENkNTVENXVsUG9sLVFKZnRSRHBBT2pnSzN6VmlXVFJkWElaWm1GaURUUTdBT0dCWUNrSFdqMFduMEx4TzM0b055ZGhFZ285SGkyS3lNVE9qejFtMl9JMnpadmRXVGlZR0tMSFRWYnpfQVU3bmVIRU45c3NoVmMwU2lwWndXOHowdHd1Y05rS0JDV29vUGZwd2xIZjlIaU5fbjNEN2Y5MDJjWk1sbnhhczhIUzVZVHRFd2czbUJ3OWZHZC1wbVZlbE9vd1AxNmg3Mk5uNG1tTlZnaVl0dVlMdXE1RzZ4VDRfYjk0YmtTdm1pVzJmZG1aa3ZKZjBka3o2YWJTdXh3N2puRFYxZ3Rub29oU2s1TnRNQkxvS01lUHRTSjA1alBxa0RfdlhOekxKVWtuc1pBMHVqU2tyNXp1STJweUstSW5CbTNLWUE="},"docker-registry.default.svc:5000":{"username":"serviceaccount","password":"eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJleHRlbmRlZC10ZXN0LXJlZ2lzdHJ5LW1pZ3JhdGlvbi1mNWh0aC1ucGNmeiIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJidWlsZGVyLXRva2VuLW50eDdwIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6ImJ1aWxkZXIiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIyNjk4MjQwNi1jNjE4LTExZTctODQ0OC0wZTY0MGMyODRkZGUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6ZXh0ZW5kZWQtdGVzdC1yZWdpc3RyeS1taWdyYXRpb24tZjVodGgtbnBjZno6YnVpbGRlciJ9.NMpfPCMUlhLOeNCrhCd55D5ulPol-QJftRDpAOjgK3zViWTRdXIZZmFiDTQ7AOGBYCkHWj0Wn0LxO34oNydhEgo9Hi2KyMTOjz1m2_I2zZvdWTiYGKLHTVbz_AU7neHEN9sshVc0SipZwW8z0twucNkKBCWooPfpwlHf9HiN_n3D7f902cZMlnxas8HS5YTtEwg3mBw9fGd-pmVelOowP16h72Nn4mmNVgiYtuYLuq5G6xT4_b94bkSvmiW2fdmZkvJf0dkz6abSuxw7jnDV1gtnoohSk5NtMBLoKMePtSJ05jPqkD_vXNzLJUknsZA0ujSkr5zuI2pyK-InBm3KYA","email":"serviceaccount@example.org","auth":"c2VydmljZWFjY291bnQ6ZXlKaGJHY2lPaUpTVXpJMU5pSXNJblI1Y0NJNklrcFhWQ0o5LmV5SnBjM01pT2lKcmRXSmxjbTVsZEdWekwzTmxjblpwWTJWaFkyTnZkVzUwSWl3aWEzVmlaWEp1WlhSbGN5NXBieTl6WlhKMmFXTmxZV05qYjNWdWRDOXVZVzFsYzNCaFkyVWlPaUpsZUhSbGJtUmxaQzEwWlhOMExYSmxaMmx6ZEhKNUxXMXBaM0poZEdsdmJpMW1OV2gwYUMxdWNHTm1laUlzSW10MVltVnlibVYwWlhNdWFXOHZjMlZ5ZG1salpXRmpZMjkxYm5RdmMyVmpjbVYwTG01aGJXVWlPaUppZFdsc1pHVnlMWFJ2YTJWdUxXNTBlRGR3SWl3aWEzVmlaWEp1WlhSbGN5NXBieTl6WlhKMmFXTmxZV05qYjNWdWRDOXpaWEoyYVdObExXRmpZMjkxYm5RdWJtRnRaU0k2SW1KMWFXeGtaWElpTENKcmRXSmxjbTVsZEdWekxtbHZMM05sY25acFkyVmhZMk52ZFc1MEwzTmxjblpwWTJVdFlXTmpiM1Z1ZEM1MWFXUWlPaUl5TmprNE1qUXdOaTFqTmpFNExURXhaVGN0T0RRME9DMHdaVFkwTUdNeU9EUmtaR1VpTENKemRXSWlPaUp6ZVhOMFpXMDZjMlZ5ZG1salpXRmpZMjkxYm5RNlpYaDBaVzVrWldRdGRHVnpkQzF5WldkcGMzUnllUzF0YVdkeVlYUnBiMjR0WmpWb2RHZ3RibkJqWm5vNlluVnBiR1JsY2lKOS5OTXBmUENNVWxoTE9lTkNyaENkNTVENXVsUG9sLVFKZnRSRHBBT2pnSzN6VmlXVFJkWElaWm1GaURUUTdBT0dCWUNrSFdqMFduMEx4TzM0b055ZGhFZ285SGkyS3lNVE9qejFtMl9JMnpadmRXVGlZR0tMSFRWYnpfQVU3bmVIRU45c3NoVmMwU2lwWndXOHowdHd1Y05rS0JDV29vUGZwd2xIZjlIaU5fbjNEN2Y5MDJjWk1sbnhhczhIUzVZVHRFd2czbUJ3OWZHZC1wbVZlbE9vd1AxNmg3Mk5uNG1tTlZnaVl0dVlMdXE1RzZ4VDRfYjk0YmtTdm1pVzJmZG1aa3ZKZjBka3o2YWJTdXh3N2puRFYxZ3Rub29oU2s1TnRNQkxvS01lUHRTSjA1alBxa0RfdlhOekxKVWtuc1pBMHVqU2tyNXp1STJweUstSW5CbTNLWUE="}} 
STEP: json unmarshal err <nil> 
STEP: found auth true with auth cfg len 1 
STEP: dockercfg with svrAddr 172.30.247.126:5000 user serviceaccount pass eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJleHRlbmRlZC10ZXN0LXJlZ2lzdHJ5LW1pZ3JhdGlvbi1mNWh0aC1ucGNmeiIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJidWlsZGVyLXRva2VuLW50eDdwIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6ImJ1aWxkZXIiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIyNjk4MjQwNi1jNjE4LTExZTctODQ0OC0wZTY0MGMyODRkZGUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6ZXh0ZW5kZWQtdGVzdC1yZWdpc3RyeS1taWdyYXRpb24tZjVodGgtbnBjZno6YnVpbGRlciJ9.NMpfPCMUlhLOeNCrhCd55D5ulPol-QJftRDpAOjgK3zViWTRdXIZZmFiDTQ7AOGBYCkHWj0Wn0LxO34oNydhEgo9Hi2KyMTOjz1m2_I2zZvdWTiYGKLHTVbz_AU7neHEN9sshVc0SipZwW8z0twucNkKBCWooPfpwlHf9HiN_n3D7f902cZMlnxas8HS5YTtEwg3mBw9fGd-pmVelOowP16h72Nn4mmNVgiYtuYLuq5G6xT4_b94bkSvmiW2fdmZkvJf0dkz6abSuxw7jnDV1gtnoohSk5NtMBLoKMePtSJ05jPqkD_vXNzLJUknsZA0ujSkr5zuI2pyK-InBm3KYA email serviceaccount@example.org 
STEP: removing image...
Nov 10 13:07:52.378: INFO: Running 'oc rsh --config=/etc/origin/master/admin.kubeconfig --namespace=default dc/docker-registry find /registry -mindepth 1 -delete'
[AfterEach] [Conformance][Feature:ImageRegistry][registry][migration][Serial] manifest migration from etcd to registry storage
  /tmp/openshift/build-rpm-release/rpm/BUILD/origin-3.7.0/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:131
Nov 10 13:07:52.726: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "extended-test-registry-migration-f5hth-npcfz" for this suite.
Nov 10 13:07:58.765: INFO: namespace: extended-test-registry-migration-f5hth-npcfz, resource: bindings, ignored listing per whitelist
Nov 10 13:07:58.860: INFO: namespace extended-test-registry-migration-f5hth-npcfz deletion completed in 6.130589968s
[AfterEach] [Conformance][Feature:ImageRegistry][registry][migration][Serial] manifest migration from etcd to registry storage
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/registry/registry.go:53

• [SLOW TEST:10.251 seconds]
[Conformance][Feature:ImageRegistry][registry][migration][Serial] manifest migration from etcd to registry storage
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/registry/registry.go:150
  registry can get access to manifest [local] [Suite:openshift/conformance/serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/registry/registry.go:149
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSNov 10 13:07:58.861: INFO: Running AfterSuite actions on all node
Nov 10 13:07:58.861: INFO: Running AfterSuite actions on node 1

Ran 6 of 805 Specs in 220.464 seconds
SUCCESS! -- 6 Passed | 0 Failed | 0 Pending | 799 Skipped Nov 10 13:07:58.869: INFO: Error running cluster/log-dump/log-dump.sh: fork/exec /data/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/cluster/log-dump/log-dump.sh: no such file or directory
PASS

Ginkgo ran 1 suite in 3m41.114963913s
Test Suite Passed
[INFO] [CLEANUP] Beginning cleanup routines...
[INFO] [CLEANUP] Dumping cluster events to _output/scripts/conformance/artifacts/events.txt
Logged into "https://ip-172-18-11-139.ec2.internal:8443" as "system:admin" using existing credentials.

You have access to the following projects and can switch between them with 'oc project <projectname>':

  * default
    kube-public
    kube-system
    logging
    management-infra
    openshift
    openshift-infra
    openshift-node

Using project "default".
[INFO] [CLEANUP] Dumping container logs to _output/scripts/conformance/logs/containers
[INFO] [CLEANUP] Truncating log files over 200M
[INFO] [CLEANUP] Stopping docker containers
[INFO] [CLEANUP] Removing docker containers
Error response from daemon: You cannot remove a running container 2779e617104fbe5e11bbd1943b93b055cb0d1b3084fb2a2e2f3a5aa4d464fb7a. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container aefc83df0de18f578b5da080123f217dea331f152a17b960ab438dd297868025. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container 1f16e289b180167dcd475b4fd4405e9c35142b6759b510dca45359e212694f8a. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container 393ce2e953a7f8ff9213726a548d76331af0fd966f420d811c8999385f0e65fb. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container 0f4129216b7d731793d2590d5de6e01113925e243edd9061d31528d136ec8361. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container 4a808bece059bf770b5b06cf0c95cf75dfd9396a6847172128a3071ec4135d05. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container 689ae6ed0332af4de4d3588f18d5cb45d259ff24eade844d48b37a16aae54b7d. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container ded10af918f35661286f453c9bbe7d5f25ea30587631644c4db16b67b3ffa717. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container c9f3eaecc2b0654a1ab3ae6aae1f2b61e61f052d3a8c506f53a1ca7ad47bac60. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container b1348ae3a99ce15245ae88c924797013d8550b1615d3cf2735397fd538b5c10c. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container a4e752c5c9b30209b52fd069d8e56c3b9325e147507aa80bcbc21d3b1481eb36. Stop the container before attempting removal or use -f
[INFO] [CLEANUP] Killing child processes
[INFO] [CLEANUP] Pruning etcd data directory
rm: cannot remove ‘/tmp/etcd/openshift-backup-post-3.0-20171110122612’: Permission denied
rm: cannot remove ‘/tmp/etcd/openshift-backup-pre-upgrade-20171110122550’: Permission denied
rm: cannot remove ‘/tmp/etcd/member’: Permission denied
[INFO] test/extended/conformance.sh exited with code 0 after 00h 37m 28s
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: RUN EXTENDED TESTS [00h 38m 40s] ##########
[PostBuildScript] - Execution post build scripts.
[workspace@2] $ /bin/bash /tmp/jenkins8235070144225450608.sh
########## STARTING STAGE: DOWNLOAD ARTIFACTS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382
++ export PATH=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/artifacts/gathered
+ rm -rf /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/artifacts/gathered
+ mkdir -p /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/artifacts/gathered
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo stat /data/src/github.com/openshift/origin/_output/scripts
  File: ‘/data/src/github.com/openshift/origin/_output/scripts’
  Size: 168       	Blocks: 0          IO Block: 4096   directory
Device: ca02h/51714d	Inode: 62998218    Links: 10
Access: (2755/drwxr-sr-x)  Uid: ( 1001/  origin)   Gid: ( 1003/origin-git)
Context: unconfined_u:object_r:default_t:s0
Access: 2017-11-10 12:29:36.564628249 +0000
Modify: 2017-11-10 12:30:51.797618659 +0000
Change: 2017-11-10 12:30:51.797618659 +0000
 Birth: -
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo chmod -R o+rX /data/src/github.com/openshift/origin/_output/scripts
+ scp -r -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel:/data/src/github.com/openshift/origin/_output/scripts /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/artifacts/gathered
+ tree /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/artifacts/gathered
/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/artifacts/gathered
└── scripts
    ├── ansible_junit
    │   ├── lSXWiSKeeF.xml
    │   ├── oLROrrwJwF.xml
    │   └── woEJdOoRRG.xml
    ├── build-base-images
    │   ├── artifacts
    │   ├── logs
    │   └── openshift.local.home
    ├── build-images
    │   ├── artifacts
    │   ├── logs
    │   │   └── scripts.log
    │   └── openshift.local.home
    ├── conformance
    │   ├── artifacts
    │   │   ├── events.txt
    │   │   └── junit
    │   │       ├── conformance_parallel_01.xml
    │   │       ├── conformance_parallel_02.xml
    │   │       ├── conformance_parallel_03.xml
    │   │       ├── conformance_parallel_04.xml
    │   │       ├── conformance_parallel_05.xml
    │   │       └── conformance_serial_01.xml
    │   ├── logs
    │   │   ├── containers
    │   │   │   ├── k8s_alert-buffer_prometheus-0_kube-system_4f6fc6f2-c613-11e7-8448-0e640c284dde_0.log
    │   │   │   ├── k8s_alertmanager_prometheus-0_kube-system_4f6fc6f2-c613-11e7-8448-0e640c284dde_0.log
    │   │   │   ├── k8s_alerts-proxy_prometheus-0_kube-system_4f6fc6f2-c613-11e7-8448-0e640c284dde_0.log
    │   │   │   ├── k8s_POD_docker-registry-1-q5sc0_default_d048c066-c611-11e7-b10d-0e640c284dde_0.log
    │   │   │   ├── k8s_POD_docker-registry-2-snxhf_default_cd667c22-c612-11e7-8448-0e640c284dde_0.log
    │   │   │   ├── k8s_POD_prometheus-0_kube-system_4f6fc6f2-c613-11e7-8448-0e640c284dde_0.log
    │   │   │   ├── k8s_POD_registry-console-1-nb3fn_default_ef6986d6-c611-11e7-b10d-0e640c284dde_0.log
    │   │   │   ├── k8s_POD_registry-console-1-zts8s_default_876ab639-c612-11e7-8448-0e640c284dde_0.log
    │   │   │   ├── k8s_POD_router-2-wmdk9_default_cf0f8d47-c612-11e7-8448-0e640c284dde_0.log
    │   │   │   ├── k8s_prometheus_prometheus-0_kube-system_4f6fc6f2-c613-11e7-8448-0e640c284dde_0.log
    │   │   │   ├── k8s_prom-proxy_prometheus-0_kube-system_4f6fc6f2-c613-11e7-8448-0e640c284dde_0.log
    │   │   │   ├── k8s_registry-console_registry-console-1-zts8s_default_876ab639-c612-11e7-8448-0e640c284dde_0.log
    │   │   │   ├── k8s_registry_docker-registry-2-snxhf_default_cd667c22-c612-11e7-8448-0e640c284dde_0.log
    │   │   │   └── k8s_router_router-2-wmdk9_default_cf0f8d47-c612-11e7-8448-0e640c284dde_0.log
    │   │   └── scripts.log
    │   └── openshift.local.home
    ├── env
    │   ├── artifacts
    │   ├── logs
    │   │   └── scripts.log
    │   └── openshift.local.home
    ├── origin_version
    │   ├── artifacts
    │   ├── logs
    │   └── openshift.local.home
    ├── tmp.dyBjTt4f8A
    │   ├── artifacts
    │   ├── logs
    │   └── openshift.local.home
    └── tmp.eN96orNpzC
        ├── artifacts
        ├── logs
        └── openshift.local.home

32 directories, 27 files
+ exit 0
[workspace@2] $ /bin/bash /tmp/jenkins4927415036415038700.sh
########## STARTING STAGE: GENERATE ARTIFACTS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382
++ export PATH=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/artifacts/generated
+ rm -rf /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/artifacts/generated
+ mkdir /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/artifacts/generated
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo docker version && sudo docker info && sudo docker images && sudo docker ps -a 2>&1'
WARNING: bridge-nf-call-iptables is disabled
WARNING: bridge-nf-call-ip6tables is disabled
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo yum history info openshift-ansible 2>&1'
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo cat /etc/sysconfig/docker /etc/sysconfig/docker-network /etc/sysconfig/docker-storage /etc/sysconfig/docker-storage-setup /etc/systemd/system/docker.service 2>&1'
+ true
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo yum history info origin 2>&1'
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'oc get --raw /metrics --server=https://$( uname --nodename ):10250 --config=/etc/origin/master/admin.kubeconfig 2>&1'
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo ausearch -m AVC -m SELINUX_ERR -m USER_AVC 2>&1'
+ true
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'oc get --raw /metrics --config=/etc/origin/master/admin.kubeconfig 2>&1'
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo df -h && sudo pvs && sudo vgs && sudo lvs 2>&1'
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo yum list installed 2>&1'
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo journalctl _PID=1 --no-pager --all --lines=all 2>&1'
+ tree /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/artifacts/generated
/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/artifacts/generated
├── avc_denials.log
├── docker.config
├── docker.info
├── filesystem.info
├── installed_packages.log
├── master-metrics.log
├── node-metrics.log
├── openshift_ansible_package_history.log
├── origin_package_history.log
└── pid1.journal

0 directories, 10 files
+ exit 0
[workspace@2] $ /bin/bash /tmp/jenkins1009928587043435911.sh
########## STARTING STAGE: FETCH SYSTEMD JOURNALS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382
++ export PATH=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/artifacts/journals
+ rm -rf /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/artifacts/journals
+ mkdir /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/artifacts/journals
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit docker.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit dnsmasq.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit systemd-journald.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit origin-master.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit origin-master-api.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit origin-master-controllers.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit origin-node.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit openvswitch.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit ovs-vswitchd.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit ovsdb-server.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit etcd.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit systemd-journald.service --no-pager --all --lines=all
+ tree /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/artifacts/journals
/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/artifacts/journals
├── dnsmasq.service
├── docker.service
├── etcd.service
├── openvswitch.service
├── origin-master-api.service
├── origin-master-controllers.service
├── origin-master.service
├── origin-node.service
├── ovsdb-server.service
├── ovs-vswitchd.service
└── systemd-journald.service

0 directories, 11 files
+ exit 0
[workspace@2] $ /bin/bash /tmp/jenkins1909027006093924156.sh
########## STARTING STAGE: FORWARD PARAMETERS TO THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382
++ export PATH=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/.config
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo chmod o+rw /etc/environment
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'echo '\''BUILD_URL=https://ci.openshift.redhat.com/jenkins/job/test_branch_origin_extended_conformance_install_update/1875/'\'' >> /etc/environment'
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: FORWARD PARAMETERS TO THE REMOTE HOST [00h 00m 00s] ##########
[workspace@2] $ /bin/bash /tmp/jenkins674061411548376223.sh
########## STARTING STAGE: RECORD THE ENDING METADATA ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382
++ export PATH=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/.config
++ mktemp
+ script=/tmp/tmp.f0iuwo28gO
+ cat
+ chmod +x /tmp/tmp.f0iuwo28gO
+ scp -F ./.config/origin-ci-tool/inventory/.ssh_config /tmp/tmp.f0iuwo28gO openshiftdevel:/tmp/tmp.f0iuwo28gO
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config -t openshiftdevel 'bash -l -c "timeout 300 /tmp/tmp.f0iuwo28gO"'
+ cd /data/src/github.com/openshift/aos-cd-jobs
+ trap 'exit 0' EXIT
+ sjb/gcs/finished.py
+ exit 0
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: RECORD THE ENDING METADATA [00h 00m 01s] ##########
[workspace@2] $ /bin/bash /tmp/jenkins1589725420716612523.sh
########## STARTING STAGE: ASSEMBLE GCS OUTPUT ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382
++ export PATH=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/.config
+ trap 'exit 0' EXIT
+ mkdir -p gcs/artifacts gcs/artifacts/generated gcs/artifacts/journals gcs/artifacts/gathered
+ scp -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel:/data/finished.json gcs/
+ cat /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/builds/1875/log
+ cp artifacts/generated/avc_denials.log artifacts/generated/docker.config artifacts/generated/docker.info artifacts/generated/filesystem.info artifacts/generated/installed_packages.log artifacts/generated/master-metrics.log artifacts/generated/node-metrics.log artifacts/generated/openshift_ansible_package_history.log artifacts/generated/origin_package_history.log artifacts/generated/pid1.journal gcs/artifacts/generated/
+ cp artifacts/journals/dnsmasq.service artifacts/journals/docker.service artifacts/journals/etcd.service artifacts/journals/openvswitch.service artifacts/journals/origin-master-api.service artifacts/journals/origin-master-controllers.service artifacts/journals/origin-master.service artifacts/journals/origin-node.service artifacts/journals/ovsdb-server.service artifacts/journals/ovs-vswitchd.service artifacts/journals/systemd-journald.service gcs/artifacts/journals/
+ cp -r artifacts/gathered/scripts gcs/artifacts/
++ gcs_path
++ bucket=gs://origin-ci-test/
++ [[ -z 280ce02e-c60c-11e7-80ad-0a58ac100437 ]]
++ suffix=test_branch_origin_extended_conformance_install_update/1875/
++ [[ -n openshift ]]
++ [[ origin != \o\r\i\g\i\n ]]
++ [[ -n '' ]]
++ [[ master:d076bb5669a279b16b15ceb126c3e75fca3ebb31 =~ ^[^:]+:[^:]+(,[^:]+:[^:]+){2,} ]]
++ prefix=logs/
++ echo gs://origin-ci-test/logs/test_branch_origin_extended_conformance_install_update/1875/
++ return 0
+ path=gs://origin-ci-test/logs/test_branch_origin_extended_conformance_install_update/1875/
+ gsutil -m cp -r gcs/artifacts gcs/build-log.txt gcs/finished.json gcs/started.json gs://origin-ci-test/logs/test_branch_origin_extended_conformance_install_update/1875/
Copying file://gcs/artifacts/journals/docker.service [Content-Type=application/octet-stream]...
Copying file://gcs/artifacts/generated/openshift_ansible_package_history.log [Content-Type=text/plain]...
Copying file://gcs/artifacts/journals/dnsmasq.service [Content-Type=application/octet-stream]...
Copying file://gcs/artifacts/generated/docker.config [Content-Type=application/octet-stream]...
Copying file://gcs/artifacts/generated/docker.info [Content-Type=application/octet-stream]...
Copying file://gcs/artifacts/generated/avc_denials.log [Content-Type=text/plain]...
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
Copying file://gcs/artifacts/journals/openvswitch.service [Content-Type=application/octet-stream]...
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
Copying file://gcs/artifacts/generated/origin_package_history.log [Content-Type=text/plain]...
Copying file://gcs/artifacts/journals/etcd.service [Content-Type=application/octet-stream]...
Copying file://gcs/artifacts/generated/master-metrics.log [Content-Type=text/plain]...
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
Copying file://gcs/artifacts/generated/pid1.journal [Content-Type=application/octet-stream]...
Copying file://gcs/artifacts/generated/node-metrics.log [Content-Type=text/plain]...
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
Copying file://gcs/artifacts/journals/ovsdb-server.service [Content-Type=application/octet-stream]...
Copying file://gcs/artifacts/generated/filesystem.info [Content-Type=application/octet-stream]...
Copying file://gcs/artifacts/journals/origin-master-api.service [Content-Type=application/octet-stream]...
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
Copying file://gcs/artifacts/journals/origin-master.service [Content-Type=application/octet-stream]...
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
Copying file://gcs/artifacts/journals/origin-node.service [Content-Type=application/octet-stream]...
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
Copying file://gcs/artifacts/journals/origin-master-controllers.service [Content-Type=application/octet-stream]...
/ [0/51 files][    0.0 B/908.7 MiB]   0% Done                                   
Copying file://gcs/artifacts/journals/ovs-vswitchd.service [Content-Type=application/octet-stream]...
/ [0/51 files][  4.9 KiB/908.7 MiB]   0% Done                                   
Copying file://gcs/artifacts/generated/installed_packages.log [Content-Type=text/plain]...
/ [0/51 files][  4.9 KiB/908.7 MiB]   0% Done                                   
==> NOTE: You are uploading one or more large file(s), which would run
significantly faster if you enable parallel composite uploads. This
feature can be enabled by editing the
"parallel_composite_upload_threshold" value in your .boto
configuration file. However, note that if you do this large files will
be uploaded as `composite objects
<https://cloud.google.com/storage/docs/composite-objects>`_,which
means that any user who downloads such objects will need to have a
compiled crcmod installed (see "gsutil help crcmod"). This is because
without a compiled crcmod, computing checksums on composite objects is
so slow that gsutil disables downloads of composite objects.

/ [1/51 files][  3.7 MiB/908.7 MiB]   0% Done                                   
Copying file://gcs/artifacts/journals/systemd-journald.service [Content-Type=application/octet-stream]...
/ [1/51 files][  4.3 MiB/908.7 MiB]   0% Done                                   
/ [2/51 files][  5.5 MiB/908.7 MiB]   0% Done                                   
Copying file://gcs/artifacts/scripts/env/logs/scripts.log [Content-Type=text/plain]...
/ [2/51 files][  6.5 MiB/908.7 MiB]   0% Done                                   
/ [3/51 files][  7.8 MiB/908.7 MiB]   0% Done                                   
Copying file://gcs/artifacts/scripts/build-images/logs/scripts.log [Content-Type=text/plain]...
/ [3/51 files][  8.8 MiB/908.7 MiB]   0% Done                                   
/ [4/51 files][  9.4 MiB/908.7 MiB]   1% Done                                   
Copying file://gcs/artifacts/scripts/ansible_junit/woEJdOoRRG.xml [Content-Type=text/xml]...
/ [4/51 files][  9.6 MiB/908.7 MiB]   1% Done                                   
/ [5/51 files][ 10.4 MiB/908.7 MiB]   1% Done                                   
Copying file://gcs/artifacts/scripts/ansible_junit/lSXWiSKeeF.xml [Content-Type=text/xml]...
/ [5/51 files][ 10.9 MiB/908.7 MiB]   1% Done                                   
/ [6/51 files][ 14.6 MiB/908.7 MiB]   1% Done                                   
/ [7/51 files][ 14.6 MiB/908.7 MiB]   1% Done                                   
Copying file://gcs/artifacts/scripts/ansible_junit/oLROrrwJwF.xml [Content-Type=text/xml]...
/ [7/51 files][ 14.9 MiB/908.7 MiB]   1% Done                                   
Copying file://gcs/artifacts/scripts/conformance/logs/scripts.log [Content-Type=text/plain]...
/ [7/51 files][ 14.9 MiB/908.7 MiB]   1% Done                                   
/ [8/51 files][ 19.9 MiB/908.7 MiB]   2% Done                                   
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_alertmanager_prometheus-0_kube-system_4f6fc6f2-c613-11e7-8448-0e640c284dde_0.log [Content-Type=text/plain]...
/ [8/51 files][ 20.1 MiB/908.7 MiB]   2% Done                                   
/ [9/51 files][ 21.4 MiB/908.7 MiB]   2% Done                                   
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_alert-buffer_prometheus-0_kube-system_4f6fc6f2-c613-11e7-8448-0e640c284dde_0.log [Content-Type=text/plain]...
/ [9/51 files][ 21.9 MiB/908.7 MiB]   2% Done                                   
/ [10/51 files][ 21.9 MiB/908.7 MiB]   2% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_alerts-proxy_prometheus-0_kube-system_4f6fc6f2-c613-11e7-8448-0e640c284dde_0.log [Content-Type=text/plain]...
/ [10/51 files][ 22.4 MiB/908.7 MiB]   2% Done                                  
/ [11/51 files][ 22.7 MiB/908.7 MiB]   2% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_prometheus_prometheus-0_kube-system_4f6fc6f2-c613-11e7-8448-0e640c284dde_0.log [Content-Type=text/plain]...
/ [11/51 files][ 23.7 MiB/908.7 MiB]   2% Done                                  
/ [12/51 files][ 23.7 MiB/908.7 MiB]   2% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_prom-proxy_prometheus-0_kube-system_4f6fc6f2-c613-11e7-8448-0e640c284dde_0.log [Content-Type=text/plain]...
/ [12/51 files][ 24.8 MiB/908.7 MiB]   2% Done                                  
/ [13/51 files][ 25.8 MiB/908.7 MiB]   2% Done                                  
-
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_POD_prometheus-0_kube-system_4f6fc6f2-c613-11e7-8448-0e640c284dde_0.log [Content-Type=text/plain]...
- [13/51 files][ 26.1 MiB/908.7 MiB]   2% Done                                  
- [14/51 files][ 29.2 MiB/908.7 MiB]   3% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_router_router-2-wmdk9_default_cf0f8d47-c612-11e7-8448-0e640c284dde_0.log [Content-Type=text/plain]...
- [14/51 files][ 29.9 MiB/908.7 MiB]   3% Done                                  
- [15/51 files][ 31.2 MiB/908.7 MiB]   3% Done                                  
- [16/51 files][ 31.7 MiB/908.7 MiB]   3% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_POD_router-2-wmdk9_default_cf0f8d47-c612-11e7-8448-0e640c284dde_0.log [Content-Type=text/plain]...
- [16/51 files][ 31.8 MiB/908.7 MiB]   3% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_registry_docker-registry-2-snxhf_default_cd667c22-c612-11e7-8448-0e640c284dde_0.log [Content-Type=text/plain]...
- [16/51 files][ 31.8 MiB/908.7 MiB]   3% Done                                  
- [17/51 files][ 32.5 MiB/908.7 MiB]   3% Done                                  
- [18/51 files][ 33.1 MiB/908.7 MiB]   3% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_POD_docker-registry-2-snxhf_default_cd667c22-c612-11e7-8448-0e640c284dde_0.log [Content-Type=text/plain]...
- [18/51 files][ 33.1 MiB/908.7 MiB]   3% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_registry-console_registry-console-1-zts8s_default_876ab639-c612-11e7-8448-0e640c284dde_0.log [Content-Type=text/plain]...
- [18/51 files][ 33.3 MiB/908.7 MiB]   3% Done                                  
- [19/51 files][ 35.2 MiB/908.7 MiB]   3% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_POD_registry-console-1-zts8s_default_876ab639-c612-11e7-8448-0e640c284dde_0.log [Content-Type=text/plain]...
- [19/51 files][ 35.4 MiB/908.7 MiB]   3% Done                                  
- [20/51 files][ 36.2 MiB/908.7 MiB]   3% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_POD_registry-console-1-nb3fn_default_ef6986d6-c611-11e7-b10d-0e640c284dde_0.log [Content-Type=text/plain]...
- [20/51 files][ 37.0 MiB/908.7 MiB]   4% Done                                  
- [21/51 files][ 38.0 MiB/908.7 MiB]   4% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_POD_docker-registry-1-q5sc0_default_d048c066-c611-11e7-b10d-0e640c284dde_0.log [Content-Type=text/plain]...
- [21/51 files][ 38.8 MiB/908.7 MiB]   4% Done                                  
- [22/51 files][ 40.9 MiB/908.7 MiB]   4% Done                                  
Copying file://gcs/artifacts/scripts/conformance/artifacts/events.txt [Content-Type=text/plain]...
- [22/51 files][ 41.6 MiB/908.7 MiB]   4% Done                                  
- [23/51 files][ 42.1 MiB/908.7 MiB]   4% Done                                  
Copying file://gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_01.xml [Content-Type=text/xml]...
- [23/51 files][ 42.9 MiB/908.7 MiB]   4% Done                                  
- [24/51 files][ 44.2 MiB/908.7 MiB]   4% Done                                  
Copying file://gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_02.xml [Content-Type=text/xml]...
- [24/51 files][ 45.0 MiB/908.7 MiB]   4% Done                                  
- [25/51 files][ 46.6 MiB/908.7 MiB]   5% Done                                  
Copying file://gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_05.xml [Content-Type=text/xml]...
- [25/51 files][ 47.1 MiB/908.7 MiB]   5% Done                                  
- [26/51 files][ 49.2 MiB/908.7 MiB]   5% Done                                  
Copying file://gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_04.xml [Content-Type=text/xml]...
- [26/51 files][ 49.5 MiB/908.7 MiB]   5% Done                                  
- [27/51 files][ 50.5 MiB/908.7 MiB]   5% Done                                  
Copying file://gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_03.xml [Content-Type=text/xml]...
- [28/51 files][ 50.5 MiB/908.7 MiB]   5% Done                                  
- [28/51 files][ 50.8 MiB/908.7 MiB]   5% Done                                  
Copying file://gcs/artifacts/scripts/conformance/artifacts/junit/conformance_serial_01.xml [Content-Type=text/xml]...
- [28/51 files][ 50.8 MiB/908.7 MiB]   5% Done                                  
- [29/51 files][ 50.8 MiB/908.7 MiB]   5% Done                                  
Copying file://gcs/build-log.txt [Content-Type=text/plain]...
- [29/51 files][ 51.0 MiB/908.7 MiB]   5% Done                                  
- [30/51 files][ 52.9 MiB/908.7 MiB]   5% Done                                  
- [31/51 files][ 53.2 MiB/908.7 MiB]   5% Done                                  
Copying file://gcs/finished.json [Content-Type=application/json]...
Copying file://gcs/started.json [Content-Type=application/json]...
- [31/51 files][ 53.7 MiB/908.7 MiB]   5% Done                                  
- [31/51 files][ 53.7 MiB/908.7 MiB]   5% Done                                  
- [32/51 files][ 54.5 MiB/908.7 MiB]   5% Done                                  
- [33/51 files][ 55.2 MiB/908.7 MiB]   6% Done                                  
- [34/51 files][ 58.3 MiB/908.7 MiB]   6% Done                                  
- [35/51 files][ 60.7 MiB/908.7 MiB]   6% Done                                  
- [36/51 files][ 61.0 MiB/908.7 MiB]   6% Done                                  
\
\ [37/51 files][ 65.1 MiB/908.7 MiB]   7% Done                                  
\ [38/51 files][ 66.7 MiB/908.7 MiB]   7% Done                                  
\ [39/51 files][ 68.2 MiB/908.7 MiB]   7% Done                                  
\ [40/51 files][ 71.0 MiB/908.7 MiB]   7% Done                                  
\ [41/51 files][ 75.9 MiB/908.7 MiB]   8% Done                                  
\ [42/51 files][ 75.9 MiB/908.7 MiB]   8% Done                                  
\ [43/51 files][ 76.2 MiB/908.7 MiB]   8% Done                                  
\ [44/51 files][ 76.2 MiB/908.7 MiB]   8% Done                                  
\ [45/51 files][ 78.3 MiB/908.7 MiB]   8% Done                                  
\ [46/51 files][ 81.4 MiB/908.7 MiB]   8% Done                                  
\ [47/51 files][ 81.9 MiB/908.7 MiB]   9% Done                                  
|
| [48/51 files][127.9 MiB/908.7 MiB]  14% Done                                  
/
-
- [48/51 files][193.6 MiB/908.7 MiB]  21% Done                                  
\
\ [48/51 files][258.8 MiB/908.7 MiB]  28% Done                                  
|
| [49/51 files][299.4 MiB/908.7 MiB]  32% Done                                  
/
/ [50/51 files][329.4 MiB/908.7 MiB]  36% Done                                  
-
\
\ [50/51 files][394.1 MiB/908.7 MiB]  43% Done                                  
|
/
/ [50/51 files][459.0 MiB/908.7 MiB]  50% Done                                  
-
- [50/51 files][523.8 MiB/908.7 MiB]  57% Done                                  
\
|
| [50/51 files][588.7 MiB/908.7 MiB]  64% Done                                  
/
-
- [50/51 files][653.7 MiB/908.7 MiB]  71% Done                                  
\
\ [50/51 files][718.9 MiB/908.7 MiB]  79% Done  64.9 MiB/s ETA 00:00:03         
|
/
/ [50/51 files][784.1 MiB/908.7 MiB]  86% Done  64.9 MiB/s ETA 00:00:02         
-
\
\ [50/51 files][849.4 MiB/908.7 MiB]  93% Done  64.8 MiB/s ETA 00:00:01         
|
| [51/51 files][908.7 MiB/908.7 MiB] 100% Done  60.4 MiB/s ETA 00:00:00         
/
Operation completed over 51 objects/908.7 MiB.                                   
+ exit 0
[workspace@2] $ /bin/bash /tmp/jenkins2683774085458186876.sh
########## STARTING STAGE: DEPROVISION CLOUD RESOURCES ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382
++ export PATH=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/.config
+ oct deprovision

PLAYBOOK: main.yml *************************************************************
4 plays in /var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml

PLAY [ensure we have the parameters necessary to deprovision virtual hosts] ****

TASK [ensure all required variables are set] ***********************************
task path: /var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:9
skipping: [localhost] => (item=origin_ci_inventory_dir)  => {
    "changed": false, 
    "generated_timestamp": "2017-11-10 08:11:19.333530", 
    "item": "origin_ci_inventory_dir", 
    "skip_reason": "Conditional check failed", 
    "skipped": true
}
skipping: [localhost] => (item=origin_ci_aws_region)  => {
    "changed": false, 
    "generated_timestamp": "2017-11-10 08:11:19.336312", 
    "item": "origin_ci_aws_region", 
    "skip_reason": "Conditional check failed", 
    "skipped": true
}

PLAY [deprovision virtual hosts in EC2] ****************************************

TASK [Gathering Facts] *********************************************************
ok: [localhost]

TASK [deprovision a virtual EC2 host] ******************************************
task path: /var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:28
included: /var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml for localhost

TASK [update the SSH configuration to remove AWS EC2 specifics] ****************
task path: /var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:2
ok: [localhost] => {
    "changed": false, 
    "generated_timestamp": "2017-11-10 08:11:20.076893", 
    "msg": ""
}

TASK [rename EC2 instance for termination reaper] ******************************
task path: /var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:8
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2017-11-10 08:11:20.607687", 
    "msg": "Tags {'Name': 'oct-terminate'} created for resource i-02df36264147e8dad."
}

TASK [tear down the EC2 instance] **********************************************
task path: /var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:15
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2017-11-10 08:11:21.376412", 
    "instance_ids": [
        "i-02df36264147e8dad"
    ], 
    "instances": [
        {
            "ami_launch_index": "0", 
            "architecture": "x86_64", 
            "block_device_mapping": {
                "/dev/sda1": {
                    "delete_on_termination": true, 
                    "status": "attached", 
                    "volume_id": "vol-0917627adec3fca86"
                }, 
                "/dev/sdb": {
                    "delete_on_termination": true, 
                    "status": "attached", 
                    "volume_id": "vol-0caa0bf9547334971"
                }
            }, 
            "dns_name": "ec2-54-210-152-184.compute-1.amazonaws.com", 
            "ebs_optimized": false, 
            "groups": {
                "sg-7e73221a": "default"
            }, 
            "hypervisor": "xen", 
            "id": "i-02df36264147e8dad", 
            "image_id": "ami-edef2697", 
            "instance_type": "m4.xlarge", 
            "kernel": null, 
            "key_name": "libra", 
            "launch_time": "2017-11-10T11:42:55.000Z", 
            "placement": "us-east-1d", 
            "private_dns_name": "ip-172-18-11-139.ec2.internal", 
            "private_ip": "172.18.11.139", 
            "public_dns_name": "ec2-54-210-152-184.compute-1.amazonaws.com", 
            "public_ip": "54.210.152.184", 
            "ramdisk": null, 
            "region": "us-east-1", 
            "root_device_name": "/dev/sda1", 
            "root_device_type": "ebs", 
            "state": "running", 
            "state_code": 16, 
            "tags": {
                "Name": "oct-terminate", 
                "openshift_etcd": "", 
                "openshift_master": "", 
                "openshift_node": ""
            }, 
            "tenancy": "default", 
            "virtualization_type": "hvm"
        }
    ], 
    "tagged_instances": []
}

TASK [remove the serialized host variables] ************************************
task path: /var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:21
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2017-11-10 08:11:21.600646", 
    "path": "/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/.config/origin-ci-tool/inventory/host_vars/172.18.11.139.yml", 
    "state": "absent"
}

PLAY [deprovision virtual hosts locally manged by Vagrant] *********************

TASK [Gathering Facts] *********************************************************
ok: [localhost]

PLAY [clean up local configuration for deprovisioned instances] ****************

TASK [remove inventory configuration directory] ********************************
task path: /var/lib/jenkins/origin-ci-tool/b4433dfdce6a5fba26d100d9416d78fd95716382/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:61
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2017-11-10 08:11:21.995337", 
    "path": "/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_install_update/workspace@2/.config/origin-ci-tool/inventory", 
    "state": "absent"
}

PLAY RECAP *********************************************************************
localhost                  : ok=8    changed=4    unreachable=0    failed=0   

+ set +o xtrace
########## FINISHED STAGE: SUCCESS: DEPROVISION CLOUD RESOURCES [00h 00m 04s] ##########
Archiving artifacts
[WS-CLEANUP] Deleting project workspace...[WS-CLEANUP] done
Finished: SUCCESS