SuccessConsole Output

Skipping 4,488 KB.. Full Log
    /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
------------------------------
Oct 25 07:32:51.234: INFO: Running AfterSuite actions on all node


[Feature:Builds][Conformance] s2i build with a root user image  
  should create a root build and pass with a privileged SCC [Suite:openshift/conformance/parallel/minimal]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/s2i_root.go:85

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:51
[BeforeEach] [Feature:Builds][Conformance] s2i build with a root user image
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:141
STEP: Creating a kubernetes client
Oct 25 07:29:51.297: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
[BeforeEach] [Feature:Builds][Conformance] s2i build with a root user image
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/cli.go:88
Oct 25 07:29:51.364: INFO: configPath is now "/tmp/configfile027237031"
Oct 25 07:29:51.364: INFO: The user is now "e2e-test-s2i-build-root-rcgdx-user"
Oct 25 07:29:51.364: INFO: OS_TEST_NAMESPACE env setting e2e-test-build-valuefrom-7c8r8, ARTIFACT_DIR env setting /data/src/github.com/openshift/origin/_output/scripts/conformance/artifacts BASETMPDIR /tmp/openshift/conformance
Oct 25 07:29:51.364: INFO: Creating project "e2e-test-s2i-build-root-rcgdx"
Oct 25 07:29:51.425: INFO: Waiting on permissions in project "e2e-test-s2i-build-root-rcgdx" ...
[BeforeEach] 
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/s2i_root.go:25
Oct 25 07:29:51.499: INFO: 

 docker info output: 
Containers: 43
 Running: 32
 Paused: 0
 Stopped: 11
Images: 66
Server Version: 1.13.1
Storage Driver: overlay2
 Backing Filesystem: xfs
 Supports d_type: true
 Native Overlay Diff: true
Logging Driver: json-file
Cgroup Driver: systemd
Plugins: 
 Volume: local
 Network: bridge host macvlan null overlay
 Authorization: rhel-push-plugin
Swarm: inactive
Runtimes: docker-runc runc
Default Runtime: docker-runc
Init Binary: /usr/libexec/docker/docker-init-current
containerd version:  (expected: aa8187dbd3b7ad67d8e5e3a15115d3eef43a7ed1)
runc version: e9c345b3f906d5dc5e8100b05ce37073a811c74a (expected: 9df8b306d01f59d3a8029be411de015b7304dd8f)
init version: 5b117de7f824f3d3825737cf09581645abbe35d4 (expected: 949e6facb77383876aeff8a6944dde66b3089574)
Security Options:
 seccomp
  Profile: /etc/docker/seccomp.json
 selinux
Kernel Version: 3.10.0-862.3.3.el7.x86_64
Operating System: Red Hat Enterprise Linux Server 7.5 (Maipo)
OSType: linux
Architecture: x86_64
Number of Docker Hooks: 3
CPUs: 4
Total Memory: 15.51 GiB
Name: ip-172-18-6-4.ec2.internal
ID: 6WRR:FJU2:46WT:RR52:7YZX:NEJ2:L22M:D5KY:3JL4:DPKI:QS4L:JCJ6
Docker Root Dir: /var/lib/docker
Debug Mode (client): false
Debug Mode (server): false
Registry: https://registry.access.redhat.com/v1/
Experimental: false
Insecure Registries:
 ci.dev.openshift.redhat.com:5000
 172.30.0.0/16
 127.0.0.0/8
Live Restore Enabled: false
Registries: registry.access.redhat.com (secure), docker.io (secure)



[JustBeforeEach] 
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/s2i_root.go:29
STEP: waiting for default service account
STEP: waiting for builder service account
STEP: creating a root build container
Oct 25 07:29:51.706: INFO: Running 'oc new-build --config=/tmp/configfile027237031 --namespace=e2e-test-s2i-build-root-rcgdx -D FROM centos/nodejs-6-centos7
USER 0 --name nodejsroot'
--> Found Docker image 570924c (2 weeks old) from Docker Hub for "centos/nodejs-6-centos7"

    Node.js 6 
    --------- 
    Node.js 6 available as container is a base platform for building and running various Node.js 6 applications and frameworks. Node.js is a platform built on Chrome's JavaScript runtime for easily building fast, scalable network applications. Node.js uses an event-driven, non-blocking I/O model that makes it lightweight and efficient, perfect for data-intensive real-time applications that run across distributed devices.

    Tags: builder, nodejs, nodejs6

    * An image stream tag will be created as "nodejs-6-centos7:latest" that will track the source image
    * A Docker build using a predefined Dockerfile will be created
      * The resulting image will be pushed to image stream tag "nodejsroot:latest"
      * Every time "nodejs-6-centos7:latest" changes a new build will be triggered

--> Creating resources with label build=nodejsroot ...
    imagestream.image.openshift.io "nodejs-6-centos7" created
    imagestream.image.openshift.io "nodejsroot" created
    buildconfig.build.openshift.io "nodejsroot" created
--> Success
[It] should create a root build and pass with a privileged SCC [Suite:openshift/conformance/parallel/minimal]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/s2i_root.go:85
STEP: adding builder account to privileged SCC
Oct 25 07:32:13.075: INFO: Running 'oc new-app --config=/tmp/configfile027237031 --namespace=e2e-test-s2i-build-root-rcgdx nodejsroot~https://github.com/sclorg/nodejs-ex --name nodejspass'
--> Found image af524e7 (About a minute old) in image stream "e2e-test-s2i-build-root-rcgdx/nodejsroot" under tag "latest" for "nodejsroot"

    Node.js 6 
    --------- 
    Node.js 6 available as container is a base platform for building and running various Node.js 6 applications and frameworks. Node.js is a platform built on Chrome's JavaScript runtime for easily building fast, scalable network applications. Node.js uses an event-driven, non-blocking I/O model that makes it lightweight and efficient, perfect for data-intensive real-time applications that run across distributed devices.

    Tags: builder, nodejs, nodejs6

    * A source build using source code from https://github.com/sclorg/nodejs-ex will be created
      * The resulting image will be pushed to image stream tag "nodejspass:latest"
      * Use 'start-build' to trigger a new build
    * This image will be deployed in deployment config "nodejspass"
    * Port 8080/tcp will be load balanced by service "nodejspass"
      * Other containers can access this service through the hostname "nodejspass"
    * WARNING: Image "e2e-test-s2i-build-root-rcgdx/nodejsroot:latest" runs as the 'root' user which may not be permitted by your cluster administrator

--> Creating resources ...
    imagestream.image.openshift.io "nodejspass" created
    buildconfig.build.openshift.io "nodejspass" created
    deploymentconfig.apps.openshift.io "nodejspass" created
    service "nodejspass" created
--> Success
    Build scheduled, use 'oc logs -f bc/nodejspass' to track its progress.
    Application is not exposed. You can expose services to the outside world by executing one or more of the commands below:
     'oc expose svc/nodejspass' 
    Run 'oc status' to view your app.
[AfterEach] 
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/s2i_root.go:45
[AfterEach] [Feature:Builds][Conformance] s2i build with a root user image
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/cli.go:77
STEP: Deleting namespaces
Oct 25 07:34:14.465: INFO: namespace : e2e-test-s2i-build-root-rcgdx api call to delete is complete 
STEP: Waiting for namespaces to vanish
[AfterEach] [Feature:Builds][Conformance] s2i build with a root user image
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:142
Oct 25 07:34:36.468: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready


• [SLOW TEST:285.174 seconds]
[Feature:Builds][Conformance] s2i build with a root user image
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/s2i_root.go:17
  
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/s2i_root.go:24
    should create a root build and pass with a privileged SCC [Suite:openshift/conformance/parallel/minimal]
    /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/s2i_root.go:85
------------------------------
Oct 25 07:34:36.473: INFO: Running AfterSuite actions on all node
Oct 25 07:34:36.473: INFO: Running AfterSuite actions on node 1
Oct 25 07:34:36.473: INFO: Dumping logs locally to: /data/src/github.com/openshift/origin/_output/scripts/conformance/artifacts/junit
Checking for custom logdump instances, if any
Sourcing kube-util.sh
Detecting project
/data/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/cluster/log-dump/../../cluster/../cluster/gce/util.sh: line 141: gcloud: command not found
Oct 25 07:34:36.521: INFO: Error running cluster/log-dump/log-dump.sh: exit status 127


Ran 225 of 1251 Specs in 1852.489 seconds
SUCCESS! -- 225 Passed | 0 Failed | 0 Pending | 1026 Skipped 

Ginkgo ran 1 suite in 30m54.587137564s
Test Suite Passed
[INFO] Running serial tests
I1025 07:34:37.117873   30820 test.go:85] Extended test version v4.0.0-alpha.0+d2f3ad8-409
Oct 25 07:34:42.732: INFO: Overriding default scale value of zero to 1
Oct 25 07:34:42.733: INFO: Overriding default milliseconds value of zero to 5000
I1025 07:34:43.045004    1166 test.go:85] Extended test version v4.0.0-alpha.0+d2f3ad8-409
Running Suite: Extended
=======================
Random Seed: 1540452882 - Will randomize all specs
Will run 9 of 1251 specs

