Console Output

Skipping 6,610 KB.. Full Log
Nov 10 11:37:31.093: INFO: 	Container prometheus 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 11:37:31.096183   37417 reflector.go:213] Starting reflector *v1.Event (0s) from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/events.go:135
I1110 11:37:31.096229   37417 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.14f5b67212baf42a], 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 11:37:32.112: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-sched-pred-pbvz4" for this suite.
Nov 10 11:37:54.200: INFO: namespace: e2e-tests-sched-pred-pbvz4, resource: bindings, ignored listing per whitelist
Nov 10 11:37:54.237: INFO: namespace e2e-tests-sched-pred-pbvz4 deletion completed in 22.122357571s
[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 11:37:54.237988   37417 request.go:785] Error in request: resource name may not be empty

• [SLOW TEST:23.390 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
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[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 11:37:54.238: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Nov 10 11:37:54.322: 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-9752ce5c-c60b-11e7-b4c9-0e3ab1e41cac in namespace e2e-tests-kubelet-hlzhq
I1110 11:37:54.385045   37417 runners.go:176] Created replication controller with name: cleanup10-9752ce5c-c60b-11e7-b4c9-0e3ab1e41cac, namespace: e2e-tests-kubelet-hlzhq, replica count: 10
I1110 11:37:54.385113   37417 reflector.go:213] Starting reflector *v1.Pod (0s) from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/utils/pod_store.go:53
I1110 11:37:54.385150   37417 reflector.go:251] Listing and watching *v1.Pod from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/utils/pod_store.go:53
I1110 11:38:04.385325   37417 runners.go:176] cleanup10-9752ce5c-c60b-11e7-b4c9-0e3ab1e41cac Pods: 10 out of 10 created, 0 running, 10 pending, 0 waiting, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I1110 11:38:14.385473   37417 runners.go:176] cleanup10-9752ce5c-c60b-11e7-b4c9-0e3ab1e41cac Pods: 10 out of 10 created, 10 running, 0 pending, 0 waiting, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
Nov 10 11:38:15.385: INFO: Checking pods on node ip-172-18-15-242.ec2.internal via /runningpods endpoint
Nov 10 11:38:15.396: INFO: Resource usage on node "ip-172-18-15-242.ec2.internal":
container cpu(cores) memory_working_set(MB) memory_rss(MB)
"/"       0.888      7964.95                1753.62
"runtime" 0.228      297.83                 289.60
"kubelet" 0.271      257.44                 256.87

STEP: Deleting the RC
STEP: deleting { ReplicationController} cleanup10-9752ce5c-c60b-11e7-b4c9-0e3ab1e41cac in namespace e2e-tests-kubelet-hlzhq
I1110 11:38:15.399493   37417 reflector.go:213] Starting reflector *v1.Pod (0s) from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/utils/pod_store.go:53
I1110 11:38:15.399535   37417 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 11:38:15.567: INFO: Deleting { ReplicationController} cleanup10-9752ce5c-c60b-11e7-b4c9-0e3ab1e41cac took: 67.940148ms
Nov 10 11:38:15.567: INFO: Terminating { ReplicationController} cleanup10-9752ce5c-c60b-11e7-b4c9-0e3ab1e41cac pods took: 40.75µs
Nov 10 11:38:27.967: INFO: Garbage collecting { ReplicationController} cleanup10-9752ce5c-c60b-11e7-b4c9-0e3ab1e41cac pods took: 12.468128032s
Nov 10 11:38:28.968: INFO: Checking pods on node ip-172-18-15-242.ec2.internal via /runningpods endpoint
Nov 10 11:38:28.973: INFO: Deleting 10 pods on 1 nodes completed in 1.005603452s after the RC was deleted
Nov 10 11:38:28.973: INFO: CPU usage of containers on node "ip-172-18-15-242.ec2.internal"
:container 5th%  20th% 50th% 70th% 90th% 95th% 99th%
"/"       0.000 0.000 0.680 0.888 0.888 0.888 0.888
"runtime" 0.000 0.000 0.081 0.081 0.081 0.081 0.081
"kubelet" 0.000 0.000 0.103 0.240 0.240 0.240 0.240

