SuccessConsole Output

Skipping 3,329 KB.. Full Log
[It] should support remote command execution over websockets [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/pods.go:470
Feb 20 23:07:45.002: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: creating the pod
STEP: submitting the pod to kubernetes
[AfterEach] [k8s.io] Pods
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Feb 20 23:07:51.114: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-pods-dwtr8" for this suite.
Feb 20 23:08:31.133: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Feb 20 23:08:31.255: INFO: namespace: e2e-tests-pods-dwtr8, resource: bindings, ignored listing per whitelist
Feb 20 23:08:31.287: INFO: namespace e2e-tests-pods-dwtr8 deletion completed in 40.167847873s


• [SLOW TEST:46.534 seconds]
[k8s.io] Pods
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:643
  should support remote command execution over websockets [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/pods.go:470
------------------------------
[Feature:DeploymentConfig] deploymentconfigs with test deployments [Conformance] 
  should run a deployment to completion and then scale to zero [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:303

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [Feature:DeploymentConfig] deploymentconfigs
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Feb 20 23:04:14.576: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Feb 20 23:04:14.612: INFO: configPath is now "/tmp/extended-test-cli-deployment-2fqsl-8jspz-user.kubeconfig"
Feb 20 23:04:14.613: INFO: The user is now "extended-test-cli-deployment-2fqsl-8jspz-user"
Feb 20 23:04:14.613: INFO: Creating project "extended-test-cli-deployment-2fqsl-8jspz"
Feb 20 23:04:14.685: INFO: Waiting on permissions in project "extended-test-cli-deployment-2fqsl-8jspz" ...
STEP: Waiting for a default service account to be provisioned in namespace
[JustBeforeEach] [Feature:DeploymentConfig] deploymentconfigs
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:43
[It] should run a deployment to completion and then scale to zero [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:303
Feb 20 23:04:14.704: INFO: Running 'oc create --config=/tmp/extended-test-cli-deployment-2fqsl-8jspz-user.kubeconfig --namespace=extended-test-cli-deployment-2fqsl-8jspz -f /tmp/fixture-testdata-dir302855502/test/extended/testdata/deployments/test-deployment-test.yaml'
Feb 20 23:04:43.112: INFO: Running 'oc logs --config=/tmp/extended-test-cli-deployment-2fqsl-8jspz-user.kubeconfig --namespace=extended-test-cli-deployment-2fqsl-8jspz -f dc/deployment-test'
STEP: checking the logs for substrings
--> pre: Running hook pod ...
test pre hook executed
--> pre: Success
--> Scaling deployment-test-1 to 2
--> Success
STEP: verifying the deployment is marked complete and scaled to zero
Feb 20 23:05:48.165: INFO: Latest rollout of dc/deployment-test (rc/deployment-test-1) is complete.
STEP: verifying that scaling does not result in new pods
Feb 20 23:05:48.165: INFO: Running 'oc scale --config=/tmp/extended-test-cli-deployment-2fqsl-8jspz-user.kubeconfig --namespace=extended-test-cli-deployment-2fqsl-8jspz dc/deployment-test --replicas=1'
STEP: ensuring no scale up of the deployment happens
STEP: verifying the scale is updated on the deployment config
STEP: deploying a few more times
Feb 20 23:05:59.453: INFO: Running 'oc rollout --config=/tmp/extended-test-cli-deployment-2fqsl-8jspz-user.kubeconfig --namespace=extended-test-cli-deployment-2fqsl-8jspz latest deployment-test'
STEP: waiting for the rollout #2 to finish
Feb 20 23:06:41.695: INFO: Running 'oc logs --config=/tmp/extended-test-cli-deployment-2fqsl-8jspz-user.kubeconfig --namespace=extended-test-cli-deployment-2fqsl-8jspz -f pods/deployment-test-2-deploy'
Feb 20 23:07:21.653: INFO: Latest rollout of dc/deployment-test (rc/deployment-test-2) is complete.
STEP: checking the logs for substrings
--> pre: Running hook pod ...
test pre hook executed
--> pre: Success
--> Scaling up deployment-test-2 from 0 to 1, scaling down deployment-test-1 from 0 to 0 (keep 1 pods available, don't exceed 2 pods)
    Scaling deployment-test-2 up to 1
--> Success
Feb 20 23:07:21.653: INFO: Running 'oc rollout --config=/tmp/extended-test-cli-deployment-2fqsl-8jspz-user.kubeconfig --namespace=extended-test-cli-deployment-2fqsl-8jspz latest deployment-test'
STEP: waiting for the rollout #3 to finish
Feb 20 23:07:28.695: INFO: Running 'oc logs --config=/tmp/extended-test-cli-deployment-2fqsl-8jspz-user.kubeconfig --namespace=extended-test-cli-deployment-2fqsl-8jspz -f pods/deployment-test-3-deploy'
Feb 20 23:07:46.285: INFO: Latest rollout of dc/deployment-test (rc/deployment-test-3) is complete.
STEP: checking the logs for substrings
--> pre: Running hook pod ...
test pre hook executed
--> pre: Success
--> Scaling up deployment-test-3 from 0 to 1, scaling down deployment-test-2 from 0 to 0 (keep 1 pods available, don't exceed 2 pods)
    Scaling deployment-test-3 up to 1
--> Success
rpc error: code = Unknown desc = container with ID starting with ef4336b8262906fe7540a2fe7b6892cd77c699c2dcb344576bcc7dec4f35960d not found: ID does not exist
Feb 20 23:07:46.285: INFO: Running 'oc rollout --config=/tmp/extended-test-cli-deployment-2fqsl-8jspz-user.kubeconfig --namespace=extended-test-cli-deployment-2fqsl-8jspz latest deployment-test'
STEP: waiting for the rollout #4 to finish
Feb 20 23:07:51.313: INFO: Running 'oc logs --config=/tmp/extended-test-cli-deployment-2fqsl-8jspz-user.kubeconfig --namespace=extended-test-cli-deployment-2fqsl-8jspz -f pods/deployment-test-4-deploy'
Feb 20 23:08:28.247: INFO: Latest rollout of dc/deployment-test (rc/deployment-test-4) is complete.
STEP: checking the logs for substrings
--> pre: Running hook pod ...
test pre hook executed
--> pre: Success
--> Scaling up deployment-test-4 from 0 to 1, scaling down deployment-test-3 from 0 to 0 (keep 1 pods available, don't exceed 2 pods)
    Scaling deployment-test-4 up to 1
--> Success
[AfterEach] with test deployments [Conformance]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:299
[AfterEach] [Feature:DeploymentConfig] deploymentconfigs
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:62
[AfterEach] [Feature:DeploymentConfig] deploymentconfigs
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Feb 20 23:08:30.247: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "extended-test-cli-deployment-2fqsl-8jspz" for this suite.
Feb 20 23:08:36.267: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Feb 20 23:08:36.408: INFO: namespace: extended-test-cli-deployment-2fqsl-8jspz, resource: bindings, ignored listing per whitelist
Feb 20 23:08:36.470: INFO: namespace extended-test-cli-deployment-2fqsl-8jspz deletion completed in 6.216307265s


• [SLOW TEST:261.895 seconds]
[Feature:DeploymentConfig] deploymentconfigs
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:37
  with test deployments [Conformance]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:298
    should run a deployment to completion and then scale to zero [Suite:openshift/conformance/parallel]
    /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:303
------------------------------
S
------------------------------
[sig-storage] Projected 
  should provide container's memory limit [Conformance] [Suite:openshift/conformance/parallel] [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:648

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [sig-storage] Projected
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Feb 20 23:08:01.960: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Feb 20 23:08:02.050: 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-storage] Projected
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/projected.go:858
[It] should provide container's memory limit [Conformance] [Suite:openshift/conformance/parallel] [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:648
STEP: Creating a pod to test downward API volume plugin
Feb 20 23:08:02.275: INFO: Waiting up to 5m0s for pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86" in namespace "e2e-tests-projected-psctv" to be "success or failure"
Feb 20 23:08:02.278: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 2.904393ms
Feb 20 23:08:04.282: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 2.00695832s
Feb 20 23:08:06.285: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 4.010339312s
Feb 20 23:08:08.288: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 6.012706156s
Feb 20 23:08:10.291: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 8.015658317s
Feb 20 23:08:12.293: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 10.01853405s
Feb 20 23:08:14.299: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 12.023806736s
Feb 20 23:08:16.302: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 14.02668201s
Feb 20 23:08:18.304: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 16.029134752s
Feb 20 23:08:20.307: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 18.032068059s
Feb 20 23:08:22.310: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 20.034921304s
Feb 20 23:08:24.313: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 22.038215124s
Feb 20 23:08:26.316: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 24.041614547s
Feb 20 23:08:28.319: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 26.044594934s
Feb 20 23:08:30.323: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 28.048120349s
Feb 20 23:08:32.327: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86": Phase="Succeeded", Reason="", readiness=false. Elapsed: 30.051703756s
STEP: Saw pod success
Feb 20 23:08:32.327: INFO: Pod "downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86" satisfied condition "success or failure"
Feb 20 23:08:32.329: INFO: Trying to get logs from node ip-172-18-4-243.ec2.internal pod downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86 container client-container: <nil>
STEP: delete the pod
Feb 20 23:08:32.357: INFO: Waiting for pod downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86 to disappear
Feb 20 23:08:32.360: INFO: Pod downwardapi-volume-e67adabe-1692-11e8-bd1d-0eb4d2946e86 no longer exists
[AfterEach] [sig-storage] Projected
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Feb 20 23:08:32.360: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-projected-psctv" for this suite.
Feb 20 23:08:38.379: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Feb 20 23:08:38.448: INFO: namespace: e2e-tests-projected-psctv, resource: bindings, ignored listing per whitelist
Feb 20 23:08:38.556: INFO: namespace e2e-tests-projected-psctv deletion completed in 6.193782639s


• [SLOW TEST:36.597 seconds]
[sig-storage] Projected
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/projected.go:34
  should provide container's memory limit [Conformance] [Suite:openshift/conformance/parallel] [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:648
------------------------------
[sig-storage] Downward API volume 
  should provide container's cpu request  [Conformance] [Suite:openshift/conformance/parallel] [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:648

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [sig-storage] Downward API volume
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Feb 20 23:08:09.383: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Feb 20 23:08:09.471: 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-storage] Downward API volume
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/downwardapi_volume.go:38
[It] should provide container's cpu request  [Conformance] [Suite:openshift/conformance/parallel] [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:648
STEP: Creating a pod to test downward API volume plugin
Feb 20 23:08:09.545: INFO: Waiting up to 5m0s for pod "downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86" in namespace "e2e-tests-downward-api-wjfn7" to be "success or failure"
Feb 20 23:08:09.548: INFO: Pod "downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 3.263058ms
Feb 20 23:08:11.551: INFO: Pod "downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 2.006152898s
Feb 20 23:08:13.554: INFO: Pod "downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 4.008735747s
Feb 20 23:08:15.557: INFO: Pod "downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 6.011815199s
Feb 20 23:08:17.560: INFO: Pod "downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 8.014774877s
Feb 20 23:08:19.563: INFO: Pod "downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 10.017506774s
Feb 20 23:08:21.565: INFO: Pod "downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 12.019944089s
Feb 20 23:08:23.567: INFO: Pod "downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 14.022253493s
Feb 20 23:08:25.572: INFO: Pod "downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 16.026757519s
Feb 20 23:08:27.577: INFO: Pod "downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 18.031646291s
Feb 20 23:08:29.583: INFO: Pod "downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 20.037633015s
Feb 20 23:08:31.586: INFO: Pod "downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 22.040859181s
Feb 20 23:08:33.588: INFO: Pod "downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86": Phase="Succeeded", Reason="", readiness=false. Elapsed: 24.043355459s
STEP: Saw pod success
Feb 20 23:08:33.589: INFO: Pod "downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86" satisfied condition "success or failure"
Feb 20 23:08:33.590: INFO: Trying to get logs from node ip-172-18-4-243.ec2.internal pod downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86 container client-container: <nil>
STEP: delete the pod
Feb 20 23:08:33.605: INFO: Waiting for pod downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86 to disappear
Feb 20 23:08:33.608: INFO: Pod downwardapi-volume-ead05b54-1692-11e8-b73e-0eb4d2946e86 no longer exists
[AfterEach] [sig-storage] Downward API volume
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Feb 20 23:08:33.608: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-downward-api-wjfn7" for this suite.
Feb 20 23:08:39.622: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Feb 20 23:08:39.716: INFO: namespace: e2e-tests-downward-api-wjfn7, resource: bindings, ignored listing per whitelist
Feb 20 23:08:39.795: INFO: namespace e2e-tests-downward-api-wjfn7 deletion completed in 6.184166449s


• [SLOW TEST:30.412 seconds]
[sig-storage] Downward API volume
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/downwardapi_volume.go:33
  should provide container's cpu request  [Conformance] [Suite:openshift/conformance/parallel] [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:648
------------------------------
SSSSSS
------------------------------
[sig-storage] Projected 
  should be consumable from pods in volume with mappings [Conformance] [Suite:openshift/conformance/parallel] [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:648

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [sig-storage] Projected
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Feb 20 23:08:31.288: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Feb 20 23:08:31.383: 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-storage] Projected
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/projected.go:858
[It] should be consumable from pods in volume with mappings [Conformance] [Suite:openshift/conformance/parallel] [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:648
STEP: Creating configMap with name projected-configmap-test-volume-map-f7de6379-1692-11e8-975a-0eb4d2946e86
STEP: Creating a pod to test consume configMaps
Feb 20 23:08:31.449: INFO: Waiting up to 5m0s for pod "pod-projected-configmaps-f7ded889-1692-11e8-975a-0eb4d2946e86" in namespace "e2e-tests-projected-j49gr" to be "success or failure"
Feb 20 23:08:31.458: INFO: Pod "pod-projected-configmaps-f7ded889-1692-11e8-975a-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 8.810228ms
Feb 20 23:08:33.461: INFO: Pod "pod-projected-configmaps-f7ded889-1692-11e8-975a-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 2.012027624s
Feb 20 23:08:35.467: INFO: Pod "pod-projected-configmaps-f7ded889-1692-11e8-975a-0eb4d2946e86": Phase="Succeeded", Reason="", readiness=false. Elapsed: 4.018191079s
STEP: Saw pod success
Feb 20 23:08:35.467: INFO: Pod "pod-projected-configmaps-f7ded889-1692-11e8-975a-0eb4d2946e86" satisfied condition "success or failure"
Feb 20 23:08:35.476: INFO: Trying to get logs from node ip-172-18-4-243.ec2.internal pod pod-projected-configmaps-f7ded889-1692-11e8-975a-0eb4d2946e86 container projected-configmap-volume-test: <nil>
STEP: delete the pod
Feb 20 23:08:35.577: INFO: Waiting for pod pod-projected-configmaps-f7ded889-1692-11e8-975a-0eb4d2946e86 to disappear
Feb 20 23:08:35.627: INFO: Pod pod-projected-configmaps-f7ded889-1692-11e8-975a-0eb4d2946e86 no longer exists
[AfterEach] [sig-storage] Projected
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Feb 20 23:08:35.627: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-projected-j49gr" for this suite.
Feb 20 23:08:41.704: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Feb 20 23:08:41.891: INFO: namespace: e2e-tests-projected-j49gr, resource: bindings, ignored listing per whitelist
Feb 20 23:08:41.932: INFO: namespace e2e-tests-projected-j49gr deletion completed in 6.281223677s


• [SLOW TEST:10.643 seconds]
[sig-storage] Projected
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/projected.go:34
  should be consumable from pods in volume with mappings [Conformance] [Suite:openshift/conformance/parallel] [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:648
------------------------------
S
------------------------------
[Conformance][templates] templateinstance impersonation tests 
  should pass impersonation creation tests [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/templates/templateinstance_impersonation.go:231

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [Conformance][templates] templateinstance impersonation tests
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Feb 20 23:08:38.558: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Feb 20 23:08:38.594: INFO: configPath is now "/tmp/extended-test-templates-rxfxp-th742-user.kubeconfig"
Feb 20 23:08:38.594: INFO: The user is now "extended-test-templates-rxfxp-th742-user"
Feb 20 23:08:38.594: INFO: Creating project "extended-test-templates-rxfxp-th742"
Feb 20 23:08:38.626: INFO: Waiting on permissions in project "extended-test-templates-rxfxp-th742" ...
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] [Conformance][templates] templateinstance impersonation tests
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/templates/templateinstance_impersonation.go:57
Feb 20 23:08:38.985: INFO: configPath is now "/tmp/extended-test-templates-rxfxp-th742-adminuser.kubeconfig"
Feb 20 23:08:39.016: INFO: configPath is now "/tmp/extended-test-templates-rxfxp-th742-impersonateuser.kubeconfig"
Feb 20 23:08:39.051: INFO: configPath is now "/tmp/extended-test-templates-rxfxp-th742-impersonatebygroupuser.kubeconfig"
Feb 20 23:08:39.085: INFO: configPath is now "/tmp/extended-test-templates-rxfxp-th742-edituser1.kubeconfig"
Feb 20 23:08:39.113: INFO: configPath is now "/tmp/extended-test-templates-rxfxp-th742-edituser2.kubeconfig"
Feb 20 23:08:39.142: INFO: configPath is now "/tmp/extended-test-templates-rxfxp-th742-viewuser.kubeconfig"
Feb 20 23:08:39.172: INFO: configPath is now "/tmp/extended-test-templates-rxfxp-th742-impersonatebygroupuser.kubeconfig"
[It] should pass impersonation creation tests [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/templates/templateinstance_impersonation.go:231
STEP: testing as system:admin user
STEP: testing as extended-test-templates-rxfxp-th742-adminuser user
Feb 20 23:08:39.221: INFO: configPath is now "/tmp/extended-test-templates-rxfxp-th742-adminuser.kubeconfig"
STEP: testing as extended-test-templates-rxfxp-th742-impersonateuser user
Feb 20 23:08:39.244: INFO: configPath is now "/tmp/extended-test-templates-rxfxp-th742-impersonateuser.kubeconfig"
STEP: testing as extended-test-templates-rxfxp-th742-impersonatebygroupuser user
Feb 20 23:08:39.275: INFO: configPath is now "/tmp/extended-test-templates-rxfxp-th742-impersonatebygroupuser.kubeconfig"
STEP: testing as extended-test-templates-rxfxp-th742-edituser1 user
Feb 20 23:08:39.305: INFO: configPath is now "/tmp/extended-test-templates-rxfxp-th742-edituser1.kubeconfig"
STEP: testing as extended-test-templates-rxfxp-th742-edituser2 user
Feb 20 23:08:39.337: INFO: configPath is now "/tmp/extended-test-templates-rxfxp-th742-edituser2.kubeconfig"
STEP: testing as extended-test-templates-rxfxp-th742-viewuser user
Feb 20 23:08:39.363: INFO: configPath is now "/tmp/extended-test-templates-rxfxp-th742-viewuser.kubeconfig"
[AfterEach] [Conformance][templates] templateinstance impersonation tests
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Feb 20 23:08:39.365: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "extended-test-templates-rxfxp-th742" for this suite.
Feb 20 23:08:45.377: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Feb 20 23:08:45.461: INFO: namespace: extended-test-templates-rxfxp-th742, resource: bindings, ignored listing per whitelist
Feb 20 23:08:45.561: INFO: namespace extended-test-templates-rxfxp-th742 deletion completed in 6.193703878s
[AfterEach] [Conformance][templates] templateinstance impersonation tests
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/templates/templateinstance_impersonation.go:221


• [SLOW TEST:7.042 seconds]
[Conformance][templates] templateinstance impersonation tests
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/templates/templateinstance_impersonation.go:27
  should pass impersonation creation tests [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/templates/templateinstance_impersonation.go:231
------------------------------
[k8s.io] Probing container 
  with readiness probe should not be ready before initial delay and never restart  [Conformance] [Suite:openshift/conformance/parallel] [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:648

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [k8s.io] Probing container
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Feb 20 23:07:38.990: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Feb 20 23:07:39.084: INFO: About to run a Kube e2e test, ensuring namespace is privileged
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] [k8s.io] Probing container
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/container_probe.go:48
[It] with readiness probe should not be ready before initial delay and never restart  [Conformance] [Suite:openshift/conformance/parallel] [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:648
Feb 20 23:08:09.179: INFO: Container started at 2018-02-20 23:07:43 +0000 UTC, pod became ready at 2018-02-20 23:08:07 +0000 UTC
[AfterEach] [k8s.io] Probing container
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Feb 20 23:08:09.179: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-container-probe-hjl7b" for this suite.
Feb 20 23:08:49.191: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Feb 20 23:08:49.283: INFO: namespace: e2e-tests-container-probe-hjl7b, resource: bindings, ignored listing per whitelist
Feb 20 23:08:49.374: INFO: namespace e2e-tests-container-probe-hjl7b deletion completed in 40.193117746s


• [SLOW TEST:70.384 seconds]
[k8s.io] Probing container
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:643
  with readiness probe should not be ready before initial delay and never restart  [Conformance] [Suite:openshift/conformance/parallel] [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:648
------------------------------
[sig-storage] Downward API volume 
  should set mode on item file  [Conformance] [Suite:openshift/conformance/parallel] [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:648

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [sig-storage] Downward API volume
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Feb 20 23:08:41.935: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Feb 20 23:08:42.069: 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-storage] Downward API volume
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/downwardapi_volume.go:38
[It] should set mode on item file  [Conformance] [Suite:openshift/conformance/parallel] [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:648
STEP: Creating a pod to test downward API volume plugin
Feb 20 23:08:42.163: INFO: Waiting up to 5m0s for pod "downwardapi-volume-fe418b53-1692-11e8-975a-0eb4d2946e86" in namespace "e2e-tests-downward-api-2lw27" to be "success or failure"
Feb 20 23:08:42.171: INFO: Pod "downwardapi-volume-fe418b53-1692-11e8-975a-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 7.42227ms
Feb 20 23:08:44.176: INFO: Pod "downwardapi-volume-fe418b53-1692-11e8-975a-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 2.012424602s
Feb 20 23:08:46.206: INFO: Pod "downwardapi-volume-fe418b53-1692-11e8-975a-0eb4d2946e86": Phase="Pending", Reason="", readiness=false. Elapsed: 4.043038692s
Feb 20 23:08:48.210: INFO: Pod "downwardapi-volume-fe418b53-1692-11e8-975a-0eb4d2946e86": Phase="Succeeded", Reason="", readiness=false. Elapsed: 6.046368422s
STEP: Saw pod success
Feb 20 23:08:48.210: INFO: Pod "downwardapi-volume-fe418b53-1692-11e8-975a-0eb4d2946e86" satisfied condition "success or failure"
Feb 20 23:08:48.212: INFO: Trying to get logs from node ip-172-18-4-243.ec2.internal pod downwardapi-volume-fe418b53-1692-11e8-975a-0eb4d2946e86 container client-container: <nil>
STEP: delete the pod
Feb 20 23:08:48.231: INFO: Waiting for pod downwardapi-volume-fe418b53-1692-11e8-975a-0eb4d2946e86 to disappear
Feb 20 23:08:48.234: INFO: Pod downwardapi-volume-fe418b53-1692-11e8-975a-0eb4d2946e86 no longer exists
[AfterEach] [sig-storage] Downward API volume
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Feb 20 23:08:48.234: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-downward-api-2lw27" for this suite.
Feb 20 23:08:54.274: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Feb 20 23:08:54.434: INFO: namespace: e2e-tests-downward-api-2lw27, resource: bindings, ignored listing per whitelist
Feb 20 23:08:54.462: INFO: namespace e2e-tests-downward-api-2lw27 deletion completed in 6.225663331s


• [SLOW TEST:12.527 seconds]
[sig-storage] Downward API volume
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/downwardapi_volume.go:33
  should set mode on item file  [Conformance] [Suite:openshift/conformance/parallel] [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:648
------------------------------
S
------------------------------
Feb 20 23:08:54.464: INFO: Running AfterSuite actions on all node


[Conformance][Area:Networking][Feature:Router] openshift router metrics The HAProxy router 
  should expose a health check on the metrics port [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/router/metrics.go:83

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [Conformance][Area:Networking][Feature:Router] openshift router metrics
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Feb 20 23:08:49.376: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Feb 20 23:08:49.401: INFO: configPath is now "/tmp/extended-test-router-metrics-zwg7j-bx2bn-user.kubeconfig"
Feb 20 23:08:49.401: INFO: The user is now "extended-test-router-metrics-zwg7j-bx2bn-user"
Feb 20 23:08:49.401: INFO: Creating project "extended-test-router-metrics-zwg7j-bx2bn"
Feb 20 23:08:49.483: INFO: Waiting on permissions in project "extended-test-router-metrics-zwg7j-bx2bn" ...
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] [Conformance][Area:Networking][Feature:Router] openshift router metrics
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/router/metrics.go:36
[It] should expose a health check on the metrics port [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/router/metrics.go:83
Feb 20 23:08:49.531: INFO: Creating new exec pod
STEP: listening on the health port
Feb 20 23:08:51.552: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://ip-172-18-4-243.ec2.internal:8443 --kubeconfig=/etc/origin/master/admin.kubeconfig exec --namespace=extended-test-router-metrics-zwg7j-bx2bn execpod -- /bin/sh -c curl -s -o /dev/null -w '%{http_code}' "http://172.18.4.243:1936/healthz"'
Feb 20 23:08:51.883: INFO: stderr: ""
[AfterEach] [Conformance][Area:Networking][Feature:Router] openshift router metrics
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Feb 20 23:08:51.892: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "extended-test-router-metrics-zwg7j-bx2bn" for this suite.
Feb 20 23:08:57.906: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Feb 20 23:08:58.042: INFO: namespace: extended-test-router-metrics-zwg7j-bx2bn, resource: bindings, ignored listing per whitelist
Feb 20 23:08:58.075: INFO: namespace extended-test-router-metrics-zwg7j-bx2bn deletion completed in 6.180384851s
[AfterEach] [Conformance][Area:Networking][Feature:Router] openshift router metrics
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/router/metrics.go:76


• [SLOW TEST:8.700 seconds]
[Conformance][Area:Networking][Feature:Router] openshift router metrics
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/router/metrics.go:26
  The HAProxy router
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/router/metrics.go:82
    should expose a health check on the metrics port [Suite:openshift/conformance/parallel]
    /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/router/metrics.go:83
------------------------------
Feb 20 23:08:58.077: INFO: Running AfterSuite actions on all node


[Feature:Builds][pruning] prune builds based on settings in the buildconfig  
  should prune errored builds based on the failedBuildsHistoryLimit setting [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/build_pruning.go:198

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [Feature:Builds][pruning] prune builds based on settings in the buildconfig
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Feb 20 23:08:45.603: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Feb 20 23:08:45.629: INFO: configPath is now "/tmp/extended-test-build-pruning-r25pt-f4vmj-user.kubeconfig"
Feb 20 23:08:45.629: INFO: The user is now "extended-test-build-pruning-r25pt-f4vmj-user"
Feb 20 23:08:45.629: INFO: Creating project "extended-test-build-pruning-r25pt-f4vmj"
Feb 20 23:08:45.712: INFO: Waiting on permissions in project "extended-test-build-pruning-r25pt-f4vmj" ...
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] 
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/build_pruning.go:37
Feb 20 23:08:45.968: INFO: 

 docker info output: 
Containers: 1
 Running: 0
 Paused: 0
 Stopped: 1
Images: 78
Server Version: 1.12.6
Storage Driver: devicemapper
 Pool Name: docker-docker--pool
 Pool Blocksize: 524.3 kB
 Base Device Size: 10.74 GB
 Backing Filesystem: xfs
 Data file: 
 Metadata file: 
 Data Space Used: 8.686 GB
 Data Space Total: 14.92 GB
 Data Space Available: 6.237 GB
 Metadata Space Used: 2.109 MB
 Metadata Space Total: 54.53 MB
 Metadata Space Available: 52.42 MB
 Thin Pool Minimum Free Space: 1.492 GB
 Udev Sync Supported: true
 Deferred Removal Enabled: true
 Deferred Deletion Enabled: true
 Deferred Deleted Device Count: 0
 Library Version: 1.02.140-RHEL7 (2017-05-03)
Logging Driver: json-file
Cgroup Driver: systemd
Plugins:
 Volume: local
 Network: bridge overlay host null
 Authorization: rhel-push-plugin
Swarm: inactive
Runtimes: docker-runc runc
Default Runtime: docker-runc
Security Options: seccomp selinux
Kernel Version: 3.10.0-693.17.1.el7.x86_64
Operating System: Red Hat Enterprise Linux Server 7.4 (Maipo)
OSType: linux
Architecture: x86_64
Number of Docker Hooks: 3
CPUs: 4
Total Memory: 15.51 GiB
Name: ip-172-18-4-243.ec2.internal
ID: AZMC:SIUC:OWFM:4YR4:5BXE:S7GJ:5GN2:S7WD:W6F4:KHQU:HNQ7:LSUW
Docker Root Dir: /var/lib/containers/docker
Debug Mode (client): false
Debug Mode (server): false
Registry: https://registry.access.redhat.com/v1/
Insecure Registries:
 ci.dev.openshift.redhat.com:5000
 172.30.0.0/16
 127.0.0.0/8
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/build_pruning.go:41
STEP: waiting for builder service account
STEP: waiting for openshift namespace imagestreams
Feb 20 23:08:46.073: INFO: Running scan #0 

Feb 20 23:08:46.073: INFO: Checking language ruby 

Feb 20 23:08:46.080: INFO: Checking tag 2.4 

Feb 20 23:08:46.080: INFO: Checking tag latest 

Feb 20 23:08:46.080: INFO: Checking tag 2.0 

Feb 20 23:08:46.080: INFO: Checking tag 2.2 

Feb 20 23:08:46.080: INFO: Checking tag 2.3 

Feb 20 23:08:46.080: INFO: Checking language nodejs 

Feb 20 23:08:46.086: INFO: Checking tag 0.10 

Feb 20 23:08:46.086: INFO: Checking tag 4 

Feb 20 23:08:46.086: INFO: Checking tag 6 

Feb 20 23:08:46.086: INFO: Checking tag 8 

Feb 20 23:08:46.086: INFO: Checking tag latest 

Feb 20 23:08:46.086: INFO: Checking language perl 

Feb 20 23:08:46.091: INFO: Checking tag latest 

Feb 20 23:08:46.091: INFO: Checking tag 5.16 

Feb 20 23:08:46.091: INFO: Checking tag 5.20 

Feb 20 23:08:46.091: INFO: Checking tag 5.24 

Feb 20 23:08:46.091: INFO: Checking language php 

Feb 20 23:08:46.096: INFO: Checking tag 5.5 

Feb 20 23:08:46.096: INFO: Checking tag 5.6 

Feb 20 23:08:46.096: INFO: Checking tag 7.0 

Feb 20 23:08:46.096: INFO: Checking tag 7.1 

Feb 20 23:08:46.096: INFO: Checking tag latest 

Feb 20 23:08:46.096: INFO: Checking language python 

Feb 20 23:08:46.101: INFO: Checking tag 3.4 

Feb 20 23:08:46.101: INFO: Checking tag 3.5 

Feb 20 23:08:46.101: INFO: Checking tag 3.6 

Feb 20 23:08:46.101: INFO: Checking tag latest 

Feb 20 23:08:46.101: INFO: Checking tag 2.7 

Feb 20 23:08:46.101: INFO: Checking tag 3.3 

Feb 20 23:08:46.101: INFO: Checking language wildfly 

Feb 20 23:08:46.105: INFO: Checking tag 10.0 

Feb 20 23:08:46.105: INFO: Checking tag 10.1 

Feb 20 23:08:46.105: INFO: Checking tag 8.1 

Feb 20 23:08:46.105: INFO: Checking tag 9.0 

Feb 20 23:08:46.105: INFO: Checking tag latest 

Feb 20 23:08:46.105: INFO: Checking language mysql 

Feb 20 23:08:46.110: INFO: Checking tag 5.5 

Feb 20 23:08:46.110: INFO: Checking tag 5.6 

Feb 20 23:08:46.110: INFO: Checking tag 5.7 

Feb 20 23:08:46.110: INFO: Checking tag latest 

Feb 20 23:08:46.110: INFO: Checking language postgresql 

Feb 20 23:08:46.114: INFO: Checking tag 9.5 

Feb 20 23:08:46.114: INFO: Checking tag 9.6 

Feb 20 23:08:46.114: INFO: Checking tag latest 

Feb 20 23:08:46.114: INFO: Checking tag 9.2 

Feb 20 23:08:46.114: INFO: Checking tag 9.4 

Feb 20 23:08:46.114: INFO: Checking language mongodb 

Feb 20 23:08:46.120: INFO: Checking tag latest 

Feb 20 23:08:46.120: INFO: Checking tag 2.4 

Feb 20 23:08:46.120: INFO: Checking tag 2.6 

Feb 20 23:08:46.120: INFO: Checking tag 3.2 

Feb 20 23:08:46.120: INFO: Checking tag 3.4 

Feb 20 23:08:46.120: INFO: Checking language jenkins 

Feb 20 23:08:46.124: INFO: Checking tag 1 

Feb 20 23:08:46.124: INFO: Checking tag 2 

Feb 20 23:08:46.124: INFO: Checking tag latest 

Feb 20 23:08:46.124: INFO: Success! 

STEP: creating test image stream
Feb 20 23:08:46.124: INFO: Running 'oc create --config=/tmp/extended-test-build-pruning-r25pt-f4vmj-user.kubeconfig --namespace=extended-test-build-pruning-r25pt-f4vmj -f /tmp/fixture-testdata-dir748677586/test/extended/testdata/builds/build-pruning/imagestream.yaml'
imagestream "myphp" created
[It] should prune errored builds based on the failedBuildsHistoryLimit setting [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/build_pruning.go:198
STEP: creating test failed build config
Feb 20 23:08:46.454: INFO: Running 'oc create --config=/tmp/extended-test-build-pruning-r25pt-f4vmj-user.kubeconfig --namespace=extended-test-build-pruning-r25pt-f4vmj -f /tmp/fixture-testdata-dir748677586/test/extended/testdata/builds/build-pruning/errored-build-config.yaml'
buildconfig "myphp" created
STEP: starting four test builds
Feb 20 23:08:46.799: INFO: Running 'oc start-build --config=/tmp/extended-test-build-pruning-r25pt-f4vmj-user.kubeconfig --namespace=extended-test-build-pruning-r25pt-f4vmj myphp -o=name'
Feb 20 23:08:47.304: INFO: 

start-build output with args [myphp -o=name]:
Error><nil>
StdOut>
build/myphp-1
StdErr>



Feb 20 23:08:47.305: INFO: Waiting for myphp-1 to complete

Feb 20 23:08:53.313: INFO: WaitForABuild returning with error: The build "myphp-1" status is "Error"
Feb 20 23:08:53.313: INFO: Done waiting for myphp-1: util.BuildResult{BuildPath:"build/myphp-1", BuildName:"myphp-1", StartBuildStdErr:"", StartBuildStdOut:"build/myphp-1", StartBuildErr:error(nil), BuildConfigName:"", Build:(*build.Build)(0xc4217dbb00), BuildAttempt:true, BuildSuccess:false, BuildFailure:true, BuildCancelled:false, BuildTimeout:false, LogDumper:(util.LogDumperFunc)(nil), Oc:(*util.CLI)(0xc4212e4620)}
 with error: The build "myphp-1" status is "Error"

Feb 20 23:08:53.313: INFO: Running 'oc start-build --config=/tmp/extended-test-build-pruning-r25pt-f4vmj-user.kubeconfig --namespace=extended-test-build-pruning-r25pt-f4vmj myphp -o=name'
Feb 20 23:08:53.779: INFO: 

start-build output with args [myphp -o=name]:
Error><nil>
StdOut>
build/myphp-2
StdErr>



Feb 20 23:08:53.781: INFO: Waiting for myphp-2 to complete

Feb 20 23:08:59.793: INFO: WaitForABuild returning with error: The build "myphp-2" status is "Error"
Feb 20 23:08:59.793: INFO: Done waiting for myphp-2: util.BuildResult{BuildPath:"build/myphp-2", BuildName:"myphp-2", StartBuildStdErr:"", StartBuildStdOut:"build/myphp-2", StartBuildErr:error(nil), BuildConfigName:"", Build:(*build.Build)(0xc421c20900), BuildAttempt:true, BuildSuccess:false, BuildFailure:true, BuildCancelled:false, BuildTimeout:false, LogDumper:(util.LogDumperFunc)(nil), Oc:(*util.CLI)(0xc4212e4620)}
 with error: The build "myphp-2" status is "Error"

Feb 20 23:08:59.793: INFO: Running 'oc start-build --config=/tmp/extended-test-build-pruning-r25pt-f4vmj-user.kubeconfig --namespace=extended-test-build-pruning-r25pt-f4vmj myphp -o=name'
Feb 20 23:09:00.199: INFO: 

start-build output with args [myphp -o=name]:
Error><nil>
StdOut>
build/myphp-3
StdErr>



Feb 20 23:09:00.201: INFO: Waiting for myphp-3 to complete

Feb 20 23:09:06.212: INFO: WaitForABuild returning with error: The build "myphp-3" status is "Error"
Feb 20 23:09:06.212: INFO: Done waiting for myphp-3: util.BuildResult{BuildPath:"build/myphp-3", BuildName:"myphp-3", StartBuildStdErr:"", StartBuildStdOut:"build/myphp-3", StartBuildErr:error(nil), BuildConfigName:"", Build:(*build.Build)(0xc4218dd500), BuildAttempt:true, BuildSuccess:false, BuildFailure:true, BuildCancelled:false, BuildTimeout:false, LogDumper:(util.LogDumperFunc)(nil), Oc:(*util.CLI)(0xc4212e4620)}
 with error: The build "myphp-3" status is "Error"

Feb 20 23:09:06.212: INFO: Running 'oc start-build --config=/tmp/extended-test-build-pruning-r25pt-f4vmj-user.kubeconfig --namespace=extended-test-build-pruning-r25pt-f4vmj myphp -o=name'
Feb 20 23:09:06.642: INFO: 

start-build output with args [myphp -o=name]:
Error><nil>
StdOut>
build/myphp-4
StdErr>



Feb 20 23:09:06.644: INFO: Waiting for myphp-4 to complete

Feb 20 23:09:12.659: INFO: WaitForABuild returning with error: The build "myphp-4" status is "Error"
Feb 20 23:09:12.659: INFO: Done waiting for myphp-4: util.BuildResult{BuildPath:"build/myphp-4", BuildName:"myphp-4", StartBuildStdErr:"", StartBuildStdOut:"build/myphp-4", StartBuildErr:error(nil), BuildConfigName:"", Build:(*build.Build)(0xc4213f4600), BuildAttempt:true, BuildSuccess:false, BuildFailure:true, BuildCancelled:false, BuildTimeout:false, LogDumper:(util.LogDumperFunc)(nil), Oc:(*util.CLI)(0xc4212e4620)}
 with error: The build "myphp-4" status is "Error"

STEP: waiting up to one minute for pruning to complete
2 builds exist, retrying...[AfterEach] 
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/build_pruning.go:56
[AfterEach] [Feature:Builds][pruning] prune builds based on settings in the buildconfig
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Feb 20 23:09:12.668: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "extended-test-build-pruning-r25pt-f4vmj" for this suite.
Feb 20 23:09:18.692: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Feb 20 23:09:18.814: INFO: namespace: extended-test-build-pruning-r25pt-f4vmj, resource: bindings, ignored listing per whitelist
Feb 20 23:09:18.945: INFO: namespace extended-test-build-pruning-r25pt-f4vmj deletion completed in 6.273217116s


• [SLOW TEST:33.343 seconds]
[Feature:Builds][pruning] prune builds based on settings in the buildconfig
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/build_pruning.go:21
  
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/build_pruning.go:35
    should prune errored builds based on the failedBuildsHistoryLimit setting [Suite:openshift/conformance/parallel]
    /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/build_pruning.go:198
------------------------------
Feb 20 23:09:18.947: INFO: Running AfterSuite actions on all node


[Feature:Builds][Conformance][valueFrom] process valueFrom in build strategy environment variables  
  should successfully resolve valueFrom in s2i build environment variables [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/valuefrom.go:61

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [Feature:Builds][Conformance][valueFrom] process valueFrom in build strategy environment variables
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Feb 20 23:08:36.475: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Feb 20 23:08:36.506: INFO: configPath is now "/tmp/extended-test-build-valuefrom-dsfwc-6rvt9-user.kubeconfig"
Feb 20 23:08:36.506: INFO: The user is now "extended-test-build-valuefrom-dsfwc-6rvt9-user"
Feb 20 23:08:36.506: INFO: Creating project "extended-test-build-valuefrom-dsfwc-6rvt9"
Feb 20 23:08:36.610: INFO: Waiting on permissions in project "extended-test-build-valuefrom-dsfwc-6rvt9" ...
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] 
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/valuefrom.go:27
Feb 20 23:08:36.694: INFO: 

 docker info output: 
Containers: 0
 Running: 0
 Paused: 0
 Stopped: 0
Images: 78
Server Version: 1.12.6
Storage Driver: devicemapper
 Pool Name: docker-docker--pool
 Pool Blocksize: 524.3 kB
 Base Device Size: 10.74 GB
 Backing Filesystem: xfs
 Data file: 
 Metadata file: 
 Data Space Used: 8.675 GB
 Data Space Total: 14.92 GB
 Data Space Available: 6.248 GB
 Metadata Space Used: 2.073 MB
 Metadata Space Total: 54.53 MB
 Metadata Space Available: 52.45 MB
 Thin Pool Minimum Free Space: 1.492 GB
 Udev Sync Supported: true
 Deferred Removal Enabled: true
 Deferred Deletion Enabled: true
 Deferred Deleted Device Count: 0
 Library Version: 1.02.140-RHEL7 (2017-05-03)
Logging Driver: json-file
Cgroup Driver: systemd
Plugins:
 Volume: local
 Network: bridge host null overlay
 Authorization: rhel-push-plugin
Swarm: inactive
Runtimes: docker-runc runc
Default Runtime: docker-runc
Security Options: seccomp selinux
Kernel Version: 3.10.0-693.17.1.el7.x86_64
Operating System: Red Hat Enterprise Linux Server 7.4 (Maipo)
OSType: linux
Architecture: x86_64
Number of Docker Hooks: 3
CPUs: 4
Total Memory: 15.51 GiB
Name: ip-172-18-4-243.ec2.internal
ID: AZMC:SIUC:OWFM:4YR4:5BXE:S7GJ:5GN2:S7WD:W6F4:KHQU:HNQ7:LSUW
Docker Root Dir: /var/lib/containers/docker
Debug Mode (client): false
Debug Mode (server): false
Registry: https://registry.access.redhat.com/v1/
Insecure Registries:
 ci.dev.openshift.redhat.com:5000
 172.30.0.0/16
 127.0.0.0/8
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/valuefrom.go:38
STEP: waiting for builder service account
STEP: waiting for openshift namespace imagestreams
Feb 20 23:08:36.802: INFO: Running scan #0 

Feb 20 23:08:36.802: INFO: Checking language ruby 

Feb 20 23:08:36.806: INFO: Checking tag 2.0 

Feb 20 23:08:36.806: INFO: Checking tag 2.2 

Feb 20 23:08:36.806: INFO: Checking tag 2.3 

Feb 20 23:08:36.806: INFO: Checking tag 2.4 

Feb 20 23:08:36.806: INFO: Checking tag latest 

Feb 20 23:08:36.806: INFO: Checking language nodejs 

Feb 20 23:08:36.811: INFO: Checking tag 6 

Feb 20 23:08:36.811: INFO: Checking tag 8 

Feb 20 23:08:36.811: INFO: Checking tag latest 

Feb 20 23:08:36.811: INFO: Checking tag 0.10 

Feb 20 23:08:36.811: INFO: Checking tag 4 

Feb 20 23:08:36.811: INFO: Checking language perl 

Feb 20 23:08:36.815: INFO: Checking tag 5.16 

Feb 20 23:08:36.815: INFO: Checking tag 5.20 

Feb 20 23:08:36.815: INFO: Checking tag 5.24 

Feb 20 23:08:36.815: INFO: Checking tag latest 

Feb 20 23:08:36.815: INFO: Checking language php 

Feb 20 23:08:36.819: INFO: Checking tag 7.1 

Feb 20 23:08:36.819: INFO: Checking tag latest 

Feb 20 23:08:36.819: INFO: Checking tag 5.5 

Feb 20 23:08:36.819: INFO: Checking tag 5.6 

Feb 20 23:08:36.819: INFO: Checking tag 7.0 

Feb 20 23:08:36.819: INFO: Checking language python 

Feb 20 23:08:36.822: INFO: Checking tag 3.4 

Feb 20 23:08:36.822: INFO: Checking tag 3.5 

Feb 20 23:08:36.822: INFO: Checking tag 3.6 

Feb 20 23:08:36.822: INFO: Checking tag latest 

Feb 20 23:08:36.822: INFO: Checking tag 2.7 

Feb 20 23:08:36.822: INFO: Checking tag 3.3 

Feb 20 23:08:36.822: INFO: Checking language wildfly 

Feb 20 23:08:36.826: INFO: Checking tag latest 

Feb 20 23:08:36.826: INFO: Checking tag 10.0 

Feb 20 23:08:36.826: INFO: Checking tag 10.1 

Feb 20 23:08:36.826: INFO: Checking tag 8.1 

Feb 20 23:08:36.826: INFO: Checking tag 9.0 

Feb 20 23:08:36.826: INFO: Checking language mysql 

Feb 20 23:08:36.829: INFO: Checking tag 5.5 

Feb 20 23:08:36.829: INFO: Checking tag 5.6 

Feb 20 23:08:36.829: INFO: Checking tag 5.7 

Feb 20 23:08:36.829: INFO: Checking tag latest 

Feb 20 23:08:36.829: INFO: Checking language postgresql 

Feb 20 23:08:36.833: INFO: Checking tag 9.6 

Feb 20 23:08:36.833: INFO: Checking tag latest 

Feb 20 23:08:36.833: INFO: Checking tag 9.2 

Feb 20 23:08:36.833: INFO: Checking tag 9.4 

Feb 20 23:08:36.833: INFO: Checking tag 9.5 

Feb 20 23:08:36.833: INFO: Checking language mongodb 

Feb 20 23:08:36.836: INFO: Checking tag 2.4 

Feb 20 23:08:36.836: INFO: Checking tag 2.6 

Feb 20 23:08:36.836: INFO: Checking tag 3.2 

Feb 20 23:08:36.836: INFO: Checking tag 3.4 

Feb 20 23:08:36.836: INFO: Checking tag latest 

Feb 20 23:08:36.836: INFO: Checking language jenkins 

Feb 20 23:08:36.839: INFO: Checking tag 1 

Feb 20 23:08:36.839: INFO: Checking tag 2 

Feb 20 23:08:36.839: INFO: Checking tag latest 

Feb 20 23:08:36.839: INFO: Success! 

STEP: creating test image stream
Feb 20 23:08:36.839: INFO: Running 'oc create --config=/tmp/extended-test-build-valuefrom-dsfwc-6rvt9-user.kubeconfig --namespace=extended-test-build-valuefrom-dsfwc-6rvt9 -f /tmp/fixture-testdata-dir302855502/test/extended/testdata/builds/valuefrom/test-is.json'
imagestream "test" created
STEP: creating test secret
Feb 20 23:08:37.121: INFO: Running 'oc create --config=/tmp/extended-test-build-valuefrom-dsfwc-6rvt9-user.kubeconfig --namespace=extended-test-build-valuefrom-dsfwc-6rvt9 -f /tmp/fixture-testdata-dir302855502/test/extended/testdata/builds/valuefrom/test-secret.yaml'
secret "mysecret" created
STEP: creating test configmap
Feb 20 23:08:37.366: INFO: Running 'oc create --config=/tmp/extended-test-build-valuefrom-dsfwc-6rvt9-user.kubeconfig --namespace=extended-test-build-valuefrom-dsfwc-6rvt9 -f /tmp/fixture-testdata-dir302855502/test/extended/testdata/builds/valuefrom/test-configmap.yaml'
configmap "myconfigmap" created
[It] should successfully resolve valueFrom in s2i build environment variables [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/valuefrom.go:61
STEP: creating test successful build config
Feb 20 23:08:37.726: INFO: Running 'oc create --config=/tmp/extended-test-build-valuefrom-dsfwc-6rvt9-user.kubeconfig --namespace=extended-test-build-valuefrom-dsfwc-6rvt9 -f /tmp/fixture-testdata-dir302855502/test/extended/testdata/builds/valuefrom/successful-sti-build-value-from-config.yaml'
buildconfig "mys2itest" created
STEP: starting test build
Feb 20 23:08:38.071: INFO: Running 'oc start-build --config=/tmp/extended-test-build-valuefrom-dsfwc-6rvt9-user.kubeconfig --namespace=extended-test-build-valuefrom-dsfwc-6rvt9 mys2itest -o=name'
Feb 20 23:08:38.354: INFO: 

start-build output with args [mys2itest -o=name]:
Error><nil>
StdOut>
build/mys2itest-1
StdErr>



Feb 20 23:08:38.355: INFO: Waiting for mys2itest-1 to complete

Feb 20 23:09:34.367: INFO: Done waiting for mys2itest-1: util.BuildResult{BuildPath:"build/mys2itest-1", BuildName:"mys2itest-1", StartBuildStdErr:"", StartBuildStdOut:"build/mys2itest-1", StartBuildErr:error(nil), BuildConfigName:"", Build:(*build.Build)(0xc421ad4000), BuildAttempt:true, BuildSuccess:true, BuildFailure:false, BuildCancelled:false, BuildTimeout:false, LogDumper:(util.LogDumperFunc)(nil), Oc:(*util.CLI)(0xc4212e6fc0)}
 with error: <nil>

Feb 20 23:09:34.367: INFO: Running 'oc logs --config=/tmp/extended-test-build-valuefrom-dsfwc-6rvt9-user.kubeconfig --namespace=extended-test-build-valuefrom-dsfwc-6rvt9 -f build/mys2itest-1 --timestamps'
[AfterEach] 
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/valuefrom.go:31
[AfterEach] [Feature:Builds][Conformance][valueFrom] process valueFrom in build strategy environment variables
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Feb 20 23:09:34.806: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "extended-test-build-valuefrom-dsfwc-6rvt9" for this suite.
Feb 20 23:09:40.823: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Feb 20 23:09:40.884: INFO: namespace: extended-test-build-valuefrom-dsfwc-6rvt9, resource: bindings, ignored listing per whitelist
Feb 20 23:09:41.001: INFO: namespace extended-test-build-valuefrom-dsfwc-6rvt9 deletion completed in 6.191711595s


• [SLOW TEST:64.526 seconds]
[Feature:Builds][Conformance][valueFrom] process valueFrom in build strategy environment variables
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/valuefrom.go:13
  
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/valuefrom.go:26
    should successfully resolve valueFrom in s2i build environment variables [Suite:openshift/conformance/parallel]
    /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/builds/valuefrom.go:61
------------------------------
Feb 20 23:09:41.003: INFO: Running AfterSuite actions on all node


[sig-storage] Projected 
  optional updates should be reflected in volume [Conformance] [Suite:openshift/conformance/parallel] [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:648

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [sig-storage] Projected
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Feb 20 23:08:39.804: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
STEP: Building a namespace api object
Feb 20 23:08:39.869: 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-storage] Projected
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/projected.go:858
[It] optional updates should be reflected in volume [Conformance] [Suite:openshift/conformance/parallel] [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:648
Feb 20 23:08:39.934: INFO: Couldn't get node TTL annotation (using default value of 0): No TTL annotation found on the node
STEP: Creating secret with name s-test-opt-del-fceec86f-1692-11e8-b73e-0eb4d2946e86
STEP: Creating secret with name s-test-opt-upd-fceec8b2-1692-11e8-b73e-0eb4d2946e86
STEP: Creating the pod
STEP: Deleting secret s-test-opt-del-fceec86f-1692-11e8-b73e-0eb4d2946e86
STEP: Updating secret s-test-opt-upd-fceec8b2-1692-11e8-b73e-0eb4d2946e86
STEP: Creating secret with name s-test-opt-create-fceec8cd-1692-11e8-b73e-0eb4d2946e86
STEP: waiting to observe update in volume
[AfterEach] [sig-storage] Projected
  /go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Feb 20 23:09:58.373: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-projected-6xhsv" for this suite.
Feb 20 23:10:20.387: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Feb 20 23:10:20.520: INFO: namespace: e2e-tests-projected-6xhsv, resource: bindings, ignored listing per whitelist
Feb 20 23:10:20.543: INFO: namespace e2e-tests-projected-6xhsv deletion completed in 22.168072612s


• [SLOW TEST:100.739 seconds]
[sig-storage] Projected
/go/src/github.com/openshift/origin/_output/local/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/projected.go:34
  optional updates should be reflected in volume [Conformance] [Suite:openshift/conformance/parallel] [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:648
------------------------------
Feb 20 23:10:20.544: INFO: Running AfterSuite actions on all node
Feb 20 23:10:20.545: INFO: Running AfterSuite actions on node 1


Ran 216 of 444 Specs in 2319.483 seconds
SUCCESS! -- 216 Passed | 0 Failed | 0 Pending | 228 Skipped 

Ginkgo ran 1 suite in 38m40.545400503s
Test Suite Passed
[INFO] Running serial tests
I0220 23:10:20.945824   57829 test.go:94] Extended test version v3.9.0-alpha.4+9fb6f85-409
I0220 23:10:23.100874   58790 test.go:94] Extended test version v3.9.0-alpha.4+9fb6f85-409
Running Suite: Extended
=======================
Random Seed: 1519168222 - Will randomize all specs
Will run 0 of 444 specs

I0220 23:10:23.182106   58790 e2e.go:56] The --provider flag is not set.  Treating as a conformance test.  Some tests may not be run.
Feb 20 23:10:23.182: INFO: >>> kubeConfig: /etc/origin/master/admin.kubeconfig
Feb 20 23:10:23.184: INFO: Waiting up to 4h0m0s for all (but 0) nodes to be schedulable
Feb 20 23:10:23.198: INFO: Waiting up to 10m0s for all pods (need at least 0) in namespace 'kube-system' to be running and ready
Feb 20 23:10:23.206: INFO: 1 / 1 pods in namespace 'kube-system' are running and ready (0 seconds elapsed)
Feb 20 23:10:23.206: INFO: expected 0 pod replicas in namespace 'kube-system', 0 are Running and Ready.
Feb 20 23:10:23.208: INFO: Waiting for pods to enter Success, but no pods in "kube-system" match label map[name:e2e-image-puller]
Feb 20 23:10:23.208: INFO: Dumping network health container logs from all nodes...
Feb 20 23:10:23.211: INFO: e2e test version: v1.9.1+a0ce1bc657
Feb 20 23:10:23.212: INFO: kube-apiserver version: v1.9.1+a0ce1bc657
I0220 23:10:23.212063   58790 e2e.go:56] The --provider flag is not set.  Treating as a conformance test.  Some tests may not be run.
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSFeb 20 23:10:23.215: INFO: Running AfterSuite actions on all node
Feb 20 23:10:23.215: INFO: Running AfterSuite actions on node 1

Ran 0 of 444 Specs in 0.034 seconds
SUCCESS! -- 0 Passed | 0 Failed | 0 Pending | 444 Skipped Feb 20 23:10:23.219: 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 147: gcloud: command not found
Feb 20 23:10:23.260: INFO: Error running cluster/log-dump/log-dump.sh: exit status 127
PASS

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

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

  * default
    kube-public
    kube-service-catalog
    kube-system
    logging
    management-infra
    openshift
    openshift-ansible-service-broker
    openshift-infra
    openshift-node
    openshift-template-service-broker
    openshift-web-console

Using project "default".
[INFO] [CLEANUP] Dumping container logs to _output/scripts/conformance/logs/containers
[INFO] [CLEANUP] Truncating log files over 200M
[INFO] [CLEANUP] Stopping docker containers
[INFO] [CLEANUP] Removing docker containers
[INFO] [CLEANUP] Killing child processes
[INFO] [CLEANUP] Pruning etcd data directory
rm: cannot remove ‘/tmp/etcd/member’: Permission denied
[INFO] test/extended/conformance.sh exited with code 0 after 00h 38m 47s
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: RUN EXTENDED TESTS [00h 48m 00s] ##########
[PostBuildScript] - Execution post build scripts.
[workspace@7] $ /bin/bash /tmp/jenkins7168624975794343598.sh
########## STARTING STAGE: DOWNLOAD ARTIFACTS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704
++ export PATH=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/gathered
+ rm -rf /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/gathered
+ mkdir -p /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/gathered
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo stat /data/src/github.com/openshift/origin/_output/scripts
  File: ‘/data/src/github.com/openshift/origin/_output/scripts’
  Size: 126       	Blocks: 0          IO Block: 4096   directory
Device: ca02h/51714d	Inode: 92885885    Links: 8
Access: (2755/drwxr-sr-x)  Uid: ( 1001/  origin)   Gid: ( 1003/origin-git)
Context: unconfined_u:object_r:default_t:s0
Access: 2018-02-20 22:22:19.730584695 +0000
Modify: 2018-02-20 22:31:37.855251460 +0000
Change: 2018-02-20 22:31:37.855251460 +0000
 Birth: -
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo chmod -R o+rX /data/src/github.com/openshift/origin/_output/scripts
+ scp -r -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel:/data/src/github.com/openshift/origin/_output/scripts /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/gathered
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo stat /var/log/pods
  File: ‘/var/log/pods’
  Size: 4096      	Blocks: 8          IO Block: 4096   directory
Device: ca02h/51714d	Inode: 138414758   Links: 13
Access: (0755/drwxr-xr-x)  Uid: (    0/    root)   Gid: (    0/    root)
Context: system_u:object_r:var_log_t:s0
Access: 2018-02-20 23:09:47.727281509 +0000
Modify: 2018-02-20 23:09:47.727281509 +0000
Change: 2018-02-20 23:09:47.727281509 +0000
 Birth: -
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo chmod -R o+rX /var/log/pods
+ scp -r -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel:/var/log/pods /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/gathered
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo stat /etc/crio
  File: ‘/etc/crio’
  Size: 87        	Blocks: 0          IO Block: 4096   directory
Device: ca02h/51714d	Inode: 184689687   Links: 2
Access: (0755/drwxr-xr-x)  Uid: (    0/    root)   Gid: (    0/    root)
Context: system_u:object_r:container_config_t:s0
Access: 2018-02-20 22:12:16.964277731 +0000
Modify: 2018-02-20 22:12:17.903248620 +0000
Change: 2018-02-20 22:12:17.903248620 +0000
 Birth: -
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo chmod -R o+rX /etc/crio
+ scp -r -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel:/etc/crio /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/gathered
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo stat /etc/systemd/system
  File: ‘/etc/systemd/system’
  Size: 4096      	Blocks: 8          IO Block: 4096   directory
Device: ca02h/51714d	Inode: 374907      Links: 15
Access: (0755/drwxr-xr-x)  Uid: (    0/    root)   Gid: (    0/    root)
Context: system_u:object_r:systemd_unit_file_t:s0
Access: 2018-02-20 22:17:59.108669883 +0000
Modify: 2018-02-20 22:17:55.933768318 +0000
Change: 2018-02-20 22:17:55.933768318 +0000
 Birth: -
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo chmod -R o+rX /etc/systemd/system
+ scp -r -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel:/etc/systemd/system /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/gathered
scp: /etc/systemd/system/firewalld.service: not a regular file
scp: /etc/systemd/system/origin-master.service: not a regular file
++ export status=FAILURE
++ status=FAILURE
+ exit 0
[workspace@7] $ /bin/bash /tmp/jenkins2680091047890723985.sh
########## STARTING STAGE: GENERATE ARTIFACTS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704
++ export PATH=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/generated
+ rm -rf /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/generated
+ mkdir /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/generated
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo cat /etc/crio/crio.conf 2>&1'
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo docker version && sudo docker info && sudo docker images && sudo docker ps -a 2>&1'
WARNING: bridge-nf-call-iptables is disabled
WARNING: bridge-nf-call-ip6tables is disabled
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo runc exec cri-o crio --version 2>&1'
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo cat /etc/sysconfig/docker /etc/sysconfig/docker-network /etc/sysconfig/docker-storage /etc/sysconfig/docker-storage-setup /etc/systemd/system/docker.service 2>&1'
+ true
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo cat /var/log/audit/audit.log 2>&1'
+ ssh -F ./.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 ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'oc get --raw /metrics --server=https://$( uname --nodename ):10250 --config=/etc/origin/master/admin.kubeconfig 2>&1'
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo ausearch -m AVC -m SELINUX_ERR -m USER_AVC 2>&1'
+ true
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo ausearch -m avc -ts recent 2>&1'
+ true
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'oc get --raw /metrics --config=/etc/origin/master/admin.kubeconfig 2>&1'
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo df -T -h && sudo pvs && sudo vgs && sudo lvs && sudo findmnt --all 2>&1'
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo journalctl -xe 2>&1'
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo yum list installed 2>&1'
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'dmesg 2>&1'
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo journalctl _PID=1 --no-pager --all --lines=all 2>&1'
+ tree /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/generated
/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/generated
├── auditd.log
├── avc_denials.log
├── avc_selinux_denials.log
├── containers.log
├── crio.commit
├── crio.conf
├── dmesg.log
├── docker.config
├── docker.info
├── filesystem.info
├── installed_packages.log
├── journal_xe.log
├── master-metrics.log
├── node-metrics.log
└── pid1.journal

0 directories, 15 files
+ exit 0
[workspace@7] $ /bin/bash /tmp/jenkins4154458080286403968.sh
########## STARTING STAGE: FETCH SYSTEMD JOURNALS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704
++ export PATH=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/journals
+ rm -rf /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/journals
+ mkdir /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/journals
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit docker.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit dnsmasq.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit systemd-journald.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit origin-master.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit origin-master-api.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit origin-master-controllers.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit origin-node.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit openvswitch.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit ovs-vswitchd.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit ovsdb-server.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit etcd.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit cri-o.service --no-pager --all --lines=all
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit systemd-journald.service --no-pager --all --lines=all
+ tree /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/journals
/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/artifacts/journals
├── cri-o.service
├── 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, 12 files
+ exit 0
[workspace@7] $ /bin/bash /tmp/jenkins3385642538972577206.sh
########## STARTING STAGE: FORWARD PARAMETERS TO THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704
++ export PATH=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo chmod o+rw /etc/environment
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'echo '\''BUILD_URL=https://ci.openshift.redhat.com/jenkins/job/test_pull_request_origin_extended_conformance_crio/4616/'\'' >> /etc/environment'
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: FORWARD PARAMETERS TO THE REMOTE HOST [00h 00m 01s] ##########
[workspace@7] $ /bin/bash /tmp/jenkins5471204928079846918.sh
########## STARTING STAGE: RECORD THE ENDING METADATA ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704
++ export PATH=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config
++ mktemp
+ script=/tmp/tmp.DFahAHQNtx
+ cat
+ chmod +x /tmp/tmp.DFahAHQNtx
+ scp -F ./.config/origin-ci-tool/inventory/.ssh_config /tmp/tmp.DFahAHQNtx openshiftdevel:/tmp/tmp.DFahAHQNtx
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config -t openshiftdevel 'bash -l -c "timeout 300 /tmp/tmp.DFahAHQNtx"'
+ cd /data/src/github.com/openshift/aos-cd-jobs
+ trap 'exit 0' EXIT
+ sjb/gcs/finished.py
+ exit 0
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: RECORD THE ENDING METADATA [00h 00m 00s] ##########
[workspace@7] $ /bin/bash /tmp/jenkins8998835699961584469.sh
########## STARTING STAGE: ASSEMBLE GCS OUTPUT ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704
++ export PATH=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config
+ trap 'exit 0' EXIT
+ mkdir -p gcs/artifacts gcs/artifacts/generated gcs/artifacts/journals gcs/artifacts/gathered
+ scp -F ./.config/origin-ci-tool/inventory/.ssh_config openshiftdevel:/data/finished.json gcs/
+ cat /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/builds/4616/log
+ cp artifacts/generated/auditd.log artifacts/generated/avc_denials.log artifacts/generated/avc_selinux_denials.log artifacts/generated/containers.log artifacts/generated/crio.commit artifacts/generated/crio.conf artifacts/generated/dmesg.log artifacts/generated/docker.config artifacts/generated/docker.info artifacts/generated/filesystem.info artifacts/generated/installed_packages.log artifacts/generated/journal_xe.log artifacts/generated/master-metrics.log artifacts/generated/node-metrics.log artifacts/generated/pid1.journal gcs/artifacts/generated/
+ cp artifacts/journals/cri-o.service 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/crio artifacts/gathered/pods artifacts/gathered/scripts artifacts/gathered/system gcs/artifacts/
++ pwd
+ scp -F ./.config/origin-ci-tool/inventory/.ssh_config -r /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/gcs openshiftdevel:/data
+ scp -F ./.config/origin-ci-tool/inventory/.ssh_config /var/lib/jenkins/.config/gcloud/gcs-publisher-credentials.json openshiftdevel:/data/credentials.json
+ exit 0
[workspace@7] $ /bin/bash /tmp/jenkins1815335475490769934.sh
########## STARTING STAGE: PUSH THE ARTIFACTS AND METADATA ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704
++ export PATH=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config
++ mktemp
+ script=/tmp/tmp.8mr1euytTw
+ cat
+ chmod +x /tmp/tmp.8mr1euytTw
+ scp -F ./.config/origin-ci-tool/inventory/.ssh_config /tmp/tmp.8mr1euytTw openshiftdevel:/tmp/tmp.8mr1euytTw
+ ssh -F ./.config/origin-ci-tool/inventory/.ssh_config -t openshiftdevel 'bash -l -c "timeout 300 /tmp/tmp.8mr1euytTw"'
+ cd /home/origin
+ trap 'exit 0' EXIT
+ [[ -n {"type":"presubmit","job":"test_pull_request_origin_extended_conformance_crio","buildid":"6f57f560-1683-11e8-9ee3-0a58ac100ecc","refs":{"org":"openshift","repo":"origin","base_ref":"master","base_sha":"3fc05224aef1bdfa1fb15b8ead428cc5cc27a283","pulls":[{"number":18686,"author":"smarterclayton","sha":"bd22261f2e0a705b428919406c18781864c203f6"}]}} ]]
++ jq --compact-output .buildid
+ [[ "6f57f560-1683-11e8-9ee3-0a58ac100ecc" =~ ^"[0-9]+"$ ]]
Using BUILD_NUMBER
+ echo 'Using BUILD_NUMBER'
++ jq --compact-output '.buildid |= "4616"'
+ JOB_SPEC='{"type":"presubmit","job":"test_pull_request_origin_extended_conformance_crio","buildid":"4616","refs":{"org":"openshift","repo":"origin","base_ref":"master","base_sha":"3fc05224aef1bdfa1fb15b8ead428cc5cc27a283","pulls":[{"number":18686,"author":"smarterclayton","sha":"bd22261f2e0a705b428919406c18781864c203f6"}]}}'
+ docker run -e 'JOB_SPEC={"type":"presubmit","job":"test_pull_request_origin_extended_conformance_crio","buildid":"4616","refs":{"org":"openshift","repo":"origin","base_ref":"master","base_sha":"3fc05224aef1bdfa1fb15b8ead428cc5cc27a283","pulls":[{"number":18686,"author":"smarterclayton","sha":"bd22261f2e0a705b428919406c18781864c203f6"}]}}' -v /data:/data:z registry.svc.ci.openshift.org/ci/gcsupload:latest --dry-run=false --gcs-bucket=origin-ci-test --gcs-credentials-file=/data/credentials.json --path-strategy=single --default-org=openshift --default-repo=origin /data/gcs/
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
6d987f6f4279: Pulling fs layer
186fc760c1fb: Pulling fs layer
1c5839a97a69: Pulling fs layer
186fc760c1fb: Verifying Checksum
186fc760c1fb: Download complete
6d987f6f4279: Verifying Checksum
6d987f6f4279: Download complete
1c5839a97a69: Verifying Checksum
1c5839a97a69: Download complete
6d987f6f4279: Pull complete
186fc760c1fb: Pull complete
1c5839a97a69: Pull complete
Digest: sha256:155975586399aafa90b4827194aa49b145dac376dafce50a74a28e10ffaded46
time="2018-02-20T23:14:40Z" level=info msg="Gathering artifacts from artifact directory: /data/gcs/" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/crio/crio.conf in artifact directory. Uploading as artifacts/crio/crio.conf\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/crio/crio.conf.11865.2018-02-20@22:12:17~ in artifact directory. Uploading as artifacts/crio/crio.conf.11865.2018-02-20@22:12:17~\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/crio/seccomp.json in artifact directory. Uploading as artifacts/crio/seccomp.json\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/generated/auditd.log in artifact directory. Uploading as artifacts/generated/auditd.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/generated/avc_denials.log in artifact directory. Uploading as artifacts/generated/avc_denials.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/generated/avc_selinux_denials.log in artifact directory. Uploading as artifacts/generated/avc_selinux_denials.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/generated/containers.log in artifact directory. Uploading as artifacts/generated/containers.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/generated/crio.commit in artifact directory. Uploading as artifacts/generated/crio.commit\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/generated/crio.conf in artifact directory. Uploading as artifacts/generated/crio.conf\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/generated/dmesg.log in artifact directory. Uploading as artifacts/generated/dmesg.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/generated/docker.config in artifact directory. Uploading as artifacts/generated/docker.config\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/generated/docker.info in artifact directory. Uploading as artifacts/generated/docker.info\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/generated/filesystem.info in artifact directory. Uploading as artifacts/generated/filesystem.info\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/generated/installed_packages.log in artifact directory. Uploading as artifacts/generated/installed_packages.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/generated/journal_xe.log in artifact directory. Uploading as artifacts/generated/journal_xe.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/generated/master-metrics.log in artifact directory. Uploading as artifacts/generated/master-metrics.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/generated/node-metrics.log in artifact directory. Uploading as artifacts/generated/node-metrics.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/generated/pid1.journal in artifact directory. Uploading as artifacts/generated/pid1.journal\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/journals/cri-o.service in artifact directory. Uploading as artifacts/journals/cri-o.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/journals/dnsmasq.service in artifact directory. Uploading as artifacts/journals/dnsmasq.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/journals/docker.service in artifact directory. Uploading as artifacts/journals/docker.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/journals/etcd.service in artifact directory. Uploading as artifacts/journals/etcd.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/journals/openvswitch.service in artifact directory. Uploading as artifacts/journals/openvswitch.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/journals/origin-master-api.service in artifact directory. Uploading as artifacts/journals/origin-master-api.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/journals/origin-master-controllers.service in artifact directory. Uploading as artifacts/journals/origin-master-controllers.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/journals/origin-master.service in artifact directory. Uploading as artifacts/journals/origin-master.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/journals/origin-node.service in artifact directory. Uploading as artifacts/journals/origin-node.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/journals/ovs-vswitchd.service in artifact directory. Uploading as artifacts/journals/ovs-vswitchd.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/journals/ovsdb-server.service in artifact directory. Uploading as artifacts/journals/ovsdb-server.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/journals/systemd-journald.service in artifact directory. Uploading as artifacts/journals/systemd-journald.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/022f6dcd-168c-11e8-8b52-0eb4d2946e86/256b9c6269974ab513ea72417f64318b449fedfd2e3a5b111dfe007f7092e5c0.log in artifact directory. Uploading as artifacts/pods/022f6dcd-168c-11e8-8b52-0eb4d2946e86/256b9c6269974ab513ea72417f64318b449fedfd2e3a5b111dfe007f7092e5c0.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/022f6dcd-168c-11e8-8b52-0eb4d2946e86/router_0.log in artifact directory. Uploading as artifacts/pods/022f6dcd-168c-11e8-8b52-0eb4d2946e86/router_0.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/0a823caa-168c-11e8-8b52-0eb4d2946e86/bb48e442365ce4791db775f82d6a952ffa1d3e3cc8bc0d9c636d3a8262bb5367.log in artifact directory. Uploading as artifacts/pods/0a823caa-168c-11e8-8b52-0eb4d2946e86/bb48e442365ce4791db775f82d6a952ffa1d3e3cc8bc0d9c636d3a8262bb5367.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/0a823caa-168c-11e8-8b52-0eb4d2946e86/registry_0.log in artifact directory. Uploading as artifacts/pods/0a823caa-168c-11e8-8b52-0eb4d2946e86/registry_0.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/1807f01d-168c-11e8-8b52-0eb4d2946e86/c186fbb2af04e0b2f4c734d5018f5ce41bd8056308dd302b0dbfcb55e2cf0626.log in artifact directory. Uploading as artifacts/pods/1807f01d-168c-11e8-8b52-0eb4d2946e86/c186fbb2af04e0b2f4c734d5018f5ce41bd8056308dd302b0dbfcb55e2cf0626.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/1807f01d-168c-11e8-8b52-0eb4d2946e86/registry-console_0.log in artifact directory. Uploading as artifacts/pods/1807f01d-168c-11e8-8b52-0eb4d2946e86/registry-console_0.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/alert-buffer_0.log in artifact directory. Uploading as artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/alert-buffer_0.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/alertmanager-proxy_0.log in artifact directory. Uploading as artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/alertmanager-proxy_0.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/alertmanager_0.log in artifact directory. Uploading as artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/alertmanager_0.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/alerts-proxy_0.log in artifact directory. Uploading as artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/alerts-proxy_0.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/d07795d8b7a80a0f433e24dbf4b66fbadbb7550d7ac86fb268026ba08c554f5b.log in artifact directory. Uploading as artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/d07795d8b7a80a0f433e24dbf4b66fbadbb7550d7ac86fb268026ba08c554f5b.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/prom-proxy_0.log in artifact directory. Uploading as artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/prom-proxy_0.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/prometheus_0.log in artifact directory. Uploading as artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/prometheus_0.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/5471b7f0-168c-11e8-8b52-0eb4d2946e86/2922613087c937c1cfc18736a9340f9cfaae48264ea6fc7c66f0ba8de7f9f63b.log in artifact directory. Uploading as artifacts/pods/5471b7f0-168c-11e8-8b52-0eb4d2946e86/2922613087c937c1cfc18736a9340f9cfaae48264ea6fc7c66f0ba8de7f9f63b.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/5471b7f0-168c-11e8-8b52-0eb4d2946e86/apiserver_0.log in artifact directory. Uploading as artifacts/pods/5471b7f0-168c-11e8-8b52-0eb4d2946e86/apiserver_0.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/5471fe78-168c-11e8-8b52-0eb4d2946e86/controller-manager_0.log in artifact directory. Uploading as artifacts/pods/5471fe78-168c-11e8-8b52-0eb4d2946e86/controller-manager_0.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/5471fe78-168c-11e8-8b52-0eb4d2946e86/d21df58a357f88836ff1ed9aca54330bfbd8d3db5aa6363202f1b8a13971cd68.log in artifact directory. Uploading as artifacts/pods/5471fe78-168c-11e8-8b52-0eb4d2946e86/d21df58a357f88836ff1ed9aca54330bfbd8d3db5aa6363202f1b8a13971cd68.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/6541647e-168c-11e8-8b52-0eb4d2946e86/27741c527a726061a35bfd582cef0993bfd77383092bff125f5dc540064e6634.log in artifact directory. Uploading as artifacts/pods/6541647e-168c-11e8-8b52-0eb4d2946e86/27741c527a726061a35bfd582cef0993bfd77383092bff125f5dc540064e6634.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/6541647e-168c-11e8-8b52-0eb4d2946e86/deployment_0.log in artifact directory. Uploading as artifacts/pods/6541647e-168c-11e8-8b52-0eb4d2946e86/deployment_0.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/6622f1cb-168c-11e8-8b52-0eb4d2946e86/18e6e2ad8d451f8f86f5544f27dcfcc61d657d892dcefb8247de7f872a6a7961.log in artifact directory. Uploading as artifacts/pods/6622f1cb-168c-11e8-8b52-0eb4d2946e86/18e6e2ad8d451f8f86f5544f27dcfcc61d657d892dcefb8247de7f872a6a7961.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/6622f1cb-168c-11e8-8b52-0eb4d2946e86/deployment_0.log in artifact directory. Uploading as artifacts/pods/6622f1cb-168c-11e8-8b52-0eb4d2946e86/deployment_0.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/6ba176ac-168c-11e8-8b52-0eb4d2946e86/a8eca96bf8fe0bdaa60563d3b022734d09216dda89c4596070eaa01e48fd3877.log in artifact directory. Uploading as artifacts/pods/6ba176ac-168c-11e8-8b52-0eb4d2946e86/a8eca96bf8fe0bdaa60563d3b022734d09216dda89c4596070eaa01e48fd3877.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/6ba176ac-168c-11e8-8b52-0eb4d2946e86/c_0.log in artifact directory. Uploading as artifacts/pods/6ba176ac-168c-11e8-8b52-0eb4d2946e86/c_0.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/81326fcb-168c-11e8-8b52-0eb4d2946e86/27454db06ab5736941658e35712ab9a95eefc7292ea1688ed079ec2d55b890eb.log in artifact directory. Uploading as artifacts/pods/81326fcb-168c-11e8-8b52-0eb4d2946e86/27454db06ab5736941658e35712ab9a95eefc7292ea1688ed079ec2d55b890eb.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/81326fcb-168c-11e8-8b52-0eb4d2946e86/webconsole_0.log in artifact directory. Uploading as artifacts/pods/81326fcb-168c-11e8-8b52-0eb4d2946e86/webconsole_0.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/pods/fcf0b5b9-1692-11e8-8b52-0eb4d2946e86/54a940690ff18429843901e3741bf2c7871811e9e520a74ad80a36b6dba1fcf2.log in artifact directory. Uploading as artifacts/pods/fcf0b5b9-1692-11e8-8b52-0eb4d2946e86/54a940690ff18429843901e3741bf2c7871811e9e520a74ad80a36b6dba1fcf2.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/scripts/ansible_junit/cDyFQoYEVz.xml in artifact directory. Uploading as artifacts/scripts/ansible_junit/cDyFQoYEVz.xml\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/scripts/ansible_junit/kHRTevekai.xml in artifact directory. Uploading as artifacts/scripts/ansible_junit/kHRTevekai.xml\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/scripts/ansible_junit/wQYdxEMDkN.xml in artifact directory. Uploading as artifacts/scripts/ansible_junit/wQYdxEMDkN.xml\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/scripts/build-images/logs/scripts.log in artifact directory. Uploading as artifacts/scripts/build-images/logs/scripts.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/scripts/conformance/artifacts/events.txt in artifact directory. Uploading as artifacts/scripts/conformance/artifacts/events.txt\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_01.xml in artifact directory. Uploading as artifacts/scripts/conformance/artifacts/junit/conformance_parallel_01.xml\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_02.xml in artifact directory. Uploading as artifacts/scripts/conformance/artifacts/junit/conformance_parallel_02.xml\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_03.xml in artifact directory. Uploading as artifacts/scripts/conformance/artifacts/junit/conformance_parallel_03.xml\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_04.xml in artifact directory. Uploading as artifacts/scripts/conformance/artifacts/junit/conformance_parallel_04.xml\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_05.xml in artifact directory. Uploading as artifacts/scripts/conformance/artifacts/junit/conformance_parallel_05.xml\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/scripts/conformance/artifacts/junit/conformance_serial_01.xml in artifact directory. Uploading as artifacts/scripts/conformance/artifacts/junit/conformance_serial_01.xml\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/scripts/conformance/logs/scripts.log in artifact directory. Uploading as artifacts/scripts/conformance/logs/scripts.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/scripts/shell/logs/6b7c683be03f386c33ec1048a1a6e86d81a18ba1f019bd75c9fdf9cbae503679.json in artifact directory. Uploading as artifacts/scripts/shell/logs/6b7c683be03f386c33ec1048a1a6e86d81a18ba1f019bd75c9fdf9cbae503679.json\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/scripts/shell/logs/8b99a344a120412610818f89223e4221dbb752ecad32d51c07ecac5b7b013015.json in artifact directory. Uploading as artifacts/scripts/shell/logs/8b99a344a120412610818f89223e4221dbb752ecad32d51c07ecac5b7b013015.json\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/scripts/shell/logs/scripts.log in artifact directory. Uploading as artifacts/scripts/shell/logs/scripts.log\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/basic.target.wants/iptables.service in artifact directory. Uploading as artifacts/system/basic.target.wants/iptables.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/basic.target.wants/microcode.service in artifact directory. Uploading as artifacts/system/basic.target.wants/microcode.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/cri-o.service in artifact directory. Uploading as artifacts/system/cri-o.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/dbus-org.freedesktop.NetworkManager.service in artifact directory. Uploading as artifacts/system/dbus-org.freedesktop.NetworkManager.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/dbus-org.freedesktop.nm-dispatcher.service in artifact directory. Uploading as artifacts/system/dbus-org.freedesktop.nm-dispatcher.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/default.target in artifact directory. Uploading as artifacts/system/default.target\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/default.target.wants/systemd-readahead-collect.service in artifact directory. Uploading as artifacts/system/default.target.wants/systemd-readahead-collect.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/default.target.wants/systemd-readahead-replay.service in artifact directory. Uploading as artifacts/system/default.target.wants/systemd-readahead-replay.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/dev-virtio\\x2dports-org.qemu.guest_agent.0.device.wants/qemu-guest-agent.service in artifact directory. Uploading as artifacts/system/dev-virtio\\x2dports-org.qemu.guest_agent.0.device.wants/qemu-guest-agent.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/docker.service.d/custom.conf in artifact directory. Uploading as artifacts/system/docker.service.d/custom.conf\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/getty.target.wants/getty@tty1.service in artifact directory. Uploading as artifacts/system/getty.target.wants/getty@tty1.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/local-fs.target.wants/ostree-remount.service in artifact directory. Uploading as artifacts/system/local-fs.target.wants/ostree-remount.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/NetworkManager.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/NetworkManager.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/atd.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/atd.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/auditd.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/auditd.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/chronyd.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/chronyd.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/cloud-config.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/cloud-config.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/cloud-final.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/cloud-final.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/cloud-init-local.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/cloud-init-local.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/cloud-init.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/cloud-init.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/cri-o.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/cri-o.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/crond.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/crond.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/dnsmasq.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/dnsmasq.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/docker.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/docker.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/etcd.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/etcd.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/irqbalance.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/irqbalance.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/kdump.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/kdump.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/nfs-client.target in artifact directory. Uploading as artifacts/system/multi-user.target.wants/nfs-client.target\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/origin-master-api.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/origin-master-api.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/origin-master-controllers.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/origin-master-controllers.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/origin-node.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/origin-node.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/postfix.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/postfix.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/remote-fs.target in artifact directory. Uploading as artifacts/system/multi-user.target.wants/remote-fs.target\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/rsyslog.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/rsyslog.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/sshd.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/sshd.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/sysstat.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/sysstat.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/multi-user.target.wants/tuned.service in artifact directory. Uploading as artifacts/system/multi-user.target.wants/tuned.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/openvswitch.service.d/01-avoid-oom.conf in artifact directory. Uploading as artifacts/system/openvswitch.service.d/01-avoid-oom.conf\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/origin-node.service in artifact directory. Uploading as artifacts/system/origin-node.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/origin-node.service.wants/origin-master-api.service in artifact directory. Uploading as artifacts/system/origin-node.service.wants/origin-master-api.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/remote-fs.target.wants/nfs-client.target in artifact directory. Uploading as artifacts/system/remote-fs.target.wants/nfs-client.target\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/sockets.target.wants/dm-event.socket in artifact directory. Uploading as artifacts/system/sockets.target.wants/dm-event.socket\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/sockets.target.wants/iscsid.socket in artifact directory. Uploading as artifacts/system/sockets.target.wants/iscsid.socket\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/sockets.target.wants/iscsiuio.socket in artifact directory. Uploading as artifacts/system/sockets.target.wants/iscsiuio.socket\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/sockets.target.wants/rpcbind.socket in artifact directory. Uploading as artifacts/system/sockets.target.wants/rpcbind.socket\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/sysinit.target.wants/iscsi.service in artifact directory. Uploading as artifacts/system/sysinit.target.wants/iscsi.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/sysinit.target.wants/lvm2-lvmetad.socket in artifact directory. Uploading as artifacts/system/sysinit.target.wants/lvm2-lvmetad.socket\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/sysinit.target.wants/lvm2-lvmpolld.socket in artifact directory. Uploading as artifacts/system/sysinit.target.wants/lvm2-lvmpolld.socket\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/sysinit.target.wants/lvm2-monitor.service in artifact directory. Uploading as artifacts/system/sysinit.target.wants/lvm2-monitor.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/sysinit.target.wants/multipathd.service in artifact directory. Uploading as artifacts/system/sysinit.target.wants/multipathd.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/artifacts/system/system-update.target.wants/systemd-readahead-drop.service in artifact directory. Uploading as artifacts/system/system-update.target.wants/systemd-readahead-drop.service\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/build-log.txt in artifact directory. Uploading as build-log.txt\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/finished.json in artifact directory. Uploading as finished.json\n" 
time="2018-02-20T23:14:40Z" level=info msg="Found /data/gcs/started.json in artifact directory. Uploading as started.json\n" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/0a823caa-168c-11e8-8b52-0eb4d2946e86/bb48e442365ce4791db775f82d6a952ffa1d3e3cc8bc0d9c636d3a8262bb5367.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/sockets.target.wants/dm-event.socket" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/auditd.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/chronyd.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/irqbalance.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/generated/auditd.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/generated/docker.info" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/journals/origin-master-controllers.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/alerts-proxy_0.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/dbus-org.freedesktop.nm-dispatcher.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/sockets.target.wants/iscsid.socket" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/started.json" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/atd.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/generated/crio.conf" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/journals/cri-o.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/6541647e-168c-11e8-8b52-0eb4d2946e86/deployment_0.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/scripts/ansible_junit/kHRTevekai.xml" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/scripts/conformance/artifacts/junit/conformance_serial_01.xml" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/journals/ovs-vswitchd.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/nfs-client.target" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/directory/test_pull_request_origin_extended_conformance_crio/4616" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/generated/crio.commit" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/docker.service.d/custom.conf" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/sysinit.target.wants/lvm2-monitor.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/generated/node-metrics.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_01.xml" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/generated/installed_packages.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/5471b7f0-168c-11e8-8b52-0eb4d2946e86/apiserver_0.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/sshd.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/d07795d8b7a80a0f433e24dbf4b66fbadbb7550d7ac86fb268026ba08c554f5b.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/81326fcb-168c-11e8-8b52-0eb4d2946e86/27454db06ab5736941658e35712ab9a95eefc7292ea1688ed079ec2d55b890eb.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/fcf0b5b9-1692-11e8-8b52-0eb4d2946e86/54a940690ff18429843901e3741bf2c7871811e9e520a74ad80a36b6dba1fcf2.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/origin-node.service.wants/origin-master-api.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/remote-fs.target.wants/nfs-client.target" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/generated/containers.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/generated/journal_xe.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/5471b7f0-168c-11e8-8b52-0eb4d2946e86/2922613087c937c1cfc18736a9340f9cfaae48264ea6fc7c66f0ba8de7f9f63b.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/finished.json" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/sockets.target.wants/iscsiuio.socket" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/generated/pid1.journal" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/default.target.wants/systemd-readahead-replay.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/postfix.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/generated/filesystem.info" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/journals/docker.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/journals/openvswitch.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/journals/origin-master-api.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/scripts/conformance/artifacts/events.txt" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/sysstat.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/crio/crio.conf.11865.2018-02-20@22:12:17~" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/022f6dcd-168c-11e8-8b52-0eb4d2946e86/256b9c6269974ab513ea72417f64318b449fedfd2e3a5b111dfe007f7092e5c0.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/6ba176ac-168c-11e8-8b52-0eb4d2946e86/c_0.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/dev-virtio\\x2dports-org.qemu.guest_agent.0.device.wants/qemu-guest-agent.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/getty.target.wants/getty@tty1.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/generated/docker.config" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/6541647e-168c-11e8-8b52-0eb4d2946e86/27741c527a726061a35bfd582cef0993bfd77383092bff125f5dc540064e6634.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/6ba176ac-168c-11e8-8b52-0eb4d2946e86/a8eca96bf8fe0bdaa60563d3b022734d09216dda89c4596070eaa01e48fd3877.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_05.xml" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/basic.target.wants/microcode.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_03.xml" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/crond.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/origin-master-controllers.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/remote-fs.target" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/crio/crio.conf" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/generated/avc_selinux_denials.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/generated/avc_denials.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/NetworkManager.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/cri-o.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/etcd.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/origin-node.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/journals/origin-node.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/default.target" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/0a823caa-168c-11e8-8b52-0eb4d2946e86/registry_0.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/5471fe78-168c-11e8-8b52-0eb4d2946e86/d21df58a357f88836ff1ed9aca54330bfbd8d3db5aa6363202f1b8a13971cd68.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/default.target.wants/systemd-readahead-collect.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/openvswitch.service.d/01-avoid-oom.conf" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/alertmanager_0.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/6622f1cb-168c-11e8-8b52-0eb4d2946e86/deployment_0.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/basic.target.wants/iptables.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/sockets.target.wants/rpcbind.socket" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/sysinit.target.wants/multipathd.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/dbus-org.freedesktop.NetworkManager.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/dnsmasq.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/origin-node.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_02.xml" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/origin-master-api.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/generated/master-metrics.log" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/journals/etcd.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/journals/origin-master.service" 
time="2018-02-20T23:14:40Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/1807f01d-168c-11e8-8b52-0eb4d2946e86/c186fbb2af04e0b2f4c734d5018f5ce41bd8056308dd302b0dbfcb55e2cf0626.log" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/prom-proxy_0.log" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/alert-buffer_0.log" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/cloud-init.service" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/tuned.service" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/system-update.target.wants/systemd-readahead-drop.service" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/build-log.txt" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/scripts/ansible_junit/cDyFQoYEVz.xml" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/scripts/build-images/logs/scripts.log" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/scripts/conformance/logs/scripts.log" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/sysinit.target.wants/iscsi.service" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/6622f1cb-168c-11e8-8b52-0eb4d2946e86/18e6e2ad8d451f8f86f5544f27dcfcc61d657d892dcefb8247de7f872a6a7961.log" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/kdump.service" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/journals/systemd-journald.service" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/cri-o.service" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/local-fs.target.wants/ostree-remount.service" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/cloud-init-local.service" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/docker.service" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/crio/seccomp.json" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/generated/dmesg.log" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/prometheus_0.log" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/scripts/shell/logs/8b99a344a120412610818f89223e4221dbb752ecad32d51c07ecac5b7b013015.json" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/sysinit.target.wants/lvm2-lvmetad.socket" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/journals/ovsdb-server.service" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/scripts/ansible_junit/wQYdxEMDkN.xml" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/scripts/shell/logs/scripts.log" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/cloud-final.service" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/rsyslog.service" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/journals/dnsmasq.service" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/022f6dcd-168c-11e8-8b52-0eb4d2946e86/router_0.log" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_04.xml" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/sysinit.target.wants/lvm2-lvmpolld.socket" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/system/multi-user.target.wants/cloud-config.service" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/1807f01d-168c-11e8-8b52-0eb4d2946e86/registry-console_0.log" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/21a40d22-168f-11e8-8b52-0eb4d2946e86/alertmanager-proxy_0.log" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/5471fe78-168c-11e8-8b52-0eb4d2946e86/controller-manager_0.log" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/pods/81326fcb-168c-11e8-8b52-0eb4d2946e86/webconsole_0.log" 
time="2018-02-20T23:14:41Z" level=info msg="Queued for upload" dest="pr-logs/pull/18686/test_pull_request_origin_extended_conformance_crio/4616/artifacts/scripts/shell/logs/6b7c683be03f386c33ec1048a1a6e86d81a18ba1f019bd75c9fdf9cbae503679.json" 
+ exit 0
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: PUSH THE ARTIFACTS AND METADATA [00h 00m 40s] ##########
[workspace@7] $ /bin/bash /tmp/jenkins3609388313893890491.sh
########## STARTING STAGE: DEPROVISION CLOUD RESOURCES ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704
++ export PATH=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config
+ oct deprovision

PLAYBOOK: main.yml *************************************************************
4 plays in /var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/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/7fa507a4bc591e690754be8f19277886f5151704/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-02-20 18:15:00.951193", 
    "item": "origin_ci_inventory_dir", 
    "skip_reason": "Conditional check failed", 
    "skipped": true
}
skipping: [localhost] => (item=origin_ci_aws_region)  => {
    "changed": false, 
    "generated_timestamp": "2018-02-20 18:15:00.955297", 
    "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/7fa507a4bc591e690754be8f19277886f5151704/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:28
included: /var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/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/7fa507a4bc591e690754be8f19277886f5151704/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:2
ok: [localhost] => {
    "changed": false, 
    "generated_timestamp": "2018-02-20 18:15:01.782853", 
    "msg": ""
}

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

TASK [tear down the EC2 instance] **********************************************
task path: /var/lib/jenkins/origin-ci-tool/7fa507a4bc591e690754be8f19277886f5151704/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:15
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2018-02-20 18:15:03.164482", 
    "instance_ids": [
        "i-03eff24d76c46d22d"
    ], 
    "instances": [
        {
            "ami_launch_index": "0", 
            "architecture": "x86_64", 
            "block_device_mapping": {
                "/dev/sda1": {
                    "delete_on_termination": true, 
                    "status": "attached", 
                    "volume_id": "vol-0dea55caa120a76e9"
                }, 
                "/dev/sdb": {
                    "delete_on_termination": true, 
                    "status": "attached", 
                    "volume_id": "vol-0a6dcd8816bba61c3"
                }
            }, 
            "dns_name": "ec2-52-90-180-209.compute-1.amazonaws.com", 
            "ebs_optimized": false, 
            "groups": {
                "sg-7e73221a": "default"
            }, 
            "hypervisor": "xen", 
            "id": "i-03eff24d76c46d22d", 
            "image_id": "ami-b76e6ecd", 
            "instance_type": "m4.xlarge", 
            "kernel": null, 
            "key_name": "libra", 
            "launch_time": "2018-02-20T21:18:37.000Z", 
            "placement": "us-east-1d", 
            "private_dns_name": "ip-172-18-4-243.ec2.internal", 
            "private_ip": "172.18.4.243", 
            "public_dns_name": "ec2-52-90-180-209.compute-1.amazonaws.com", 
            "public_ip": "52.90.180.209", 
            "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/7fa507a4bc591e690754be8f19277886f5151704/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:22
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2018-02-20 18:15:03.411207", 
    "path": "/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.config/origin-ci-tool/inventory/host_vars/172.18.4.243.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/7fa507a4bc591e690754be8f19277886f5151704/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:61
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2018-02-20 18:15:03.870202", 
    "path": "/var/lib/jenkins/jobs/test_pull_request_origin_extended_conformance_crio/workspace@7/.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