I1025 07:34:43.417630    1166 e2e.go:59] The --provider flag is not set.  Treating as a conformance test.  Some tests may not be run.
Oct 25 07:34:43.417: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
Oct 25 07:34:43.419: INFO: Waiting up to 30m0s for all (but 0) nodes to be schedulable
Oct 25 07:34:43.434: INFO: Waiting up to 10m0s for all pods (need at least 0) in namespace 'kube-system' to be running and ready
Oct 25 07:34:43.453: INFO: 3 / 3 pods in namespace 'kube-system' are running and ready (0 seconds elapsed)
Oct 25 07:34:43.453: INFO: expected 0 pod replicas in namespace 'kube-system', 0 are Running and Ready.
Oct 25 07:34:43.455: INFO: Waiting for pods to enter Success, but no pods in "kube-system" match label map[name:e2e-image-puller]
Oct 25 07:34:43.456: INFO: Dumping network health container logs from all nodes to file /data/src/github.com/openshift/origin/_output/scripts/conformance/artifacts/junit/nethealth.txt
Oct 25 07:34:43.458: INFO: e2e test version: v1.11.0+d4cacc0
Oct 25 07:34:43.459: INFO: kube-apiserver version: v1.11.0+d4cacc0
I1025 07:34:43.459128    1166 e2e.go:59] The --provider flag is not set.  Treating as a conformance test.  Some tests may not be run.
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[sig-scheduling] SchedulerPredicates [Serial] 
  validates that NodeSelector is respected if matching  [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:51
[BeforeEach] [sig-scheduling] SchedulerPredicates [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:141
STEP: Creating a kubernetes client
Oct 25 07:34:43.459: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Oct 25 07:34:43.588: INFO: About to run a Kube e2e test, ensuring namespace is privileged
Oct 25 07:34:43.641: INFO: No PodSecurityPolicies found; assuming PodSecurityPolicy is disabled.
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] [sig-scheduling] SchedulerPredicates [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/predicates.go:80
Oct 25 07:34:43.644: INFO: Waiting up to 1m0s for all (but 0) nodes to be ready
Oct 25 07:34:43.649: INFO: Waiting for terminating namespaces to be deleted...
Oct 25 07:34:43.654: INFO: 
Logging pods the kubelet thinks is on node ip-172-18-6-4.ec2.internal before test
Oct 25 07:34:43.662: INFO: router-1-44mk2 from default started at 2018-10-25 06:56:52 +0000 UTC (1 container statuses recorded)
Oct 25 07:34:43.662: INFO: 	Container router ready: true, restart count 0
Oct 25 07:34:43.662: INFO: apiserver-v276x from kube-service-catalog started at 2018-10-25 06:58:24 +0000 UTC (1 container statuses recorded)
Oct 25 07:34:43.662: INFO: 	Container apiserver ready: true, restart count 0
Oct 25 07:34:43.662: INFO: registry-console-1-dtzgx from default started at 2018-10-25 06:57:17 +0000 UTC (1 container statuses recorded)
Oct 25 07:34:43.662: INFO: 	Container registry-console ready: true, restart count 0
Oct 25 07:34:43.662: INFO: docker-registry-1-hrpzq from default started at 2018-10-25 06:57:07 +0000 UTC (1 container statuses recorded)
Oct 25 07:34:43.662: INFO: 	Container registry ready: true, restart count 0
Oct 25 07:34:43.662: INFO: console-77fcfc87db-pkbbh from openshift-console started at 2018-10-25 06:57:45 +0000 UTC (1 container statuses recorded)
Oct 25 07:34:43.662: INFO: 	Container console ready: true, restart count 0
Oct 25 07:34:43.662: INFO: apiserver-6xnns from openshift-template-service-broker started at 2018-10-25 06:59:05 +0000 UTC (1 container statuses recorded)
Oct 25 07:34:43.662: INFO: 	Container c ready: true, restart count 0
Oct 25 07:34:43.662: INFO: master-etcd-ip-172-18-6-4.ec2.internal from kube-system started at <nil> (0 container statuses recorded)
Oct 25 07:34:43.662: INFO: master-controllers-ip-172-18-6-4.ec2.internal from kube-system started at <nil> (0 container statuses recorded)
Oct 25 07:34:43.662: INFO: controller-manager-gcwnl from kube-service-catalog started at 2018-10-25 06:58:29 +0000 UTC (1 container statuses recorded)
Oct 25 07:34:43.662: INFO: 	Container controller-manager ready: true, restart count 0
Oct 25 07:34:43.662: INFO: sync-stg4x from openshift-node started at 2018-10-25 06:55:13 +0000 UTC (1 container statuses recorded)
Oct 25 07:34:43.662: INFO: 	Container sync ready: true, restart count 0
Oct 25 07:34:43.662: INFO: master-api-ip-172-18-6-4.ec2.internal from kube-system started at <nil> (0 container statuses recorded)
Oct 25 07:34:43.662: INFO: asb-1-deploy from openshift-ansible-service-broker started at 2018-10-25 06:58:56 +0000 UTC (1 container statuses recorded)
Oct 25 07:34:43.662: INFO: 	Container deployment ready: false, restart count 0
Oct 25 07:34:43.662: INFO: webconsole-664bcb44b7-vqdzw from openshift-web-console started at 2018-10-25 06:57:26 +0000 UTC (1 container statuses recorded)
Oct 25 07:34:43.662: INFO: 	Container webconsole ready: true, restart count 0
Oct 25 07:34:43.662: INFO: ovs-9649r from openshift-sdn started at 2018-10-25 06:55:34 +0000 UTC (1 container statuses recorded)
Oct 25 07:34:43.662: INFO: 	Container openvswitch ready: true, restart count 0
Oct 25 07:34:43.662: INFO: sdn-6kzmb from openshift-sdn started at 2018-10-25 06:55:34 +0000 UTC (1 container statuses recorded)
Oct 25 07:34:43.662: INFO: 	Container sdn ready: true, restart count 0
[It] validates that NodeSelector is respected if matching  [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
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-7324f41e-d828-11e8-bdd6-0ed361e6162c 42
STEP: Trying to relaunch the pod, now with labels.
STEP: removing the label kubernetes.io/e2e-7324f41e-d828-11e8-bdd6-0ed361e6162c off the node ip-172-18-6-4.ec2.internal
STEP: verifying the node doesn't have the label kubernetes.io/e2e-7324f41e-d828-11e8-bdd6-0ed361e6162c
[AfterEach] [sig-scheduling] SchedulerPredicates [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:142
Oct 25 07:34:51.725: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-sched-pred-vwmgr" for this suite.
Oct 25 07:35:01.736: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Oct 25 07:35:01.781: INFO: namespace: e2e-tests-sched-pred-vwmgr, resource: bindings, ignored listing per whitelist
Oct 25 07:35:02.454: INFO: namespace e2e-tests-sched-pred-vwmgr deletion completed in 10.726712584s
[AfterEach] [sig-scheduling] SchedulerPredicates [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/predicates.go:71

• [SLOW TEST:18.995 seconds]
[sig-scheduling] SchedulerPredicates [Serial]
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/framework.go:22
  validates that NodeSelector is respected if matching  [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[sig-apps] Daemon set [Serial] 
  should retry creating failed daemon pods [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:51
[BeforeEach] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:141
STEP: Creating a kubernetes client
Oct 25 07:35:02.455: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Oct 25 07:35:02.694: 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] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/apps/daemon_set.go:99
[It] should retry creating failed daemon pods [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
STEP: Creating a simple DaemonSet "daemon-set"
STEP: Check that daemon pods launch on every node of the cluster.
Oct 25 07:35:02.765: INFO: Number of nodes with available pods: 0
Oct 25 07:35:02.765: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:35:03.771: INFO: Number of nodes with available pods: 0
Oct 25 07:35:03.771: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:35:04.770: INFO: Number of nodes with available pods: 0
Oct 25 07:35:04.770: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:35:05.770: INFO: Number of nodes with available pods: 1
Oct 25 07:35:05.770: INFO: Number of running nodes: 1, number of available pods: 1
STEP: Set a daemon pod's phase to 'Failed', check that the daemon pod is revived.
Oct 25 07:35:05.783: INFO: Number of nodes with available pods: 0
Oct 25 07:35:05.784: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:35:06.789: INFO: Number of nodes with available pods: 0
Oct 25 07:35:06.789: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:35:07.790: INFO: Number of nodes with available pods: 0
Oct 25 07:35:07.790: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:35:08.788: INFO: Number of nodes with available pods: 1
Oct 25 07:35:08.788: INFO: Number of running nodes: 1, number of available pods: 1
STEP: Wait for the failed daemon pod to be completely deleted.
[AfterEach] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/apps/daemon_set.go:65
STEP: Deleting DaemonSet "daemon-set"
STEP: deleting {extensions DaemonSet} daemon-set in namespace e2e-tests-daemonsets-kkkx5, will wait for the garbage collector to delete the pods
Oct 25 07:35:08.847: INFO: Deleting {extensions DaemonSet} daemon-set took: 3.488312ms
Oct 25 07:35:08.948: INFO: Terminating {extensions DaemonSet} daemon-set pods took: 100.25288ms
Oct 25 07:35:20.550: INFO: Number of nodes with available pods: 0
Oct 25 07:35:20.550: INFO: Number of running nodes: 0, number of available pods: 0
Oct 25 07:35:20.554: INFO: daemonset: {"kind":"DaemonSetList","apiVersion":"apps/v1","metadata":{"selfLink":"/apis/apps/v1/namespaces/e2e-tests-daemonsets-kkkx5/daemonsets","resourceVersion":"31266"},"items":null}

Oct 25 07:35:20.556: INFO: pods: {"kind":"PodList","apiVersion":"v1","metadata":{"selfLink":"/api/v1/namespaces/e2e-tests-daemonsets-kkkx5/pods","resourceVersion":"31266"},"items":null}

[AfterEach] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:142
Oct 25 07:35:20.560: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-daemonsets-kkkx5" for this suite.
Oct 25 07:35:26.571: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Oct 25 07:35:26.594: INFO: namespace: e2e-tests-daemonsets-kkkx5, resource: bindings, ignored listing per whitelist
Oct 25 07:35:27.298: INFO: namespace e2e-tests-daemonsets-kkkx5 deletion completed in 6.735521269s

• [SLOW TEST:24.842 seconds]
[sig-apps] Daemon set [Serial]
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/apps/framework.go:22
  should retry creating failed daemon pods [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[sig-network] Service endpoints latency 
  should not be very high  [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s] [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:51
[BeforeEach] [sig-network] Service endpoints latency
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:141
STEP: Creating a kubernetes client
Oct 25 07:35:27.299: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Oct 25 07:35:27.414: 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] [Suite:openshift/conformance/serial/minimal] [Suite:k8s] [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
STEP: creating replication controller svc-latency-rc in namespace e2e-tests-svc-latency-r9qz6
I1025 07:35:27.475274    1166 runners.go:177] Created replication controller with name: svc-latency-rc, namespace: e2e-tests-svc-latency-r9qz6, replica count: 1
I1025 07:35:28.525779    1166 runners.go:177] svc-latency-rc Pods: 1 out of 1 created, 0 running, 1 pending, 0 waiting, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I1025 07:35:29.526019    1166 runners.go:177] svc-latency-rc Pods: 1 out of 1 created, 0 running, 1 pending, 0 waiting, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
I1025 07:35:30.526193    1166 runners.go:177] svc-latency-rc Pods: 1 out of 1 created, 1 running, 0 pending, 0 waiting, 0 inactive, 0 terminating, 0 unknown, 0 runningButNotReady 
Oct 25 07:35:30.632: INFO: Created: latency-svc-9hlq2
Oct 25 07:35:30.643: INFO: Got endpoints: latency-svc-9hlq2 [16.685162ms]
Oct 25 07:35:30.661: INFO: Created: latency-svc-vf7qn
Oct 25 07:35:30.667: INFO: Created: latency-svc-2z48w
Oct 25 07:35:30.680: INFO: Created: latency-svc-kvkrv
Oct 25 07:35:30.682: INFO: Got endpoints: latency-svc-vf7qn [39.062176ms]
Oct 25 07:35:30.690: INFO: Got endpoints: latency-svc-2z48w [47.193898ms]
Oct 25 07:35:30.696: INFO: Created: latency-svc-6ntvt
Oct 25 07:35:30.698: INFO: Got endpoints: latency-svc-kvkrv [54.728161ms]
Oct 25 07:35:30.702: INFO: Created: latency-svc-8nwgl
Oct 25 07:35:30.706: INFO: Got endpoints: latency-svc-6ntvt [62.840728ms]
Oct 25 07:35:30.709: INFO: Created: latency-svc-kqwwn
Oct 25 07:35:30.709: INFO: Got endpoints: latency-svc-8nwgl [66.254566ms]
Oct 25 07:35:30.718: INFO: Created: latency-svc-4wxvg
Oct 25 07:35:30.723: INFO: Created: latency-svc-xs899
Oct 25 07:35:30.729: INFO: Got endpoints: latency-svc-kqwwn [85.979892ms]
Oct 25 07:35:30.729: INFO: Got endpoints: latency-svc-4wxvg [86.520058ms]
Oct 25 07:35:30.732: INFO: Created: latency-svc-j4pwq
Oct 25 07:35:30.733: INFO: Got endpoints: latency-svc-xs899 [90.148409ms]
Oct 25 07:35:30.738: INFO: Got endpoints: latency-svc-j4pwq [94.96911ms]
Oct 25 07:35:30.742: INFO: Created: latency-svc-dgklq
Oct 25 07:35:30.745: INFO: Got endpoints: latency-svc-dgklq [101.531287ms]
Oct 25 07:35:30.755: INFO: Created: latency-svc-x664f
Oct 25 07:35:30.758: INFO: Got endpoints: latency-svc-x664f [114.932108ms]
Oct 25 07:35:30.761: INFO: Created: latency-svc-2bh5w
Oct 25 07:35:30.767: INFO: Created: latency-svc-pprsg
Oct 25 07:35:30.767: INFO: Got endpoints: latency-svc-2bh5w [123.490065ms]
Oct 25 07:35:30.774: INFO: Got endpoints: latency-svc-pprsg [130.796219ms]
Oct 25 07:35:30.779: INFO: Created: latency-svc-4kcfw
Oct 25 07:35:30.791: INFO: Created: latency-svc-hnlcs
Oct 25 07:35:30.801: INFO: Created: latency-svc-xg7ft
Oct 25 07:35:30.804: INFO: Got endpoints: latency-svc-4kcfw [160.180527ms]
Oct 25 07:35:30.804: INFO: Got endpoints: latency-svc-hnlcs [160.632278ms]
Oct 25 07:35:30.808: INFO: Created: latency-svc-xh5x6
Oct 25 07:35:30.808: INFO: Got endpoints: latency-svc-xg7ft [126.503878ms]
Oct 25 07:35:30.818: INFO: Created: latency-svc-2hqck
Oct 25 07:35:30.820: INFO: Got endpoints: latency-svc-xh5x6 [129.580687ms]
Oct 25 07:35:30.828: INFO: Got endpoints: latency-svc-2hqck [130.005606ms]
Oct 25 07:35:30.830: INFO: Created: latency-svc-wtfwq
Oct 25 07:35:30.839: INFO: Created: latency-svc-gxthl
Oct 25 07:35:30.841: INFO: Got endpoints: latency-svc-wtfwq [135.50921ms]
Oct 25 07:35:30.850: INFO: Created: latency-svc-q7b6g
Oct 25 07:35:30.855: INFO: Got endpoints: latency-svc-gxthl [145.106084ms]
Oct 25 07:35:30.858: INFO: Got endpoints: latency-svc-q7b6g [128.449909ms]
Oct 25 07:35:30.860: INFO: Created: latency-svc-l86f4
Oct 25 07:35:30.867: INFO: Got endpoints: latency-svc-l86f4 [137.595662ms]
Oct 25 07:35:30.885: INFO: Created: latency-svc-df774
Oct 25 07:35:30.885: INFO: Created: latency-svc-l7wjp
Oct 25 07:35:30.888: INFO: Got endpoints: latency-svc-df774 [154.662344ms]
Oct 25 07:35:30.892: INFO: Created: latency-svc-ccbm4
Oct 25 07:35:30.892: INFO: Got endpoints: latency-svc-l7wjp [153.85789ms]
Oct 25 07:35:30.900: INFO: Got endpoints: latency-svc-ccbm4 [155.375633ms]
Oct 25 07:35:30.904: INFO: Created: latency-svc-dst6r
Oct 25 07:35:30.912: INFO: Got endpoints: latency-svc-dst6r [154.001416ms]
Oct 25 07:35:30.915: INFO: Created: latency-svc-pp5bw
Oct 25 07:35:30.922: INFO: Created: latency-svc-xl2zn
Oct 25 07:35:30.923: INFO: Got endpoints: latency-svc-pp5bw [156.280731ms]
Oct 25 07:35:30.929: INFO: Created: latency-svc-w9whh
Oct 25 07:35:30.930: INFO: Got endpoints: latency-svc-xl2zn [155.312832ms]
Oct 25 07:35:30.938: INFO: Got endpoints: latency-svc-w9whh [134.395879ms]
Oct 25 07:35:30.939: INFO: Created: latency-svc-25c5m
Oct 25 07:35:30.946: INFO: Got endpoints: latency-svc-25c5m [141.982977ms]
Oct 25 07:35:30.949: INFO: Created: latency-svc-r6lg8
Oct 25 07:35:30.955: INFO: Created: latency-svc-9hmnc
Oct 25 07:35:30.964: INFO: Got endpoints: latency-svc-r6lg8 [155.488737ms]
Oct 25 07:35:30.968: INFO: Created: latency-svc-7xqf2
Oct 25 07:35:30.969: INFO: Got endpoints: latency-svc-9hmnc [148.8943ms]
Oct 25 07:35:30.975: INFO: Created: latency-svc-rlmjx
Oct 25 07:35:30.982: INFO: Got endpoints: latency-svc-7xqf2 [154.117269ms]
Oct 25 07:35:30.985: INFO: Got endpoints: latency-svc-rlmjx [143.880095ms]
Oct 25 07:35:30.989: INFO: Created: latency-svc-np9pp
Oct 25 07:35:30.999: INFO: Created: latency-svc-wqwc2
Oct 25 07:35:31.009: INFO: Got endpoints: latency-svc-np9pp [154.098633ms]
Oct 25 07:35:31.009: INFO: Got endpoints: latency-svc-wqwc2 [151.308905ms]
Oct 25 07:35:31.010: INFO: Created: latency-svc-q6ll9
Oct 25 07:35:31.020: INFO: Got endpoints: latency-svc-q6ll9 [152.588513ms]
Oct 25 07:35:31.022: INFO: Created: latency-svc-nd64b
Oct 25 07:35:31.031: INFO: Created: latency-svc-hlc5g
Oct 25 07:35:31.034: INFO: Got endpoints: latency-svc-nd64b [145.548616ms]
Oct 25 07:35:31.035: INFO: Got endpoints: latency-svc-hlc5g [142.887379ms]
Oct 25 07:35:31.039: INFO: Created: latency-svc-wq4dm
Oct 25 07:35:31.045: INFO: Got endpoints: latency-svc-wq4dm [144.174569ms]
Oct 25 07:35:31.054: INFO: Created: latency-svc-qnfmd
Oct 25 07:35:31.054: INFO: Created: latency-svc-9rzln
Oct 25 07:35:31.065: INFO: Created: latency-svc-vbchl
Oct 25 07:35:31.065: INFO: Got endpoints: latency-svc-qnfmd [141.786508ms]
Oct 25 07:35:31.065: INFO: Got endpoints: latency-svc-9rzln [153.013159ms]
Oct 25 07:35:31.069: INFO: Got endpoints: latency-svc-vbchl [139.215351ms]
Oct 25 07:35:31.074: INFO: Created: latency-svc-qd55q
Oct 25 07:35:31.081: INFO: Created: latency-svc-jcpbv
Oct 25 07:35:31.087: INFO: Got endpoints: latency-svc-qd55q [148.905543ms]
Oct 25 07:35:31.093: INFO: Got endpoints: latency-svc-jcpbv [146.909927ms]
Oct 25 07:35:31.101: INFO: Created: latency-svc-bzjl9
Oct 25 07:35:31.102: INFO: Got endpoints: latency-svc-bzjl9 [137.929493ms]
Oct 25 07:35:31.107: INFO: Created: latency-svc-5g5mv
Oct 25 07:35:31.115: INFO: Got endpoints: latency-svc-5g5mv [146.487315ms]
Oct 25 07:35:31.119: INFO: Created: latency-svc-dt7d4
Oct 25 07:35:31.125: INFO: Got endpoints: latency-svc-dt7d4 [143.342203ms]
Oct 25 07:35:31.128: INFO: Created: latency-svc-wr4bv
Oct 25 07:35:31.133: INFO: Got endpoints: latency-svc-wr4bv [148.257924ms]
Oct 25 07:35:31.141: INFO: Created: latency-svc-cspdb
Oct 25 07:35:31.145: INFO: Got endpoints: latency-svc-cspdb [136.326502ms]
Oct 25 07:35:31.153: INFO: Created: latency-svc-9vvxt
Oct 25 07:35:31.158: INFO: Got endpoints: latency-svc-9vvxt [148.507496ms]
Oct 25 07:35:31.161: INFO: Created: latency-svc-txfxm
Oct 25 07:35:31.167: INFO: Got endpoints: latency-svc-txfxm [147.320014ms]
Oct 25 07:35:31.169: INFO: Created: latency-svc-29x62
Oct 25 07:35:31.175: INFO: Created: latency-svc-46k9q
Oct 25 07:35:31.177: INFO: Got endpoints: latency-svc-29x62 [143.374212ms]
Oct 25 07:35:31.196: INFO: Got endpoints: latency-svc-46k9q [160.618445ms]
Oct 25 07:35:31.196: INFO: Created: latency-svc-cxt6m
Oct 25 07:35:31.203: INFO: Got endpoints: latency-svc-cxt6m [158.771149ms]
Oct 25 07:35:31.206: INFO: Created: latency-svc-rvhc7
Oct 25 07:35:31.213: INFO: Got endpoints: latency-svc-rvhc7 [147.923957ms]
Oct 25 07:35:31.216: INFO: Created: latency-svc-shbn7
Oct 25 07:35:31.227: INFO: Got endpoints: latency-svc-shbn7 [161.555777ms]
Oct 25 07:35:31.233: INFO: Created: latency-svc-2f6pf
Oct 25 07:35:31.245: INFO: Got endpoints: latency-svc-2f6pf [175.590944ms]
Oct 25 07:35:31.248: INFO: Created: latency-svc-szzzf
Oct 25 07:35:31.257: INFO: Got endpoints: latency-svc-szzzf [169.707312ms]
Oct 25 07:35:31.260: INFO: Created: latency-svc-jqhzl
Oct 25 07:35:31.263: INFO: Created: latency-svc-gnj6k
Oct 25 07:35:31.271: INFO: Got endpoints: latency-svc-jqhzl [168.630901ms]
Oct 25 07:35:31.273: INFO: Got endpoints: latency-svc-gnj6k [180.33939ms]
Oct 25 07:35:31.278: INFO: Created: latency-svc-lg7lk
Oct 25 07:35:31.282: INFO: Got endpoints: latency-svc-lg7lk [166.72789ms]
Oct 25 07:35:31.289: INFO: Created: latency-svc-txt99
Oct 25 07:35:31.289: INFO: Created: latency-svc-2plns
Oct 25 07:35:31.307: INFO: Created: latency-svc-hljb4
Oct 25 07:35:31.311: INFO: Created: latency-svc-hc7h7
Oct 25 07:35:31.315: INFO: Got endpoints: latency-svc-2plns [189.977467ms]
Oct 25 07:35:31.316: INFO: Got endpoints: latency-svc-txt99 [182.240767ms]
Oct 25 07:35:31.316: INFO: Got endpoints: latency-svc-hljb4 [170.732722ms]
Oct 25 07:35:31.328: INFO: Got endpoints: latency-svc-hc7h7 [170.226383ms]
Oct 25 07:35:31.333: INFO: Created: latency-svc-mxwch
Oct 25 07:35:31.341: INFO: Got endpoints: latency-svc-mxwch [173.767246ms]
Oct 25 07:35:31.351: INFO: Created: latency-svc-p655v
Oct 25 07:35:31.352: INFO: Created: latency-svc-ssd8q
Oct 25 07:35:31.360: INFO: Got endpoints: latency-svc-p655v [164.16944ms]
Oct 25 07:35:31.361: INFO: Got endpoints: latency-svc-ssd8q [183.309968ms]
Oct 25 07:35:31.371: INFO: Created: latency-svc-mkrwc
Oct 25 07:35:31.385: INFO: Created: latency-svc-qw5nl
Oct 25 07:35:31.385: INFO: Got endpoints: latency-svc-mkrwc [181.798049ms]
Oct 25 07:35:31.392: INFO: Got endpoints: latency-svc-qw5nl [179.254176ms]
Oct 25 07:35:31.395: INFO: Created: latency-svc-n6shq
Oct 25 07:35:31.405: INFO: Created: latency-svc-cndhl
Oct 25 07:35:31.407: INFO: Got endpoints: latency-svc-n6shq [179.565433ms]
Oct 25 07:35:31.421: INFO: Created: latency-svc-grx8b
Oct 25 07:35:31.425: INFO: Got endpoints: latency-svc-cndhl [179.969956ms]
Oct 25 07:35:31.429: INFO: Created: latency-svc-4njgh
Oct 25 07:35:31.438: INFO: Got endpoints: latency-svc-grx8b [181.421182ms]
Oct 25 07:35:31.446: INFO: Got endpoints: latency-svc-4njgh [175.046584ms]
Oct 25 07:35:31.457: INFO: Created: latency-svc-dlngn
Oct 25 07:35:31.457: INFO: Created: latency-svc-jhfrk
Oct 25 07:35:31.466: INFO: Got endpoints: latency-svc-jhfrk [193.043534ms]
Oct 25 07:35:31.476: INFO: Got endpoints: latency-svc-dlngn [193.900068ms]
Oct 25 07:35:31.478: INFO: Created: latency-svc-ncf2k
Oct 25 07:35:31.486: INFO: Got endpoints: latency-svc-ncf2k [170.869793ms]
Oct 25 07:35:31.492: INFO: Created: latency-svc-6vn79
Oct 25 07:35:31.499: INFO: Got endpoints: latency-svc-6vn79 [183.448926ms]
Oct 25 07:35:31.505: INFO: Created: latency-svc-mr5dk
Oct 25 07:35:31.513: INFO: Got endpoints: latency-svc-mr5dk [196.945641ms]
Oct 25 07:35:31.518: INFO: Created: latency-svc-vqnqn
Oct 25 07:35:31.526: INFO: Created: latency-svc-86s9x
Oct 25 07:35:31.528: INFO: Got endpoints: latency-svc-vqnqn [200.163149ms]
Oct 25 07:35:31.537: INFO: Created: latency-svc-2lflq
Oct 25 07:35:31.541: INFO: Got endpoints: latency-svc-86s9x [199.998111ms]
Oct 25 07:35:31.546: INFO: Created: latency-svc-tbh77
Oct 25 07:35:31.552: INFO: Got endpoints: latency-svc-2lflq [191.692595ms]
Oct 25 07:35:31.553: INFO: Got endpoints: latency-svc-tbh77 [192.648239ms]
Oct 25 07:35:31.565: INFO: Created: latency-svc-2mbkj
Oct 25 07:35:31.568: INFO: Created: latency-svc-g59t4
Oct 25 07:35:31.571: INFO: Got endpoints: latency-svc-2mbkj [185.574687ms]
Oct 25 07:35:31.576: INFO: Got endpoints: latency-svc-g59t4 [183.714691ms]
Oct 25 07:35:31.579: INFO: Created: latency-svc-wv2tg
Oct 25 07:35:31.586: INFO: Got endpoints: latency-svc-wv2tg [179.702483ms]
Oct 25 07:35:31.588: INFO: Created: latency-svc-g96t8
Oct 25 07:35:31.592: INFO: Got endpoints: latency-svc-g96t8 [167.620943ms]
Oct 25 07:35:31.603: INFO: Created: latency-svc-sgt8z
Oct 25 07:35:31.613: INFO: Created: latency-svc-ltmwx
Oct 25 07:35:31.616: INFO: Got endpoints: latency-svc-sgt8z [177.348148ms]
Oct 25 07:35:31.620: INFO: Created: latency-svc-txffq
Oct 25 07:35:31.626: INFO: Got endpoints: latency-svc-ltmwx [180.030914ms]
Oct 25 07:35:31.639: INFO: Created: latency-svc-9jhwm
Oct 25 07:35:31.639: INFO: Got endpoints: latency-svc-9jhwm [163.23066ms]
Oct 25 07:35:31.640: INFO: Got endpoints: latency-svc-txffq [173.13451ms]
Oct 25 07:35:31.641: INFO: Created: latency-svc-fpv27
Oct 25 07:35:31.652: INFO: Created: latency-svc-xx9rh
Oct 25 07:35:31.660: INFO: Got endpoints: latency-svc-fpv27 [173.776986ms]
Oct 25 07:35:31.662: INFO: Created: latency-svc-6js85
Oct 25 07:35:31.667: INFO: Got endpoints: latency-svc-xx9rh [166.983538ms]
Oct 25 07:35:31.670: INFO: Got endpoints: latency-svc-6js85 [157.636071ms]
Oct 25 07:35:31.679: INFO: Created: latency-svc-h2zgz
Oct 25 07:35:31.684: INFO: Created: latency-svc-tld2c
Oct 25 07:35:31.690: INFO: Got endpoints: latency-svc-tld2c [149.192784ms]
Oct 25 07:35:31.690: INFO: Got endpoints: latency-svc-h2zgz [162.166593ms]
Oct 25 07:35:31.694: INFO: Created: latency-svc-xwv9q
Oct 25 07:35:31.701: INFO: Created: latency-svc-pwznn
Oct 25 07:35:31.707: INFO: Created: latency-svc-4cq7s
Oct 25 07:35:31.709: INFO: Got endpoints: latency-svc-xwv9q [157.202079ms]
Oct 25 07:35:31.711: INFO: Got endpoints: latency-svc-pwznn [157.602872ms]
Oct 25 07:35:31.719: INFO: Got endpoints: latency-svc-4cq7s [148.242841ms]
Oct 25 07:35:31.720: INFO: Created: latency-svc-rmjh4
Oct 25 07:35:31.727: INFO: Got endpoints: latency-svc-rmjh4 [150.969486ms]
Oct 25 07:35:31.733: INFO: Created: latency-svc-rz6l4
Oct 25 07:35:31.740: INFO: Created: latency-svc-xzgmv
Oct 25 07:35:31.744: INFO: Got endpoints: latency-svc-xzgmv [151.978733ms]
Oct 25 07:35:31.749: INFO: Got endpoints: latency-svc-rz6l4 [162.383666ms]
Oct 25 07:35:31.750: INFO: Created: latency-svc-rkrpz
Oct 25 07:35:31.760: INFO: Got endpoints: latency-svc-rkrpz [144.041078ms]
Oct 25 07:35:31.761: INFO: Created: latency-svc-8pj7z
Oct 25 07:35:31.771: INFO: Created: latency-svc-qcbr2
Oct 25 07:35:31.773: INFO: Got endpoints: latency-svc-8pj7z [147.068672ms]
Oct 25 07:35:31.778: INFO: Got endpoints: latency-svc-qcbr2 [138.681378ms]
Oct 25 07:35:31.781: INFO: Created: latency-svc-74pxk
Oct 25 07:35:31.786: INFO: Created: latency-svc-6sftd
Oct 25 07:35:31.794: INFO: Got endpoints: latency-svc-6sftd [134.167625ms]
Oct 25 07:35:31.799: INFO: Created: latency-svc-2hmjh
Oct 25 07:35:31.802: INFO: Got endpoints: latency-svc-74pxk [162.435589ms]
Oct 25 07:35:31.805: INFO: Created: latency-svc-s9rp2
Oct 25 07:35:31.813: INFO: Got endpoints: latency-svc-s9rp2 [142.31436ms]
Oct 25 07:35:31.813: INFO: Got endpoints: latency-svc-2hmjh [146.36931ms]
Oct 25 07:35:31.816: INFO: Created: latency-svc-v9mt6
Oct 25 07:35:31.822: INFO: Created: latency-svc-92846
Oct 25 07:35:31.824: INFO: Got endpoints: latency-svc-v9mt6 [133.335773ms]
Oct 25 07:35:31.828: INFO: Got endpoints: latency-svc-92846 [137.930281ms]
Oct 25 07:35:31.829: INFO: Created: latency-svc-9hrdc
Oct 25 07:35:31.833: INFO: Created: latency-svc-4vcqf
Oct 25 07:35:31.836: INFO: Got endpoints: latency-svc-9hrdc [126.527579ms]
Oct 25 07:35:31.845: INFO: Got endpoints: latency-svc-4vcqf [133.633185ms]
Oct 25 07:35:31.849: INFO: Created: latency-svc-vn8hk
Oct 25 07:35:31.860: INFO: Got endpoints: latency-svc-vn8hk [140.390268ms]
Oct 25 07:35:31.865: INFO: Created: latency-svc-mnkfx
Oct 25 07:35:31.868: INFO: Got endpoints: latency-svc-mnkfx [140.187779ms]
Oct 25 07:35:31.870: INFO: Created: latency-svc-mfk25
Oct 25 07:35:31.877: INFO: Got endpoints: latency-svc-mfk25 [132.192101ms]
Oct 25 07:35:31.880: INFO: Created: latency-svc-fblnq
Oct 25 07:35:31.887: INFO: Created: latency-svc-df85t
Oct 25 07:35:31.895: INFO: Got endpoints: latency-svc-df85t [135.003575ms]
Oct 25 07:35:31.895: INFO: Got endpoints: latency-svc-fblnq [146.307856ms]
Oct 25 07:35:31.897: INFO: Created: latency-svc-xfcw5
Oct 25 07:35:31.901: INFO: Got endpoints: latency-svc-xfcw5 [128.412834ms]
Oct 25 07:35:31.909: INFO: Created: latency-svc-4m6rn
Oct 25 07:35:31.936: INFO: Created: latency-svc-552qv
Oct 25 07:35:31.938: INFO: Created: latency-svc-lll2p
Oct 25 07:35:31.939: INFO: Got endpoints: latency-svc-4m6rn [160.163309ms]
Oct 25 07:35:31.941: INFO: Created: latency-svc-z2jfn
Oct 25 07:35:31.942: INFO: Created: latency-svc-8nwj2
Oct 25 07:35:31.944: INFO: Got endpoints: latency-svc-552qv [149.209781ms]
Oct 25 07:35:31.947: INFO: Got endpoints: latency-svc-lll2p [144.91242ms]
Oct 25 07:35:31.957: INFO: Created: latency-svc-w6hv6
Oct 25 07:35:31.958: INFO: Got endpoints: latency-svc-8nwj2 [144.971667ms]
Oct 25 07:35:31.958: INFO: Got endpoints: latency-svc-z2jfn [145.460971ms]
Oct 25 07:35:31.969: INFO: Created: latency-svc-t66q7
Oct 25 07:35:31.987: INFO: Got endpoints: latency-svc-t66q7 [158.244261ms]
Oct 25 07:35:31.987: INFO: Got endpoints: latency-svc-w6hv6 [163.16181ms]
Oct 25 07:35:31.989: INFO: Created: latency-svc-5z62z
Oct 25 07:35:32.002: INFO: Got endpoints: latency-svc-5z62z [166.339051ms]
Oct 25 07:35:32.003: INFO: Created: latency-svc-jhd9n
Oct 25 07:35:32.017: INFO: Got endpoints: latency-svc-jhd9n [172.011159ms]
Oct 25 07:35:32.028: INFO: Created: latency-svc-dzgww
Oct 25 07:35:32.029: INFO: Got endpoints: latency-svc-dzgww [169.459965ms]
Oct 25 07:35:32.038: INFO: Created: latency-svc-kdcsg
Oct 25 07:35:32.043: INFO: Got endpoints: latency-svc-kdcsg [175.014482ms]
Oct 25 07:35:32.048: INFO: Created: latency-svc-n8nql
Oct 25 07:35:32.054: INFO: Created: latency-svc-ts8kr
Oct 25 07:35:32.056: INFO: Got endpoints: latency-svc-n8nql [179.370179ms]
Oct 25 07:35:32.068: INFO: Created: latency-svc-4g2f6
Oct 25 07:35:32.071: INFO: Got endpoints: latency-svc-ts8kr [175.819724ms]
Oct 25 07:35:32.073: INFO: Got endpoints: latency-svc-4g2f6 [178.102819ms]
Oct 25 07:35:32.076: INFO: Created: latency-svc-m6cnf
Oct 25 07:35:32.080: INFO: Got endpoints: latency-svc-m6cnf [178.878245ms]
Oct 25 07:35:32.082: INFO: Created: latency-svc-knxlq
Oct 25 07:35:32.091: INFO: Created: latency-svc-j8c5m
Oct 25 07:35:32.094: INFO: Got endpoints: latency-svc-knxlq [155.54989ms]
Oct 25 07:35:32.100: INFO: Got endpoints: latency-svc-j8c5m [153.39258ms]
Oct 25 07:35:32.103: INFO: Created: latency-svc-tvdll
Oct 25 07:35:32.109: INFO: Got endpoints: latency-svc-tvdll [165.735114ms]
Oct 25 07:35:32.111: INFO: Created: latency-svc-9qb5k
Oct 25 07:35:32.121: INFO: Got endpoints: latency-svc-9qb5k [163.078577ms]
Oct 25 07:35:32.126: INFO: Created: latency-svc-288k5
Oct 25 07:35:32.126: INFO: Got endpoints: latency-svc-288k5 [167.605402ms]
Oct 25 07:35:32.130: INFO: Created: latency-svc-vc2z6
Oct 25 07:35:32.136: INFO: Created: latency-svc-kvvlc
Oct 25 07:35:32.140: INFO: Got endpoints: latency-svc-vc2z6 [153.260479ms]
Oct 25 07:35:32.143: INFO: Got endpoints: latency-svc-kvvlc [155.663136ms]
Oct 25 07:35:32.156: INFO: Created: latency-svc-d7grn
Oct 25 07:35:32.159: INFO: Created: latency-svc-pbz9p
Oct 25 07:35:32.162: INFO: Got endpoints: latency-svc-d7grn [160.054382ms]
Oct 25 07:35:32.169: INFO: Got endpoints: latency-svc-pbz9p [152.725334ms]
Oct 25 07:35:32.172: INFO: Created: latency-svc-zxsqm
Oct 25 07:35:32.180: INFO: Got endpoints: latency-svc-zxsqm [151.167112ms]
Oct 25 07:35:32.188: INFO: Created: latency-svc-scvcp
Oct 25 07:35:32.189: INFO: Created: latency-svc-ms8jg
Oct 25 07:35:32.190: INFO: Got endpoints: latency-svc-scvcp [147.277113ms]
Oct 25 07:35:32.198: INFO: Created: latency-svc-sq8kt
Oct 25 07:35:32.199: INFO: Got endpoints: latency-svc-ms8jg [143.240311ms]
Oct 25 07:35:32.208: INFO: Created: latency-svc-slq5g
Oct 25 07:35:32.215: INFO: Got endpoints: latency-svc-sq8kt [143.844079ms]
Oct 25 07:35:32.226: INFO: Got endpoints: latency-svc-slq5g [152.225181ms]
Oct 25 07:35:32.232: INFO: Created: latency-svc-d299c
Oct 25 07:35:32.238: INFO: Created: latency-svc-9qs8s
Oct 25 07:35:32.246: INFO: Got endpoints: latency-svc-d299c [165.957003ms]
Oct 25 07:35:32.252: INFO: Got endpoints: latency-svc-9qs8s [157.813994ms]
Oct 25 07:35:32.259: INFO: Created: latency-svc-5bkvs
Oct 25 07:35:32.263: INFO: Got endpoints: latency-svc-5bkvs [162.513856ms]
Oct 25 07:35:32.265: INFO: Created: latency-svc-qpfwt
Oct 25 07:35:32.274: INFO: Got endpoints: latency-svc-qpfwt [164.385366ms]
Oct 25 07:35:32.282: INFO: Created: latency-svc-4nv4s
Oct 25 07:35:32.288: INFO: Got endpoints: latency-svc-4nv4s [167.168983ms]
Oct 25 07:35:32.292: INFO: Created: latency-svc-7bwm9
Oct 25 07:35:32.299: INFO: Got endpoints: latency-svc-7bwm9 [173.35008ms]
Oct 25 07:35:32.308: INFO: Created: latency-svc-v2q6v
Oct 25 07:35:32.310: INFO: Created: latency-svc-nk4jx
Oct 25 07:35:32.315: INFO: Created: latency-svc-mvvbc
Oct 25 07:35:32.321: INFO: Got endpoints: latency-svc-nk4jx [178.066825ms]
Oct 25 07:35:32.321: INFO: Got endpoints: latency-svc-v2q6v [180.782307ms]
Oct 25 07:35:32.321: INFO: Got endpoints: latency-svc-mvvbc [158.983733ms]
Oct 25 07:35:32.327: INFO: Created: latency-svc-xnvw9
Oct 25 07:35:32.336: INFO: Got endpoints: latency-svc-xnvw9 [166.3397ms]
Oct 25 07:35:32.342: INFO: Created: latency-svc-jqp99
Oct 25 07:35:32.347: INFO: Created: latency-svc-7z9d7
Oct 25 07:35:32.349: INFO: Got endpoints: latency-svc-jqp99 [168.499505ms]
Oct 25 07:35:32.355: INFO: Got endpoints: latency-svc-7z9d7 [165.249452ms]
Oct 25 07:35:32.362: INFO: Created: latency-svc-pdwpf
Oct 25 07:35:32.370: INFO: Got endpoints: latency-svc-pdwpf [170.445948ms]
Oct 25 07:35:32.373: INFO: Created: latency-svc-s7g9k
Oct 25 07:35:32.381: INFO: Got endpoints: latency-svc-s7g9k [165.901192ms]
Oct 25 07:35:32.385: INFO: Created: latency-svc-wvw2g
Oct 25 07:35:32.389: INFO: Created: latency-svc-nsxn4
Oct 25 07:35:32.401: INFO: Created: latency-svc-859rb
Oct 25 07:35:32.404: INFO: Got endpoints: latency-svc-nsxn4 [157.766602ms]
Oct 25 07:35:32.404: INFO: Got endpoints: latency-svc-wvw2g [178.596472ms]
Oct 25 07:35:32.408: INFO: Created: latency-svc-n2sfb
Oct 25 07:35:32.416: INFO: Got endpoints: latency-svc-n2sfb [153.047032ms]
Oct 25 07:35:32.416: INFO: Got endpoints: latency-svc-859rb [164.096132ms]
Oct 25 07:35:32.424: INFO: Created: latency-svc-7tgvz
Oct 25 07:35:32.429: INFO: Got endpoints: latency-svc-7tgvz [154.791106ms]
Oct 25 07:35:32.441: INFO: Created: latency-svc-f469j
Oct 25 07:35:32.441: INFO: Created: latency-svc-8hk8p
Oct 25 07:35:32.445: INFO: Created: latency-svc-npxx8
Oct 25 07:35:32.457: INFO: Created: latency-svc-q695k
Oct 25 07:35:32.460: INFO: Got endpoints: latency-svc-npxx8 [139.356158ms]
Oct 25 07:35:32.465: INFO: Got endpoints: latency-svc-q695k [144.441487ms]
Oct 25 07:35:32.466: INFO: Got endpoints: latency-svc-8hk8p [166.343205ms]
Oct 25 07:35:32.470: INFO: Created: latency-svc-rsfbg
Oct 25 07:35:32.474: INFO: Got endpoints: latency-svc-f469j [185.033695ms]
Oct 25 07:35:32.480: INFO: Got endpoints: latency-svc-rsfbg [158.189584ms]
Oct 25 07:35:32.482: INFO: Created: latency-svc-dzxlq
Oct 25 07:35:32.489: INFO: Created: latency-svc-lxflg
Oct 25 07:35:32.505: INFO: Created: latency-svc-vppsn
Oct 25 07:35:32.514: INFO: Got endpoints: latency-svc-lxflg [164.850866ms]
Oct 25 07:35:32.514: INFO: Got endpoints: latency-svc-dzxlq [178.296356ms]
Oct 25 07:35:32.518: INFO: Got endpoints: latency-svc-vppsn [163.010983ms]
Oct 25 07:35:32.521: INFO: Created: latency-svc-fmg8k
Oct 25 07:35:32.532: INFO: Got endpoints: latency-svc-fmg8k [162.66663ms]
Oct 25 07:35:32.540: INFO: Created: latency-svc-49dsm
Oct 25 07:35:32.544: INFO: Created: latency-svc-bn46k
Oct 25 07:35:32.545: INFO: Got endpoints: latency-svc-49dsm [164.566881ms]
Oct 25 07:35:32.548: INFO: Got endpoints: latency-svc-bn46k [144.299654ms]
Oct 25 07:35:32.553: INFO: Created: latency-svc-fbf22
Oct 25 07:35:32.557: INFO: Got endpoints: latency-svc-fbf22 [152.795405ms]
Oct 25 07:35:32.558: INFO: Created: latency-svc-lv8fr
Oct 25 07:35:32.565: INFO: Created: latency-svc-hcxcc
Oct 25 07:35:32.568: INFO: Got endpoints: latency-svc-lv8fr [152.129605ms]
Oct 25 07:35:32.588: INFO: Got endpoints: latency-svc-hcxcc [171.619422ms]
Oct 25 07:35:32.595: INFO: Created: latency-svc-9kfv5
Oct 25 07:35:32.601: INFO: Created: latency-svc-sbj94
Oct 25 07:35:32.608: INFO: Got endpoints: latency-svc-9kfv5 [179.145318ms]
Oct 25 07:35:32.610: INFO: Created: latency-svc-h7vzn
Oct 25 07:35:32.610: INFO: Created: latency-svc-b98tb
Oct 25 07:35:32.615: INFO: Got endpoints: latency-svc-sbj94 [155.067333ms]
Oct 25 07:35:32.617: INFO: Got endpoints: latency-svc-b98tb [151.803459ms]
Oct 25 07:35:32.625: INFO: Got endpoints: latency-svc-h7vzn [159.269988ms]
Oct 25 07:35:32.631: INFO: Created: latency-svc-6qdhb
Oct 25 07:35:32.634: INFO: Got endpoints: latency-svc-6qdhb [159.96692ms]
Oct 25 07:35:32.637: INFO: Created: latency-svc-zrjb7
Oct 25 07:35:32.649: INFO: Created: latency-svc-lv5qh
Oct 25 07:35:32.649: INFO: Got endpoints: latency-svc-zrjb7 [169.740994ms]
Oct 25 07:35:32.654: INFO: Got endpoints: latency-svc-lv5qh [139.249284ms]
Oct 25 07:35:32.657: INFO: Created: latency-svc-8jnhz
Oct 25 07:35:32.663: INFO: Got endpoints: latency-svc-8jnhz [148.53302ms]
Oct 25 07:35:32.665: INFO: Created: latency-svc-754gx
Oct 25 07:35:32.672: INFO: Created: latency-svc-fjt5n
Oct 25 07:35:32.673: INFO: Got endpoints: latency-svc-754gx [154.775967ms]
Oct 25 07:35:32.679: INFO: Got endpoints: latency-svc-fjt5n [146.639284ms]
Oct 25 07:35:32.682: INFO: Created: latency-svc-gzrjr
Oct 25 07:35:32.687: INFO: Created: latency-svc-c4cnr
Oct 25 07:35:32.694: INFO: Got endpoints: latency-svc-c4cnr [145.685223ms]
Oct 25 07:35:32.694: INFO: Got endpoints: latency-svc-gzrjr [148.775597ms]
Oct 25 07:35:32.698: INFO: Created: latency-svc-7bl6b
Oct 25 07:35:32.710: INFO: Got endpoints: latency-svc-7bl6b [152.58084ms]
Oct 25 07:35:32.711: INFO: Created: latency-svc-v7rzz
Oct 25 07:35:32.715: INFO: Got endpoints: latency-svc-v7rzz [146.385661ms]
Oct 25 07:35:32.721: INFO: Created: latency-svc-tq7qf
Oct 25 07:35:32.726: INFO: Got endpoints: latency-svc-tq7qf [137.685739ms]
Oct 25 07:35:32.733: INFO: Created: latency-svc-m7vsr
Oct 25 07:35:32.737: INFO: Got endpoints: latency-svc-m7vsr [128.653422ms]
Oct 25 07:35:32.739: INFO: Created: latency-svc-h8mcf
Oct 25 07:35:32.741: INFO: Created: latency-svc-tp896
Oct 25 07:35:32.754: INFO: Got endpoints: latency-svc-h8mcf [138.208602ms]
Oct 25 07:35:32.755: INFO: Got endpoints: latency-svc-tp896 [137.500432ms]
Oct 25 07:35:32.755: INFO: Latencies: [39.062176ms 47.193898ms 54.728161ms 62.840728ms 66.254566ms 85.979892ms 86.520058ms 90.148409ms 94.96911ms 101.531287ms 114.932108ms 123.490065ms 126.503878ms 126.527579ms 128.412834ms 128.449909ms 128.653422ms 129.580687ms 130.005606ms 130.796219ms 132.192101ms 133.335773ms 133.633185ms 134.167625ms 134.395879ms 135.003575ms 135.50921ms 136.326502ms 137.500432ms 137.595662ms 137.685739ms 137.929493ms 137.930281ms 138.208602ms 138.681378ms 139.215351ms 139.249284ms 139.356158ms 140.187779ms 140.390268ms 141.786508ms 141.982977ms 142.31436ms 142.887379ms 143.240311ms 143.342203ms 143.374212ms 143.844079ms 143.880095ms 144.041078ms 144.174569ms 144.299654ms 144.441487ms 144.91242ms 144.971667ms 145.106084ms 145.460971ms 145.548616ms 145.685223ms 146.307856ms 146.36931ms 146.385661ms 146.487315ms 146.639284ms 146.909927ms 147.068672ms 147.277113ms 147.320014ms 147.923957ms 148.242841ms 148.257924ms 148.507496ms 148.53302ms 148.775597ms 148.8943ms 148.905543ms 149.192784ms 149.209781ms 150.969486ms 151.167112ms 151.308905ms 151.803459ms 151.978733ms 152.129605ms 152.225181ms 152.58084ms 152.588513ms 152.725334ms 152.795405ms 153.013159ms 153.047032ms 153.260479ms 153.39258ms 153.85789ms 154.001416ms 154.098633ms 154.117269ms 154.662344ms 154.775967ms 154.791106ms 155.067333ms 155.312832ms 155.375633ms 155.488737ms 155.54989ms 155.663136ms 156.280731ms 157.202079ms 157.602872ms 157.636071ms 157.766602ms 157.813994ms 158.189584ms 158.244261ms 158.771149ms 158.983733ms 159.269988ms 159.96692ms 160.054382ms 160.163309ms 160.180527ms 160.618445ms 160.632278ms 161.555777ms 162.166593ms 162.383666ms 162.435589ms 162.513856ms 162.66663ms 163.010983ms 163.078577ms 163.16181ms 163.23066ms 164.096132ms 164.16944ms 164.385366ms 164.566881ms 164.850866ms 165.249452ms 165.735114ms 165.901192ms 165.957003ms 166.339051ms 166.3397ms 166.343205ms 166.72789ms 166.983538ms 167.168983ms 167.605402ms 167.620943ms 168.499505ms 168.630901ms 169.459965ms 169.707312ms 169.740994ms 170.226383ms 170.445948ms 170.732722ms 170.869793ms 171.619422ms 172.011159ms 173.13451ms 173.35008ms 173.767246ms 173.776986ms 175.014482ms 175.046584ms 175.590944ms 175.819724ms 177.348148ms 178.066825ms 178.102819ms 178.296356ms 178.596472ms 178.878245ms 179.145318ms 179.254176ms 179.370179ms 179.565433ms 179.702483ms 179.969956ms 180.030914ms 180.33939ms 180.782307ms 181.421182ms 181.798049ms 182.240767ms 183.309968ms 183.448926ms 183.714691ms 185.033695ms 185.574687ms 189.977467ms 191.692595ms 192.648239ms 193.043534ms 193.900068ms 196.945641ms 199.998111ms 200.163149ms]
Oct 25 07:35:32.755: INFO: 50 %ile: 155.067333ms
Oct 25 07:35:32.755: INFO: 90 %ile: 179.969956ms
Oct 25 07:35:32.755: INFO: 99 %ile: 199.998111ms
Oct 25 07:35:32.755: INFO: Total sample count: 200
[AfterEach] [sig-network] Service endpoints latency
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:142
Oct 25 07:35:32.755: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-svc-latency-r9qz6" for this suite.
Oct 25 07:35:42.768: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Oct 25 07:35:43.134: INFO: namespace: e2e-tests-svc-latency-r9qz6, resource: bindings, ignored listing per whitelist
Oct 25 07:35:43.483: INFO: namespace e2e-tests-svc-latency-r9qz6 deletion completed in 10.723644528s

• [SLOW TEST:16.184 seconds]
[sig-network] Service endpoints latency
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/network/framework.go:22
  should not be very high  [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s] [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[sig-scheduling] SchedulerPredicates [Serial] 
  validates that NodeSelector is respected if not matching  [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:51
[BeforeEach] [sig-scheduling] SchedulerPredicates [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:141
STEP: Creating a kubernetes client
Oct 25 07:35:43.483: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Oct 25 07:35:43.599: 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] [sig-scheduling] SchedulerPredicates [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/predicates.go:80
Oct 25 07:35:43.652: INFO: Waiting up to 1m0s for all (but 0) nodes to be ready
Oct 25 07:35:43.657: INFO: Waiting for terminating namespaces to be deleted...
Oct 25 07:35:43.661: INFO: 
Logging pods the kubelet thinks is on node ip-172-18-6-4.ec2.internal before test
Oct 25 07:35:43.669: INFO: asb-1-deploy from openshift-ansible-service-broker started at 2018-10-25 06:58:56 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:43.669: INFO: 	Container deployment ready: false, restart count 0
Oct 25 07:35:43.669: INFO: ovs-9649r from openshift-sdn started at 2018-10-25 06:55:34 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:43.669: INFO: 	Container openvswitch ready: true, restart count 0
Oct 25 07:35:43.669: INFO: sdn-6kzmb from openshift-sdn started at 2018-10-25 06:55:34 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:43.669: INFO: 	Container sdn ready: true, restart count 0
Oct 25 07:35:43.669: INFO: webconsole-664bcb44b7-vqdzw from openshift-web-console started at 2018-10-25 06:57:26 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:43.669: INFO: 	Container webconsole ready: true, restart count 0
Oct 25 07:35:43.669: INFO: router-1-44mk2 from default started at 2018-10-25 06:56:52 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:43.669: INFO: 	Container router ready: true, restart count 0
Oct 25 07:35:43.669: INFO: apiserver-v276x from kube-service-catalog started at 2018-10-25 06:58:24 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:43.669: INFO: 	Container apiserver ready: true, restart count 0
Oct 25 07:35:43.669: INFO: docker-registry-1-hrpzq from default started at 2018-10-25 06:57:07 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:43.669: INFO: 	Container registry ready: true, restart count 0
Oct 25 07:35:43.669: INFO: console-77fcfc87db-pkbbh from openshift-console started at 2018-10-25 06:57:45 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:43.669: INFO: 	Container console ready: true, restart count 0
Oct 25 07:35:43.669: INFO: registry-console-1-dtzgx from default started at 2018-10-25 06:57:17 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:43.669: INFO: 	Container registry-console ready: true, restart count 0
Oct 25 07:35:43.669: INFO: master-etcd-ip-172-18-6-4.ec2.internal from kube-system started at <nil> (0 container statuses recorded)
Oct 25 07:35:43.669: INFO: apiserver-6xnns from openshift-template-service-broker started at 2018-10-25 06:59:05 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:43.669: INFO: 	Container c ready: true, restart count 0
Oct 25 07:35:43.669: INFO: master-controllers-ip-172-18-6-4.ec2.internal from kube-system started at <nil> (0 container statuses recorded)
Oct 25 07:35:43.669: INFO: controller-manager-gcwnl from kube-service-catalog started at 2018-10-25 06:58:29 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:43.669: INFO: 	Container controller-manager ready: true, restart count 0
Oct 25 07:35:43.669: INFO: sync-stg4x from openshift-node started at 2018-10-25 06:55:13 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:43.669: INFO: 	Container sync ready: true, restart count 0
Oct 25 07:35:43.669: INFO: master-api-ip-172-18-6-4.ec2.internal from kube-system started at <nil> (0 container statuses recorded)
[It] validates that NodeSelector is respected if not matching  [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
STEP: Trying to schedule Pod with nonempty NodeSelector.
STEP: Considering event: 
Type = [Warning], Name = [restricted-pod.1560c9ca6586487e], Reason = [FailedScheduling], Message = [0/1 nodes are available: 1 node(s) didn't match node selector.]
[AfterEach] [sig-scheduling] SchedulerPredicates [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:142
Oct 25 07:35:44.687: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-sched-pred-scm6k" for this suite.
Oct 25 07:35:50.697: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Oct 25 07:35:50.797: INFO: namespace: e2e-tests-sched-pred-scm6k, resource: bindings, ignored listing per whitelist
Oct 25 07:35:51.417: INFO: namespace e2e-tests-sched-pred-scm6k deletion completed in 6.728008667s
[AfterEach] [sig-scheduling] SchedulerPredicates [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/predicates.go:71

• [SLOW TEST:7.934 seconds]
[sig-scheduling] SchedulerPredicates [Serial]
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/framework.go:22
  validates that NodeSelector is respected if not matching  [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[sig-scheduling] SchedulerPredicates [Serial] 
  validates resource limits of pods that are allowed to run  [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:51
[BeforeEach] [sig-scheduling] SchedulerPredicates [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:141
STEP: Creating a kubernetes client
Oct 25 07:35:51.419: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Oct 25 07:35:51.553: 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] [sig-scheduling] SchedulerPredicates [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/predicates.go:80
Oct 25 07:35:51.660: INFO: Waiting up to 1m0s for all (but 0) nodes to be ready
Oct 25 07:35:51.685: INFO: Waiting for terminating namespaces to be deleted...
Oct 25 07:35:51.693: INFO: 
Logging pods the kubelet thinks is on node ip-172-18-6-4.ec2.internal before test
Oct 25 07:35:51.715: INFO: docker-registry-1-hrpzq from default started at 2018-10-25 06:57:07 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:51.715: INFO: 	Container registry ready: true, restart count 0
Oct 25 07:35:51.715: INFO: console-77fcfc87db-pkbbh from openshift-console started at 2018-10-25 06:57:45 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:51.715: INFO: 	Container console ready: true, restart count 0
Oct 25 07:35:51.715: INFO: registry-console-1-dtzgx from default started at 2018-10-25 06:57:17 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:51.715: INFO: 	Container registry-console ready: true, restart count 0
Oct 25 07:35:51.715: INFO: master-etcd-ip-172-18-6-4.ec2.internal from kube-system started at <nil> (0 container statuses recorded)
Oct 25 07:35:51.715: INFO: apiserver-6xnns from openshift-template-service-broker started at 2018-10-25 06:59:05 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:51.715: INFO: 	Container c ready: true, restart count 0
Oct 25 07:35:51.715: INFO: master-controllers-ip-172-18-6-4.ec2.internal from kube-system started at <nil> (0 container statuses recorded)
Oct 25 07:35:51.715: INFO: controller-manager-gcwnl from kube-service-catalog started at 2018-10-25 06:58:29 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:51.715: INFO: 	Container controller-manager ready: true, restart count 0
Oct 25 07:35:51.715: INFO: sync-stg4x from openshift-node started at 2018-10-25 06:55:13 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:51.715: INFO: 	Container sync ready: true, restart count 0
Oct 25 07:35:51.715: INFO: master-api-ip-172-18-6-4.ec2.internal from kube-system started at <nil> (0 container statuses recorded)
Oct 25 07:35:51.715: INFO: asb-1-deploy from openshift-ansible-service-broker started at 2018-10-25 06:58:56 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:51.715: INFO: 	Container deployment ready: false, restart count 0
Oct 25 07:35:51.715: INFO: ovs-9649r from openshift-sdn started at 2018-10-25 06:55:34 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:51.715: INFO: 	Container openvswitch ready: true, restart count 0
Oct 25 07:35:51.715: INFO: sdn-6kzmb from openshift-sdn started at 2018-10-25 06:55:34 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:51.715: INFO: 	Container sdn ready: true, restart count 0
Oct 25 07:35:51.715: INFO: webconsole-664bcb44b7-vqdzw from openshift-web-console started at 2018-10-25 06:57:26 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:51.715: INFO: 	Container webconsole ready: true, restart count 0
Oct 25 07:35:51.715: INFO: router-1-44mk2 from default started at 2018-10-25 06:56:52 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:51.715: INFO: 	Container router ready: true, restart count 0
Oct 25 07:35:51.715: INFO: apiserver-v276x from kube-service-catalog started at 2018-10-25 06:58:24 +0000 UTC (1 container statuses recorded)
Oct 25 07:35:51.715: INFO: 	Container apiserver ready: true, restart count 0
[It] validates resource limits of pods that are allowed to run  [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
STEP: verifying the node has the label node ip-172-18-6-4.ec2.internal
Oct 25 07:35:51.730: INFO: Pod docker-registry-1-hrpzq requesting resource cpu=100m on Node ip-172-18-6-4.ec2.internal
Oct 25 07:35:51.730: INFO: Pod registry-console-1-dtzgx requesting resource cpu=0m on Node ip-172-18-6-4.ec2.internal
Oct 25 07:35:51.730: INFO: Pod router-1-44mk2 requesting resource cpu=100m on Node ip-172-18-6-4.ec2.internal
Oct 25 07:35:51.730: INFO: Pod apiserver-v276x requesting resource cpu=0m on Node ip-172-18-6-4.ec2.internal
Oct 25 07:35:51.730: INFO: Pod controller-manager-gcwnl requesting resource cpu=0m on Node ip-172-18-6-4.ec2.internal
Oct 25 07:35:51.730: INFO: Pod master-api-ip-172-18-6-4.ec2.internal requesting resource cpu=0m on Node ip-172-18-6-4.ec2.internal
Oct 25 07:35:51.730: INFO: Pod master-controllers-ip-172-18-6-4.ec2.internal requesting resource cpu=0m on Node ip-172-18-6-4.ec2.internal
Oct 25 07:35:51.730: INFO: Pod master-etcd-ip-172-18-6-4.ec2.internal requesting resource cpu=0m on Node ip-172-18-6-4.ec2.internal
Oct 25 07:35:51.730: INFO: Pod console-77fcfc87db-pkbbh requesting resource cpu=100m on Node ip-172-18-6-4.ec2.internal
Oct 25 07:35:51.730: INFO: Pod sync-stg4x requesting resource cpu=0m on Node ip-172-18-6-4.ec2.internal
Oct 25 07:35:51.730: INFO: Pod ovs-9649r requesting resource cpu=100m on Node ip-172-18-6-4.ec2.internal
Oct 25 07:35:51.730: INFO: Pod sdn-6kzmb requesting resource cpu=100m on Node ip-172-18-6-4.ec2.internal
Oct 25 07:35:51.730: INFO: Pod apiserver-6xnns requesting resource cpu=0m on Node ip-172-18-6-4.ec2.internal
Oct 25 07:35:51.730: INFO: Pod webconsole-664bcb44b7-vqdzw requesting resource cpu=100m on Node ip-172-18-6-4.ec2.internal
STEP: Starting Pods to consume most of the cluster CPU.
STEP: Creating another pod that requires unavailable amount of CPU.
STEP: Considering event: 
Type = [Normal], Name = [filler-pod-9950df61-d828-11e8-bdd6-0ed361e6162c.1560c9cc45d7b15b], Reason = [Scheduled], Message = [Successfully assigned e2e-tests-sched-pred-7rwjh/filler-pod-9950df61-d828-11e8-bdd6-0ed361e6162c to ip-172-18-6-4.ec2.internal]
STEP: Considering event: 
Type = [Normal], Name = [filler-pod-9950df61-d828-11e8-bdd6-0ed361e6162c.1560c9cca8adcf02], Reason = [Pulled], Message = [Container image "k8s.gcr.io/pause:3.1" already present on machine]
STEP: Considering event: 
Type = [Normal], Name = [filler-pod-9950df61-d828-11e8-bdd6-0ed361e6162c.1560c9ccaadc2814], Reason = [Created], Message = [Created container]
STEP: Considering event: 
Type = [Normal], Name = [filler-pod-9950df61-d828-11e8-bdd6-0ed361e6162c.1560c9ccb2505828], Reason = [Started], Message = [Started container]
STEP: Considering event: 
Type = [Warning], Name = [additional-pod.1560c9ccbdc5b69f], Reason = [FailedScheduling], Message = [0/1 nodes are available: 1 Insufficient cpu.]
STEP: removing the label node off the node ip-172-18-6-4.ec2.internal
STEP: verifying the node doesn't have the label node
[AfterEach] [sig-scheduling] SchedulerPredicates [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:142
Oct 25 07:35:54.767: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-sched-pred-7rwjh" for this suite.
Oct 25 07:36:00.778: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Oct 25 07:36:00.807: INFO: namespace: e2e-tests-sched-pred-7rwjh, resource: bindings, ignored listing per whitelist
Oct 25 07:36:01.493: INFO: namespace e2e-tests-sched-pred-7rwjh deletion completed in 6.72351453s
[AfterEach] [sig-scheduling] SchedulerPredicates [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/predicates.go:71

• [SLOW TEST:10.074 seconds]
[sig-scheduling] SchedulerPredicates [Serial]
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/scheduling/framework.go:22
  validates resource limits of pods that are allowed to run  [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[sig-apps] Daemon set [Serial] 
  should run and stop complex daemon [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:51
[BeforeEach] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:141
STEP: Creating a kubernetes client
Oct 25 07:36:01.495: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Oct 25 07:36:01.606: 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] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/apps/daemon_set.go:99
[It] should run and stop complex daemon [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
Oct 25 07:36:01.660: INFO: Creating daemon "daemon-set" with a node selector
STEP: Initially, daemon pods should not be running on any nodes.
Oct 25 07:36:01.667: INFO: Number of nodes with available pods: 0
Oct 25 07:36:01.667: INFO: Number of running nodes: 0, number of available pods: 0
STEP: Change node label to blue, check that daemon pod is launched.
Oct 25 07:36:01.682: INFO: Number of nodes with available pods: 0
Oct 25 07:36:01.682: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:02.685: INFO: Number of nodes with available pods: 0
Oct 25 07:36:02.685: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:03.685: INFO: Number of nodes with available pods: 0
Oct 25 07:36:03.686: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:04.685: INFO: Number of nodes with available pods: 0
Oct 25 07:36:04.685: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:05.685: INFO: Number of nodes with available pods: 1
Oct 25 07:36:05.685: INFO: Number of running nodes: 1, number of available pods: 1
STEP: Update the node label to green, and wait for daemons to be unscheduled
Oct 25 07:36:05.697: INFO: Number of nodes with available pods: 1
Oct 25 07:36:05.697: INFO: Number of running nodes: 0, number of available pods: 1
Oct 25 07:36:06.700: INFO: Number of nodes with available pods: 0
Oct 25 07:36:06.700: INFO: Number of running nodes: 0, number of available pods: 0
STEP: Update DaemonSet node selector to green, and change its update strategy to RollingUpdate
Oct 25 07:36:06.710: INFO: Number of nodes with available pods: 0
Oct 25 07:36:06.711: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:07.713: INFO: Number of nodes with available pods: 0
Oct 25 07:36:07.713: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:08.713: INFO: Number of nodes with available pods: 0
Oct 25 07:36:08.713: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:09.713: INFO: Number of nodes with available pods: 0
Oct 25 07:36:09.713: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:10.713: INFO: Number of nodes with available pods: 0
Oct 25 07:36:10.713: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:11.713: INFO: Number of nodes with available pods: 1
Oct 25 07:36:11.713: INFO: Number of running nodes: 1, number of available pods: 1
[AfterEach] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/apps/daemon_set.go:65
STEP: Deleting DaemonSet "daemon-set"
STEP: deleting {extensions DaemonSet} daemon-set in namespace e2e-tests-daemonsets-xhtsv, will wait for the garbage collector to delete the pods
Oct 25 07:36:11.774: INFO: Deleting {extensions DaemonSet} daemon-set took: 4.36188ms
Oct 25 07:36:11.874: INFO: Terminating {extensions DaemonSet} daemon-set pods took: 100.230749ms
Oct 25 07:36:14.977: INFO: Number of nodes with available pods: 0
Oct 25 07:36:14.977: INFO: Number of running nodes: 0, number of available pods: 0
Oct 25 07:36:14.979: INFO: daemonset: {"kind":"DaemonSetList","apiVersion":"apps/v1","metadata":{"selfLink":"/apis/apps/v1/namespaces/e2e-tests-daemonsets-xhtsv/daemonsets","resourceVersion":"33102"},"items":null}

Oct 25 07:36:14.981: INFO: pods: {"kind":"PodList","apiVersion":"v1","metadata":{"selfLink":"/api/v1/namespaces/e2e-tests-daemonsets-xhtsv/pods","resourceVersion":"33102"},"items":null}

[AfterEach] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:142
Oct 25 07:36:14.994: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-daemonsets-xhtsv" for this suite.
Oct 25 07:36:21.004: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Oct 25 07:36:21.072: INFO: namespace: e2e-tests-daemonsets-xhtsv, resource: bindings, ignored listing per whitelist
Oct 25 07:36:21.723: INFO: namespace e2e-tests-daemonsets-xhtsv deletion completed in 6.726584649s

• [SLOW TEST:20.228 seconds]
[sig-apps] Daemon set [Serial]
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/apps/framework.go:22
  should run and stop complex daemon [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[sig-apps] Daemon set [Serial] 
  should rollback without unnecessary restarts [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:51
[BeforeEach] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:141
STEP: Creating a kubernetes client
Oct 25 07:36:21.723: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Oct 25 07:36:21.842: 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] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/apps/daemon_set.go:99
[It] should rollback without unnecessary restarts [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
Oct 25 07:36:21.889: INFO: Requires at least 2 nodes (not -1)
[AfterEach] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/apps/daemon_set.go:65
Oct 25 07:36:21.894: INFO: daemonset: {"kind":"DaemonSetList","apiVersion":"apps/v1","metadata":{"selfLink":"/apis/apps/v1/namespaces/e2e-tests-daemonsets-v6fwc/daemonsets","resourceVersion":"33178"},"items":null}

Oct 25 07:36:21.896: INFO: pods: {"kind":"PodList","apiVersion":"v1","metadata":{"selfLink":"/api/v1/namespaces/e2e-tests-daemonsets-v6fwc/pods","resourceVersion":"33178"},"items":null}

[AfterEach] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:142
Oct 25 07:36:21.901: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-daemonsets-v6fwc" for this suite.
Oct 25 07:36:27.911: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Oct 25 07:36:27.972: INFO: namespace: e2e-tests-daemonsets-v6fwc, resource: bindings, ignored listing per whitelist
Oct 25 07:36:28.629: INFO: namespace e2e-tests-daemonsets-v6fwc deletion completed in 6.726534669s

S [SKIPPING] [6.906 seconds]
[sig-apps] Daemon set [Serial]
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/apps/framework.go:22
  should rollback without unnecessary restarts [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s] [It]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684

  Oct 25 07:36:21.889: Requires at least 2 nodes (not -1)

  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/util.go:308
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[sig-apps] Daemon set [Serial] 
  should update pod when spec was updated and update strategy is RollingUpdate [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:51
[BeforeEach] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:141
STEP: Creating a kubernetes client
Oct 25 07:36:28.630: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Oct 25 07:36:28.734: 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] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/apps/daemon_set.go:99
[It] should update pod when spec was updated and update strategy is RollingUpdate [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
Oct 25 07:36:28.789: INFO: Creating simple daemon set daemon-set
STEP: Check that daemon pods launch on every node of the cluster.
Oct 25 07:36:28.797: INFO: Number of nodes with available pods: 0
Oct 25 07:36:28.797: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:29.809: INFO: Number of nodes with available pods: 0
Oct 25 07:36:29.809: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:30.802: INFO: Number of nodes with available pods: 0
Oct 25 07:36:30.802: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:31.802: INFO: Number of nodes with available pods: 1
Oct 25 07:36:31.802: INFO: Number of running nodes: 1, number of available pods: 1
STEP: Update daemon pods image.
STEP: Check that daemon pods images are updated.
Oct 25 07:36:31.819: INFO: Wrong image for pod: daemon-set-7c7wm. Expected: gcr.io/kubernetes-e2e-test-images/redis-amd64:1.0, got: gcr.io/kubernetes-e2e-test-images/serve-hostname-amd64:1.0.
Oct 25 07:36:32.823: INFO: Wrong image for pod: daemon-set-7c7wm. Expected: gcr.io/kubernetes-e2e-test-images/redis-amd64:1.0, got: gcr.io/kubernetes-e2e-test-images/serve-hostname-amd64:1.0.
Oct 25 07:36:33.824: INFO: Wrong image for pod: daemon-set-7c7wm. Expected: gcr.io/kubernetes-e2e-test-images/redis-amd64:1.0, got: gcr.io/kubernetes-e2e-test-images/serve-hostname-amd64:1.0.
Oct 25 07:36:34.824: INFO: Wrong image for pod: daemon-set-7c7wm. Expected: gcr.io/kubernetes-e2e-test-images/redis-amd64:1.0, got: gcr.io/kubernetes-e2e-test-images/serve-hostname-amd64:1.0.
Oct 25 07:36:34.824: INFO: Pod daemon-set-7c7wm is not available
Oct 25 07:36:35.823: INFO: Wrong image for pod: daemon-set-7c7wm. Expected: gcr.io/kubernetes-e2e-test-images/redis-amd64:1.0, got: gcr.io/kubernetes-e2e-test-images/serve-hostname-amd64:1.0.
Oct 25 07:36:35.823: INFO: Pod daemon-set-7c7wm is not available
Oct 25 07:36:36.823: INFO: Wrong image for pod: daemon-set-7c7wm. Expected: gcr.io/kubernetes-e2e-test-images/redis-amd64:1.0, got: gcr.io/kubernetes-e2e-test-images/serve-hostname-amd64:1.0.
Oct 25 07:36:36.823: INFO: Pod daemon-set-7c7wm is not available
Oct 25 07:36:37.823: INFO: Wrong image for pod: daemon-set-7c7wm. Expected: gcr.io/kubernetes-e2e-test-images/redis-amd64:1.0, got: gcr.io/kubernetes-e2e-test-images/serve-hostname-amd64:1.0.
Oct 25 07:36:37.823: INFO: Pod daemon-set-7c7wm is not available
Oct 25 07:36:38.823: INFO: Wrong image for pod: daemon-set-7c7wm. Expected: gcr.io/kubernetes-e2e-test-images/redis-amd64:1.0, got: gcr.io/kubernetes-e2e-test-images/serve-hostname-amd64:1.0.
Oct 25 07:36:38.823: INFO: Pod daemon-set-7c7wm is not available
Oct 25 07:36:39.823: INFO: Wrong image for pod: daemon-set-7c7wm. Expected: gcr.io/kubernetes-e2e-test-images/redis-amd64:1.0, got: gcr.io/kubernetes-e2e-test-images/serve-hostname-amd64:1.0.
Oct 25 07:36:39.824: INFO: Pod daemon-set-7c7wm is not available
Oct 25 07:36:40.823: INFO: Pod daemon-set-rbj9h is not available
STEP: Check that daemon pods are still running on every node of the cluster.
Oct 25 07:36:40.830: INFO: Number of nodes with available pods: 0
Oct 25 07:36:40.830: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:41.835: INFO: Number of nodes with available pods: 0
Oct 25 07:36:41.835: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:42.835: INFO: Number of nodes with available pods: 0
Oct 25 07:36:42.835: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:43.835: INFO: Number of nodes with available pods: 1
Oct 25 07:36:43.835: INFO: Number of running nodes: 1, number of available pods: 1
[AfterEach] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/apps/daemon_set.go:65
STEP: Deleting DaemonSet "daemon-set"
STEP: deleting {extensions DaemonSet} daemon-set in namespace e2e-tests-daemonsets-qj7hf, will wait for the garbage collector to delete the pods
Oct 25 07:36:43.901: INFO: Deleting {extensions DaemonSet} daemon-set took: 4.282398ms
Oct 25 07:36:44.001: INFO: Terminating {extensions DaemonSet} daemon-set pods took: 100.225825ms
Oct 25 07:36:47.504: INFO: Number of nodes with available pods: 0
Oct 25 07:36:47.504: INFO: Number of running nodes: 0, number of available pods: 0
Oct 25 07:36:47.506: INFO: daemonset: {"kind":"DaemonSetList","apiVersion":"apps/v1","metadata":{"selfLink":"/apis/apps/v1/namespaces/e2e-tests-daemonsets-qj7hf/daemonsets","resourceVersion":"33328"},"items":null}

Oct 25 07:36:47.508: INFO: pods: {"kind":"PodList","apiVersion":"v1","metadata":{"selfLink":"/api/v1/namespaces/e2e-tests-daemonsets-qj7hf/pods","resourceVersion":"33328"},"items":null}

[AfterEach] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:142
Oct 25 07:36:47.512: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-daemonsets-qj7hf" for this suite.
Oct 25 07:36:53.523: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Oct 25 07:36:53.548: INFO: namespace: e2e-tests-daemonsets-qj7hf, resource: bindings, ignored listing per whitelist
Oct 25 07:36:54.239: INFO: namespace e2e-tests-daemonsets-qj7hf deletion completed in 6.725163328s

• [SLOW TEST:25.609 seconds]
[sig-apps] Daemon set [Serial]
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/apps/framework.go:22
  should update pod when spec was updated and update strategy is RollingUpdate [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[sig-apps] Daemon set [Serial] 
  should run and stop simple daemon [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:51
[BeforeEach] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:141
STEP: Creating a kubernetes client
Oct 25 07:36:54.240: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Oct 25 07:36:54.359: 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] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/apps/daemon_set.go:99
[It] should run and stop simple daemon [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
STEP: Creating simple DaemonSet "daemon-set"
STEP: Check that daemon pods launch on every node of the cluster.
Oct 25 07:36:54.425: INFO: Number of nodes with available pods: 0
Oct 25 07:36:54.425: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:55.430: INFO: Number of nodes with available pods: 0
Oct 25 07:36:55.430: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:56.430: INFO: Number of nodes with available pods: 0
Oct 25 07:36:56.430: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:57.430: INFO: Number of nodes with available pods: 0
Oct 25 07:36:57.430: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:58.430: INFO: Number of nodes with available pods: 1
Oct 25 07:36:58.430: INFO: Number of running nodes: 1, number of available pods: 1
STEP: Stop a daemon pod, check that the daemon pod is revived.
Oct 25 07:36:58.442: INFO: Number of nodes with available pods: 0
Oct 25 07:36:58.442: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:36:59.447: INFO: Number of nodes with available pods: 0
Oct 25 07:36:59.447: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:37:00.447: INFO: Number of nodes with available pods: 0
Oct 25 07:37:00.447: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:37:01.447: INFO: Number of nodes with available pods: 0
Oct 25 07:37:01.447: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:37:02.447: INFO: Number of nodes with available pods: 0
Oct 25 07:37:02.447: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:37:03.447: INFO: Number of nodes with available pods: 0
Oct 25 07:37:03.447: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:37:04.447: INFO: Number of nodes with available pods: 0
Oct 25 07:37:04.447: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:37:05.447: INFO: Number of nodes with available pods: 0
Oct 25 07:37:05.447: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:37:06.447: INFO: Number of nodes with available pods: 0
Oct 25 07:37:06.447: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:37:07.447: INFO: Number of nodes with available pods: 0
Oct 25 07:37:07.447: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:37:08.447: INFO: Number of nodes with available pods: 0
Oct 25 07:37:08.447: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:37:09.447: INFO: Number of nodes with available pods: 0
Oct 25 07:37:09.447: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:37:10.447: INFO: Number of nodes with available pods: 0
Oct 25 07:37:10.447: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:37:11.447: INFO: Number of nodes with available pods: 0
Oct 25 07:37:11.447: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:37:12.452: INFO: Number of nodes with available pods: 0
Oct 25 07:37:12.452: INFO: Node ip-172-18-6-4.ec2.internal is running more than one daemon pod
Oct 25 07:37:13.447: INFO: Number of nodes with available pods: 1
Oct 25 07:37:13.447: INFO: Number of running nodes: 1, number of available pods: 1
[AfterEach] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/apps/daemon_set.go:65
STEP: Deleting DaemonSet "daemon-set"
STEP: deleting {extensions DaemonSet} daemon-set in namespace e2e-tests-daemonsets-bw97q, will wait for the garbage collector to delete the pods
Oct 25 07:37:13.506: INFO: Deleting {extensions DaemonSet} daemon-set took: 4.542389ms
Oct 25 07:37:13.606: INFO: Terminating {extensions DaemonSet} daemon-set pods took: 100.238097ms
Oct 25 07:37:16.909: INFO: Number of nodes with available pods: 0
Oct 25 07:37:16.909: INFO: Number of running nodes: 0, number of available pods: 0
Oct 25 07:37:16.911: INFO: daemonset: {"kind":"DaemonSetList","apiVersion":"apps/v1","metadata":{"selfLink":"/apis/apps/v1/namespaces/e2e-tests-daemonsets-bw97q/daemonsets","resourceVersion":"33480"},"items":null}

Oct 25 07:37:16.912: INFO: pods: {"kind":"PodList","apiVersion":"v1","metadata":{"selfLink":"/api/v1/namespaces/e2e-tests-daemonsets-bw97q/pods","resourceVersion":"33480"},"items":null}

[AfterEach] [sig-apps] Daemon set [Serial]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:142
Oct 25 07:37:16.919: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-daemonsets-bw97q" for this suite.
Oct 25 07:37:22.929: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Oct 25 07:37:23.019: INFO: namespace: e2e-tests-daemonsets-bw97q, resource: bindings, ignored listing per whitelist
Oct 25 07:37:23.646: INFO: namespace e2e-tests-daemonsets-bw97q deletion completed in 6.725486074s

• [SLOW TEST:29.407 seconds]
[sig-apps] Daemon set [Serial]
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/apps/framework.go:22
  should run and stop simple daemon [Conformance] [Suite:openshift/conformance/serial/minimal] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:684
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSOct 25 07:37:23.647: INFO: Running AfterSuite actions on all node
Oct 25 07:37:23.647: INFO: Running AfterSuite actions on node 1
Oct 25 07:37:23.647: INFO: Dumping logs locally to: /data/src/github.com/openshift/origin/_output/scripts/conformance/artifacts/junit
Checking for custom logdump instances, if any
Sourcing kube-util.sh
Detecting project
/data/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/cluster/log-dump/../../cluster/../cluster/gce/util.sh: line 141: gcloud: command not found
Oct 25 07:37:23.689: INFO: Error running cluster/log-dump/log-dump.sh: exit status 127

Ran 8 of 1251 Specs in 160.272 seconds
SUCCESS! -- 8 Passed | 0 Failed | 0 Pending | 1243 Skipped Oct 25 07:37:23.696: INFO: Dumping logs locally to: /data/src/github.com/openshift/origin/_output/scripts/conformance/artifacts/junit
Checking for custom logdump instances, if any
Sourcing kube-util.sh
Detecting project
/data/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/cluster/log-dump/../../cluster/../cluster/gce/util.sh: line 141: gcloud: command not found
Oct 25 07:37:23.736: INFO: Error running cluster/log-dump/log-dump.sh: exit status 127
PASS

Ginkgo ran 1 suite in 2m41.221704812s
Test Suite Passed
[INFO] test/extended/conformance.sh exited with code 0 after 00h 33m 50s
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: RUN EXTENDED TESTS [00h 37m 55s] ##########
[PostBuildScript] - Executing post build scripts.
[workspace] $ /bin/bash /tmp/jenkins5265941621615741209.sh
########## STARTING STAGE: DOWNLOAD ARTIFACTS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd
++ export PATH=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/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/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/artifacts/gathered
+ rm -rf /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/artifacts/gathered
+ mkdir -p /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/artifacts/gathered
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.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: 84        	Blocks: 0          IO Block: 4096   directory
Device: ca02h/51714d	Inode: 188772545   Links: 6
Access: (2755/drwxr-sr-x)  Uid: ( 1001/  origin)   Gid: ( 1003/origin-git)
Context: unconfined_u:object_r:container_file_t:s0
Access: 2018-10-25 06:59:28.670909202 +0000
Modify: 2018-10-25 07:03:33.513934022 +0000
Change: 2018-10-25 07:03:33.513934022 +0000
 Birth: -
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo chmod -R o+rX /data/src/github.com/openshift/origin/_output/scripts
+ scp -r -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.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/workspace/artifacts/gathered
+ tree /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/artifacts/gathered
/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/artifacts/gathered
└── scripts
    ├── ansible_junit
    │   ├── gbmbxPvBSY.xml
    │   ├── RJsaXGyGxX.xml
    │   └── YxHxJueBES.xml
    ├── build-base-images
    │   ├── artifacts
    │   ├── logs
    │   └── openshift.local.home
    ├── conformance
    │   ├── artifacts
    │   │   └── 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
    │   │       └── nethealth.txt
    │   ├── logs
    │   │   └── scripts.log
    │   └── openshift.local.home
    └── shell
        ├── artifacts
        ├── logs
        │   └── scripts.log
        └── openshift.local.home

15 directories, 12 files
+ exit 0
[workspace] $ /bin/bash /tmp/jenkins4942623889072850053.sh
########## STARTING STAGE: GENERATE ARTIFACTS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd
++ export PATH=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/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/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/artifacts/generated
+ rm -rf /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/artifacts/generated
+ mkdir /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/artifacts/generated
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo docker version && sudo docker info && sudo docker images && sudo docker ps -a 2>&1'
  WARNING: You're not using the default seccomp profile
WARNING: bridge-nf-call-iptables is disabled
WARNING: bridge-nf-call-ip6tables is disabled
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo cat /etc/etcd/etcd.conf 2>&1'
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.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 /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo find /var/lib/docker/containers -name *.log | sudo xargs tail -vn +1 2>&1'
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.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 /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo ausearch -m AVC -m SELINUX_ERR -m USER_AVC 2>&1'
+ true
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'oc get --raw /metrics --config=/etc/origin/master/admin.kubeconfig 2>&1'
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo df -T -h && sudo pvs && sudo vgs && sudo lvs && sudo findmnt --all 2>&1'
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo yum list installed 2>&1'
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo journalctl --dmesg --no-pager --all --lines=all 2>&1'
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.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/workspace/artifacts/generated
/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/artifacts/generated
├── avc_denials.log
├── containers.log
├── dmesg.log
├── docker.config
├── docker.info
├── etcd.conf
├── filesystem.info
├── installed_packages.log
├── master-metrics.log
├── node-metrics.log
└── pid1.journal

0 directories, 11 files
+ exit 0
[workspace] $ /bin/bash /tmp/jenkins4175856483753841246.sh
########## STARTING STAGE: FETCH SYSTEMD JOURNALS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd
++ export PATH=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/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/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/artifacts/journals
+ rm -rf /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/artifacts/journals
+ mkdir /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/artifacts/journals
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit docker.service --no-pager --all --lines=all
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit dnsmasq.service --no-pager --all --lines=all
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit systemd-journald.service --no-pager --all --lines=all
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit origin-master.service --no-pager --all --lines=all
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit origin-master-api.service --no-pager --all --lines=all
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit origin-master-controllers.service --no-pager --all --lines=all
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit origin-node.service --no-pager --all --lines=all
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit openvswitch.service --no-pager --all --lines=all
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit ovs-vswitchd.service --no-pager --all --lines=all
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit ovsdb-server.service --no-pager --all --lines=all
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit etcd.service --no-pager --all --lines=all
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.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/workspace/artifacts/journals
/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/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/jenkins6859401578920397080.sh
########## STARTING STAGE: ASSEMBLE GCS OUTPUT ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd
++ export PATH=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/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/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config
+ trap 'exit 0' EXIT
+ mkdir -p gcs/artifacts gcs/artifacts/generated gcs/artifacts/journals gcs/artifacts/gathered
++ python -c 'import json; import urllib; print json.load(urllib.urlopen('\''https://ci.openshift.redhat.com/jenkins/job/test_pull_request_origin_extended_conformance_install/16432/api/json'\''))['\''result'\'']'
+ result=SUCCESS
+ cat
++ date +%s
+ cat /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/builds/16432/log
+ cp artifacts/generated/avc_denials.log artifacts/generated/containers.log artifacts/generated/dmesg.log artifacts/generated/docker.config artifacts/generated/docker.info artifacts/generated/etcd.conf artifacts/generated/filesystem.info artifacts/generated/installed_packages.log artifacts/generated/master-metrics.log artifacts/generated/node-metrics.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/
++ pwd
+ scp -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config -r /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/gcs openshiftdevel:/data
+ scp -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config /var/lib/jenkins/.config/gcloud/gcs-publisher-credentials.json openshiftdevel:/data/credentials.json
+ exit 0
[workspace] $ /bin/bash /tmp/jenkins234057274683818978.sh
########## STARTING STAGE: PUSH THE ARTIFACTS AND METADATA ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd
++ export PATH=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/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/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config
++ mktemp
+ script=/tmp/tmp.fvQLDCrkSF
+ cat
+ chmod +x /tmp/tmp.fvQLDCrkSF
+ scp -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config /tmp/tmp.fvQLDCrkSF openshiftdevel:/tmp/tmp.fvQLDCrkSF
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/.ssh_config -t openshiftdevel 'bash -l -c "timeout 300 /tmp/tmp.fvQLDCrkSF"'
+ cd /home/origin
+ trap 'exit 0' EXIT
+ [[ -n {"type":"presubmit","job":"test_pull_request_origin_extended_conformance_install","buildid":"1055342590880124928","prowjobid":"a26916f4-d81d-11e8-b73f-0a58ac10266e","refs":{"org":"openshift","repo":"origin","base_ref":"master","base_sha":"96895a8c545e51b8cf4a8cb7ad38d1fd5f5f2f29","pulls":[{"number":21346,"author":"sjug","sha":"b5cd108a7fd25ca31bc277fc2dae0b2a887e63ef"}]}} ]]
++ jq --compact-output '.buildid |= "16432"'
+ JOB_SPEC='{"type":"presubmit","job":"test_pull_request_origin_extended_conformance_install","buildid":"16432","prowjobid":"a26916f4-d81d-11e8-b73f-0a58ac10266e","refs":{"org":"openshift","repo":"origin","base_ref":"master","base_sha":"96895a8c545e51b8cf4a8cb7ad38d1fd5f5f2f29","pulls":[{"number":21346,"author":"sjug","sha":"b5cd108a7fd25ca31bc277fc2dae0b2a887e63ef"}]}}'
+ docker run -e 'JOB_SPEC={"type":"presubmit","job":"test_pull_request_origin_extended_conformance_install","buildid":"16432","prowjobid":"a26916f4-d81d-11e8-b73f-0a58ac10266e","refs":{"org":"openshift","repo":"origin","base_ref":"master","base_sha":"96895a8c545e51b8cf4a8cb7ad38d1fd5f5f2f29","pulls":[{"number":21346,"author":"sjug","sha":"b5cd108a7fd25ca31bc277fc2dae0b2a887e63ef"}]}}' -v /data:/data:z registry.svc.ci.openshift.org/ci/gcsupload:latest --dry-run=false --gcs-path=gs://origin-ci-test --gcs-credentials-file=/data/credentials.json --path-strategy=single --default-org=openshift --default-repo=origin /data/gcs/artifacts /data/gcs/build-log.txt /data/gcs/finished.json
Unable to find image 'registry.svc.ci.openshift.org/ci/gcsupload:latest' locally
Trying to pull repository registry.svc.ci.openshift.org/ci/gcsupload ... 
latest: Pulling from registry.svc.ci.openshift.org/ci/gcsupload
a073c86ecf9e: Already exists
cc3fc741b1a9: Already exists
c2227a877946: Pulling fs layer
c2227a877946: Verifying Checksum
c2227a877946: Download complete
c2227a877946: Pull complete
Digest: sha256:7d6e7ab558e1351c9fb2d37bb82f6294bcdb99648124bbdf3237eae8c2182ec1
Status: Downloaded newer image for registry.svc.ci.openshift.org/ci/gcsupload:latest
{"component":"gcsupload","level":"info","msg":"Gathering artifacts from artifact directory: /data/gcs/artifacts","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/avc_denials.log in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/avc_denials.log\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/containers.log in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/containers.log\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/dmesg.log in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/dmesg.log\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/docker.config in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/docker.config\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/docker.info in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/docker.info\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/etcd.conf in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/etcd.conf\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/filesystem.info in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/filesystem.info\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/installed_packages.log in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/installed_packages.log\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/master-metrics.log in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/master-metrics.log\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/node-metrics.log in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/node-metrics.log\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/pid1.journal in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/pid1.journal\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/journals/dnsmasq.service in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/dnsmasq.service\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/journals/docker.service in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/docker.service\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/journals/etcd.service in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/etcd.service\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/journals/openvswitch.service in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/openvswitch.service\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/journals/origin-master-api.service in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/origin-master-api.service\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/journals/origin-master-controllers.service in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/origin-master-controllers.service\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/journals/origin-master.service in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/origin-master.service\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/journals/origin-node.service in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/origin-node.service\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/journals/ovs-vswitchd.service in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/ovs-vswitchd.service\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/journals/ovsdb-server.service in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/ovsdb-server.service\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/journals/systemd-journald.service in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/systemd-journald.service\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/scripts/ansible_junit/RJsaXGyGxX.xml in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/ansible_junit/RJsaXGyGxX.xml\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/scripts/ansible_junit/YxHxJueBES.xml in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/ansible_junit/YxHxJueBES.xml\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/scripts/ansible_junit/gbmbxPvBSY.xml in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/ansible_junit/gbmbxPvBSY.xml\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_01.xml in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_01.xml\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_02.xml in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_02.xml\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_03.xml in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_03.xml\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_04.xml in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_04.xml\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_05.xml in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_05.xml\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/scripts/conformance/artifacts/junit/conformance_serial_01.xml in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_serial_01.xml\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/scripts/conformance/artifacts/junit/nethealth.txt in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/nethealth.txt\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/scripts/conformance/logs/scripts.log in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/logs/scripts.log\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/scripts/shell/logs/scripts.log in artifact directory. Uploading as pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/shell/logs/scripts.log\n","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/latest-build.txt","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/installed_packages.log","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/openvswitch.service","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_01.xml","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_02.xml","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/filesystem.info","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/pid1.journal","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/ansible_junit/RJsaXGyGxX.xml","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_03.xml","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_serial_01.xml","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/directory/test_pull_request_origin_extended_conformance_install/16432.txt","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/etcd.conf","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/ovs-vswitchd.service","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_04.xml","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/dmesg.log","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/node-metrics.log","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/origin-master-controllers.service","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_05.xml","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/docker.config","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/docker.service","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/systemd-journald.service","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/shell/logs/scripts.log","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/finished.json","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/directory/test_pull_request_origin_extended_conformance_install/latest-build.txt","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/avc_denials.log","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/master-metrics.log","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/dnsmasq.service","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/ansible_junit/gbmbxPvBSY.xml","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/containers.log","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/ovsdb-server.service","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/nethealth.txt","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/build-log.txt","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/ansible_junit/YxHxJueBES.xml","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/logs/scripts.log","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/docker.info","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/etcd.service","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/origin-master-api.service","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/origin-master.service","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/origin-node.service","level":"info","msg":"Queued for upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/etcd.conf","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/directory/test_pull_request_origin_extended_conformance_install/16432.txt","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/finished.json","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/systemd-journald.service","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/dnsmasq.service","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/ansible_junit/RJsaXGyGxX.xml","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/filesystem.info","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_05.xml","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/origin-master-controllers.service","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/openvswitch.service","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/shell/logs/scripts.log","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/logs/scripts.log","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/nethealth.txt","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/ovs-vswitchd.service","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/ovsdb-server.service","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/docker.info","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/ansible_junit/YxHxJueBES.xml","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/etcd.service","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/origin-master-api.service","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/avc_denials.log","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/node-metrics.log","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_serial_01.xml","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/origin-master.service","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_01.xml","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/dmesg.log","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/directory/test_pull_request_origin_extended_conformance_install/latest-build.txt","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/ansible_junit/gbmbxPvBSY.xml","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/latest-build.txt","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/docker.config","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/installed_packages.log","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_03.xml","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_02.xml","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:12Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_04.xml","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:13Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/pid1.journal","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:13Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/docker.service","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:13Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/build-log.txt","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:13Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/master-metrics.log","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:13Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/journals/origin-node.service","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:14Z"}
{"component":"gcsupload","dest":"pr-logs/pull/21346/test_pull_request_origin_extended_conformance_install/16432/artifacts/generated/containers.log","level":"info","msg":"Finished upload","time":"2018-10-25T07:38:24Z"}
{"component":"gcsupload","level":"info","msg":"Finished upload to GCS","time":"2018-10-25T07:38:24Z"}
+ exit 0
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: PUSH THE ARTIFACTS AND METADATA [00h 00m 20s] ##########
[workspace] $ /bin/bash /tmp/jenkins6768958092160722518.sh
########## STARTING STAGE: DEPROVISION CLOUD RESOURCES ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd
++ export PATH=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/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/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config
+ oct deprovision

PLAYBOOK: main.yml *************************************************************
4 plays in /var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/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/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:9
skipping: [localhost] => (item=origin_ci_inventory_dir)  => {
    "changed": false, 
    "generated_timestamp": "2018-10-25 03:38:25.738165", 
    "item": "origin_ci_inventory_dir", 
    "skip_reason": "Conditional check failed", 
    "skipped": true
}
skipping: [localhost] => (item=origin_ci_aws_region)  => {
    "changed": false, 
    "generated_timestamp": "2018-10-25 03:38:25.742596", 
    "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/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:28
included: /var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/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/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:2
ok: [localhost] => {
    "changed": false, 
    "generated_timestamp": "2018-10-25 03:38:26.504993", 
    "msg": ""
}

TASK [rename EC2 instance for termination reaper] ******************************
task path: /var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:8
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2018-10-25 03:38:27.267582", 
    "msg": "Tags {'Name': 'oct-terminate'} created for resource i-0e7882e71fd8ff9df."
}

TASK [tear down the EC2 instance] **********************************************
task path: /var/lib/jenkins/origin-ci-tool/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:15
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2018-10-25 03:38:28.099258", 
    "instance_ids": [
        "i-0e7882e71fd8ff9df"
    ], 
    "instances": [
        {
            "ami_launch_index": "0", 
            "architecture": "x86_64", 
            "block_device_mapping": {
                "/dev/sda1": {
                    "delete_on_termination": true, 
                    "status": "attached", 
                    "volume_id": "vol-0a983d09b69eea368"
                }, 
                "/dev/sdb": {
                    "delete_on_termination": true, 
                    "status": "attached", 
                    "volume_id": "vol-0e29caca1caaae06b"
                }
            }, 
            "dns_name": "ec2-35-175-232-80.compute-1.amazonaws.com", 
            "ebs_optimized": false, 
            "groups": {
                "sg-7e73221a": "default"
            }, 
            "hypervisor": "xen", 
            "id": "i-0e7882e71fd8ff9df", 
            "image_id": "ami-0b77b87a37c3e662c", 
            "instance_type": "m4.xlarge", 
            "kernel": null, 
            "key_name": "libra", 
            "launch_time": "2018-10-25T06:17:48.000Z", 
            "placement": "us-east-1d", 
            "private_dns_name": "ip-172-18-6-4.ec2.internal", 
            "private_ip": "172.18.6.4", 
            "public_dns_name": "ec2-35-175-232-80.compute-1.amazonaws.com", 
            "public_ip": "35.175.232.80", 
            "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/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:22
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2018-10-25 03:38:28.336108", 
    "path": "/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/workspace/.config/origin-ci-tool/inventory/host_vars/172.18.6.4.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/dae8b1fdd92e4c6b040802a9f6893334ae0660fd/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:61
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2018-10-25 03:38:28.777564", 
    "path": "/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_install/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 04s] ##########
Archiving artifacts
[WS-CLEANUP] Deleting project workspace...[WS-CLEANUP] done
Finished: SUCCESS