[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 11:38:28.979: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-kubelet-hlzhq" for this suite.
Nov 10 11:38:35.026: INFO: namespace: e2e-tests-kubelet-hlzhq, resource: bindings, ignored listing per whitelist
Nov 10 11:38:35.100: INFO: namespace e2e-tests-kubelet-hlzhq deletion completed in 6.117457734s

• [SLOW TEST:40.862 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
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[k8s.io] SchedulerPredicates [Serial] 
  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
[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 11:38:35.101: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Nov 10 11:38:35.188: 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 11:38:35.233: INFO: Waiting up to 1m0s for all (but 0) nodes to be ready
Nov 10 11:38:35.237: INFO: Waiting for terminating namespaces to be deleted...
Nov 10 11:38:35.241: INFO: 
Logging pods the kubelet thinks is on node ip-172-18-15-242.ec2.internal before test
Nov 10 11:38:35.248: INFO: router-2-hxbnw from default started at 2017-11-10 11:02:58 +0000 UTC (1 container statuses recorded)
Nov 10 11:38:35.248: INFO: 	Container router ready: true, restart count 0
Nov 10 11:38:35.248: INFO: docker-registry-2-tkwcm from default started at 2017-11-10 11:00:56 +0000 UTC (1 container statuses recorded)
Nov 10 11:38:35.248: INFO: 	Container registry ready: true, restart count 0
Nov 10 11:38:35.248: INFO: prometheus-0 from kube-system started at 2017-11-10 11:05:05 +0000 UTC (5 container statuses recorded)
Nov 10 11:38:35.248: INFO: 	Container alert-buffer ready: true, restart count 0
Nov 10 11:38:35.248: INFO: 	Container alertmanager ready: true, restart count 0
Nov 10 11:38:35.248: INFO: 	Container alerts-proxy ready: true, restart count 0
Nov 10 11:38:35.248: INFO: 	Container prom-proxy ready: true, restart count 0
Nov 10 11:38:35.248: INFO: 	Container prometheus ready: true, restart count 0
Nov 10 11:38:35.248: INFO: registry-console-1-gmsqx from default started at 2017-11-10 11:00:43 +0000 UTC (1 container statuses recorded)
Nov 10 11:38:35.248: INFO: 	Container registry-console 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-b21516f1-c60b-11e7-b4c9-0e3ab1e41cac 42
STEP: Trying to relaunch the pod, now with labels.
STEP: removing the label kubernetes.io/e2e-b21516f1-c60b-11e7-b4c9-0e3ab1e41cac off the node ip-172-18-15-242.ec2.internal
STEP: verifying the node doesn't have the label kubernetes.io/e2e-b21516f1-c60b-11e7-b4c9-0e3ab1e41cac
[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 11:38:43.315: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-sched-pred-68qv4" for this suite.
Nov 10 11:39:05.433: INFO: namespace: e2e-tests-sched-pred-68qv4, resource: bindings, ignored listing per whitelist
Nov 10 11:39:05.436: INFO: namespace e2e-tests-sched-pred-68qv4 deletion completed in 22.119376031s
[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 11:39:05.437026   37417 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
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[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 11:39:05.438: 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 11:39:05.593527   37417 request.go:641] Throttling request took 77.164358ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:05.640528   37417 request.go:641] Throttling request took 124.14108ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:05.691510   37417 request.go:641] Throttling request took 175.096ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:05.743522   37417 request.go:641] Throttling request took 227.108925ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:05.791499   37417 request.go:641] Throttling request took 275.06529ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:05.840492   37417 request.go:641] Throttling request took 324.044127ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:05.893509   37417 request.go:641] Throttling request took 377.055954ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:05.942498   37417 request.go:641] Throttling request took 426.006622ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:05.990505   37417 request.go:641] Throttling request took 473.985759ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.040507   37417 request.go:641] Throttling request took 523.960396ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.089521   37417 request.go:641] Throttling request took 572.946389ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.139520   37417 request.go:641] Throttling request took 622.927382ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.190181   37417 request.go:641] Throttling request took 673.556816ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.240493   37417 request.go:641] Throttling request took 723.860271ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.290515   37417 request.go:641] Throttling request took 773.846997ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.339520   37417 request.go:641] Throttling request took 822.836289ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.391526   37417 request.go:641] Throttling request took 874.819009ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.441499   37417 request.go:641] Throttling request took 924.766893ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.489702   37417 request.go:641] Throttling request took 972.944851ms, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.540544   37417 request.go:641] Throttling request took 1.023757041s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.590522   37417 request.go:641] Throttling request took 1.073726762s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.642505   37417 request.go:641] Throttling request took 1.12568642s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.691515   37417 request.go:641] Throttling request took 1.174671191s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.739835   37417 request.go:641] Throttling request took 1.222976092s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.789975   37417 request.go:641] Throttling request took 1.273090861s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.839603   37417 request.go:641] Throttling request took 1.322706392s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.889979   37417 request.go:641] Throttling request took 1.373061008s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.941328   37417 request.go:641] Throttling request took 1.424388336s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:06.991666   37417 request.go:641] Throttling request took 1.474705534s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.041553   37417 request.go:641] Throttling request took 1.524559842s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.090544   37417 request.go:641] Throttling request took 1.573538861s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.139634   37417 request.go:641] Throttling request took 1.622605823s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.189558   37417 request.go:641] Throttling request took 1.672513749s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.240496   37417 request.go:641] Throttling request took 1.723438575s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.292504   37417 request.go:641] Throttling request took 1.775420742s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.340000   37417 request.go:641] Throttling request took 1.822904172s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.390613   37417 request.go:641] Throttling request took 1.873486841s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.441521   37417 request.go:641] Throttling request took 1.92438296s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.490534   37417 request.go:641] Throttling request took 1.973350533s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.541117   37417 request.go:641] Throttling request took 2.0239338s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.589575   37417 request.go:641] Throttling request took 2.072357715s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.639845   37417 request.go:641] Throttling request took 2.122616677s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.689704   37417 request.go:641] Throttling request took 2.172449021s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.739518   37417 request.go:641] Throttling request took 2.222255331s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.792505   37417 request.go:641] Throttling request took 2.275174227s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.842508   37417 request.go:641] Throttling request took 2.325168049s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.889519   37417 request.go:641] Throttling request took 2.372140688s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.941316   37417 request.go:641] Throttling request took 2.42389668s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
I1110 11:39:07.991100   37417 request.go:641] Throttling request took 2.473616595s, request: POST:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces
STEP: Waiting 10 seconds
STEP: Deleting namespaces
Nov 10 11:39:19.085: INFO: namespace : e2e-tests-nslifetest-11-8lt4l api call to delete is complete 
Nov 10 11:39:19.094: INFO: namespace : e2e-tests-nslifetest-10-ppg7h api call to delete is complete 
Nov 10 11:39:19.094: INFO: namespace : e2e-tests-nslifetest-18-r6w8q api call to delete is complete 
Nov 10 11:39:19.094: INFO: namespace : e2e-tests-nslifetest-13-995x2 api call to delete is complete 
Nov 10 11:39:19.094: INFO: namespace : e2e-tests-nslifetest-99-6f6w9 api call to delete is complete 
Nov 10 11:39:19.094: INFO: namespace : e2e-tests-nslifetest-12-wgzdm api call to delete is complete 
Nov 10 11:39:19.099: INFO: namespace : e2e-tests-nslifetest-17-5bnxp api call to delete is complete 
Nov 10 11:39:19.099: INFO: namespace : e2e-tests-nslifetest-70-2gh2r api call to delete is complete 
Nov 10 11:39:19.099: INFO: namespace : e2e-tests-nslifetest-7-5wg66 api call to delete is complete 
Nov 10 11:39:19.102: INFO: namespace : e2e-tests-nslifetest-16-dmzjb api call to delete is complete 
Nov 10 11:39:19.103: INFO: namespace : e2e-tests-nslifetest-3-55v5n api call to delete is complete 
Nov 10 11:39:19.103: INFO: namespace : e2e-tests-nslifetest-64-vrvkm api call to delete is complete 
Nov 10 11:39:19.103: INFO: namespace : e2e-tests-nslifetest-23-6rz2z api call to delete is complete 
Nov 10 11:39:19.103: INFO: namespace : e2e-tests-nslifetest-19-ptntk api call to delete is complete 
Nov 10 11:39:19.103: INFO: namespace : e2e-tests-nslifetest-61-t95r7 api call to delete is complete 
Nov 10 11:39:19.103: INFO: namespace : e2e-tests-nslifetest-24-tstcx api call to delete is complete 
Nov 10 11:39:19.103: INFO: namespace : e2e-tests-nslifetest-29-r2skn api call to delete is complete 
Nov 10 11:39:19.103: INFO: namespace : e2e-tests-nslifetest-71-n8lmk api call to delete is complete 
Nov 10 11:39:19.111: INFO: namespace : e2e-tests-nslifetest-74-nnmv8 api call to delete is complete 
Nov 10 11:39:19.111: INFO: namespace : e2e-tests-nslifetest-15-rfhq4 api call to delete is complete 
Nov 10 11:39:19.111: INFO: namespace : e2e-tests-nslifetest-22-n2h2d api call to delete is complete 
Nov 10 11:39:19.111: INFO: namespace : e2e-tests-nslifetest-0-m2qh5 api call to delete is complete 
Nov 10 11:39:19.111: INFO: namespace : e2e-tests-nslifetest-62-j8gkz api call to delete is complete 
Nov 10 11:39:19.114: INFO: namespace : e2e-tests-nslifetest-21-7b9rm api call to delete is complete 
Nov 10 11:39:19.114: INFO: namespace : e2e-tests-nslifetest-1-72cl4 api call to delete is complete 
Nov 10 11:39:19.114: INFO: namespace : e2e-tests-nslifetest-14-9pd4p api call to delete is complete 
Nov 10 11:39:19.114: INFO: namespace : e2e-tests-nslifetest-63-5tx4c api call to delete is complete 
Nov 10 11:39:19.114: INFO: namespace : e2e-tests-nslifetest-60-xx5qx api call to delete is complete 
Nov 10 11:39:19.114: INFO: namespace : e2e-tests-nslifetest-69-m6vf7 api call to delete is complete 
Nov 10 11:39:19.114: INFO: namespace : e2e-tests-nslifetest-6-jwx7l api call to delete is complete 
Nov 10 11:39:19.117: INFO: namespace : e2e-tests-nslifetest-25-vmfll api call to delete is complete 
Nov 10 11:39:19.117: INFO: namespace : e2e-tests-nslifetest-27-rskm7 api call to delete is complete 
Nov 10 11:39:19.117: INFO: namespace : e2e-tests-nslifetest-20-fdkjw api call to delete is complete 
Nov 10 11:39:19.117: INFO: namespace : e2e-tests-nslifetest-72-zwvqb api call to delete is complete 
Nov 10 11:39:19.120: INFO: namespace : e2e-tests-nslifetest-65-j54jd api call to delete is complete 
Nov 10 11:39:19.120: INFO: namespace : e2e-tests-nslifetest-66-m9b24 api call to delete is complete 
Nov 10 11:39:19.123: INFO: namespace : e2e-tests-nslifetest-28-g29dp api call to delete is complete 
Nov 10 11:39:19.123: INFO: namespace : e2e-tests-nslifetest-33-fd7j4 api call to delete is complete 
Nov 10 11:39:19.123: INFO: namespace : e2e-tests-nslifetest-73-nt7rl api call to delete is complete 
Nov 10 11:39:19.123: INFO: namespace : e2e-tests-nslifetest-34-ghfrr api call to delete is complete 
Nov 10 11:39:19.123: INFO: namespace : e2e-tests-nslifetest-2-n69sp api call to delete is complete 
Nov 10 11:39:19.123: INFO: namespace : e2e-tests-nslifetest-30-92wtw api call to delete is complete 
Nov 10 11:39:19.123: INFO: namespace : e2e-tests-nslifetest-75-bzl2q api call to delete is complete 
Nov 10 11:39:19.123: INFO: namespace : e2e-tests-nslifetest-32-7gqjm api call to delete is complete 
Nov 10 11:39:19.123: INFO: namespace : e2e-tests-nslifetest-35-h6qv2 api call to delete is complete 
Nov 10 11:39:19.123: INFO: namespace : e2e-tests-nslifetest-31-l59n5 api call to delete is complete 
Nov 10 11:39:19.126: INFO: namespace : e2e-tests-nslifetest-67-f4swp api call to delete is complete 
Nov 10 11:39:19.126: INFO: namespace : e2e-tests-nslifetest-26-n99rr api call to delete is complete 
Nov 10 11:39:19.127: INFO: namespace : e2e-tests-nslifetest-68-sxd2t api call to delete is complete 
Nov 10 11:39:19.128: INFO: namespace : e2e-tests-nslifetest-36-bbsnj api call to delete is complete 
I1110 11:39:19.139524   37417 request.go:641] Throttling request took 86.233757ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-76-8wdcw
Nov 10 11:39:19.143: INFO: namespace : e2e-tests-nslifetest-76-8wdcw api call to delete is complete 
I1110 11:39:19.189553   37417 request.go:641] Throttling request took 136.136639ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-37-bnwl4
Nov 10 11:39:19.195: INFO: namespace : e2e-tests-nslifetest-37-bnwl4 api call to delete is complete 
I1110 11:39:19.241521   37417 request.go:641] Throttling request took 188.038159ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-38-br92s
Nov 10 11:39:19.247: INFO: namespace : e2e-tests-nslifetest-38-br92s api call to delete is complete 
I1110 11:39:19.289558   37417 request.go:641] Throttling request took 236.031476ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-77-rltcn
Nov 10 11:39:19.294: INFO: namespace : e2e-tests-nslifetest-77-rltcn api call to delete is complete 
I1110 11:39:19.339603   37417 request.go:641] Throttling request took 286.027992ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-39-vlxzh
Nov 10 11:39:19.344: INFO: namespace : e2e-tests-nslifetest-39-vlxzh api call to delete is complete 
I1110 11:39:19.389530   37417 request.go:641] Throttling request took 335.808086ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-89-gdtbw
Nov 10 11:39:19.392: INFO: namespace : e2e-tests-nslifetest-89-gdtbw api call to delete is complete 
I1110 11:39:19.439565   37417 request.go:641] Throttling request took 385.823534ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-4-kdfbg
Nov 10 11:39:19.443: INFO: namespace : e2e-tests-nslifetest-4-kdfbg api call to delete is complete 
I1110 11:39:19.489540   37417 request.go:641] Throttling request took 435.754386ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-88-99fqq
Nov 10 11:39:19.492: INFO: namespace : e2e-tests-nslifetest-88-99fqq api call to delete is complete 
I1110 11:39:19.539796   37417 request.go:641] Throttling request took 485.897162ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-40-bgr4t
Nov 10 11:39:19.545: INFO: namespace : e2e-tests-nslifetest-40-bgr4t api call to delete is complete 
I1110 11:39:19.589523   37417 request.go:641] Throttling request took 535.580974ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-78-cs827
Nov 10 11:39:19.592: INFO: namespace : e2e-tests-nslifetest-78-cs827 api call to delete is complete 
I1110 11:39:19.639570   37417 request.go:641] Throttling request took 585.53615ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-9-mdtvq
Nov 10 11:39:19.642: INFO: namespace : e2e-tests-nslifetest-9-mdtvq api call to delete is complete 
I1110 11:39:19.689520   37417 request.go:641] Throttling request took 635.476773ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-41-6dkvb
Nov 10 11:39:19.692: INFO: namespace : e2e-tests-nslifetest-41-6dkvb api call to delete is complete 
I1110 11:39:19.739519   37417 request.go:641] Throttling request took 685.438606ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-79-58dgx
Nov 10 11:39:19.742: INFO: namespace : e2e-tests-nslifetest-79-58dgx api call to delete is complete 
I1110 11:39:19.789519   37417 request.go:641] Throttling request took 735.374591ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-42-lfvwv
Nov 10 11:39:19.792: INFO: namespace : e2e-tests-nslifetest-42-lfvwv api call to delete is complete 
I1110 11:39:19.839513   37417 request.go:641] Throttling request took 785.340292ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-8-zcvq4
Nov 10 11:39:19.842: INFO: namespace : e2e-tests-nslifetest-8-zcvq4 api call to delete is complete 
I1110 11:39:19.889521   37417 request.go:641] Throttling request took 835.27254ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-43-5x645
Nov 10 11:39:19.893: INFO: namespace : e2e-tests-nslifetest-43-5x645 api call to delete is complete 
I1110 11:39:19.939578   37417 request.go:641] Throttling request took 885.308761ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-80-zqd99
Nov 10 11:39:19.942: INFO: namespace : e2e-tests-nslifetest-80-zqd99 api call to delete is complete 
I1110 11:39:19.989542   37417 request.go:641] Throttling request took 935.263614ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-90-h8q4s
Nov 10 11:39:19.994: INFO: namespace : e2e-tests-nslifetest-90-h8q4s api call to delete is complete 
I1110 11:39:20.039530   37417 request.go:641] Throttling request took 985.174322ms, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-44-k95w6
Nov 10 11:39:20.043: INFO: namespace : e2e-tests-nslifetest-44-k95w6 api call to delete is complete 
I1110 11:39:20.089522   37417 request.go:641] Throttling request took 1.035162793s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-81-r48z4
Nov 10 11:39:20.092: INFO: namespace : e2e-tests-nslifetest-81-r48z4 api call to delete is complete 
I1110 11:39:20.139576   37417 request.go:641] Throttling request took 1.085128926s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-91-zrpm4
Nov 10 11:39:20.142: INFO: namespace : e2e-tests-nslifetest-91-zrpm4 api call to delete is complete 
I1110 11:39:20.191938   37417 request.go:641] Throttling request took 1.137474706s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-82-np24z
Nov 10 11:39:20.196: INFO: namespace : e2e-tests-nslifetest-82-np24z api call to delete is complete 
I1110 11:39:20.239588   37417 request.go:641] Throttling request took 1.18501138s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-46-24bv7
Nov 10 11:39:20.242: INFO: namespace : e2e-tests-nslifetest-46-24bv7 api call to delete is complete 
I1110 11:39:20.289521   37417 request.go:641] Throttling request took 1.234887833s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-92-dfvdb
Nov 10 11:39:20.292: INFO: namespace : e2e-tests-nslifetest-92-dfvdb api call to delete is complete 
I1110 11:39:20.339592   37417 request.go:641] Throttling request took 1.28490081s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-47-jp5k4
Nov 10 11:39:20.343: INFO: namespace : e2e-tests-nslifetest-47-jp5k4 api call to delete is complete 
I1110 11:39:20.389528   37417 request.go:641] Throttling request took 1.334776271s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-93-fb6q5
Nov 10 11:39:20.392: INFO: namespace : e2e-tests-nslifetest-93-fb6q5 api call to delete is complete 
I1110 11:39:20.439568   37417 request.go:641] Throttling request took 1.384765079s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-48-9ntwd
Nov 10 11:39:20.443: INFO: namespace : e2e-tests-nslifetest-48-9ntwd api call to delete is complete 
I1110 11:39:20.489521   37417 request.go:641] Throttling request took 1.434611621s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-49-wsq9k
Nov 10 11:39:20.492: INFO: namespace : e2e-tests-nslifetest-49-wsq9k api call to delete is complete 
I1110 11:39:20.539532   37417 request.go:641] Throttling request took 1.484604807s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-94-56fxt
Nov 10 11:39:20.542: INFO: namespace : e2e-tests-nslifetest-94-56fxt api call to delete is complete 
I1110 11:39:20.589522   37417 request.go:641] Throttling request took 1.534490946s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-5-msgkb
Nov 10 11:39:20.592: INFO: namespace : e2e-tests-nslifetest-5-msgkb api call to delete is complete 
I1110 11:39:20.639522   37417 request.go:641] Throttling request took 1.584465259s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-95-bt5hc
Nov 10 11:39:20.642: INFO: namespace : e2e-tests-nslifetest-95-bt5hc api call to delete is complete 
I1110 11:39:20.689531   37417 request.go:641] Throttling request took 1.634364398s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-50-bttdj
Nov 10 11:39:20.692: INFO: namespace : e2e-tests-nslifetest-50-bttdj api call to delete is complete 
I1110 11:39:20.739597   37417 request.go:641] Throttling request took 1.684264747s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-96-b5pwq
Nov 10 11:39:20.743: INFO: namespace : e2e-tests-nslifetest-96-b5pwq api call to delete is complete 
I1110 11:39:20.789527   37417 request.go:641] Throttling request took 1.734178721s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-51-g746g
Nov 10 11:39:20.792: INFO: namespace : e2e-tests-nslifetest-51-g746g api call to delete is complete 
I1110 11:39:20.839543   37417 request.go:641] Throttling request took 1.784064007s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-97-2zqt9
Nov 10 11:39:20.843: INFO: namespace : e2e-tests-nslifetest-97-2zqt9 api call to delete is complete 
I1110 11:39:20.889533   37417 request.go:641] Throttling request took 1.833978668s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-52-j6rng
Nov 10 11:39:20.894: INFO: namespace : e2e-tests-nslifetest-52-j6rng api call to delete is complete 
I1110 11:39:20.939594   37417 request.go:641] Throttling request took 1.883959351s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-53-qks68
Nov 10 11:39:20.943: INFO: namespace : e2e-tests-nslifetest-53-qks68 api call to delete is complete 
I1110 11:39:20.989679   37417 request.go:641] Throttling request took 1.933974719s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-54-jq9pk
Nov 10 11:39:20.993: INFO: namespace : e2e-tests-nslifetest-54-jq9pk api call to delete is complete 
I1110 11:39:21.039530   37417 request.go:641] Throttling request took 1.983818854s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-98-hzcdg
Nov 10 11:39:21.042: INFO: namespace : e2e-tests-nslifetest-98-hzcdg api call to delete is complete 
I1110 11:39:21.089554   37417 request.go:641] Throttling request took 2.033713068s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-55-2np64
Nov 10 11:39:21.093: INFO: namespace : e2e-tests-nslifetest-55-2np64 api call to delete is complete 
I1110 11:39:21.139622   37417 request.go:641] Throttling request took 2.083757411s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-56-bqd4k
Nov 10 11:39:21.143: INFO: namespace : e2e-tests-nslifetest-56-bqd4k api call to delete is complete 
I1110 11:39:21.189542   37417 request.go:641] Throttling request took 2.13367669s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-85-x8qqn
Nov 10 11:39:21.192: INFO: namespace : e2e-tests-nslifetest-85-x8qqn api call to delete is complete 
I1110 11:39:21.239612   37417 request.go:641] Throttling request took 2.183588405s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-83-trpbh
Nov 10 11:39:21.243: INFO: namespace : e2e-tests-nslifetest-83-trpbh api call to delete is complete 
I1110 11:39:21.289555   37417 request.go:641] Throttling request took 2.233508542s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-57-2fsh7
Nov 10 11:39:21.293: INFO: namespace : e2e-tests-nslifetest-57-2fsh7 api call to delete is complete 
I1110 11:39:21.339616   37417 request.go:641] Throttling request took 2.283550226s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-84-lkl48
Nov 10 11:39:21.343: INFO: namespace : e2e-tests-nslifetest-84-lkl48 api call to delete is complete 
I1110 11:39:21.389535   37417 request.go:641] Throttling request took 2.333458018s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-58-8rdlw
Nov 10 11:39:21.393: INFO: namespace : e2e-tests-nslifetest-58-8rdlw api call to delete is complete 
I1110 11:39:21.439694   37417 request.go:641] Throttling request took 2.383515768s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-86-l2zhv
Nov 10 11:39:21.444: INFO: namespace : e2e-tests-nslifetest-86-l2zhv api call to delete is complete 
I1110 11:39:21.489539   37417 request.go:641] Throttling request took 2.433352102s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-87-9zc6m
Nov 10 11:39:21.493: INFO: namespace : e2e-tests-nslifetest-87-9zc6m api call to delete is complete 
I1110 11:39:21.539542   37417 request.go:641] Throttling request took 2.483306383s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-59-cxkgb
Nov 10 11:39:21.543: INFO: namespace : e2e-tests-nslifetest-59-cxkgb api call to delete is complete 
I1110 11:39:21.592133   37417 request.go:641] Throttling request took 2.537666976s, request: DELETE:https://ip-172-18-15-242.ec2.internal:8443/api/v1/namespaces/e2e-tests-nslifetest-45-2ww2k
Nov 10 11:39:21.596: INFO: namespace : e2e-tests-nslifetest-45-2ww2k api call to delete is complete 
STEP: Waiting for namespaces to vanish
Nov 10 11:39:23.602: INFO: Remaining namespaces : 100
Nov 10 11:39:25.613: INFO: Remaining namespaces : 95
Nov 10 11:39:29.298: INFO: Remaining namespaces : 90
Nov 10 11:39:29.606: INFO: Remaining namespaces : 90
Nov 10 11:39:34.388: INFO: Remaining namespaces : 80
Nov 10 11:39:35.616: INFO: Remaining namespaces : 76
Nov 10 11:39:39.109: INFO: Remaining namespaces : 72
Nov 10 11:39:39.615: INFO: Remaining namespaces : 70
Nov 10 11:39:44.100: INFO: Remaining namespaces : 68
Nov 10 11:39:48.658: INFO: Remaining namespaces : 60
Nov 10 11:39:49.608: INFO: Remaining namespaces : 50
Nov 10 11:39:53.368: INFO: Remaining namespaces : 50
Nov 10 11:39:53.609: INFO: Remaining namespaces : 50
Nov 10 11:39:57.896: INFO: Remaining namespaces : 40
Nov 10 11:40:02.499: INFO: Remaining namespaces : 30
Nov 10 11:40:03.606: INFO: Remaining namespaces : 22
Nov 10 11:40:07.150: INFO: Remaining namespaces : 20
Nov 10 11:40:07.625: INFO: Remaining namespaces : 20
[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 11:40:12.274: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-namespaces-j6g98" for this suite.
Nov 10 11:40:18.362: INFO: namespace: e2e-tests-namespaces-j6g98, resource: bindings, ignored listing per whitelist
Nov 10 11:40:18.454: INFO: namespace e2e-tests-namespaces-j6g98 deletion completed in 6.165024423s
STEP: Destroying namespace "e2e-tests-nslifetest-99-6f6w9" for this suite.
Nov 10 11:40:18.456: INFO: Namespace e2e-tests-nslifetest-99-6f6w9 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-0-m2qh5" for this suite.
Nov 10 11:40:18.458: INFO: Namespace e2e-tests-nslifetest-0-m2qh5 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-48-9ntwd" for this suite.
Nov 10 11:40:18.461: INFO: Namespace e2e-tests-nslifetest-48-9ntwd was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-49-wsq9k" for this suite.
Nov 10 11:40:18.462: INFO: Namespace e2e-tests-nslifetest-49-wsq9k was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-2-n69sp" for this suite.
Nov 10 11:40:18.464: INFO: Namespace e2e-tests-nslifetest-2-n69sp was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-1-72cl4" for this suite.
Nov 10 11:40:18.466: INFO: Namespace e2e-tests-nslifetest-1-72cl4 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-3-55v5n" for this suite.
Nov 10 11:40:18.468: INFO: Namespace e2e-tests-nslifetest-3-55v5n was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-50-bttdj" for this suite.
Nov 10 11:40:18.470: INFO: Namespace e2e-tests-nslifetest-50-bttdj was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-51-g746g" for this suite.
Nov 10 11:40:18.472: INFO: Namespace e2e-tests-nslifetest-51-g746g was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-6-jwx7l" for this suite.
Nov 10 11:40:18.474: INFO: Namespace e2e-tests-nslifetest-6-jwx7l was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-59-cxkgb" for this suite.
Nov 10 11:40:18.476: INFO: Namespace e2e-tests-nslifetest-59-cxkgb was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-54-jq9pk" for this suite.
Nov 10 11:40:18.478: INFO: Namespace e2e-tests-nslifetest-54-jq9pk was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-4-kdfbg" for this suite.
Nov 10 11:40:18.480: INFO: Namespace e2e-tests-nslifetest-4-kdfbg was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-5-msgkb" for this suite.
Nov 10 11:40:18.482: INFO: Namespace e2e-tests-nslifetest-5-msgkb was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-12-wgzdm" for this suite.
Nov 10 11:40:18.484: INFO: Namespace e2e-tests-nslifetest-12-wgzdm was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-52-j6rng" for this suite.
Nov 10 11:40:18.486: INFO: Namespace e2e-tests-nslifetest-52-j6rng was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-13-995x2" for this suite.
Nov 10 11:40:18.487: INFO: Namespace e2e-tests-nslifetest-13-995x2 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-53-qks68" for this suite.
Nov 10 11:40:18.489: INFO: Namespace e2e-tests-nslifetest-53-qks68 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-7-5wg66" for this suite.
Nov 10 11:40:18.491: INFO: Namespace e2e-tests-nslifetest-7-5wg66 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-8-zcvq4" for this suite.
Nov 10 11:40:18.493: INFO: Namespace e2e-tests-nslifetest-8-zcvq4 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-15-rfhq4" for this suite.
Nov 10 11:40:18.495: INFO: Namespace e2e-tests-nslifetest-15-rfhq4 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-55-2np64" for this suite.
Nov 10 11:40:18.543: INFO: Namespace e2e-tests-nslifetest-55-2np64 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-9-mdtvq" for this suite.
Nov 10 11:40:18.591: INFO: Namespace e2e-tests-nslifetest-9-mdtvq was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-56-bqd4k" for this suite.
Nov 10 11:40:18.641: INFO: Namespace e2e-tests-nslifetest-56-bqd4k was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-57-2fsh7" for this suite.
Nov 10 11:40:18.691: INFO: Namespace e2e-tests-nslifetest-57-2fsh7 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-10-ppg7h" for this suite.
Nov 10 11:40:18.741: INFO: Namespace e2e-tests-nslifetest-10-ppg7h was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-11-8lt4l" for this suite.
Nov 10 11:40:18.791: INFO: Namespace e2e-tests-nslifetest-11-8lt4l was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-58-8rdlw" for this suite.
Nov 10 11:40:18.842: INFO: Namespace e2e-tests-nslifetest-58-8rdlw was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-14-9pd4p" for this suite.
Nov 10 11:40:18.892: INFO: Namespace e2e-tests-nslifetest-14-9pd4p was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-61-t95r7" for this suite.
Nov 10 11:40:18.941: INFO: Namespace e2e-tests-nslifetest-61-t95r7 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-63-5tx4c" for this suite.
Nov 10 11:40:18.991: INFO: Namespace e2e-tests-nslifetest-63-5tx4c was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-62-j8gkz" for this suite.
Nov 10 11:40:19.041: INFO: Namespace e2e-tests-nslifetest-62-j8gkz was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-64-vrvkm" for this suite.
Nov 10 11:40:19.091: INFO: Namespace e2e-tests-nslifetest-64-vrvkm was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-20-fdkjw" for this suite.
Nov 10 11:40:19.142: INFO: Namespace e2e-tests-nslifetest-20-fdkjw was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-16-dmzjb" for this suite.
Nov 10 11:40:19.191: INFO: Namespace e2e-tests-nslifetest-16-dmzjb was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-65-j54jd" for this suite.
Nov 10 11:40:19.241: INFO: Namespace e2e-tests-nslifetest-65-j54jd was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-17-5bnxp" for this suite.
Nov 10 11:40:19.292: INFO: Namespace e2e-tests-nslifetest-17-5bnxp was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-66-m9b24" for this suite.
Nov 10 11:40:19.341: INFO: Namespace e2e-tests-nslifetest-66-m9b24 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-60-xx5qx" for this suite.
Nov 10 11:40:19.391: INFO: Namespace e2e-tests-nslifetest-60-xx5qx was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-18-r6w8q" for this suite.
Nov 10 11:40:19.441: INFO: Namespace e2e-tests-nslifetest-18-r6w8q was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-67-f4swp" for this suite.
Nov 10 11:40:19.491: INFO: Namespace e2e-tests-nslifetest-67-f4swp was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-68-sxd2t" for this suite.
Nov 10 11:40:19.541: INFO: Namespace e2e-tests-nslifetest-68-sxd2t was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-21-7b9rm" for this suite.
Nov 10 11:40:19.591: INFO: Namespace e2e-tests-nslifetest-21-7b9rm was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-19-ptntk" for this suite.
Nov 10 11:40:19.642: INFO: Namespace e2e-tests-nslifetest-19-ptntk was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-70-2gh2r" for this suite.
Nov 10 11:40:19.691: INFO: Namespace e2e-tests-nslifetest-70-2gh2r was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-23-6rz2z" for this suite.
Nov 10 11:40:19.741: INFO: Namespace e2e-tests-nslifetest-23-6rz2z was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-22-n2h2d" for this suite.
Nov 10 11:40:19.791: INFO: Namespace e2e-tests-nslifetest-22-n2h2d was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-73-nt7rl" for this suite.
Nov 10 11:40:19.841: INFO: Namespace e2e-tests-nslifetest-73-nt7rl was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-72-zwvqb" for this suite.
Nov 10 11:40:19.891: INFO: Namespace e2e-tests-nslifetest-72-zwvqb was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-69-m6vf7" for this suite.
Nov 10 11:40:19.941: INFO: Namespace e2e-tests-nslifetest-69-m6vf7 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-74-nnmv8" for this suite.
Nov 10 11:40:19.991: INFO: Namespace e2e-tests-nslifetest-74-nnmv8 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-75-bzl2q" for this suite.
Nov 10 11:40:20.041: INFO: Namespace e2e-tests-nslifetest-75-bzl2q was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-24-tstcx" for this suite.
Nov 10 11:40:20.091: INFO: Namespace e2e-tests-nslifetest-24-tstcx was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-76-8wdcw" for this suite.
Nov 10 11:40:20.141: INFO: Namespace e2e-tests-nslifetest-76-8wdcw was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-77-rltcn" for this suite.
Nov 10 11:40:20.191: INFO: Namespace e2e-tests-nslifetest-77-rltcn was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-71-n8lmk" for this suite.
Nov 10 11:40:20.241: INFO: Namespace e2e-tests-nslifetest-71-n8lmk was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-25-vmfll" for this suite.
Nov 10 11:40:20.291: INFO: Namespace e2e-tests-nslifetest-25-vmfll was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-78-cs827" for this suite.
Nov 10 11:40:20.341: INFO: Namespace e2e-tests-nslifetest-78-cs827 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-79-58dgx" for this suite.
Nov 10 11:40:20.391: INFO: Namespace e2e-tests-nslifetest-79-58dgx was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-26-n99rr" for this suite.
Nov 10 11:40:20.441: INFO: Namespace e2e-tests-nslifetest-26-n99rr was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-80-zqd99" for this suite.
Nov 10 11:40:20.491: INFO: Namespace e2e-tests-nslifetest-80-zqd99 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-27-rskm7" for this suite.
Nov 10 11:40:20.541: INFO: Namespace e2e-tests-nslifetest-27-rskm7 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-81-r48z4" for this suite.
Nov 10 11:40:20.591: INFO: Namespace e2e-tests-nslifetest-81-r48z4 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-28-g29dp" for this suite.
Nov 10 11:40:20.641: INFO: Namespace e2e-tests-nslifetest-28-g29dp was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-82-np24z" for this suite.
Nov 10 11:40:20.691: INFO: Namespace e2e-tests-nslifetest-82-np24z was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-29-r2skn" for this suite.
Nov 10 11:40:20.741: INFO: Namespace e2e-tests-nslifetest-29-r2skn was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-83-trpbh" for this suite.
Nov 10 11:40:20.791: INFO: Namespace e2e-tests-nslifetest-83-trpbh was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-84-lkl48" for this suite.
Nov 10 11:40:20.841: INFO: Namespace e2e-tests-nslifetest-84-lkl48 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-30-92wtw" for this suite.
Nov 10 11:40:20.891: INFO: Namespace e2e-tests-nslifetest-30-92wtw was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-31-l59n5" for this suite.
Nov 10 11:40:20.942: INFO: Namespace e2e-tests-nslifetest-31-l59n5 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-85-x8qqn" for this suite.
Nov 10 11:40:20.991: INFO: Namespace e2e-tests-nslifetest-85-x8qqn was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-32-7gqjm" for this suite.
Nov 10 11:40:21.041: INFO: Namespace e2e-tests-nslifetest-32-7gqjm was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-86-l2zhv" for this suite.
Nov 10 11:40:21.091: INFO: Namespace e2e-tests-nslifetest-86-l2zhv was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-33-fd7j4" for this suite.
Nov 10 11:40:21.141: INFO: Namespace e2e-tests-nslifetest-33-fd7j4 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-87-9zc6m" for this suite.
Nov 10 11:40:21.191: INFO: Namespace e2e-tests-nslifetest-87-9zc6m was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-34-ghfrr" for this suite.
Nov 10 11:40:21.241: INFO: Namespace e2e-tests-nslifetest-34-ghfrr was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-88-99fqq" for this suite.
Nov 10 11:40:21.291: INFO: Namespace e2e-tests-nslifetest-88-99fqq was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-35-h6qv2" for this suite.
Nov 10 11:40:21.341: INFO: Namespace e2e-tests-nslifetest-35-h6qv2 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-89-gdtbw" for this suite.
Nov 10 11:40:21.391: INFO: Namespace e2e-tests-nslifetest-89-gdtbw was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-36-bbsnj" for this suite.
Nov 10 11:40:21.441: INFO: Namespace e2e-tests-nslifetest-36-bbsnj was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-90-h8q4s" for this suite.
Nov 10 11:40:21.491: INFO: Namespace e2e-tests-nslifetest-90-h8q4s was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-91-zrpm4" for this suite.
Nov 10 11:40:21.541: INFO: Namespace e2e-tests-nslifetest-91-zrpm4 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-37-bnwl4" for this suite.
Nov 10 11:40:21.591: INFO: Namespace e2e-tests-nslifetest-37-bnwl4 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-92-dfvdb" for this suite.
Nov 10 11:40:21.641: INFO: Namespace e2e-tests-nslifetest-92-dfvdb was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-38-br92s" for this suite.
Nov 10 11:40:21.691: INFO: Namespace e2e-tests-nslifetest-38-br92s was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-39-vlxzh" for this suite.
Nov 10 11:40:21.741: INFO: Namespace e2e-tests-nslifetest-39-vlxzh was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-93-fb6q5" for this suite.
Nov 10 11:40:21.791: INFO: Namespace e2e-tests-nslifetest-93-fb6q5 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-40-bgr4t" for this suite.
Nov 10 11:40:21.841: INFO: Namespace e2e-tests-nslifetest-40-bgr4t was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-94-56fxt" for this suite.
Nov 10 11:40:21.891: INFO: Namespace e2e-tests-nslifetest-94-56fxt was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-95-bt5hc" for this suite.
Nov 10 11:40:21.941: INFO: Namespace e2e-tests-nslifetest-95-bt5hc was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-41-6dkvb" for this suite.
Nov 10 11:40:21.991: INFO: Namespace e2e-tests-nslifetest-41-6dkvb was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-42-lfvwv" for this suite.
Nov 10 11:40:22.041: INFO: Namespace e2e-tests-nslifetest-42-lfvwv was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-96-b5pwq" for this suite.
Nov 10 11:40:22.091: INFO: Namespace e2e-tests-nslifetest-96-b5pwq was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-43-5x645" for this suite.
Nov 10 11:40:22.141: INFO: Namespace e2e-tests-nslifetest-43-5x645 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-97-2zqt9" for this suite.
Nov 10 11:40:22.191: INFO: Namespace e2e-tests-nslifetest-97-2zqt9 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-98-hzcdg" for this suite.
Nov 10 11:40:22.241: INFO: Namespace e2e-tests-nslifetest-98-hzcdg was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-44-k95w6" for this suite.
Nov 10 11:40:22.291: INFO: Namespace e2e-tests-nslifetest-44-k95w6 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-45-2ww2k" for this suite.
Nov 10 11:40:22.341: INFO: Namespace e2e-tests-nslifetest-45-2ww2k was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-46-24bv7" for this suite.
Nov 10 11:40:22.391: INFO: Namespace e2e-tests-nslifetest-46-24bv7 was already deleted
STEP: Destroying namespace "e2e-tests-nslifetest-47-jp5k4" for this suite.
Nov 10 11:40:22.441: INFO: Namespace e2e-tests-nslifetest-47-jp5k4 was already deleted

• [SLOW TEST:77.003 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
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[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 11:40:22.442: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Nov 10 11:40:22.506: 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-sfp54
I1110 11:40:22.565672   37417 runners.go:176] Created replication controller with name: svc-latency-rc, namespace: e2e-tests-svc-latency-sfp54, replica count: 1
I1110 11:40:22.565729   37417 reflector.go:213] Starting reflector *v1.Pod (0s) from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/utils/pod_store.go:53
I1110 11:40:22.565757   37417 reflector.go:251] Listing and watching *v1.Pod from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/utils/pod_store.go:53
I1110 11:40:23.565938   37417 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 11:40:24.566161   37417 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 11:40:25.566389   37417 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 11:40:26.566621   37417 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 11:40:26.566876   37417 reflector.go:213] Starting reflector *v1.Endpoints (0s) from github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/service_latency.go:310
I1110 11:40:26.566903   37417 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 11:40:26.671: INFO: Created: latency-svc-vwfb4
Nov 10 11:40:26.679: INFO: Got endpoints: latency-svc-vwfb4 [12.285922ms]
Nov 10 11:40:26.694: INFO: Created: latency-svc-6vdpr
Nov 10 11:40:26.698: INFO: Created: latency-svc-6pxmv
Nov 10 11:40:26.704: INFO: Got endpoints: latency-svc-6pxmv [23.836416ms]
Nov 10 11:40:26.705: INFO: Got endpoints: latency-svc-6vdpr [25.727284ms]
Nov 10 11:40:26.709: INFO: Created: latency-svc-h9cws
Nov 10 11:40:26.713: INFO: Got endpoints: latency-svc-h9cws [33.299354ms]
Nov 10 11:40:26.716: INFO: Created: latency-svc-hncl8
Nov 10 11:40:26.721: INFO: Got endpoints: latency-svc-hncl8 [40.267678ms]
Nov 10 11:40:26.728: INFO: Created: latency-svc-qqwmm
Nov 10 11:40:26.730: INFO: Created: latency-svc-xnd7j
Nov 10 11:40:26.730: INFO: Got endpoints: latency-svc-xnd7j [51.041851ms]
Nov 10 11:40:26.733: INFO: Got endpoints: latency-svc-qqwmm [52.750094ms]
Nov 10 11:40:26.736: INFO: Created: latency-svc-s2w8m
Nov 10 11:40:26.746: INFO: Created: latency-svc-k4tvp
Nov 10 11:40:26.749: INFO: Created: latency-svc-rxq6s
Nov 10 11:40:26.752: INFO: Got endpoints: latency-svc-s2w8m [72.900384ms]
Nov 10 11:40:26.755: INFO: Got endpoints: latency-svc-rxq6s [74.397329ms]
Nov 10 11:40:26.755: INFO: Got endpoints: latency-svc-k4tvp [76.015073ms]
Nov 10 11:40:26.760: INFO: Created: latency-svc-htvzq
Nov 10 11:40:26.760: INFO: Created: latency-svc-xs9k5
Nov 10 11:40:26.763: INFO: Got endpoints: latency-svc-xs9k5 [83.243541ms]
Nov 10 11:40:26.766: INFO: Created: latency-svc-q69b9
Nov 10 11:40:26.767: INFO: Got endpoints: latency-svc-htvzq [87.033296ms]
Nov 10 11:40:26.771: INFO: Created: latency-svc-x9zvh
Nov 10 11:40:26.774: INFO: Got endpoints: latency-svc-q69b9 [93.382104ms]
Nov 10 11:40:26.778: INFO: Created: latency-svc-2pl9h
Nov 10 11:40:26.779: INFO: Created: latency-svc-8wz5h
Nov 10 11:40:26.783: INFO: Got endpoints: latency-svc-x9zvh [103.271979ms]
Nov 10 11:40:26.789: INFO: Created: latency-svc-8kccg
Nov 10 11:40:26.789: INFO: Got endpoints: latency-svc-8wz5h [108.875252ms]
Nov 10 11:40:26.789: INFO: Got endpoints: latency-svc-2pl9h [108.498699ms]
Nov 10 11:40:26.793: INFO: Got endpoints: latency-svc-8kccg [89.167831ms]
Nov 10 11:40:26.799: INFO: Created: latency-svc-jcrgt
Nov 10 11:40:26.800: INFO: Created: latency-svc-tpbb4
Nov 10 11:40:26.807: INFO: Got endpoints: latency-svc-tpbb4 [102.810975ms]
Nov 10 11:40:26.810: INFO: Created: latency-svc-29j5w
Nov 10 11:40:26.810: INFO: Got endpoints: latency-svc-jcrgt [96.962071ms]
Nov 10 11:40:26.810: INFO: Created: latency-svc-rxx7m
Nov 10 11:40:26.813: INFO: Got endpoints: latency-svc-29j5w [91.898731ms]
Nov 10 11:40:26.830: INFO: Created: latency-svc-vzvvc
Nov 10 11:40:26.831: INFO: Created: latency-svc-bkl9n
Nov 10 11:40:26.831: INFO: Got endpoints: latency-svc-bkl9n [97.736379ms]
Nov 10 11:40:26.831: INFO: Got endpoints: latency-svc-rxx7m [100.449206ms]
Nov 10 11:40:26.837: INFO: Created: latency-svc-c5mvp
Nov 10 11:40:26.837: INFO: Got endpoints: latency-svc-vzvvc [84.490703ms]
Nov 10 11:40:26.839: INFO: Created: latency-svc-6vm9m
Nov 10 11:40:26.841: INFO: Got endpoints: latency-svc-c5mvp [85.942994ms]
Nov 10 11:40:26.845: INFO: Created: latency-svc-2rx59
Nov 10 11:40:26.848: INFO: Got endpoints: latency-svc-2rx59 [81.414099ms]
Nov 10 11:40:26.848: INFO: Got endpoints: latency-svc-6vm9m [92.99611ms]
Nov 10 11:40:26.851: INFO: Created: latency-svc-rlv9c
Nov 10 11:40:26.856: INFO: Got endpoints: latency-svc-rlv9c [92.654247ms]
Nov 10 11:40:26.856: INFO: Created: latency-svc-6f5zt
Nov 10 11:40:26.860: INFO: Created: latency-svc-jwjcm
Nov 10 11:40:26.867: INFO: Got endpoints: latency-svc-jwjcm [83.909188ms]
Nov 10 11:40:26.867: INFO: Got endpoints: latency-svc-6f5zt [93.380212ms]
Nov 10 11:40:26.873: INFO: Created: latency-svc-gbdvd
Nov 10 11:40:26.876: INFO: Got endpoints: latency-svc-gbdvd [87.137492ms]
Nov 10 11:40:26.877: INFO: Created: latency-svc-jzgkb
Nov 10 11:40:26.881: INFO: Created: latency-svc-mljt8
Nov 10 11:40:26.888: INFO: Got endpoints: latency-svc-jzgkb [98.779967ms]
Nov 10 11:40:26.892: INFO: Got endpoints: latency-svc-mljt8 [98.044016ms]
Nov 10 11:40:26.894: INFO: Created: latency-svc-tmm4z
Nov 10 11:40:26.896: INFO: Got endpoints: latency-svc-tmm4z [88.71061ms]
Nov 10 11:40:26.898: INFO: Created: latency-svc-ffktx
Nov 10 11:40:26.906: INFO: Created: latency-svc-czgdp
Nov 10 11:40:26.909: INFO: Created: latency-svc-ljwvp
Nov 10 11:40:26.913: INFO: Created: latency-svc-jmm6n
Nov 10 11:40:26.920: INFO: Got endpoints: latency-svc-ffktx [110.052713ms]
Nov 10 11:40:26.920: INFO: Got endpoints: latency-svc-czgdp [107.417368ms]
Nov 10 11:40:26.921: INFO: Got endpoints: latency-svc-ljwvp [90.068134ms]
Nov 10 11:40:26.923: INFO: Created: latency-svc-wqp2x
Nov 10 11:40:26.926: INFO: Created: latency-svc-dg4fx
Nov 10 11:40:26.931: INFO: Created: latency-svc-smwlw
Nov 10 11:40:26.933: INFO: Created: latency-svc-68smz
Nov 10 11:40:26.936: INFO: Created: latency-svc-qmpbw
Nov 10 11:40:26.940: INFO: Created: latency-svc-2cpnz
Nov 10 11:40:26.946: INFO: Created: latency-svc-mfrdr
Nov 10 11:40:26.949: INFO: Created: latency-svc-msbsd
Nov 10 11:40:26.954: INFO: Created: latency-svc-wnwr5
Nov 10 11:40:26.955: INFO: Got endpoints: latency-svc-jmm6n [123.90495ms]
Nov 10 11:40:26.960: INFO: Created: latency-svc-g6lp5
Nov 10 11:40:26.965: INFO: Created: latency-svc-k4tbc
Nov 10 11:40:26.967: INFO: Created: latency-svc-8wc5j
Nov 10 11:40:26.971: INFO: Created: latency-svc-68c6v
Nov 10 11:40:26.978: INFO: Created: latency-svc-8vsrr
Nov 10 11:40:26.980: INFO: Created: latency-svc-kqq8r
Nov 10 11:40:27.002: INFO: Got endpoints: latency-svc-wqp2x [164.626733ms]
Nov 10 11:40:27.007: INFO: Created: latency-svc-xbfh7
Nov 10 11:40:27.057: INFO: Got endpoints: latency-svc-dg4fx [216.26472ms]
Nov 10 11:40:27.066: INFO: Created: latency-svc-2tfvb
Nov 10 11:40:27.102: INFO: Got endpoints: latency-svc-smwlw [254.019941ms]
Nov 10 11:40:27.110: INFO: Created: latency-svc-c6rc7
Nov 10 11:40:27.152: INFO: Got endpoints: latency-svc-68smz [303.951136ms]
Nov 10 11:40:27.165: INFO: Created: latency-svc-nts55
Nov 10 11:40:27.203: INFO: Got endpoints: latency-svc-qmpbw [347.038547ms]
Nov 10 11:40:27.209: INFO: Created: latency-svc-x4hx9
Nov 10 11:40:27.255: INFO: Got endpoints: latency-svc-2cpnz [387.96538ms]
Nov 10 11:40:27.260: INFO: Created: latency-svc-gxfls
Nov 10 11:40:27.301: INFO: Got endpoints: latency-svc-mfrdr [433.63214ms]
Nov 10 11:40:27.307: INFO: Created: latency-svc-4bhvz
Nov 10 11:40:27.353: INFO: Got endpoints: latency-svc-msbsd [476.134846ms]
Nov 10 11:40:27.360: INFO: Created: latency-svc-wl6zb
Nov 10 11:40:27.404: INFO: Got endpoints: latency-svc-wnwr5 [515.418753ms]
Nov 10 11:40:27.412: INFO: Created: latency-svc-zzrvh
Nov 10 11:40:27.452: INFO: Got endpoints: latency-svc-g6lp5 [560.061897ms]
Nov 10 11:40:27.457: INFO: Created: latency-svc-jrpj9
Nov 10 11:40:27.506: INFO: Got endpoints: latency-svc-k4tbc [609.584303ms]
Nov 10 11:40:27.510: INFO: Created: latency-svc-gnxmb
Nov 10 11:40:27.552: INFO: Got endpoints: latency-svc-8wc5j [631.547131ms]
Nov 10 11:40:27.560: INFO: Created: latency-svc-6j5st
Nov 10 11:40:27.603: INFO: Got endpoints: latency-svc-68c6v [681.863856ms]
Nov 10 11:40:27.608: INFO: Created: latency-svc-pf9b2
Nov 10 11:40:27.652: INFO: Got endpoints: latency-svc-8vsrr [731.88929ms]
Nov 10 11:40:27.658: INFO: Created: latency-svc-9tv5d
Nov 10 11:40:27.704: INFO: Got endpoints: latency-svc-kqq8r [749.541947ms]
Nov 10 11:40:27.711: INFO: Created: latency-svc-45zsm
Nov 10 11:40:27.752: INFO: Got endpoints: latency-svc-xbfh7 [750.543539ms]
Nov 10 11:40:27.758: INFO: Created: latency-svc-vskx4
Nov 10 11:40:27.803: INFO: Got endpoints: latency-svc-2tfvb [745.312381ms]
Nov 10 11:40:27.807: INFO: Created: latency-svc-p826p
Nov 10 11:40:27.852: INFO: Got endpoints: latency-svc-c6rc7 [750.04601ms]
Nov 10 11:40:27.859: INFO: Created: latency-svc-m2gwg
Nov 10 11:40:27.907: INFO: Got endpoints: latency-svc-nts55 [755.047005ms]
Nov 10 11:40:27.915: INFO: Created: latency-svc-9x29s
Nov 10 11:40:27.952: INFO: Got endpoints: latency-svc-x4hx9 [748.667416ms]
Nov 10 11:40:27.958: INFO: Created: latency-svc-wgb9b
Nov 10 11:40:28.001: INFO: Got endpoints: latency-svc-gxfls [746.018156ms]
Nov 10 11:40:28.015: INFO: Created: latency-svc-kl2vw
Nov 10 11:40:28.053: INFO: Got endpoints: latency-svc-4bhvz [752.055384ms]
Nov 10 11:40:28.060: INFO: Created: latency-svc-hgs67
Nov 10 11:40:28.105: INFO: Got endpoints: latency-svc-wl6zb [752.913741ms]
Nov 10 11:40:28.111: INFO: Created: latency-svc-fz526
Nov 10 11:40:28.155: INFO: Got endpoints: latency-svc-zzrvh [750.951977ms]
Nov 10 11:40:28.160: INFO: Created: latency-svc-jn8qg
Nov 10 11:40:28.202: INFO: Got endpoints: latency-svc-jrpj9 [750.174549ms]
Nov 10 11:40:28.207: INFO: Created: latency-svc-nt4mt
Nov 10 11:40:28.256: INFO: Got endpoints: latency-svc-gnxmb [750.496327ms]
Nov 10 11:40:28.262: INFO: Created: latency-svc-6zx4t
Nov 10 11:40:28.308: INFO: Got endpoints: latency-svc-6j5st [756.058082ms]
Nov 10 11:40:28.315: INFO: Created: latency-svc-7n8xs
Nov 10 11:40:28.354: INFO: Got endpoints: latency-svc-pf9b2 [751.677005ms]
Nov 10 11:40:28.361: INFO: Created: latency-svc-qvchq
Nov 10 11:40:28.412: INFO: Got endpoints: latency-svc-9tv5d [759.693091ms]
Nov 10 11:40:28.419: INFO: Created: latency-svc-25vbl
Nov 10 11:40:28.450: INFO: Got endpoints: latency-svc-45zsm [746.325414ms]
Nov 10 11:40:28.461: INFO: Created: latency-svc-xzls2
Nov 10 11:40:28.504: INFO: Got endpoints: latency-svc-vskx4 [751.733993ms]
Nov 10 11:40:28.512: INFO: Created: latency-svc-7j69d
Nov 10 11:40:28.552: INFO: Got endpoints: latency-svc-p826p [749.652124ms]
Nov 10 11:40:28.560: INFO: Created: latency-svc-b9gdv
Nov 10 11:40:28.613: INFO: Got endpoints: latency-svc-m2gwg [760.89155ms]
Nov 10 11:40:28.619: INFO: Created: latency-svc-gdcf9
Nov 10 11:40:28.653: INFO: Got endpoints: latency-svc-9x29s [745.708115ms]
Nov 10 11:40:28.660: INFO: Created: latency-svc-shkjw
Nov 10 11:40:28.703: INFO: Got endpoints: latency-svc-wgb9b [750.996171ms]
Nov 10 11:40:28.710: INFO: Created: latency-svc-cvlhk
Nov 10 11:40:28.751: INFO: Got endpoints: latency-svc-kl2vw [749.976866ms]
Nov 10 11:40:28.758: INFO: Created: latency-svc-jwnbw
Nov 10 11:40:28.803: INFO: Got endpoints: latency-svc-hgs67 [749.622737ms]
Nov 10 11:40:28.809: INFO: Created: latency-svc-7v7kl
Nov 10 11:40:28.855: INFO: Got endpoints: latency-svc-fz526 [749.608897ms]
Nov 10 11:40:28.861: INFO: Created: latency-svc-nzc8d
Nov 10 11:40:28.902: INFO: Got endpoints: latency-svc-jn8qg [747.461916ms]
Nov 10 11:40:28.911: INFO: Created: latency-svc-2xxbw
Nov 10 11:40:28.953: INFO: Got endpoints: latency-svc-nt4mt [751.586051ms]
Nov 10 11:40:28.959: INFO: Created: latency-svc-glwfl
Nov 10 11:40:29.008: INFO: Got endpoints: latency-svc-6zx4t [751.812494ms]
Nov 10 11:40:29.016: INFO: Created: latency-svc-csjrx
Nov 10 11:40:29.054: INFO: Got endpoints: latency-svc-7n8xs [746.301988ms]
Nov 10 11:40:29.060: INFO: Created: latency-svc-c2z4w
Nov 10 11:40:29.102: INFO: Got endpoints: latency-svc-qvchq [748.026841ms]
Nov 10 11:40:29.108: INFO: Created: latency-svc-ff4fn
Nov 10 11:40:29.152: INFO: Got endpoints: latency-svc-25vbl [740.270949ms]
Nov 10 11:40:29.158: INFO: Created: latency-svc-kbrvl
Nov 10 11:40:29.201: INFO: Got endpoints: latency-svc-xzls2 [750.128937ms]
Nov 10 11:40:29.207: INFO: Created: latency-svc-842hv
Nov 10 11:40:29.253: INFO: Got endpoints: latency-svc-7j69d [748.806962ms]
Nov 10 11:40:29.261: INFO: Created: latency-svc-nfptb
Nov 10 11:40:29.303: INFO: Got endpoints: latency-svc-b9gdv [750.562181ms]
Nov 10 11:40:29.310: INFO: Created: latency-svc-dbqg5
Nov 10 11:40:29.352: INFO: Got endpoints: latency-svc-gdcf9 [739.113174ms]
Nov 10 11:40:29.358: INFO: Created: latency-svc-qs2g2
Nov 10 11:40:29.403: INFO: Got endpoints: latency-svc-shkjw [749.661529ms]
Nov 10 11:40:29.411: INFO: Created: latency-svc-bxjf8
Nov 10 11:40:29.451: INFO: Got endpoints: latency-svc-cvlhk [748.17954ms]
Nov 10 11:40:29.463: INFO: Created: latency-svc-2xdcl
Nov 10 11:40:29.502: INFO: Got endpoints: latency-svc-jwnbw [750.580615ms]
Nov 10 11:40:29.507: INFO: Created: latency-svc-2xh77
Nov 10 11:40:29.551: INFO: Got endpoints: latency-svc-7v7kl [748.488225ms]
Nov 10 11:40:29.557: INFO: Created: latency-svc-bxcn7
Nov 10 11:40:29.605: INFO: Got endpoints: latency-svc-nzc8d [749.982947ms]
Nov 10 11:40:29.611: INFO: Created: latency-svc-4svr9
Nov 10 11:40:29.655: INFO: Got endpoints: latency-svc-2xxbw [752.823514ms]
Nov 10 11:40:29.661: INFO: Created: latency-svc-8lm5r
Nov 10 11:40:29.702: INFO: Got endpoints: latency-svc-glwfl [748.07862ms]
Nov 10 11:40:29.706: INFO: Created: latency-svc-wwvx4
Nov 10 11:40:29.755: INFO: Got endpoints: latency-svc-csjrx [747.220679ms]
Nov 10 11:40:29.762: INFO: Created: latency-svc-zpdmp
Nov 10 11:40:29.802: INFO: Got endpoints: latency-svc-c2z4w [747.951123ms]
Nov 10 11:40:29.809: INFO: Created: latency-svc-gbxqb
Nov 10 11:40:29.858: INFO: Got endpoints: latency-svc-ff4fn [755.401393ms]
Nov 10 11:40:29.867: INFO: Created: latency-svc-nzq7z
Nov 10 11:40:29.903: INFO: Got endpoints: latency-svc-kbrvl [750.178893ms]
Nov 10 11:40:29.915: INFO: Created: latency-svc-rlhkg
Nov 10 11:40:29.952: INFO: Got endpoints: latency-svc-842hv [751.524779ms]
Nov 10 11:40:29.959: INFO: Created: latency-svc-qggm8
Nov 10 11:40:30.006: INFO: Got endpoints: latency-svc-nfptb [753.262286ms]
Nov 10 11:40:30.014: INFO: Created: latency-svc-p9tjc
Nov 10 11:40:30.055: INFO: Got endpoints: latency-svc-dbqg5 [751.467793ms]
Nov 10 11:40:30.061: INFO: Created: latency-svc-dwqn5
Nov 10 11:40:30.111: INFO: Got endpoints: latency-svc-qs2g2 [758.834413ms]
Nov 10 11:40:30.119: INFO: Created: latency-svc-56z6q
Nov 10 11:40:30.154: INFO: Got endpoints: latency-svc-bxjf8 [751.262376ms]
Nov 10 11:40:30.160: INFO: Created: latency-svc-hcnpk
Nov 10 11:40:30.207: INFO: Got endpoints: latency-svc-2xdcl [755.976507ms]
Nov 10 11:40:30.213: INFO: Created: latency-svc-zldfj
Nov 10 11:40:30.254: INFO: Got endpoints: latency-svc-2xh77 [752.300644ms]
Nov 10 11:40:30.264: INFO: Created: latency-svc-lnbd5
Nov 10 11:40:30.304: INFO: Got endpoints: latency-svc-bxcn7 [752.751987ms]
Nov 10 11:40:30.309: INFO: Created: latency-svc-qbx6v
Nov 10 11:40:30.350: INFO: Got endpoints: latency-svc-4svr9 [744.971987ms]
Nov 10 11:40:30.357: INFO: Created: latency-svc-q2m26
Nov 10 11:40:30.403: INFO: Got endpoints: latency-svc-8lm5r [748.080558ms]
Nov 10 11:40:30.408: INFO: Created: latency-svc-tz8r4
Nov 10 11:40:30.456: INFO: Got endpoints: latency-svc-wwvx4 [754.037286ms]
Nov 10 11:40:30.464: INFO: Created: latency-svc-n85ql
Nov 10 11:40:30.503: INFO: Got endpoints: latency-svc-zpdmp [747.661055ms]
Nov 10 11:40:30.514: INFO: Created: latency-svc-mprlq
Nov 10 11:40:30.553: INFO: Got endpoints: latency-svc-gbxqb [750.917851ms]
Nov 10 11:40:30.566: INFO: Created: latency-svc-b7dvt
Nov 10 11:40:30.606: INFO: Got endpoints: latency-svc-nzq7z [748.137277ms]
Nov 10 11:40:30.622: INFO: Created: latency-svc-p5q75
Nov 10 11:40:30.653: INFO: Got endpoints: latency-svc-rlhkg [750.481117ms]
Nov 10 11:40:30.666: INFO: Created: latency-svc-kkxtv
Nov 10 11:40:30.702: INFO: Got endpoints: latency-svc-qggm8 [749.928999ms]
Nov 10 11:40:30.708: INFO: Created: latency-svc-xlzbk
Nov 10 11:40:30.756: INFO: Got endpoints: latency-svc-p9tjc [750.044437ms]
Nov 10 11:40:30.767: INFO: Created: latency-svc-xx442
Nov 10 11:40:30.802: INFO: Got endpoints: latency-svc-dwqn5 [747.301752ms]
Nov 10 11:40:30.808: INFO: Created: latency-svc-tcsc5
Nov 10 11:40:30.854: INFO: Got endpoints: latency-svc-56z6q [742.648352ms]
Nov 10 11:40:30.859: INFO: Created: latency-svc-hlnmv
Nov 10 11:40:30.902: INFO: Got endpoints: latency-svc-hcnpk [747.9519ms]
Nov 10 11:40:30.908: INFO: Created: latency-svc-7zldp
Nov 10 11:40:30.954: INFO: Got endpoints: latency-svc-zldfj [747.04095ms]
Nov 10 11:40:30.962: INFO: Created: latency-svc-v8xcv
Nov 10 11:40:31.003: INFO: Got endpoints: latency-svc-lnbd5 [748.996889ms]
Nov 10 11:40:31.009: INFO: Created: latency-svc-rv7sh
Nov 10 11:40:31.055: INFO: Got endpoints: latency-svc-qbx6v [751.025277ms]
Nov 10 11:40:31.062: INFO: Created: latency-svc-b4k55
Nov 10 11:40:31.104: INFO: Got endpoints: latency-svc-q2m26 [753.512256ms]
Nov 10 11:40:31.111: INFO: Created: latency-svc-6bwkm
Nov 10 11:40:31.157: INFO: Got endpoints: latency-svc-tz8r4 [754.000878ms]
Nov 10 11:40:31.170: INFO: Created: latency-svc-w8b5b
Nov 10 11:40:31.202: INFO: Got endpoints: latency-svc-n85ql [746.446517ms]
Nov 10 11:40:31.216: INFO: Created: latency-svc-jmbfc
Nov 10 11:40:31.259: INFO: Got endpoints: latency-svc-mprlq [756.133987ms]
Nov 10 11:40:31.263: INFO: Created: latency-svc-2m6vv
Nov 10 11:40:31.302: INFO: Got endpoints: latency-svc-b7dvt [748.963701ms]
Nov 10 11:40:31.310: INFO: Created: latency-svc-mfsw8
Nov 10 11:40:31.356: INFO: Got endpoints: latency-svc-p5q75 [750.108116ms]
Nov 10 11:40:31.361: INFO: Created: latency-svc-9vbg5
Nov 10 11:40:31.403: INFO: Got endpoints: latency-svc-kkxtv [749.490398ms]
Nov 10 11:40:31.412: INFO: Created: latency-svc-46vqm
Nov 10 11:40:31.454: INFO: Got endpoints: latency-svc-xlzbk [751.620884ms]
Nov 10 11:40:31.461: INFO: Created: latency-svc-v4lqk
Nov 10 11:40:31.504: INFO: Got endpoints: latency-svc-xx442 [747.965358ms]
Nov 10 11:40:31.510: INFO: Created: latency-svc-dhf5w
Nov 10 11:40:31.553: INFO: Got endpoints: latency-svc-tcsc5 [751.273361ms]
Nov 10 11:40:31.559: INFO: Created: latency-svc-p2nvb
Nov 10 11:40:31.603: INFO: Got endpoints: latency-svc-hlnmv [749.468684ms]
Nov 10 11:40:31.610: INFO: Created: latency-svc-5gb7h
Nov 10 11:40:31.652: INFO: Got endpoints: latency-svc-7zldp [750.024937ms]
Nov 10 11:40:31.663: INFO: Created: latency-svc-vwjvr
Nov 10 11:40:31.701: INFO: Got endpoints: latency-svc-v8xcv [747.052749ms]
Nov 10 11:40:31.712: INFO: Created: latency-svc-9tc7k
Nov 10 11:40:31.754: INFO: Got endpoints: latency-svc-rv7sh [750.851094ms]
Nov 10 11:40:31.760: INFO: Created: latency-svc-sxqm7
Nov 10 11:40:31.802: INFO: Got endpoints: latency-svc-b4k55 [746.918213ms]
Nov 10 11:40:31.809: INFO: Created: latency-svc-5wtvk
Nov 10 11:40:31.852: INFO: Got endpoints: latency-svc-6bwkm [748.186441ms]
Nov 10 11:40:31.859: INFO: Created: latency-svc-8chlh
Nov 10 11:40:31.904: INFO: Got endpoints: latency-svc-w8b5b [746.539575ms]
Nov 10 11:40:31.915: INFO: Created: latency-svc-fs2qw
Nov 10 11:40:31.954: INFO: Got endpoints: latency-svc-jmbfc [751.700453ms]
Nov 10 11:40:31.962: INFO: Created: latency-svc-n96qh
Nov 10 11:40:32.007: INFO: Got endpoints: latency-svc-2m6vv [747.348711ms]
Nov 10 11:40:32.013: INFO: Created: latency-svc-gjjvh
Nov 10 11:40:32.051: INFO: Got endpoints: latency-svc-mfsw8 [748.81565ms]
Nov 10 11:40:32.059: INFO: Created: latency-svc-psxd8
Nov 10 11:40:32.103: INFO: Got endpoints: latency-svc-9vbg5 [746.7098ms]
Nov 10 11:40:32.109: INFO: Created: latency-svc-gr5lr
Nov 10 11:40:32.153: INFO: Got endpoints: latency-svc-46vqm [750.11875ms]
Nov 10 11:40:32.163: INFO: Created: latency-svc-r75cq
Nov 10 11:40:32.206: INFO: Got endpoints: latency-svc-v4lqk [752.279457ms]
Nov 10 11:40:32.213: INFO: Created: latency-svc-rfzv2
Nov 10 11:40:32.257: INFO: Got endpoints: latency-svc-dhf5w [753.01337ms]
Nov 10 11:40:32.267: INFO: Created: latency-svc-f7x5j
Nov 10 11:40:32.301: INFO: Got endpoints: latency-svc-p2nvb [748.039802ms]
Nov 10 11:40:32.309: INFO: Created: latency-svc-zkz8z
Nov 10 11:40:32.351: INFO: Got endpoints: latency-svc-5gb7h [747.192731ms]
Nov 10 11:40:32.361: INFO: Created: latency-svc-8sb8s
Nov 10 11:40:32.411: INFO: Got endpoints: latency-svc-vwjvr [758.903879ms]
Nov 10 11:40:32.423: INFO: Created: latency-svc-sldlw
Nov 10 11:40:32.454: INFO: Got endpoints: latency-svc-9tc7k [752.760446ms]
Nov 10 11:40:32.462: INFO: Created: latency-svc-b8b6q
Nov 10 11:40:32.508: INFO: Got endpoints: latency-svc-sxqm7 [753.665809ms]
Nov 10 11:40:32.516: INFO: Created: latency-svc-whwzn
Nov 10 11:40:32.552: INFO: Got endpoints: latency-svc-5wtvk [749.640056ms]
Nov 10 11:40:32.561: INFO: Created: latency-svc-dwvkr
Nov 10 11:40:32.602: INFO: Got endpoints: latency-svc-8chlh [749.560431ms]
Nov 10 11:40:32.609: INFO: Created: latency-svc-6k2r2
Nov 10 11:40:32.650: INFO: Got endpoints: latency-svc-fs2qw [746.326506ms]
Nov 10 11:40:32.656: INFO: Created: latency-svc-759tw
Nov 10 11:40:32.705: INFO: Got endpoints: latency-svc-n96qh [751.097037ms]
Nov 10 11:40:32.712: INFO: Created: latency-svc-jkdvk
Nov 10 11:40:32.759: INFO: Got endpoints: latency-svc-gjjvh [752.438507ms]
Nov 10 11:40:32.766: INFO: Created: latency-svc-79vxv
Nov 10 11:40:32.802: INFO: Got endpoints: latency-svc-psxd8 [751.201673ms]
Nov 10 11:40:32.807: INFO: Created: latency-svc-wwds9
Nov 10 11:40:32.852: INFO: Got endpoints: latency-svc-gr5lr [749.277501ms]
Nov 10 11:40:32.858: INFO: Created: latency-svc-bpd2s
Nov 10 11:40:32.902: INFO: Got endpoints: latency-svc-r75cq [748.971669ms]
Nov 10 11:40:32.909: INFO: Created: latency-svc-qdccw
Nov 10 11:40:32.955: INFO: Got endpoints: latency-svc-rfzv2 [748.927799ms]
Nov 10 11:40:32.963: INFO: Created: latency-svc-s42ws
Nov 10 11:40:33.006: INFO: Got endpoints: latency-svc-f7x5j [749.013056ms]
Nov 10 11:40:33.015: INFO: Created: latency-svc-f5g48
Nov 10 11:40:33.055: INFO: Got endpoints: latency-svc-zkz8z [754.068509ms]
Nov 10 11:40:33.064: INFO: Created: latency-svc-qd4js
Nov 10 11:40:33.102: INFO: Got endpoints: latency-svc-8sb8s [751.087854ms]
Nov 10 11:40:33.107: INFO: Created: latency-svc-kvzcp
Nov 10 11:40:33.153: INFO: Got endpoints: latency-svc-sldlw [741.452752ms]
Nov 10 11:40:33.159: INFO: Created: latency-svc-95xcd
Nov 10 11:40:33.205: INFO: Got endpoints: latency-svc-b8b6q [750.376034ms]
Nov 10 11:40:33.216: INFO: Created: latency-svc-q5wd9
Nov 10 11:40:33.255: INFO: Got endpoints: latency-svc-whwzn [746.872869ms]
Nov 10 11:40:33.261: INFO: Created: latency-svc-99jkt
Nov 10 11:40:33.306: INFO: Got endpoints: latency-svc-dwvkr [754.321578ms]
Nov 10 11:40:33.315: INFO: Created: latency-svc-fqdwd
Nov 10 11:40:33.352: INFO: Got endpoints: latency-svc-6k2r2 [750.425772ms]
Nov 10 11:40:33.357: INFO: Created: latency-svc-npk6n
Nov 10 11:40:33.408: INFO: Got endpoints: latency-svc-759tw [757.563323ms]
Nov 10 11:40:33.417: INFO: Created: latency-svc-2hkqs
Nov 10 11:40:33.454: INFO: Got endpoints: latency-svc-jkdvk [749.087421ms]
Nov 10 11:40:33.462: INFO: Created: latency-svc-2d8p6
Nov 10 11:40:33.502: INFO: Got endpoints: latency-svc-79vxv [743.31488ms]
Nov 10 11:40:33.509: INFO: Created: latency-svc-2nk7b
Nov 10 11:40:33.551: INFO: Got endpoints: latency-svc-wwds9 [748.31695ms]
Nov 10 11:40:33.557: INFO: Created: latency-svc-lq87c
Nov 10 11:40:33.604: INFO: Got endpoints: latency-svc-bpd2s [751.708957ms]
Nov 10 11:40:33.610: INFO: Created: latency-svc-9lbql
Nov 10 11:40:33.660: INFO: Got endpoints: latency-svc-qdccw [758.383935ms]
Nov 10 11:40:33.667: INFO: Created: latency-svc-9wkrp
Nov 10 11:40:33.703: INFO: Got endpoints: latency-svc-s42ws [747.960929ms]
Nov 10 11:40:33.712: INFO: Created: latency-svc-w762k
Nov 10 11:40:33.753: INFO: Got endpoints: latency-svc-f5g48 [746.954527ms]
Nov 10 11:40:33.758: INFO: Created: latency-svc-h5q87
Nov 10 11:40:33.801: INFO: Got endpoints: latency-svc-qd4js [745.281203ms]
Nov 10 11:40:33.807: INFO: Created: latency-svc-s7bwd
Nov 10 11:40:33.855: INFO: Got endpoints: latency-svc-kvzcp [753.128078ms]
Nov 10 11:40:33.862: INFO: Created: latency-svc-pvhqk
Nov 10 11:40:33.907: INFO: Got endpoints: latency-svc-95xcd [753.954967ms]
Nov 10 11:40:33.915: INFO: Created: latency-svc-gt2l5
Nov 10 11:40:33.954: INFO: Got endpoints: latency-svc-q5wd9 [749.370857ms]
Nov 10 11:40:33.963: INFO: Created: latency-svc-ql6k8
Nov 10 11:40:34.001: INFO: Got endpoints: latency-svc-99jkt [746.417862ms]
Nov 10 11:40:34.009: INFO: Created: latency-svc-bk9l8
Nov 10 11:40:34.052: INFO: Got endpoints: latency-svc-fqdwd [745.892175ms]
Nov 10 11:40:34.059: INFO: Created: latency-svc-h9xpw
Nov 10 11:40:34.102: INFO: Got endpoints: latency-svc-npk6n [750.246378ms]
Nov 10 11:40:34.110: INFO: Created: latency-svc-22wb9
Nov 10 11:40:34.157: INFO: Got endpoints: latency-svc-2hkqs [749.660189ms]
Nov 10 11:40:34.163: INFO: Created: latency-svc-rxfv7
Nov 10 11:40:34.209: INFO: Got endpoints: latency-svc-2d8p6 [755.011202ms]
Nov 10 11:40:34.228: INFO: Created: latency-svc-zxm5r
Nov 10 11:40:34.252: INFO: Got endpoints: latency-svc-2nk7b [749.161917ms]
Nov 10 11:40:34.263: INFO: Created: latency-svc-fflv7
Nov 10 11:40:34.306: INFO: Got endpoints: latency-svc-lq87c [755.263609ms]
Nov 10 11:40:34.311: INFO: Created: latency-svc-57hnc
Nov 10 11:40:34.352: INFO: Got endpoints: latency-svc-9lbql [747.879751ms]
Nov 10 11:40:34.359: INFO: Created: latency-svc-bdm64
Nov 10 11:40:34.406: INFO: Got endpoints: latency-svc-9wkrp [746.16601ms]
Nov 10 11:40:34.418: INFO: Created: latency-svc-29mvl
Nov 10 11:40:34.453: INFO: Got endpoints: latency-svc-w762k [749.969267ms]
Nov 10 11:40:34.502: INFO: Got endpoints: latency-svc-h5q87 [748.596855ms]
Nov 10 11:40:34.553: INFO: Got endpoints: latency-svc-s7bwd [752.547305ms]
Nov 10 11:40:34.602: INFO: Got endpoints: latency-svc-pvhqk [747.454481ms]
Nov 10 11:40:34.652: INFO: Got endpoints: latency-svc-gt2l5 [745.178892ms]
Nov 10 11:40:34.701: INFO: Got endpoints: latency-svc-ql6k8 [747.093841ms]
Nov 10 11:40:34.752: INFO: Got endpoints: latency-svc-bk9l8 [750.500269ms]
Nov 10 11:40:34.802: INFO: Got endpoints: latency-svc-h9xpw [749.561103ms]
Nov 10 11:40:34.854: INFO: Got endpoints: latency-svc-22wb9 [752.060583ms]
Nov 10 11:40:34.902: INFO: Got endpoints: latency-svc-rxfv7 [744.682873ms]
Nov 10 11:40:34.953: INFO: Got endpoints: latency-svc-zxm5r [743.265596ms]
Nov 10 11:40:35.004: INFO: Got endpoints: latency-svc-fflv7 [752.474209ms]
Nov 10 11:40:35.058: INFO: Got endpoints: latency-svc-57hnc [752.127484ms]
Nov 10 11:40:35.101: INFO: Got endpoints: latency-svc-bdm64 [748.836682ms]
Nov 10 11:40:35.151: INFO: Got endpoints: latency-svc-29mvl [744.816557ms]
Nov 10 11:40:35.151: INFO: Latencies: [23.836416ms 25.727284ms 33.299354ms 40.267678ms 51.041851ms 52.750094ms 72.900384ms 74.397329ms 76.015073ms 81.414099ms 83.243541ms 83.909188ms 84.490703ms 85.942994ms 87.033296ms 87.137492ms 88.71061ms 89.167831ms 90.068134ms 91.898731ms 92.654247ms 92.99611ms 93.380212ms 93.382104ms 96.962071ms 97.736379ms 98.044016ms 98.779967ms 100.449206ms 102.810975ms 103.271979ms 107.417368ms 108.498699ms 108.875252ms 110.052713ms 123.90495ms 164.626733ms 216.26472ms 254.019941ms 303.951136ms 347.038547ms 387.96538ms 433.63214ms 476.134846ms 515.418753ms 560.061897ms 609.584303ms 631.547131ms 681.863856ms 731.88929ms 739.113174ms 740.270949ms 741.452752ms 742.648352ms 743.265596ms 743.31488ms 744.682873ms 744.816557ms 744.971987ms 745.178892ms 745.281203ms 745.312381ms 745.708115ms 745.892175ms 746.018156ms 746.16601ms 746.301988ms 746.325414ms 746.326506ms 746.417862ms 746.446517ms 746.539575ms 746.7098ms 746.872869ms 746.918213ms 746.954527ms 747.04095ms 747.052749ms 747.093841ms 747.192731ms 747.220679ms 747.301752ms 747.348711ms 747.454481ms 747.461916ms 747.661055ms 747.879751ms 747.951123ms 747.9519ms 747.960929ms 747.965358ms 748.026841ms 748.039802ms 748.07862ms 748.080558ms 748.137277ms 748.17954ms 748.186441ms 748.31695ms 748.488225ms 748.596855ms 748.667416ms 748.806962ms 748.81565ms 748.836682ms 748.927799ms 748.963701ms 748.971669ms 748.996889ms 749.013056ms 749.087421ms 749.161917ms 749.277501ms 749.370857ms 749.468684ms 749.490398ms 749.541947ms 749.560431ms 749.561103ms 749.608897ms 749.622737ms 749.640056ms 749.652124ms 749.660189ms 749.661529ms 749.928999ms 749.969267ms 749.976866ms 749.982947ms 750.024937ms 750.044437ms 750.04601ms 750.108116ms 750.11875ms 750.128937ms 750.174549ms 750.178893ms 750.246378ms 750.376034ms 750.425772ms 750.481117ms 750.496327ms 750.500269ms 750.543539ms 750.562181ms 750.580615ms 750.851094ms 750.917851ms 750.951977ms 750.996171ms 751.025277ms 751.087854ms 751.097037ms 751.201673ms 751.262376ms 751.273361ms 751.467793ms 751.524779ms 751.586051ms 751.620884ms 751.677005ms 751.700453ms 751.708957ms 751.733993ms 751.812494ms 752.055384ms 752.060583ms 752.127484ms 752.279457ms 752.300644ms 752.438507ms 752.474209ms 752.547305ms 752.751987ms 752.760446ms 752.823514ms 752.913741ms 753.01337ms 753.128078ms 753.262286ms 753.512256ms 753.665809ms 753.954967ms 754.000878ms 754.037286ms 754.068509ms 754.321578ms 755.011202ms 755.047005ms 755.263609ms 755.401393ms 755.976507ms 756.058082ms 756.133987ms 757.563323ms 758.383935ms 758.834413ms 758.903879ms 759.693091ms 760.89155ms]
Nov 10 11:40:35.152: INFO: 50 %ile: 748.596855ms
Nov 10 11:40:35.152: INFO: 90 %ile: 753.512256ms
Nov 10 11:40:35.152: INFO: 99 %ile: 759.693091ms
Nov 10 11:40:35.152: 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 11:40:35.152: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-svc-latency-sfp54" for this suite.
Nov 10 11:40:53.264: INFO: namespace: e2e-tests-svc-latency-sfp54, resource: bindings, ignored listing per whitelist
Nov 10 11:40:53.283: INFO: namespace e2e-tests-svc-latency-sfp54 deletion completed in 18.128613714s

• [SLOW TEST:30.841 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
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[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 11:40:53.283: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Nov 10 11:40:53.356: INFO: configPath is now "/tmp/extended-test-registry-migration-jpv72-lrfk4-user.kubeconfig"
Nov 10 11:40:53.356: INFO: The user is now "extended-test-registry-migration-jpv72-lrfk4-user"
Nov 10 11:40:53.356: INFO: Creating project "extended-test-registry-migration-jpv72-lrfk4"
Nov 10 11:40:53.415: INFO: Waiting on permissions in project "extended-test-registry-migration-jpv72-lrfk4" ...
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 11:40:53.432: 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 11:40:53.717: INFO: Running 'oc policy --config=/tmp/extended-test-registry-migration-jpv72-lrfk4-user.kubeconfig --namespace=extended-test-registry-migration-jpv72-lrfk4 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
 ---> 7ef88f52c4f7
Removing intermediate container fb6675e9cc6f
Successfully built 7ef88f52c4f7
Nov 10 11:40:55.207: INFO: Running 'oc whoami --config=/tmp/extended-test-registry-migration-jpv72-lrfk4-user.kubeconfig --namespace=extended-test-registry-migration-jpv72-lrfk4 -t'
The push refers to a repository [172.30.251.173:5000/extended-test-registry-migration-jpv72-lrfk4/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:bb323b3ed4d7c263c2fd592cdd5c6479528bca2d770774766da7c72c9f389352 size: 525
STEP: checking that the image doesn't have the manifest...
STEP: getting image manifest from docker-registry...
Nov 10 11:40:55.789: INFO: Running 'oc whoami --config=/tmp/extended-test-registry-migration-jpv72-lrfk4-user.kubeconfig --namespace=extended-test-registry-migration-jpv72-lrfk4 -t'
pinged registry at https://172.30.251.173: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 11:40:56.033: INFO: Running 'oc whoami --config=/tmp/extended-test-registry-migration-jpv72-lrfk4-user.kubeconfig --namespace=extended-test-registry-migration-jpv72-lrfk4 -t'
pinged registry at https://172.30.251.173: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 11:40:56.463: INFO: Running 'oc whoami --config=/tmp/extended-test-registry-migration-jpv72-lrfk4-user.kubeconfig --namespace=extended-test-registry-migration-jpv72-lrfk4 -t'
pinged registry at https://172.30.251.173:5000, got api versions: [registry/2.0]
STEP: pulling image...
STEP: get secret list err <nil> 
STEP: secret name builder-dockercfg-nqd9w 
STEP: docker cfg token json {"172.30.251.173:5000":{"username":"serviceaccount","password":"eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJleHRlbmRlZC10ZXN0LXJlZ2lzdHJ5LW1pZ3JhdGlvbi1qcHY3Mi1scmZrNCIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJidWlsZGVyLXRva2VuLWRta3R2Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6ImJ1aWxkZXIiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIwMjAzOTEyNS1jNjBjLTExZTctODlhYy0wZTNhYjFlNDFjYWMiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6ZXh0ZW5kZWQtdGVzdC1yZWdpc3RyeS1taWdyYXRpb24tanB2NzItbHJmazQ6YnVpbGRlciJ9.c1TGB3G3l-w2WReMdFwVyvKEbO0c5crpbIPRg8Xklb18QuWWjlpQZM8S1uPswh66v2akAuQ1zpPcCjJLijNTW1YmB1sfldJ9GCHpsNmwLGbZgW8bmwu_E6FjIVfX29zduJxKYegCuC1zk_w1I-GlM2onp_Apy7NxY8OzPglOd1cD6FVRQL877rdYQd1T6YrYfym8cPkOVanIFGI_xQZKauIzlX84tOYCPkx1kOJAntcHfFUI9LcHiA7e76dwWQ6rRCvDDDZR2ckxV5_gXg54hED2FECvRmf2A0NNY9xgmuds2l2-4QF7hQ-j6YTO6ynb3kCfhMY97ol2p9fuG-Hfmg","email":"serviceaccount@example.org","auth":"c2VydmljZWFjY291bnQ6ZXlKaGJHY2lPaUpTVXpJMU5pSXNJblI1Y0NJNklrcFhWQ0o5LmV5SnBjM01pT2lKcmRXSmxjbTVsZEdWekwzTmxjblpwWTJWaFkyTnZkVzUwSWl3aWEzVmlaWEp1WlhSbGN5NXBieTl6WlhKMmFXTmxZV05qYjNWdWRDOXVZVzFsYzNCaFkyVWlPaUpsZUhSbGJtUmxaQzEwWlhOMExYSmxaMmx6ZEhKNUxXMXBaM0poZEdsdmJpMXFjSFkzTWkxc2NtWnJOQ0lzSW10MVltVnlibVYwWlhNdWFXOHZjMlZ5ZG1salpXRmpZMjkxYm5RdmMyVmpjbVYwTG01aGJXVWlPaUppZFdsc1pHVnlMWFJ2YTJWdUxXUnRhM1IySWl3aWEzVmlaWEp1WlhSbGN5NXBieTl6WlhKMmFXTmxZV05qYjNWdWRDOXpaWEoyYVdObExXRmpZMjkxYm5RdWJtRnRaU0k2SW1KMWFXeGtaWElpTENKcmRXSmxjbTVsZEdWekxtbHZMM05sY25acFkyVmhZMk52ZFc1MEwzTmxjblpwWTJVdFlXTmpiM1Z1ZEM1MWFXUWlPaUl3TWpBek9URXlOUzFqTmpCakxURXhaVGN0T0RsaFl5MHdaVE5oWWpGbE5ERmpZV01pTENKemRXSWlPaUp6ZVhOMFpXMDZjMlZ5ZG1salpXRmpZMjkxYm5RNlpYaDBaVzVrWldRdGRHVnpkQzF5WldkcGMzUnllUzF0YVdkeVlYUnBiMjR0YW5CMk56SXRiSEptYXpRNlluVnBiR1JsY2lKOS5jMVRHQjNHM2wtdzJXUmVNZEZ3Vnl2S0ViTzBjNWNycGJJUFJnOFhrbGIxOFF1V1dqbHBRWk04UzF1UHN3aDY2djJha0F1UTF6cFBjQ2pKTGlqTlRXMVltQjFzZmxkSjlHQ0hwc05td0xHYlpnVzhibXd1X0U2RmpJVmZYMjl6ZHVKeEtZZWdDdUMxemtfdzFJLUdsTTJvbnBfQXB5N054WThPelBnbE9kMWNENkZWUlFMODc3cmRZUWQxVDZZcllmeW04Y1BrT1ZhbklGR0lfeFFaS2F1SXpsWDg0dE9ZQ1BreDFrT0pBbnRjSGZGVUk5TGNIaUE3ZTc2ZHdXUTZyUkN2REREWlIyY2t4VjVfZ1hnNTRoRUQyRkVDdlJtZjJBME5OWTl4Z211ZHMybDItNFFGN2hRLWo2WVRPNnluYjNrQ2ZoTVk5N29sMnA5ZnVHLUhmbWc="},"docker-registry.default.svc:5000":{"username":"serviceaccount","password":"eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJleHRlbmRlZC10ZXN0LXJlZ2lzdHJ5LW1pZ3JhdGlvbi1qcHY3Mi1scmZrNCIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJidWlsZGVyLXRva2VuLWRta3R2Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6ImJ1aWxkZXIiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIwMjAzOTEyNS1jNjBjLTExZTctODlhYy0wZTNhYjFlNDFjYWMiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6ZXh0ZW5kZWQtdGVzdC1yZWdpc3RyeS1taWdyYXRpb24tanB2NzItbHJmazQ6YnVpbGRlciJ9.c1TGB3G3l-w2WReMdFwVyvKEbO0c5crpbIPRg8Xklb18QuWWjlpQZM8S1uPswh66v2akAuQ1zpPcCjJLijNTW1YmB1sfldJ9GCHpsNmwLGbZgW8bmwu_E6FjIVfX29zduJxKYegCuC1zk_w1I-GlM2onp_Apy7NxY8OzPglOd1cD6FVRQL877rdYQd1T6YrYfym8cPkOVanIFGI_xQZKauIzlX84tOYCPkx1kOJAntcHfFUI9LcHiA7e76dwWQ6rRCvDDDZR2ckxV5_gXg54hED2FECvRmf2A0NNY9xgmuds2l2-4QF7hQ-j6YTO6ynb3kCfhMY97ol2p9fuG-Hfmg","email":"serviceaccount@example.org","auth":"c2VydmljZWFjY291bnQ6ZXlKaGJHY2lPaUpTVXpJMU5pSXNJblI1Y0NJNklrcFhWQ0o5LmV5SnBjM01pT2lKcmRXSmxjbTVsZEdWekwzTmxjblpwWTJWaFkyTnZkVzUwSWl3aWEzVmlaWEp1WlhSbGN5NXBieTl6WlhKMmFXTmxZV05qYjNWdWRDOXVZVzFsYzNCaFkyVWlPaUpsZUhSbGJtUmxaQzEwWlhOMExYSmxaMmx6ZEhKNUxXMXBaM0poZEdsdmJpMXFjSFkzTWkxc2NtWnJOQ0lzSW10MVltVnlibVYwWlhNdWFXOHZjMlZ5ZG1salpXRmpZMjkxYm5RdmMyVmpjbVYwTG01aGJXVWlPaUppZFdsc1pHVnlMWFJ2YTJWdUxXUnRhM1IySWl3aWEzVmlaWEp1WlhSbGN5NXBieTl6WlhKMmFXTmxZV05qYjNWdWRDOXpaWEoyYVdObExXRmpZMjkxYm5RdWJtRnRaU0k2SW1KMWFXeGtaWElpTENKcmRXSmxjbTVsZEdWekxtbHZMM05sY25acFkyVmhZMk52ZFc1MEwzTmxjblpwWTJVdFlXTmpiM1Z1ZEM1MWFXUWlPaUl3TWpBek9URXlOUzFqTmpCakxURXhaVGN0T0RsaFl5MHdaVE5oWWpGbE5ERmpZV01pTENKemRXSWlPaUp6ZVhOMFpXMDZjMlZ5ZG1salpXRmpZMjkxYm5RNlpYaDBaVzVrWldRdGRHVnpkQzF5WldkcGMzUnllUzF0YVdkeVlYUnBiMjR0YW5CMk56SXRiSEptYXpRNlluVnBiR1JsY2lKOS5jMVRHQjNHM2wtdzJXUmVNZEZ3Vnl2S0ViTzBjNWNycGJJUFJnOFhrbGIxOFF1V1dqbHBRWk04UzF1UHN3aDY2djJha0F1UTF6cFBjQ2pKTGlqTlRXMVltQjFzZmxkSjlHQ0hwc05td0xHYlpnVzhibXd1X0U2RmpJVmZYMjl6ZHVKeEtZZWdDdUMxemtfdzFJLUdsTTJvbnBfQXB5N054WThPelBnbE9kMWNENkZWUlFMODc3cmRZUWQxVDZZcllmeW04Y1BrT1ZhbklGR0lfeFFaS2F1SXpsWDg0dE9ZQ1BreDFrT0pBbnRjSGZGVUk5TGNIaUE3ZTc2ZHdXUTZyUkN2REREWlIyY2t4VjVfZ1hnNTRoRUQyRkVDdlJtZjJBME5OWTl4Z211ZHMybDItNFFGN2hRLWo2WVRPNnluYjNrQ2ZoTVk5N29sMnA5ZnVHLUhmbWc="}} 
STEP: json unmarshal err <nil> 
STEP: found auth true with auth cfg len 1 
STEP: dockercfg with svrAddr 172.30.251.173:5000 user serviceaccount pass eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJleHRlbmRlZC10ZXN0LXJlZ2lzdHJ5LW1pZ3JhdGlvbi1qcHY3Mi1scmZrNCIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJidWlsZGVyLXRva2VuLWRta3R2Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6ImJ1aWxkZXIiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIwMjAzOTEyNS1jNjBjLTExZTctODlhYy0wZTNhYjFlNDFjYWMiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6ZXh0ZW5kZWQtdGVzdC1yZWdpc3RyeS1taWdyYXRpb24tanB2NzItbHJmazQ6YnVpbGRlciJ9.c1TGB3G3l-w2WReMdFwVyvKEbO0c5crpbIPRg8Xklb18QuWWjlpQZM8S1uPswh66v2akAuQ1zpPcCjJLijNTW1YmB1sfldJ9GCHpsNmwLGbZgW8bmwu_E6FjIVfX29zduJxKYegCuC1zk_w1I-GlM2onp_Apy7NxY8OzPglOd1cD6FVRQL877rdYQd1T6YrYfym8cPkOVanIFGI_xQZKauIzlX84tOYCPkx1kOJAntcHfFUI9LcHiA7e76dwWQ6rRCvDDDZR2ckxV5_gXg54hED2FECvRmf2A0NNY9xgmuds2l2-4QF7hQ-j6YTO6ynb3kCfhMY97ol2p9fuG-Hfmg email serviceaccount@example.org 
STEP: removing image...
Nov 10 11:40:57.030: 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 11:40:57.386: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "extended-test-registry-migration-jpv72-lrfk4" for this suite.
Nov 10 11:41:03.445: INFO: namespace: extended-test-registry-migration-jpv72-lrfk4, resource: bindings, ignored listing per whitelist
Nov 10 11:41:03.534: INFO: namespace extended-test-registry-migration-jpv72-lrfk4 deletion completed in 6.145067116s
[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.250 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
------------------------------
SSSSSSSSSSSSSSNov 10 11:41:03.534: INFO: Running AfterSuite actions on all node
Nov 10 11:41:03.534: INFO: Running AfterSuite actions on node 1

Ran 6 of 805 Specs in 212.717 seconds
SUCCESS! -- 6 Passed | 0 Failed | 0 Pending | 799 Skipped Nov 10 11:41:03.543: 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 3m33.354434325s
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-15-242.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 265d6629f9f1012c383788f3638edc4a543c96e57f847406c7ce738c583f914b. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container 27c9c25d2a2e8174e1efcec969526a6101e28cd2f703e9452b7f9cdbd7cf664a. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container d882a5550148082c7e306933c5df6e3f3282dfa632e910742a308cee7964c65b. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container 528534d1b1d8dd26b9d87068c2196d6d2d9c8b695b6f054b604ac63a5a26330e. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container d41c11a843ecd80623ae0018eb0710715b51adffefe0fc7190a06d6417b41d52. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container 0a2671c4c091f02716ce18be72dd0332c5e7fa1ed5eff4152901f548b2702027. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container 8d81571f0e31ed5d091a06d4821045794f7f9ab4c4e46d042dc8dab0a7438b34. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container 76aaa01fc655537bb486c4b6c4b40a424384ae588a5ab1677290bdf9046fb176. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container 6c6f4d9ced606b1e1b863a3f6ffd8337182e5c027e73db6d9254a2264ce0b504. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container 7da043da7b431812ff46a6341754c95283572155d65f933adc5315dbf1969605. Stop the container before attempting removal or use -f
Error response from daemon: You cannot remove a running container 9650f74e13bf59765ebb14faeb70412cb5c4a6b34906e12d173584aaecd89433. 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-20171110105748’: Permission denied
rm: cannot remove ‘/tmp/etcd/openshift-backup-pre-upgrade-20171110105724’: Permission denied
rm: cannot remove ‘/tmp/etcd/member’: Permission denied
[INFO] test/extended/conformance.sh exited with code 0 after 00h 38m 57s
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: RUN EXTENDED TESTS [00h 40m 21s] ##########
[PostBuildScript] - Execution post build scripts.
[workspace] $ /bin/bash /tmp/jenkins8663170740762915051.sh
########## STARTING STAGE: DOWNLOAD ARTIFACTS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/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_pull_request_origin_extended_conformance_install_update/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/artifacts/gathered
+ rm -rf /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/artifacts/gathered
+ mkdir -p /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/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: 88173974    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 11:01:02.296425046 +0000
Modify: 2017-11-10 11:02:29.428949151 +0000
Change: 2017-11-10 11:02:29.428949151 +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_pull_request_origin_extended_conformance_install_update/workspace/artifacts/gathered
+ tree /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/artifacts/gathered
/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/artifacts/gathered
└── scripts
    ├── ansible_junit
    │   ├── ceTOORsTwh.xml
    │   ├── gungvOxavX.xml
    │   └── IjlJtGrAid.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_014e8740-c607-11e7-89ac-0e3ab1e41cac_0.log
    │   │   │   ├── k8s_alertmanager_prometheus-0_kube-system_014e8740-c607-11e7-89ac-0e3ab1e41cac_0.log
    │   │   │   ├── k8s_alerts-proxy_prometheus-0_kube-system_014e8740-c607-11e7-89ac-0e3ab1e41cac_0.log
    │   │   │   ├── k8s_POD_docker-registry-1-1d9ww_default_7d062abc-c605-11e7-abed-0e3ab1e41cac_0.log
    │   │   │   ├── k8s_POD_docker-registry-2-tkwcm_default_6d492cb8-c606-11e7-89ac-0e3ab1e41cac_0.log
    │   │   │   ├── k8s_POD_prometheus-0_kube-system_014e8740-c607-11e7-89ac-0e3ab1e41cac_0.log
    │   │   │   ├── k8s_POD_registry-console-1-brdnk_default_932f71c4-c605-11e7-abed-0e3ab1e41cac_0.log
    │   │   │   ├── k8s_POD_registry-console-1-gmsqx_default_2c8db31c-c606-11e7-89ac-0e3ab1e41cac_0.log
    │   │   │   ├── k8s_POD_router-2-hxbnw_default_6cc4916e-c606-11e7-89ac-0e3ab1e41cac_0.log
    │   │   │   ├── k8s_POD_svc-latency-rc-kz24k_e2e-tests-svc-latency-sfp54_efa7bb9a-c60b-11e7-89ac-0e3ab1e41cac_0.log
    │   │   │   ├── k8s_prometheus_prometheus-0_kube-system_014e8740-c607-11e7-89ac-0e3ab1e41cac_0.log
    │   │   │   ├── k8s_prom-proxy_prometheus-0_kube-system_014e8740-c607-11e7-89ac-0e3ab1e41cac_0.log
    │   │   │   ├── k8s_registry-console_registry-console-1-gmsqx_default_2c8db31c-c606-11e7-89ac-0e3ab1e41cac_0.log
    │   │   │   ├── k8s_registry_docker-registry-2-tkwcm_default_6d492cb8-c606-11e7-89ac-0e3ab1e41cac_0.log
    │   │   │   └── k8s_router_router-2-hxbnw_default_6cc4916e-c606-11e7-89ac-0e3ab1e41cac_0.log
    │   │   └── scripts.log
    │   └── openshift.local.home
    ├── env
    │   ├── artifacts
    │   ├── logs
    │   │   └── scripts.log
    │   └── openshift.local.home
    ├── origin_version
    │   ├── artifacts
    │   ├── logs
    │   └── openshift.local.home
    ├── tmp.OILL7NHhPM
    │   ├── artifacts
    │   ├── logs
    │   └── openshift.local.home
    └── tmp.yKSVFw73Mg
        ├── artifacts
        ├── logs
        └── openshift.local.home

32 directories, 28 files
+ exit 0
[workspace] $ /bin/bash /tmp/jenkins818393416849728512.sh
########## STARTING STAGE: GENERATE ARTIFACTS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/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_pull_request_origin_extended_conformance_install_update/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/artifacts/generated
+ rm -rf /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/artifacts/generated
+ mkdir /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/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_pull_request_origin_extended_conformance_install_update/workspace/artifacts/generated
/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/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] $ /bin/bash /tmp/jenkins1486351351331442831.sh
########## STARTING STAGE: FETCH SYSTEMD JOURNALS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/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_pull_request_origin_extended_conformance_install_update/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/artifacts/journals
+ rm -rf /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/artifacts/journals
+ mkdir /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/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_pull_request_origin_extended_conformance_install_update/workspace/artifacts/journals
/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/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] $ /bin/bash /tmp/jenkins3483158407037857069.sh
########## STARTING STAGE: FORWARD PARAMETERS TO THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/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_pull_request_origin_extended_conformance_install_update/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/.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_pull_request_origin_extended_conformance_install_update/9206/'\'' >> /etc/environment'
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: FORWARD PARAMETERS TO THE REMOTE HOST [00h 00m 01s] ##########
[workspace] $ /bin/bash /tmp/jenkins7381821239641931609.sh
########## STARTING STAGE: RECORD THE ENDING METADATA ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/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_pull_request_origin_extended_conformance_install_update/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/.config
++ mktemp
+ script=/tmp/tmp.lekAycoFWS
+ cat
+ chmod +x /tmp/tmp.lekAycoFWS
+ scp -F ./.config/origin-ci-tool/inventory/.ssh_config /tmp/tmp.lekAycoFWS openshiftdevel:/tmp/tmp.lekAycoFWS
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config -t openshiftdevel 'bash -l -c "timeout 300 /tmp/tmp.lekAycoFWS"'
+ 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 00s] ##########
[workspace] $ /bin/bash /tmp/jenkins1336844084220963520.sh
########## STARTING STAGE: ASSEMBLE GCS OUTPUT ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/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_pull_request_origin_extended_conformance_install_update/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/.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_pull_request_origin_extended_conformance_install_update/builds/9206/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 1d437096-c5ff-11e7-80ad-0a58ac100437 ]]
++ suffix=test_pull_request_origin_extended_conformance_install_update/9206/
++ [[ -n openshift ]]
++ [[ origin != \o\r\i\g\i\n ]]
++ [[ -n 17247 ]]
++ prefix=pr-logs/pull/
++ segment=17247/
++ echo gs://origin-ci-test/pr-logs/pull/17247/test_pull_request_origin_extended_conformance_install_update/9206/
++ return 0
+ path=gs://origin-ci-test/pr-logs/pull/17247/test_pull_request_origin_extended_conformance_install_update/9206/
+ gsutil -m cp -r gcs/artifacts gcs/build-log.txt gcs/finished.json gcs/started.json gs://origin-ci-test/pr-logs/pull/17247/test_pull_request_origin_extended_conformance_install_update/9206/
Copying file://gcs/artifacts/generated/docker.info [Content-Type=application/octet-stream]...
Copying file://gcs/artifacts/generated/installed_packages.log [Content-Type=text/plain]...
Copying file://gcs/artifacts/generated/master-metrics.log [Content-Type=text/plain]...
Copying file://gcs/artifacts/generated/avc_denials.log [Content-Type=text/plain]...
Copying file://gcs/artifacts/generated/docker.config [Content-Type=application/octet-stream]...
/ [0/52 files][    0.0 B/909.6 MiB]   0% Done                                   
/ [0/52 files][    0.0 B/909.6 MiB]   0% Done                                   
/ [0/52 files][    0.0 B/909.6 MiB]   0% Done                                   
/ [0/52 files][    0.0 B/909.6 MiB]   0% Done                                   
/ [0/52 files][    0.0 B/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/generated/filesystem.info [Content-Type=application/octet-stream]...
/ [0/52 files][    0.0 B/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/generated/node-metrics.log [Content-Type=text/plain]...
/ [0/52 files][    0.0 B/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/generated/openshift_ansible_package_history.log [Content-Type=text/plain]...
/ [0/52 files][    0.0 B/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/generated/origin_package_history.log [Content-Type=text/plain]...
/ [0/52 files][    0.0 B/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/generated/pid1.journal [Content-Type=application/octet-stream]...
/ [0/52 files][    0.0 B/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/journals/origin-master-controllers.service [Content-Type=application/octet-stream]...
/ [0/52 files][    0.0 B/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/journals/origin-master.service [Content-Type=application/octet-stream]...
/ [0/52 files][    0.0 B/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/journals/docker.service [Content-Type=application/octet-stream]...
/ [0/52 files][ 97.9 KiB/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/journals/dnsmasq.service [Content-Type=application/octet-stream]...
/ [0/52 files][ 97.9 KiB/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/journals/openvswitch.service [Content-Type=application/octet-stream]...
Copying file://gcs/artifacts/journals/origin-node.service [Content-Type=application/octet-stream]...
/ [0/52 files][ 97.9 KiB/909.6 MiB]   0% Done                                   
/ [0/52 files][ 97.9 KiB/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/journals/etcd.service [Content-Type=application/octet-stream]...
/ [0/52 files][361.3 KiB/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/journals/origin-master-api.service [Content-Type=application/octet-stream]...
/ [0/52 files][361.3 KiB/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/journals/ovs-vswitchd.service [Content-Type=application/octet-stream]...
/ [0/52 files][361.3 KiB/909.6 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.

Copying file://gcs/artifacts/journals/ovsdb-server.service [Content-Type=application/octet-stream]...
/ [0/52 files][  2.5 MiB/909.6 MiB]   0% Done                                   
/ [1/52 files][  3.7 MiB/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/journals/systemd-journald.service [Content-Type=application/octet-stream]...
/ [1/52 files][  3.7 MiB/909.6 MiB]   0% Done                                   
/ [2/52 files][  4.2 MiB/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/scripts/env/logs/scripts.log [Content-Type=text/plain]...
/ [2/52 files][  4.4 MiB/909.6 MiB]   0% Done                                   
/ [3/52 files][  5.5 MiB/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/scripts/build-images/logs/scripts.log [Content-Type=text/plain]...
/ [3/52 files][  6.2 MiB/909.6 MiB]   0% Done                                   
/ [4/52 files][  8.1 MiB/909.6 MiB]   0% Done                                   
Copying file://gcs/artifacts/scripts/ansible_junit/ceTOORsTwh.xml [Content-Type=text/xml]...
/ [4/52 files][  8.4 MiB/909.6 MiB]   0% Done                                   
/ [5/52 files][  9.9 MiB/909.6 MiB]   1% Done                                   
/ [6/52 files][  9.9 MiB/909.6 MiB]   1% Done                                   
/ [7/52 files][  9.9 MiB/909.6 MiB]   1% Done                                   
Copying file://gcs/artifacts/scripts/conformance/logs/scripts.log [Content-Type=text/plain]...
Copying file://gcs/artifacts/scripts/ansible_junit/IjlJtGrAid.xml [Content-Type=text/xml]...
/ [7/52 files][  9.9 MiB/909.6 MiB]   1% Done                                   
/ [7/52 files][  9.9 MiB/909.6 MiB]   1% Done                                   
Copying file://gcs/artifacts/scripts/ansible_junit/gungvOxavX.xml [Content-Type=text/xml]...
/ [8/52 files][  9.9 MiB/909.6 MiB]   1% Done                                   
/ [8/52 files][ 10.2 MiB/909.6 MiB]   1% Done                                   
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_POD_svc-latency-rc-kz24k_e2e-tests-svc-latency-sfp54_efa7bb9a-c60b-11e7-89ac-0e3ab1e41cac_0.log [Content-Type=text/plain]...
/ [8/52 files][ 10.9 MiB/909.6 MiB]   1% Done                                   
-
- [9/52 files][ 11.5 MiB/909.6 MiB]   1% Done                                   
- [10/52 files][ 11.5 MiB/909.6 MiB]   1% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_alert-buffer_prometheus-0_kube-system_014e8740-c607-11e7-89ac-0e3ab1e41cac_0.log [Content-Type=text/plain]...
- [10/52 files][ 11.7 MiB/909.6 MiB]   1% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_alertmanager_prometheus-0_kube-system_014e8740-c607-11e7-89ac-0e3ab1e41cac_0.log [Content-Type=text/plain]...
- [10/52 files][ 12.0 MiB/909.6 MiB]   1% Done                                  
- [11/52 files][ 12.0 MiB/909.6 MiB]   1% Done                                  
- [12/52 files][ 12.0 MiB/909.6 MiB]   1% Done                                  
- [13/52 files][ 12.0 MiB/909.6 MiB]   1% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_alerts-proxy_prometheus-0_kube-system_014e8740-c607-11e7-89ac-0e3ab1e41cac_0.log [Content-Type=text/plain]...
- [13/52 files][ 12.2 MiB/909.6 MiB]   1% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_prometheus_prometheus-0_kube-system_014e8740-c607-11e7-89ac-0e3ab1e41cac_0.log [Content-Type=text/plain]...
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_prom-proxy_prometheus-0_kube-system_014e8740-c607-11e7-89ac-0e3ab1e41cac_0.log [Content-Type=text/plain]...
- [13/52 files][ 12.2 MiB/909.6 MiB]   1% Done                                  
- [13/52 files][ 12.5 MiB/909.6 MiB]   1% Done                                  
- [14/52 files][ 13.8 MiB/909.6 MiB]   1% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_POD_prometheus-0_kube-system_014e8740-c607-11e7-89ac-0e3ab1e41cac_0.log [Content-Type=text/plain]...
- [14/52 files][ 16.5 MiB/909.6 MiB]   1% Done                                  
- [15/52 files][ 18.8 MiB/909.6 MiB]   2% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_router_router-2-hxbnw_default_6cc4916e-c606-11e7-89ac-0e3ab1e41cac_0.log [Content-Type=text/plain]...
- [15/52 files][ 19.3 MiB/909.6 MiB]   2% Done                                  
- [16/52 files][ 19.3 MiB/909.6 MiB]   2% Done                                  
- [17/52 files][ 20.3 MiB/909.6 MiB]   2% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_POD_router-2-hxbnw_default_6cc4916e-c606-11e7-89ac-0e3ab1e41cac_0.log [Content-Type=text/plain]...
- [17/52 files][ 21.2 MiB/909.6 MiB]   2% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_registry_docker-registry-2-tkwcm_default_6d492cb8-c606-11e7-89ac-0e3ab1e41cac_0.log [Content-Type=text/plain]...
- [17/52 files][ 21.7 MiB/909.6 MiB]   2% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_POD_docker-registry-2-tkwcm_default_6d492cb8-c606-11e7-89ac-0e3ab1e41cac_0.log [Content-Type=text/plain]...
- [18/52 files][ 23.5 MiB/909.6 MiB]   2% Done                                  
- [18/52 files][ 23.8 MiB/909.6 MiB]   2% Done                                  
- [19/52 files][ 23.8 MiB/909.6 MiB]   2% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_registry-console_registry-console-1-gmsqx_default_2c8db31c-c606-11e7-89ac-0e3ab1e41cac_0.log [Content-Type=text/plain]...
- [19/52 files][ 25.3 MiB/909.6 MiB]   2% Done                                  
- [20/52 files][ 31.0 MiB/909.6 MiB]   3% Done                                  
- [21/52 files][ 31.0 MiB/909.6 MiB]   3% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_POD_registry-console-1-gmsqx_default_2c8db31c-c606-11e7-89ac-0e3ab1e41cac_0.log [Content-Type=text/plain]...
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_POD_registry-console-1-brdnk_default_932f71c4-c605-11e7-abed-0e3ab1e41cac_0.log [Content-Type=text/plain]...
- [21/52 files][ 33.9 MiB/909.6 MiB]   3% Done                                  
- [21/52 files][ 33.9 MiB/909.6 MiB]   3% Done                                  
- [22/52 files][ 33.9 MiB/909.6 MiB]   3% Done                                  
- [23/52 files][ 35.2 MiB/909.6 MiB]   3% Done                                  
Copying file://gcs/artifacts/scripts/conformance/logs/containers/k8s_POD_docker-registry-1-1d9ww_default_7d062abc-c605-11e7-abed-0e3ab1e41cac_0.log [Content-Type=text/plain]...
- [23/52 files][ 35.9 MiB/909.6 MiB]   3% Done                                  
- [24/52 files][ 38.0 MiB/909.6 MiB]   4% Done                                  
Copying file://gcs/artifacts/scripts/conformance/artifacts/events.txt [Content-Type=text/plain]...
- [24/52 files][ 38.5 MiB/909.6 MiB]   4% Done                                  
Copying file://gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_01.xml [Content-Type=text/xml]...
- [25/52 files][ 39.5 MiB/909.6 MiB]   4% Done                                  
- [25/52 files][ 39.5 MiB/909.6 MiB]   4% Done                                  
- [26/52 files][ 40.3 MiB/909.6 MiB]   4% Done                                  
Copying file://gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_04.xml [Content-Type=text/xml]...
- [26/52 files][ 41.6 MiB/909.6 MiB]   4% Done                                  
Copying file://gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_05.xml [Content-Type=text/xml]...
- [26/52 files][ 42.4 MiB/909.6 MiB]   4% Done                                  
- [27/52 files][ 43.4 MiB/909.6 MiB]   4% Done                                  
- [28/52 files][ 43.7 MiB/909.6 MiB]   4% Done                                  
- [29/52 files][ 43.7 MiB/909.6 MiB]   4% Done                                  
- [30/52 files][ 43.7 MiB/909.6 MiB]   4% Done                                  
- [31/52 files][ 43.7 MiB/909.6 MiB]   4% Done                                  
- [32/52 files][ 44.7 MiB/909.6 MiB]   4% Done                                  
- [33/52 files][ 44.7 MiB/909.6 MiB]   4% Done                                  
Copying file://gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_03.xml [Content-Type=text/xml]...
Copying file://gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_02.xml [Content-Type=text/xml]...
- [33/52 files][ 45.0 MiB/909.6 MiB]   4% Done                                  
- [33/52 files][ 46.0 MiB/909.6 MiB]   5% Done                                  
Copying file://gcs/artifacts/scripts/conformance/artifacts/junit/conformance_serial_01.xml [Content-Type=text/xml]...
\
\ [33/52 files][ 46.5 MiB/909.6 MiB]   5% Done                                  
\ [34/52 files][ 47.6 MiB/909.6 MiB]   5% Done                                  
\ [35/52 files][ 49.2 MiB/909.6 MiB]   5% Done                                  
Copying file://gcs/started.json [Content-Type=application/json]...
Copying file://gcs/build-log.txt [Content-Type=text/plain]...
\ [35/52 files][ 53.0 MiB/909.6 MiB]   5% Done                                  
Copying file://gcs/finished.json [Content-Type=application/json]...
\ [35/52 files][ 53.3 MiB/909.6 MiB]   5% Done                                  
\ [35/52 files][ 53.6 MiB/909.6 MiB]   5% Done                                  
\ [36/52 files][ 60.1 MiB/909.6 MiB]   6% Done                                  
\ [37/52 files][ 60.9 MiB/909.6 MiB]   6% Done                                  
\ [38/52 files][ 62.4 MiB/909.6 MiB]   6% Done                                  
\ [39/52 files][ 63.4 MiB/909.6 MiB]   6% Done                                  
\ [40/52 files][ 63.4 MiB/909.6 MiB]   6% Done                                  
\ [41/52 files][ 65.5 MiB/909.6 MiB]   7% Done                                  
\ [42/52 files][ 70.4 MiB/909.6 MiB]   7% Done                                  
\ [43/52 files][ 71.9 MiB/909.6 MiB]   7% Done                                  
\ [44/52 files][ 74.3 MiB/909.6 MiB]   8% Done                                  
\ [45/52 files][ 76.5 MiB/909.6 MiB]   8% Done                                  
\ [46/52 files][ 79.6 MiB/909.6 MiB]   8% Done                                  
|
| [47/52 files][100.5 MiB/909.6 MiB]  11% Done                                  
/
/ [48/52 files][125.4 MiB/909.6 MiB]  13% Done                                  
/ [49/52 files][133.6 MiB/909.6 MiB]  14% Done                                  
-
- [49/52 files][195.5 MiB/909.6 MiB]  21% Done                                  
\
|
| [49/52 files][258.7 MiB/909.6 MiB]  28% Done                                  
/
/ [50/52 files][282.6 MiB/909.6 MiB]  31% Done                                  
-
- [51/52 files][308.9 MiB/909.6 MiB]  33% Done                                  
\
|
| [51/52 files][339.1 MiB/909.6 MiB]  37% Done                                  
/
-
- [51/52 files][386.8 MiB/909.6 MiB]  42% Done                                  
\
\ [51/52 files][439.1 MiB/909.6 MiB]  48% Done                                  
|
/
/ [51/52 files][499.2 MiB/909.6 MiB]  54% Done                                  
-
\
\ [51/52 files][557.4 MiB/909.6 MiB]  61% Done                                  
|
| [51/52 files][616.0 MiB/909.6 MiB]  67% Done  55.2 MiB/s ETA 00:00:05         
/
-
- [51/52 files][665.2 MiB/909.6 MiB]  73% Done  55.5 MiB/s ETA 00:00:04         
\
\ [51/52 files][692.8 MiB/909.6 MiB]  76% Done  50.8 MiB/s ETA 00:00:04         
|
/
/ [51/52 files][749.8 MiB/909.6 MiB]  82% Done  50.0 MiB/s ETA 00:00:03         
-
\
\ [51/52 files][803.1 MiB/909.6 MiB]  88% Done  49.0 MiB/s ETA 00:00:02         
|
| [51/52 files][861.6 MiB/909.6 MiB]  94% Done  49.0 MiB/s ETA 00:00:01         
/
-
- [52/52 files][909.6 MiB/909.6 MiB] 100% Done  47.2 MiB/s ETA 00:00:00         
\
Operation completed over 52 objects/909.6 MiB.                                   
+ exit 0
[workspace] $ /bin/bash /tmp/jenkins5165186588046838125.sh
########## STARTING STAGE: DEPROVISION CLOUD RESOURCES ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/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_pull_request_origin_extended_conformance_install_update/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/.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 06:44:25.577267", 
    "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 06:44:25.583532", 
    "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 06:44:26.511201", 
    "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 06:44:27.185325", 
    "msg": "Tags {'Name': 'oct-terminate'} created for resource i-04110c3313394f3eb."
}

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 06:44:28.148433", 
    "instance_ids": [
        "i-04110c3313394f3eb"
    ], 
    "instances": [
        {
            "ami_launch_index": "0", 
            "architecture": "x86_64", 
            "block_device_mapping": {
                "/dev/sda1": {
                    "delete_on_termination": true, 
                    "status": "attached", 
                    "volume_id": "vol-07d497843eb81ebc2"
                }, 
                "/dev/sdb": {
                    "delete_on_termination": true, 
                    "status": "attached", 
                    "volume_id": "vol-0a9b0296484abad52"
                }
            }, 
            "dns_name": "ec2-54-210-186-248.compute-1.amazonaws.com", 
            "ebs_optimized": false, 
            "groups": {
                "sg-7e73221a": "default"
            }, 
            "hypervisor": "xen", 
            "id": "i-04110c3313394f3eb", 
            "image_id": "ami-edef2697", 
            "instance_type": "m4.xlarge", 
            "kernel": null, 
            "key_name": "libra", 
            "launch_time": "2017-11-10T10:09:39.000Z", 
            "placement": "us-east-1d", 
            "private_dns_name": "ip-172-18-15-242.ec2.internal", 
            "private_ip": "172.18.15.242", 
            "public_dns_name": "ec2-54-210-186-248.compute-1.amazonaws.com", 
            "public_ip": "54.210.186.248", 
            "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 06:44:28.499913", 
    "path": "/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/.config/origin-ci-tool/inventory/host_vars/172.18.15.242.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 06:44:29.068411", 
    "path": "/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install_update/workspace/.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 05s] ##########
Archiving artifacts
[WS-CLEANUP] Deleting project workspace...[WS-CLEANUP] done
Finished: SUCCESS