Skip to content

Instantly share code, notes, and snippets.

@timstclair
Created March 31, 2016 00:23
Show Gist options
  • Save timstclair/6125760a05654d36c255048c49ef7ea5 to your computer and use it in GitHub Desktop.
Save timstclair/6125760a05654d36c255048c49ef7ea5 to your computer and use it in GitHub Desktop.
docker v1.11.0-rc2 kubernetes-e2e-gce-slow results
go run hack/e2e.go -v --test --test_args=' --ginkgo.focus=\[Slow\] --ginkgo.skip=\[Serial\]|\[Disruptive\]|\[Flaky\]|\[Feature:.+\]'
++ go run hack/e2e.go -v --test '--test_args= --ginkgo.focus=\[Slow\] --ginkgo.skip=\[Serial\]|\[Disruptive\]|\[Flaky\]|\[Feature:.+\]'
2016/03/30 14:54:04 e2e.go:194: Running: get status
Your active configuration is: [default]
Project: stclair-k8s-ext
Zone: us-central1-b
Client Version: version.Info{Major:"1", Minor:"3+", GitVersion:"v1.3.0-alpha.0.875+c0dbcec3019636", GitCommit:"c0dbcec3019636ba3979d66c110e54ee0398e380", GitTreeState:"clean", BuildDate:"2016-03-30T01:27:12Z", GoVersion:"go1.4.2", Compiler:"gc", Platform:"linux/amd64"}
Server Version: version.Info{Major:"1", Minor:"3+", GitVersion:"v1.3.0-alpha.0.875+c0dbcec3019636", GitCommit:"c0dbcec3019636ba3979d66c110e54ee0398e380", GitTreeState:"clean", BuildDate:"2016-03-30T01:26:25Z", GoVersion:"go1.4.2", Compiler:"gc", Platform:"linux/amd64"}
2016/03/30 14:54:05 e2e.go:196: Step 'get status' finished in 1.112524298s
Your active configuration is: [default]
Project: stclair-k8s-ext
Zone: us-central1-b
2016/03/30 14:54:06 e2e.go:194: Running: Ginkgo tests
Setting up for KUBERNETES_PROVIDER="gce".
Your active configuration is: [default]
Project: stclair-k8s-ext
Zone: us-central1-b
Mar 30 14:54:07.915: INFO: Fetching cloud provider for "gce"
I0330 14:54:07.915712 9913 gce.go:263] Using DefaultTokenSource &oauth2.reuseTokenSource{new:(*oauth2.tokenRefresher)(0xc20826c8a0), mu:sync.Mutex{state:0, sema:0x0}, t:(*oauth2.Token)(0xc208216840)}
I0330 14:54:08.294019 9913 e2e.go:274] Starting e2e run "ed820247-f6c1-11e5-94b8-7446a0c373f1" on Ginkgo node 1
Running Suite: Kubernetes e2e suite
===================================
Random Seed: 1459374847 - Will randomize all specs
Will run 9 of 268 specs
Mar 30 14:54:08.308: INFO: >>> testContext.KubeConfig: /usr/local/google/home/stclair/.kube/config
Mar 30 14:54:08.446: INFO: Waiting up to 10m0s for all pods (need at least 0) in namespace 'kube-system' to be running and ready
Mar 30 14:54:08.731: INFO: 19 / 19 pods in namespace 'kube-system' are running and ready (0 seconds elapsed)
Mar 30 14:54:08.731: INFO: expected 7 pod replicas in namespace 'kube-system', 7 are Running and Ready.
SSSSSSSSSSSS
------------------------------
[k8s.io] Pod Disks
should schedule a pod w/ a RW PD shared between multiple containers, write to PD, delete pod, verify contents, and repeat in rapid succession [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pd.go:216
[BeforeEach] [k8s.io] Pod Disks
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:101
STEP: Creating a kubernetes client
Mar 30 14:54:08.731: INFO: >>> testContext.KubeConfig: /usr/local/google/home/stclair/.kube/config
STEP: Building a namespace api object
Mar 30 14:54:08.781: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-pod-disks-ybxub
Mar 30 14:54:08.823: INFO: Service account default in ns e2e-tests-pod-disks-ybxub with secrets found. (41.628784ms)
STEP: Waiting for a default service account to be provisioned in namespace
Mar 30 14:54:08.823: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-pod-disks-ybxub
Mar 30 14:54:08.862: INFO: Service account default in ns e2e-tests-pod-disks-ybxub with secrets found. (38.76652ms)
[BeforeEach] [k8s.io] Pod Disks
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pd.go:68
[It] should schedule a pod w/ a RW PD shared between multiple containers, write to PD, delete pod, verify contents, and repeat in rapid succession [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pd.go:216
STEP: creating PD
Mar 30 14:54:15.575: INFO: Successfully created a new PD: "e2e-ee22c20d-f6c1-11e5-94b8-7446a0c373f1".
Mar 30 14:54:15.575: INFO: PD Read/Writer Iteration #0
STEP: submitting host0Pod to kubernetes
Mar 30 14:54:15.630: INFO: Waiting up to 15m0s for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 status to be running
Mar 30 14:54:15.672: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (41.545309ms elapsed)
Mar 30 14:54:17.714: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (2.083521221s elapsed)
Mar 30 14:54:19.757: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (4.126842744s elapsed)
Mar 30 14:54:21.799: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (6.168401881s elapsed)
Mar 30 14:54:23.840: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (8.209810614s elapsed)
Mar 30 14:54:25.881: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (10.250826429s elapsed)
Mar 30 14:54:27.923: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (12.292247445s elapsed)
Mar 30 14:54:29.964: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (14.333454993s elapsed)
Mar 30 14:54:32.017: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (16.38609637s elapsed)
Mar 30 14:54:34.058: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (18.427421394s elapsed)
Mar 30 14:54:36.100: INFO: Found pod 'pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1' on node 'e2e-test-stclair-minion-gxqg'
STEP: writing a file in the container
Mar 30 14:54:36.100: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-ybxub pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 -c=mycontainer4 -- /bin/sh -c echo '1657292029208869963' > '/testpd1/tracker0''
Mar 30 14:54:37.412: INFO: Wrote value: "1657292029208869963" to PD "e2e-ee22c20d-f6c1-11e5-94b8-7446a0c373f1" from pod "pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1" container "mycontainer4"
STEP: reading a file in the container
Mar 30 14:54:37.412: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-ybxub pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 -c=mycontainer4 -- cat /testpd1/tracker0'
Mar 30 14:54:38.518: INFO: Read file "/testpd1/tracker0" with content: 1657292029208869963
STEP: deleting host0Pod
Mar 30 14:54:38.576: INFO: PD Read/Writer Iteration #1
STEP: submitting host0Pod to kubernetes
Mar 30 14:54:38.625: INFO: Waiting up to 15m0s for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 status to be running
Mar 30 14:54:38.666: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (41.025399ms elapsed)
Mar 30 14:54:40.709: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (2.083819605s elapsed)
Mar 30 14:54:42.750: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (4.125204883s elapsed)
Mar 30 14:54:44.792: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (6.167136602s elapsed)
Mar 30 14:54:46.834: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (8.208918828s elapsed)
Mar 30 14:54:48.882: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (10.257283486s elapsed)
Mar 30 14:54:50.924: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (12.298598808s elapsed)
Mar 30 14:54:52.965: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (14.34018957s elapsed)
Mar 30 14:54:55.007: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (16.381948071s elapsed)
Mar 30 14:54:57.049: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (18.423486404s elapsed)
Mar 30 14:54:59.090: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (20.46506075s elapsed)
Mar 30 14:55:01.132: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (22.506807535s elapsed)
Mar 30 14:55:03.173: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (24.548121649s elapsed)
Mar 30 14:55:05.215: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (26.589942054s elapsed)
Mar 30 14:55:07.257: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (28.631743503s elapsed)
Mar 30 14:55:09.298: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (30.673255896s elapsed)
Mar 30 14:55:11.340: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (32.714854305s elapsed)
Mar 30 14:55:13.381: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (34.75584438s elapsed)
Mar 30 14:55:15.423: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (36.797566841s elapsed)
Mar 30 14:55:17.463: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (38.838205127s elapsed)
Mar 30 14:55:19.505: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (40.879860765s elapsed)
Mar 30 14:55:21.547: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (42.921420116s elapsed)
Mar 30 14:55:23.588: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (44.96282771s elapsed)
Mar 30 14:55:25.629: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (47.004071867s elapsed)
Mar 30 14:55:27.671: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (49.045554115s elapsed)
Mar 30 14:55:29.712: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (51.087277122s elapsed)
Mar 30 14:55:31.754: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (53.128399321s elapsed)
Mar 30 14:55:33.795: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (55.169814416s elapsed)
Mar 30 14:55:35.837: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (57.211438579s elapsed)
Mar 30 14:55:37.878: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (59.25311637s elapsed)
Mar 30 14:55:39.920: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m1.294932355s elapsed)
Mar 30 14:55:41.962: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m3.337079109s elapsed)
Mar 30 14:55:44.004: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m5.379360726s elapsed)
Mar 30 14:55:46.047: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m7.421589669s elapsed)
Mar 30 14:55:48.089: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m9.463654861s elapsed)
Mar 30 14:55:50.132: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m11.50666203s elapsed)
Mar 30 14:55:52.173: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m13.54785625s elapsed)
Mar 30 14:55:54.214: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m15.589299371s elapsed)
Mar 30 14:55:56.255: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m17.630362418s elapsed)
Mar 30 14:55:58.297: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m19.671961811s elapsed)
Mar 30 14:56:00.338: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m21.7132302s elapsed)
Mar 30 14:56:02.380: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m23.754635501s elapsed)
Mar 30 14:56:04.421: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m25.796293897s elapsed)
Mar 30 14:56:06.463: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m27.837605061s elapsed)
Mar 30 14:56:08.504: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m29.878918226s elapsed)
Mar 30 14:56:10.551: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m31.925666319s elapsed)
Mar 30 14:56:12.591: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m33.966003303s elapsed)
Mar 30 14:56:14.631: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m36.00610653s elapsed)
Mar 30 14:56:16.674: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m38.049151204s elapsed)
Mar 30 14:56:18.716: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m40.090724509s elapsed)
Mar 30 14:56:20.759: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m42.133418786s elapsed)
Mar 30 14:56:22.801: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m44.17603366s elapsed)
Mar 30 14:56:24.853: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m46.227631489s elapsed)
Mar 30 14:56:26.895: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m48.269749293s elapsed)
Mar 30 14:56:28.938: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m50.313161916s elapsed)
Mar 30 14:56:30.980: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m52.355205771s elapsed)
Mar 30 14:56:33.023: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m54.397969459s elapsed)
Mar 30 14:56:35.066: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m56.441329214s elapsed)
Mar 30 14:56:37.110: INFO: Found pod 'pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1' on node 'e2e-test-stclair-minion-gxqg'
STEP: reading a file in the container
Mar 30 14:56:37.110: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-ybxub pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 -c=mycontainer1 -- cat /testpd1/tracker0'
Mar 30 14:56:38.240: INFO: Read file "/testpd1/tracker0" with content: 1657292029208869963
STEP: writing a file in the container
Mar 30 14:56:38.240: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-ybxub pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 -c=mycontainer2 -- /bin/sh -c echo '7796324381578226112' > '/testpd1/tracker1''
Mar 30 14:56:39.386: INFO: Wrote value: "7796324381578226112" to PD "e2e-ee22c20d-f6c1-11e5-94b8-7446a0c373f1" from pod "pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1" container "mycontainer2"
STEP: reading a file in the container
Mar 30 14:56:39.386: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-ybxub pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 -c=mycontainer3 -- cat /testpd1/tracker0'
Mar 30 14:56:40.506: INFO: Read file "/testpd1/tracker0" with content: 1657292029208869963
STEP: reading a file in the container
Mar 30 14:56:40.506: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-ybxub pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 -c=mycontainer3 -- cat /testpd1/tracker1'
Mar 30 14:56:41.628: INFO: Read file "/testpd1/tracker1" with content: 7796324381578226112
STEP: deleting host0Pod
Mar 30 14:56:41.716: INFO: PD Read/Writer Iteration #2
STEP: submitting host0Pod to kubernetes
Mar 30 14:56:41.779: INFO: Waiting up to 15m0s for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 status to be running
Mar 30 14:56:41.822: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (42.895598ms elapsed)
Mar 30 14:56:43.865: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (2.085345114s elapsed)
Mar 30 14:56:45.907: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (4.127416717s elapsed)
Mar 30 14:56:47.949: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (6.169394615s elapsed)
Mar 30 14:56:49.991: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (8.211841661s elapsed)
Mar 30 14:56:52.034: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (10.254847127s elapsed)
Mar 30 14:56:54.076: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (12.296995562s elapsed)
Mar 30 14:56:56.118: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (14.339065829s elapsed)
Mar 30 14:56:58.160: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (16.380672658s elapsed)
Mar 30 14:57:00.202: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (18.422727065s elapsed)
Mar 30 14:57:02.245: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (20.465419105s elapsed)
Mar 30 14:57:04.287: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (22.507753888s elapsed)
Mar 30 14:57:06.328: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (24.549034015s elapsed)
Mar 30 14:57:08.747: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (26.967803483s elapsed)
Mar 30 14:57:10.788: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (29.009147868s elapsed)
Mar 30 14:57:12.834: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (31.054356966s elapsed)
Mar 30 14:57:14.876: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (33.097020772s elapsed)
Mar 30 14:57:16.918: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (35.139220284s elapsed)
Mar 30 14:57:18.960: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (37.181053032s elapsed)
Mar 30 14:57:21.002: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (39.222514051s elapsed)
Mar 30 14:57:23.043: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (41.264159668s elapsed)
Mar 30 14:57:25.085: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (43.305794388s elapsed)
Mar 30 14:57:27.127: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (45.347419618s elapsed)
Mar 30 14:57:29.168: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (47.389192709s elapsed)
Mar 30 14:57:31.215: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (49.435611502s elapsed)
Mar 30 14:57:33.256: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (51.476892427s elapsed)
Mar 30 14:57:35.298: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (53.518298141s elapsed)
Mar 30 14:57:37.339: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (55.559877098s elapsed)
Mar 30 14:57:39.380: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (57.601061742s elapsed)
Mar 30 14:57:41.422: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (59.643125656s elapsed)
Mar 30 14:57:43.464: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m1.684527887s elapsed)
Mar 30 14:57:45.505: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m3.72583304s elapsed)
Mar 30 14:57:47.547: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m5.76724868s elapsed)
Mar 30 14:57:49.588: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m7.808623916s elapsed)
Mar 30 14:57:51.629: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m9.850029827s elapsed)
Mar 30 14:57:53.671: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m11.891658963s elapsed)
Mar 30 14:57:55.712: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m13.932805361s elapsed)
Mar 30 14:57:57.754: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m15.974290262s elapsed)
Mar 30 14:57:59.795: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m18.015980215s elapsed)
Mar 30 14:58:01.837: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m20.057433901s elapsed)
Mar 30 14:58:03.878: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m22.098649005s elapsed)
Mar 30 14:58:05.919: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m24.139700354s elapsed)
Mar 30 14:58:07.960: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m26.181046918s elapsed)
Mar 30 14:58:10.002: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m28.222775503s elapsed)
Mar 30 14:58:12.045: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m30.265650831s elapsed)
Mar 30 14:58:14.087: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m32.307368767s elapsed)
Mar 30 14:58:16.128: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m34.348649113s elapsed)
Mar 30 14:58:18.170: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m36.390537441s elapsed)
Mar 30 14:58:20.211: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m38.432053488s elapsed)
Mar 30 14:58:22.252: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m40.473165634s elapsed)
Mar 30 14:58:24.294: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m42.514760795s elapsed)
Mar 30 14:58:26.335: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m44.555883813s elapsed)
Mar 30 14:58:28.377: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m46.597600225s elapsed)
Mar 30 14:58:30.418: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m48.63920398s elapsed)
Mar 30 14:58:32.460: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m50.680502655s elapsed)
Mar 30 14:58:34.501: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m52.721996972s elapsed)
Mar 30 14:58:36.543: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m54.763818915s elapsed)
Mar 30 14:58:38.585: INFO: Waiting for pod pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-ybxub' status to be 'running'(found phase: "Pending", readiness: false) (1m56.805518982s elapsed)
Mar 30 14:58:40.626: INFO: Found pod 'pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1' on node 'e2e-test-stclair-minion-gxqg'
STEP: reading a file in the container
Mar 30 14:58:40.626: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-ybxub pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 -c=mycontainer4 -- cat /testpd1/tracker0'
Mar 30 14:58:41.756: INFO: Read file "/testpd1/tracker0" with content: 1657292029208869963
STEP: reading a file in the container
Mar 30 14:58:41.756: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-ybxub pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 -c=mycontainer4 -- cat /testpd1/tracker1'
Mar 30 14:58:42.863: INFO: Read file "/testpd1/tracker1" with content: 7796324381578226112
STEP: writing a file in the container
Mar 30 14:58:42.864: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-ybxub pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 -c=mycontainer1 -- /bin/sh -c echo '5571661102688640924' > '/testpd1/tracker2''
Mar 30 14:58:43.975: INFO: Wrote value: "5571661102688640924" to PD "e2e-ee22c20d-f6c1-11e5-94b8-7446a0c373f1" from pod "pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1" container "mycontainer1"
STEP: reading a file in the container
Mar 30 14:58:43.975: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-ybxub pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 -c=mycontainer3 -- cat /testpd1/tracker2'
Mar 30 14:58:45.137: INFO: Read file "/testpd1/tracker2" with content: 5571661102688640924
STEP: reading a file in the container
Mar 30 14:58:45.137: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-ybxub pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 -c=mycontainer3 -- cat /testpd1/tracker0'
Mar 30 14:58:46.250: INFO: Read file "/testpd1/tracker0" with content: 1657292029208869963
STEP: reading a file in the container
Mar 30 14:58:46.250: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-ybxub pd-test-f21cb1e2-f6c1-11e5-94b8-7446a0c373f1 -c=mycontainer3 -- cat /testpd1/tracker1'
Mar 30 14:58:47.354: INFO: Read file "/testpd1/tracker1" with content: 7796324381578226112
STEP: deleting host0Pod
STEP: cleaning up PD-RW test environment
STEP: Waiting for PD "e2e-ee22c20d-f6c1-11e5-94b8-7446a0c373f1" to detach from "e2e-test-stclair-minion-gxqg"
Mar 30 14:58:57.704: INFO: GCE PD "e2e-ee22c20d-f6c1-11e5-94b8-7446a0c373f1" appears to have successfully detached from "e2e-test-stclair-minion-gxqg".
STEP: Deleting PD "e2e-ee22c20d-f6c1-11e5-94b8-7446a0c373f1"
Mar 30 14:59:01.712: INFO: Successfully deleted PD "e2e-ee22c20d-f6c1-11e5-94b8-7446a0c373f1".
[AfterEach] [k8s.io] Pod Disks
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:102
Mar 30 14:59:01.712: INFO: Waiting up to 1m0s for all nodes to be ready
STEP: Destroying namespace "e2e-tests-pod-disks-ybxub" for this suite.
• [SLOW TEST:298.220 seconds]
[k8s.io] Pod Disks
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:420
should schedule a pod w/ a RW PD shared between multiple containers, write to PD, delete pod, verify contents, and repeat in rapid succession [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pd.go:216
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[k8s.io] Pod Disks
should schedule a pod w/ a readonly PD on two hosts, then remove both. [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pd.go:168
[BeforeEach] [k8s.io] Pod Disks
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:101
STEP: Creating a kubernetes client
Mar 30 14:59:06.951: INFO: >>> testContext.KubeConfig: /usr/local/google/home/stclair/.kube/config
STEP: Building a namespace api object
Mar 30 14:59:07.001: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-pod-disks-wdu0d
Mar 30 14:59:07.040: INFO: Service account default in ns e2e-tests-pod-disks-wdu0d with secrets found. (38.732889ms)
STEP: Waiting for a default service account to be provisioned in namespace
Mar 30 14:59:07.040: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-pod-disks-wdu0d
Mar 30 14:59:07.079: INFO: Service account default in ns e2e-tests-pod-disks-wdu0d with secrets found. (38.643103ms)
[BeforeEach] [k8s.io] Pod Disks
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pd.go:68
[It] should schedule a pod w/ a readonly PD on two hosts, then remove both. [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pd.go:168
STEP: creating PD
Mar 30 14:59:13.765: INFO: Successfully created a new PD: "e2e-9fe376ea-f6c2-11e5-94b8-7446a0c373f1".
STEP: submitting rwPod to ensure PD is formatted
Mar 30 14:59:13.813: INFO: Waiting up to 15m0s for pod pd-test-a3d8e6eb-f6c2-11e5-94b8-7446a0c373f1 status to be running
Mar 30 14:59:13.852: INFO: Waiting for pod pd-test-a3d8e6eb-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (39.278696ms elapsed)
Mar 30 14:59:15.891: INFO: Waiting for pod pd-test-a3d8e6eb-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (2.078670066s elapsed)
Mar 30 14:59:17.932: INFO: Waiting for pod pd-test-a3d8e6eb-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (4.119034672s elapsed)
Mar 30 14:59:19.980: INFO: Waiting for pod pd-test-a3d8e6eb-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (6.167050093s elapsed)
Mar 30 14:59:22.024: INFO: Waiting for pod pd-test-a3d8e6eb-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (8.211366371s elapsed)
Mar 30 14:59:24.065: INFO: Waiting for pod pd-test-a3d8e6eb-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (10.251951632s elapsed)
Mar 30 14:59:26.106: INFO: Waiting for pod pd-test-a3d8e6eb-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (12.293226463s elapsed)
Mar 30 14:59:28.146: INFO: Waiting for pod pd-test-a3d8e6eb-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (14.333671088s elapsed)
Mar 30 14:59:30.187: INFO: Found pod 'pd-test-a3d8e6eb-f6c2-11e5-94b8-7446a0c373f1' on node 'e2e-test-stclair-minion-gxqg'
Mar 30 14:59:30.456: INFO: Waiting for GCE PD "e2e-9fe376ea-f6c2-11e5-94b8-7446a0c373f1" to detach from "e2e-test-stclair-minion-gxqg".
Mar 30 14:59:40.744: INFO: GCE PD "e2e-9fe376ea-f6c2-11e5-94b8-7446a0c373f1" appears to have successfully detached from "e2e-test-stclair-minion-gxqg".
STEP: submitting host0ROPod to kubernetes
STEP: submitting host1ROPod to kubernetes
Mar 30 14:59:40.837: INFO: Waiting up to 15m0s for pod pd-test-a3d8e75b-f6c2-11e5-94b8-7446a0c373f1 status to be running
Mar 30 14:59:40.877: INFO: Waiting for pod pd-test-a3d8e75b-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (39.86281ms elapsed)
Mar 30 14:59:42.922: INFO: Waiting for pod pd-test-a3d8e75b-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (2.08436056s elapsed)
Mar 30 14:59:44.962: INFO: Waiting for pod pd-test-a3d8e75b-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (4.124942496s elapsed)
Mar 30 14:59:47.007: INFO: Waiting for pod pd-test-a3d8e75b-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (6.169747067s elapsed)
Mar 30 14:59:49.048: INFO: Waiting for pod pd-test-a3d8e75b-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (8.210391023s elapsed)
Mar 30 14:59:51.104: INFO: Waiting for pod pd-test-a3d8e75b-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (10.266842006s elapsed)
Mar 30 14:59:53.145: INFO: Waiting for pod pd-test-a3d8e75b-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (12.30732875s elapsed)
Mar 30 14:59:55.185: INFO: Waiting for pod pd-test-a3d8e75b-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (14.347816325s elapsed)
Mar 30 14:59:57.225: INFO: Waiting for pod pd-test-a3d8e75b-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (16.387985636s elapsed)
Mar 30 14:59:59.266: INFO: Waiting for pod pd-test-a3d8e75b-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (18.428225408s elapsed)
Mar 30 15:00:01.312: INFO: Waiting for pod pd-test-a3d8e75b-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (20.474463677s elapsed)
Mar 30 15:00:03.352: INFO: Found pod 'pd-test-a3d8e75b-f6c2-11e5-94b8-7446a0c373f1' on node 'e2e-test-stclair-minion-gxqg'
Mar 30 15:00:03.352: INFO: Waiting up to 15m0s for pod pd-test-a3d8e79a-f6c2-11e5-94b8-7446a0c373f1 status to be running
Mar 30 15:00:03.392: INFO: Waiting for pod pd-test-a3d8e79a-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-wdu0d' status to be 'running'(found phase: "Pending", readiness: false) (39.968624ms elapsed)
Mar 30 15:00:05.432: INFO: Found pod 'pd-test-a3d8e79a-f6c2-11e5-94b8-7446a0c373f1' on node 'e2e-test-stclair-minion-gyh0'
STEP: deleting host0ROPod
STEP: deleting host1ROPod
STEP: cleaning up PD-RO test environment
STEP: Waiting for PD "e2e-9fe376ea-f6c2-11e5-94b8-7446a0c373f1" to detach from "e2e-test-stclair-minion-gxqg"
Mar 30 15:00:15.792: INFO: GCE PD "e2e-9fe376ea-f6c2-11e5-94b8-7446a0c373f1" appears to have successfully detached from "e2e-test-stclair-minion-gxqg".
E0330 15:00:19.617753 9913 gce.go:415] GCE operation failed: googleapi: Error 400: Invalid value for field 'disk': 'e2e-9fe376ea-f6c2-11e5-94b8-7446a0c373f1'.
STEP: Waiting for PD "e2e-9fe376ea-f6c2-11e5-94b8-7446a0c373f1" to detach from "e2e-test-stclair-minion-gyh0"
Mar 30 15:00:19.798: INFO: GCE PD "e2e-9fe376ea-f6c2-11e5-94b8-7446a0c373f1" appears to have successfully detached from "e2e-test-stclair-minion-gyh0".
STEP: Deleting PD "e2e-9fe376ea-f6c2-11e5-94b8-7446a0c373f1"
Mar 30 15:00:23.620: INFO: Successfully deleted PD "e2e-9fe376ea-f6c2-11e5-94b8-7446a0c373f1".
[AfterEach] [k8s.io] Pod Disks
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:102
Mar 30 15:00:23.621: INFO: Waiting up to 1m0s for all nodes to be ready
STEP: Destroying namespace "e2e-tests-pod-disks-wdu0d" for this suite.
• [SLOW TEST:81.913 seconds]
[k8s.io] Pod Disks
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:420
should schedule a pod w/ a readonly PD on two hosts, then remove both. [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pd.go:168
------------------------------
SSSSSSSSSSSS
------------------------------
[k8s.io] Pod Disks
should schedule a pod w/ a RW PD, remove it, then schedule it on another host [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pd.go:121
[BeforeEach] [k8s.io] Pod Disks
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:101
STEP: Creating a kubernetes client
Mar 30 15:00:28.865: INFO: >>> testContext.KubeConfig: /usr/local/google/home/stclair/.kube/config
STEP: Building a namespace api object
Mar 30 15:00:28.913: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-pod-disks-aoyn1
Mar 30 15:00:28.951: INFO: Service account default in ns e2e-tests-pod-disks-aoyn1 with secrets found. (38.702838ms)
STEP: Waiting for a default service account to be provisioned in namespace
Mar 30 15:00:28.952: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-pod-disks-aoyn1
Mar 30 15:00:28.990: INFO: Service account default in ns e2e-tests-pod-disks-aoyn1 with secrets found. (38.767907ms)
[BeforeEach] [k8s.io] Pod Disks
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pd.go:68
[It] should schedule a pod w/ a RW PD, remove it, then schedule it on another host [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pd.go:121
STEP: creating PD
Mar 30 15:00:35.648: INFO: Successfully created a new PD: "e2e-d0b617f3-f6c2-11e5-94b8-7446a0c373f1".
STEP: submitting host0Pod to kubernetes
Mar 30 15:00:35.694: INFO: Waiting up to 15m0s for pod pd-test-d4a74386-f6c2-11e5-94b8-7446a0c373f1 status to be running
Mar 30 15:00:35.735: INFO: Waiting for pod pd-test-d4a74386-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (40.038768ms elapsed)
Mar 30 15:00:37.775: INFO: Waiting for pod pd-test-d4a74386-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (2.08058332s elapsed)
Mar 30 15:00:39.816: INFO: Waiting for pod pd-test-d4a74386-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (4.121261539s elapsed)
Mar 30 15:00:41.856: INFO: Waiting for pod pd-test-d4a74386-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (6.161582956s elapsed)
Mar 30 15:00:43.897: INFO: Waiting for pod pd-test-d4a74386-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (8.202309197s elapsed)
Mar 30 15:00:45.944: INFO: Waiting for pod pd-test-d4a74386-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (10.249155806s elapsed)
Mar 30 15:00:47.984: INFO: Waiting for pod pd-test-d4a74386-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (12.289458294s elapsed)
Mar 30 15:00:50.024: INFO: Found pod 'pd-test-d4a74386-f6c2-11e5-94b8-7446a0c373f1' on node 'e2e-test-stclair-minion-gxqg'
STEP: writing a file in the container
Mar 30 15:00:50.024: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-aoyn1 pd-test-d4a74386-f6c2-11e5-94b8-7446a0c373f1 -c=mycontainer -- /bin/sh -c echo '7977550629552150877' > '/testpd1/tracker''
Mar 30 15:00:51.132: INFO: Wrote value: 7977550629552150877
STEP: deleting host0Pod
STEP: submitting host1Pod to kubernetes
Mar 30 15:00:51.248: INFO: Waiting up to 15m0s for pod pd-test-d4a7447d-f6c2-11e5-94b8-7446a0c373f1 status to be running
Mar 30 15:00:51.288: INFO: Waiting for pod pd-test-d4a7447d-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (40.031778ms elapsed)
Mar 30 15:00:53.328: INFO: Waiting for pod pd-test-d4a7447d-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (2.080377116s elapsed)
Mar 30 15:00:55.369: INFO: Waiting for pod pd-test-d4a7447d-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (4.120881049s elapsed)
Mar 30 15:00:57.409: INFO: Waiting for pod pd-test-d4a7447d-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (6.16096268s elapsed)
Mar 30 15:00:59.449: INFO: Waiting for pod pd-test-d4a7447d-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (8.201371452s elapsed)
Mar 30 15:01:01.490: INFO: Waiting for pod pd-test-d4a7447d-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (10.241718951s elapsed)
Mar 30 15:01:03.530: INFO: Waiting for pod pd-test-d4a7447d-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (12.28223544s elapsed)
Mar 30 15:01:05.571: INFO: Waiting for pod pd-test-d4a7447d-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (14.322807181s elapsed)
Mar 30 15:01:07.612: INFO: Waiting for pod pd-test-d4a7447d-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (16.363858535s elapsed)
Mar 30 15:01:09.699: INFO: Waiting for pod pd-test-d4a7447d-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (18.450938472s elapsed)
Mar 30 15:01:11.739: INFO: Waiting for pod pd-test-d4a7447d-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (20.491330172s elapsed)
Mar 30 15:01:13.780: INFO: Waiting for pod pd-test-d4a7447d-f6c2-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-aoyn1' status to be 'running'(found phase: "Pending", readiness: false) (22.532098962s elapsed)
Mar 30 15:01:15.821: INFO: Found pod 'pd-test-d4a7447d-f6c2-11e5-94b8-7446a0c373f1' on node 'e2e-test-stclair-minion-gyh0'
STEP: reading a file in the container
Mar 30 15:01:15.821: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-aoyn1 pd-test-d4a7447d-f6c2-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd1/tracker'
Mar 30 15:01:17.139: INFO: Read value: 7977550629552150877
STEP: deleting host1Pod
STEP: cleaning up PD-RW test environment
E0330 15:01:21.212446 9913 gce.go:415] GCE operation failed: googleapi: Error 400: Invalid value for field 'disk': 'e2e-d0b617f3-f6c2-11e5-94b8-7446a0c373f1'.
STEP: Waiting for PD "e2e-d0b617f3-f6c2-11e5-94b8-7446a0c373f1" to detach from "e2e-test-stclair-minion-gxqg"
Mar 30 15:01:21.365: INFO: GCE PD "e2e-d0b617f3-f6c2-11e5-94b8-7446a0c373f1" appears to have successfully detached from "e2e-test-stclair-minion-gxqg".
E0330 15:01:25.366102 9913 gce.go:415] GCE operation failed: googleapi: Error 400: Invalid value for field 'disk': 'e2e-d0b617f3-f6c2-11e5-94b8-7446a0c373f1'.
STEP: Waiting for PD "e2e-d0b617f3-f6c2-11e5-94b8-7446a0c373f1" to detach from "e2e-test-stclair-minion-gyh0"
Mar 30 15:01:25.549: INFO: GCE PD "e2e-d0b617f3-f6c2-11e5-94b8-7446a0c373f1" appears to have successfully detached from "e2e-test-stclair-minion-gyh0".
STEP: Deleting PD "e2e-d0b617f3-f6c2-11e5-94b8-7446a0c373f1"
Mar 30 15:01:25.888: INFO: Error deleting PD "e2e-d0b617f3-f6c2-11e5-94b8-7446a0c373f1": googleapi: Error 400: The disk resource 'e2e-d0b617f3-f6c2-11e5-94b8-7446a0c373f1' is already being used by 'e2e-test-stclair-minion-gyh0', resourceInUseByAnotherResource
Mar 30 15:01:25.888: INFO: Couldn't delete PD "e2e-d0b617f3-f6c2-11e5-94b8-7446a0c373f1". Sleeping 5 seconds (googleapi: Error 400: The disk resource 'e2e-d0b617f3-f6c2-11e5-94b8-7446a0c373f1' is already being used by 'e2e-test-stclair-minion-gyh0', resourceInUseByAnotherResource)
Mar 30 15:01:34.726: INFO: Successfully deleted PD "e2e-d0b617f3-f6c2-11e5-94b8-7446a0c373f1".
[AfterEach] [k8s.io] Pod Disks
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:102
Mar 30 15:01:34.726: INFO: Waiting up to 1m0s for all nodes to be ready
STEP: Destroying namespace "e2e-tests-pod-disks-aoyn1" for this suite.
• [SLOW TEST:71.105 seconds]
[k8s.io] Pod Disks
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:420
should schedule a pod w/ a RW PD, remove it, then schedule it on another host [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pd.go:121
------------------------------
[k8s.io] Pods
should have their auto-restart back-off timer reset on image update [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pods.go:1004
[BeforeEach] [k8s.io] Pods
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:101
STEP: Creating a kubernetes client
Mar 30 15:01:39.970: INFO: >>> testContext.KubeConfig: /usr/local/google/home/stclair/.kube/config
STEP: Building a namespace api object
Mar 30 15:01:40.019: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-pods-vzskk
Mar 30 15:01:40.058: INFO: Service account default in ns e2e-tests-pods-vzskk with secrets found. (38.997051ms)
STEP: Waiting for a default service account to be provisioned in namespace
Mar 30 15:01:40.058: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-pods-vzskk
Mar 30 15:01:40.097: INFO: Service account default in ns e2e-tests-pods-vzskk with secrets found. (39.170263ms)
[It] should have their auto-restart back-off timer reset on image update [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pods.go:1004
STEP: submitting the pod to kubernetes
Mar 30 15:01:40.144: INFO: Waiting up to 5m0s for pod pod-back-off-image status to be running
Mar 30 15:01:40.183: INFO: Waiting for pod pod-back-off-image in namespace 'e2e-tests-pods-vzskk' status to be 'running'(found phase: "Pending", readiness: false) (38.419317ms elapsed)
Mar 30 15:01:42.223: INFO: Found pod 'pod-back-off-image' on node 'e2e-test-stclair-minion-gyh0'
STEP: verifying the pod is in kubernetes
STEP: getting restart delay-0
Mar 30 15:03:33.259: INFO: getRestartDelay: restartCount = 4, finishedAt=2016-03-30 15:02:40 -0700 PDT restartedAt=2016-03-30 15:03:31 -0700 PDT (51s)
STEP: getting restart delay-1
Mar 30 15:05:05.941: INFO: getRestartDelay: restartCount = 5, finishedAt=2016-03-30 15:03:36 -0700 PDT restartedAt=2016-03-30 15:05:04 -0700 PDT (1m28s)
STEP: getting restart delay-2
Mar 30 15:08:01.144: INFO: getRestartDelay: restartCount = 6, finishedAt=2016-03-30 15:05:09 -0700 PDT restartedAt=2016-03-30 15:08:00 -0700 PDT (2m51s)
STEP: updating the image
Mar 30 15:08:11.236: INFO: Waiting up to 5m0s for pod pod-back-off-image status to be running
Mar 30 15:08:11.277: INFO: Found pod 'pod-back-off-image' on node 'e2e-test-stclair-minion-gyh0'
STEP: get restart delay after image update
Mar 30 15:08:32.096: INFO: getRestartDelay: restartCount = 8, finishedAt=2016-03-30 15:08:16 -0700 PDT restartedAt=2016-03-30 15:08:30 -0700 PDT (14s)
STEP: deleting the pod
[AfterEach] [k8s.io] Pods
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:102
Mar 30 15:08:32.147: INFO: Waiting up to 1m0s for all nodes to be ready
STEP: Destroying namespace "e2e-tests-pods-vzskk" for this suite.
• [SLOW TEST:417.418 seconds]
[k8s.io] Pods
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:420
should have their auto-restart back-off timer reset on image update [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pods.go:1004
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[k8s.io] KubeProxy
should test kube-proxy [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/kubeproxy.go:106
[BeforeEach] [k8s.io] KubeProxy
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:101
STEP: Creating a kubernetes client
Mar 30 15:08:37.389: INFO: >>> testContext.KubeConfig: /usr/local/google/home/stclair/.kube/config
STEP: Building a namespace api object
Mar 30 15:08:37.436: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-e2e-kubeproxy-pwksq
Mar 30 15:08:37.474: INFO: Service account default in ns e2e-tests-e2e-kubeproxy-pwksq with secrets found. (38.271762ms)
STEP: Waiting for a default service account to be provisioned in namespace
Mar 30 15:08:37.474: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-e2e-kubeproxy-pwksq
Mar 30 15:08:37.513: INFO: Service account default in ns e2e-tests-e2e-kubeproxy-pwksq with secrets found. (38.568955ms)
[It] should test kube-proxy [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/kubeproxy.go:106
STEP: cleaning up any pre-existing namespaces used by this test
STEP: Setting up for the tests
STEP: creating a selector
STEP: Getting node addresses
STEP: Creating the service pods in kubernetes
Mar 30 15:08:37.777: INFO: Waiting up to 5m0s for pod netserver-0 status to be running
Mar 30 15:08:37.816: INFO: Waiting for pod netserver-0 in namespace 'e2e-tests-e2e-kubeproxy-pwksq' status to be 'running'(found phase: "Pending", readiness: false) (39.189298ms elapsed)
Mar 30 15:08:39.855: INFO: Waiting for pod netserver-0 in namespace 'e2e-tests-e2e-kubeproxy-pwksq' status to be 'running'(found phase: "Pending", readiness: false) (2.07857836s elapsed)
Mar 30 15:08:41.896: INFO: Found pod 'netserver-0' on node 'e2e-test-stclair-minion-gxqg'
Mar 30 15:08:41.936: INFO: Waiting up to 5m0s for pod netserver-1 status to be running
Mar 30 15:08:41.976: INFO: Found pod 'netserver-1' on node 'e2e-test-stclair-minion-gyh0'
Mar 30 15:08:42.016: INFO: Waiting up to 5m0s for pod netserver-2 status to be running
Mar 30 15:08:42.055: INFO: Found pod 'netserver-2' on node 'e2e-test-stclair-minion-iunw'
STEP: Creating the service on top of the pods in kubernetes
Mar 30 15:08:42.184: INFO: Service node-port-service in namespace e2e-tests-e2e-kubeproxy-pwksq found.
STEP: Creating test pods
Mar 30 15:08:42.313: INFO: Waiting up to 5m0s for pod test-container-pod status to be running
Mar 30 15:08:42.357: INFO: Waiting for pod test-container-pod in namespace 'e2e-tests-e2e-kubeproxy-pwksq' status to be 'running'(found phase: "Pending", readiness: false) (44.730843ms elapsed)
Mar 30 15:08:44.399: INFO: Found pod 'test-container-pod' on node 'e2e-test-stclair-minion-gyh0'
Mar 30 15:08:44.399: INFO: Waiting up to 5m0s for pod host-test-container-pod status to be running
Mar 30 15:08:44.439: INFO: Found pod 'host-test-container-pod' on node 'e2e-test-stclair-minion-gyh0'
STEP: TODO: Need to add hit externalIPs test
STEP: Hit Test with All Endpoints
STEP: Hitting endpoints from host and container
STEP: dialing(udp) endpointPodIP:endpointUdpPort from node1
STEP: Dialing from node. command:for i in $(seq 1 5); do echo 'hostName' | timeout -t 3 nc -w 1 -u 10.245.0.4 8081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:08:44.518: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 5); do echo 'hostName' | timeout -t 3 nc -w 1 -u 10.245.0.4 8081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:08:55.664: INFO: stderr: ""
Mar 30 15:08:55.664: INFO: stdout: 1
STEP: dialing(http) endpointPodIP:endpointHttpPort from node1
STEP: Dialing from node. command:for i in $(seq 1 5); do curl -s --connect-timeout 1 http://10.245.0.4:8080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:08:55.665: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 5); do curl -s --connect-timeout 1 http://10.245.0.4:8080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:09:01.838: INFO: stderr: ""
Mar 30 15:09:01.838: INFO: stdout: 1
STEP: dialing(udp) endpointPodIP:endpointUdpPort from test container
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=10.245.0.4&port=8081&tries=5'
Mar 30 15:09:01.838: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=10.245.0.4&port=8081&tries=5''
Mar 30 15:09:03.001: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 85 100 85 0 0 12002 0 --:--:-- --:--:-- --:--:-- 14166\n"
Mar 30 15:09:03.001: INFO: stdout: {"responses":["netserver-0","netserver-0","netserver-0","netserver-0","netserver-0"]}
STEP: dialing(http) endpointPodIP:endpointHttpPort from test container
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=10.245.0.4&port=8080&tries=5'
Mar 30 15:09:03.002: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=10.245.0.4&port=8080&tries=5''
Mar 30 15:09:04.137: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 85 100 85 0 0 16588 0 --:--:-- --:--:-- --:--:-- 21250\n"
Mar 30 15:09:04.137: INFO: stdout: {"responses":["netserver-0","netserver-0","netserver-0","netserver-0","netserver-0"]}
STEP: dialing(udp) endpointPodIP:endpointUdpPort from node1
STEP: Dialing from node. command:for i in $(seq 1 5); do echo 'hostName' | timeout -t 3 nc -w 1 -u 10.245.3.3 8081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:09:04.137: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 5); do echo 'hostName' | timeout -t 3 nc -w 1 -u 10.245.3.3 8081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:09:15.269: INFO: stderr: ""
Mar 30 15:09:15.269: INFO: stdout: 1
STEP: dialing(http) endpointPodIP:endpointHttpPort from node1
STEP: Dialing from node. command:for i in $(seq 1 5); do curl -s --connect-timeout 1 http://10.245.3.3:8080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:09:15.269: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 5); do curl -s --connect-timeout 1 http://10.245.3.3:8080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:09:21.344: INFO: stderr: ""
Mar 30 15:09:21.344: INFO: stdout: 1
STEP: dialing(udp) endpointPodIP:endpointUdpPort from test container
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=10.245.3.3&port=8081&tries=5'
Mar 30 15:09:21.344: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=10.245.3.3&port=8081&tries=5''
Mar 30 15:09:22.602: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 85 100 85 0 0 45551 0 --:--:-- --:--:-- --:--:-- 85000\n"
Mar 30 15:09:22.602: INFO: stdout: {"responses":["netserver-1","netserver-1","netserver-1","netserver-1","netserver-1"]}
STEP: dialing(http) endpointPodIP:endpointHttpPort from test container
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=10.245.3.3&port=8080&tries=5'
Mar 30 15:09:22.602: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=10.245.3.3&port=8080&tries=5''
Mar 30 15:09:23.723: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 85 100 85 0 0 23889 0 --:--:-- --:--:-- --:--:-- 28333\n"
Mar 30 15:09:23.723: INFO: stdout: {"responses":["netserver-1","netserver-1","netserver-1","netserver-1","netserver-1"]}
STEP: dialing(udp) endpointPodIP:endpointUdpPort from node1
STEP: Dialing from node. command:for i in $(seq 1 5); do echo 'hostName' | timeout -t 3 nc -w 1 -u 10.245.1.4 8081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:09:23.724: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 5); do echo 'hostName' | timeout -t 3 nc -w 1 -u 10.245.1.4 8081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:09:34.866: INFO: stderr: ""
Mar 30 15:09:34.866: INFO: stdout: 1
STEP: dialing(http) endpointPodIP:endpointHttpPort from node1
STEP: Dialing from node. command:for i in $(seq 1 5); do curl -s --connect-timeout 1 http://10.245.1.4:8080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:09:34.866: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 5); do curl -s --connect-timeout 1 http://10.245.1.4:8080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:09:41.037: INFO: stderr: ""
Mar 30 15:09:41.037: INFO: stdout: 1
STEP: dialing(udp) endpointPodIP:endpointUdpPort from test container
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=10.245.1.4&port=8081&tries=5'
Mar 30 15:09:41.037: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=10.245.1.4&port=8081&tries=5''
Mar 30 15:09:42.147: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 85 100 85 0 0 20325 0 --:--:-- --:--:-- --:--:-- 21250\n"
Mar 30 15:09:42.147: INFO: stdout: {"responses":["netserver-2","netserver-2","netserver-2","netserver-2","netserver-2"]}
STEP: dialing(http) endpointPodIP:endpointHttpPort from test container
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=10.245.1.4&port=8080&tries=5'
Mar 30 15:09:42.147: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=10.245.1.4&port=8080&tries=5''
Mar 30 15:09:43.337: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 85 100 85 0 0 11443 0 --:--:-- --:--:-- --:--:-- 12142\n"
Mar 30 15:09:43.338: INFO: stdout: {"responses":["netserver-2","netserver-2","netserver-2","netserver-2","netserver-2"]}
STEP: Hitting clusterIP from host and container
STEP: dialing(udp) node1 --> clusterIP:clusterUdpPort
STEP: Dialing from node. command:for i in $(seq 1 39); do echo 'hostName' | timeout -t 3 nc -w 1 -u 10.0.166.152 90; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:09:43.338: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 39); do echo 'hostName' | timeout -t 3 nc -w 1 -u 10.0.166.152 90; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:11:02.609: INFO: stderr: ""
Mar 30 15:11:02.609: INFO: stdout: 3
STEP: dialing(http) node1 --> clusterIP:clusterHttpPort
STEP: Dialing from node. command:for i in $(seq 1 39); do curl -s --connect-timeout 1 http://10.0.166.152:80/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:11:02.609: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 39); do curl -s --connect-timeout 1 http://10.0.166.152:80/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:11:42.991: INFO: stderr: ""
Mar 30 15:11:42.991: INFO: stdout: 3
STEP: dialing(udp) test container --> clusterIP:clusterUdpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=10.0.166.152&port=90&tries=39'
Mar 30 15:11:42.991: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=10.0.166.152&port=90&tries=39''
Mar 30 15:11:44.090: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 561 100 561 0 0 28551 0 --:--:-- --:--:-- --:--:-- 29526\n"
Mar 30 15:11:44.090: INFO: stdout: {"responses":["netserver-2","netserver-1","netserver-0","netserver-1","netserver-2","netserver-0","netserver-0","netserver-1","netserver-0","netserver-2","netserver-0","netserver-2","netserver-2","netserver-1","netserver-1","netserver-1","netserver-2","netserver-1","netserver-0","netserver-2","netserver-1","netserver-1","netserver-2","netserver-0","netserver-2","netserver-2","netserver-1","netserver-2","netserver-2","netserver-1","netserver-1","netserver-0","netserver-1","netserver-0","netserver-0","netserver-1","netserver-2","netserver-1","netserver-0"]}
STEP: dialing(http) test container --> clusterIP:clusterHttpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=10.0.166.152&port=80&tries=39'
Mar 30 15:11:44.091: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=10.0.166.152&port=80&tries=39''
Mar 30 15:11:45.229: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 561 100 561 0 0 15458 0 --:--:-- --:--:-- --:--:-- 15583\n"
Mar 30 15:11:45.229: INFO: stdout: {"responses":["netserver-1","netserver-1","netserver-0","netserver-0","netserver-2","netserver-0","netserver-2","netserver-0","netserver-1","netserver-2","netserver-1","netserver-2","netserver-2","netserver-2","netserver-2","netserver-0","netserver-1","netserver-2","netserver-0","netserver-1","netserver-1","netserver-0","netserver-0","netserver-2","netserver-1","netserver-2","netserver-1","netserver-1","netserver-1","netserver-0","netserver-1","netserver-2","netserver-0","netserver-2","netserver-2","netserver-2","netserver-0","netserver-2","netserver-0"]}
STEP: dialing(udp) endpoint container --> clusterIP:clusterUdpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.0.4:8080/dial?request=hostName&protocol=udp&host=10.0.166.152&port=90&tries=39'
Mar 30 15:11:45.229: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.0.4:8080/dial?request=hostName&protocol=udp&host=10.0.166.152&port=90&tries=39''
Mar 30 15:11:46.332: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 561 100 561 0 0 30101 0 --:--:-- --:--:-- --:--:-- 31166\n"
Mar 30 15:11:46.332: INFO: stdout: {"responses":["netserver-2","netserver-0","netserver-2","netserver-0","netserver-0","netserver-1","netserver-2","netserver-1","netserver-0","netserver-1","netserver-0","netserver-2","netserver-2","netserver-1","netserver-1","netserver-0","netserver-2","netserver-1","netserver-0","netserver-0","netserver-1","netserver-1","netserver-1","netserver-1","netserver-1","netserver-2","netserver-0","netserver-2","netserver-2","netserver-0","netserver-0","netserver-0","netserver-2","netserver-2","netserver-0","netserver-2","netserver-0","netserver-1","netserver-0"]}
STEP: dialing(http) endpoint container --> clusterIP:clusterHttpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.0.4:8080/dial?request=hostName&protocol=http&host=10.0.166.152&port=80&tries=39'
Mar 30 15:11:46.332: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.0.4:8080/dial?request=hostName&protocol=http&host=10.0.166.152&port=80&tries=39''
Mar 30 15:11:47.439: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 561 100 561 0 0 18579 0 --:--:-- --:--:-- --:--:-- 18700\n"
Mar 30 15:11:47.440: INFO: stdout: {"responses":["netserver-2","netserver-2","netserver-2","netserver-2","netserver-2","netserver-1","netserver-0","netserver-2","netserver-0","netserver-0","netserver-0","netserver-2","netserver-0","netserver-0","netserver-2","netserver-1","netserver-2","netserver-0","netserver-0","netserver-1","netserver-1","netserver-0","netserver-0","netserver-2","netserver-0","netserver-0","netserver-2","netserver-1","netserver-0","netserver-0","netserver-1","netserver-1","netserver-0","netserver-0","netserver-2","netserver-2","netserver-2","netserver-1","netserver-2"]}
STEP: Hitting nodePort from host and container
STEP: dialing(udp) node1 --> node1:nodeUdpPort
STEP: Dialing from node. command:for i in $(seq 1 39); do echo 'hostName' | timeout -t 3 nc -w 1 -u 23.236.56.214 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:11:47.440: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 39); do echo 'hostName' | timeout -t 3 nc -w 1 -u 23.236.56.214 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:13:06.763: INFO: stderr: ""
Mar 30 15:13:06.763: INFO: stdout: 3
STEP: dialing(http) node1 --> node1:nodeHttpPort
STEP: Dialing from node. command:for i in $(seq 1 39); do curl -s --connect-timeout 1 http://23.236.56.214:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:13:06.763: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 39); do curl -s --connect-timeout 1 http://23.236.56.214:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:13:47.147: INFO: stderr: ""
Mar 30 15:13:47.147: INFO: stdout: 3
STEP: dialing(udp) test container --> node1:nodeUdpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=23.236.56.214&port=32081&tries=39'
Mar 30 15:13:47.147: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=23.236.56.214&port=32081&tries=39''
Mar 30 15:13:48.246: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 561 100 561 0 0 10467 0 --:--:-- --:--:-- --:--:-- 10584\n"
Mar 30 15:13:48.247: INFO: stdout: {"responses":["netserver-0","netserver-0","netserver-2","netserver-2","netserver-0","netserver-0","netserver-1","netserver-1","netserver-1","netserver-2","netserver-2","netserver-0","netserver-0","netserver-2","netserver-2","netserver-1","netserver-1","netserver-2","netserver-1","netserver-1","netserver-2","netserver-1","netserver-1","netserver-2","netserver-2","netserver-1","netserver-0","netserver-0","netserver-0","netserver-0","netserver-0","netserver-1","netserver-0","netserver-0","netserver-1","netserver-0","netserver-2","netserver-1","netserver-1"]}
STEP: dialing(http) test container --> node1:nodeHttpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=23.236.56.214&port=32080&tries=39'
Mar 30 15:13:48.247: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=23.236.56.214&port=32080&tries=39''
Mar 30 15:13:49.454: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 561 100 561 0 0 7387 0 --:--:-- --:--:-- --:--:-- 7480\n"
Mar 30 15:13:49.454: INFO: stdout: {"responses":["netserver-0","netserver-0","netserver-1","netserver-2","netserver-1","netserver-2","netserver-0","netserver-2","netserver-2","netserver-2","netserver-0","netserver-1","netserver-2","netserver-1","netserver-1","netserver-1","netserver-0","netserver-2","netserver-0","netserver-2","netserver-0","netserver-1","netserver-1","netserver-2","netserver-2","netserver-0","netserver-2","netserver-1","netserver-0","netserver-0","netserver-1","netserver-2","netserver-2","netserver-2","netserver-1","netserver-0","netserver-1","netserver-1","netserver-2"]}
STEP: dialing(udp) endpoint container --> node1:nodeUdpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.0.4:8080/dial?request=hostName&protocol=udp&host=23.236.56.214&port=32081&tries=39'
Mar 30 15:13:49.454: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.0.4:8080/dial?request=hostName&protocol=udp&host=23.236.56.214&port=32081&tries=39''
Mar 30 15:13:50.595: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 561 100 561 0 0 11415 0 --:--:-- --:--:-- --:--:-- 11448\n"
Mar 30 15:13:50.595: INFO: stdout: {"responses":["netserver-1","netserver-2","netserver-2","netserver-1","netserver-0","netserver-0","netserver-0","netserver-2","netserver-2","netserver-1","netserver-1","netserver-2","netserver-1","netserver-1","netserver-0","netserver-1","netserver-2","netserver-2","netserver-0","netserver-2","netserver-2","netserver-1","netserver-2","netserver-0","netserver-1","netserver-0","netserver-2","netserver-2","netserver-2","netserver-1","netserver-2","netserver-2","netserver-0","netserver-2","netserver-2","netserver-1","netserver-2","netserver-1","netserver-1"]}
STEP: dialing(http) endpoint container --> node1:nodeHttpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.0.4:8080/dial?request=hostName&protocol=http&host=23.236.56.214&port=32080&tries=39'
Mar 30 15:13:50.595: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.0.4:8080/dial?request=hostName&protocol=http&host=23.236.56.214&port=32080&tries=39''
Mar 30 15:13:51.770: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 561 100 561 0 0 7652 0 --:--:-- --:--:-- --:--:-- 7684\n"
Mar 30 15:13:51.770: INFO: stdout: {"responses":["netserver-1","netserver-0","netserver-1","netserver-1","netserver-1","netserver-2","netserver-2","netserver-1","netserver-0","netserver-0","netserver-1","netserver-2","netserver-1","netserver-0","netserver-0","netserver-0","netserver-2","netserver-0","netserver-2","netserver-2","netserver-2","netserver-1","netserver-1","netserver-2","netserver-0","netserver-1","netserver-2","netserver-0","netserver-2","netserver-0","netserver-0","netserver-0","netserver-0","netserver-2","netserver-1","netserver-0","netserver-0","netserver-2","netserver-0"]}
STEP: dialing(udp) node --> 127.0.0.1:nodeUdpPort
STEP: Dialing from node. command:for i in $(seq 1 39); do echo 'hostName' | timeout -t 3 nc -w 1 -u 127.0.0.1 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:13:51.771: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 39); do echo 'hostName' | timeout -t 3 nc -w 1 -u 127.0.0.1 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:15:11.037: INFO: stderr: ""
Mar 30 15:15:11.037: INFO: stdout: 3
STEP: dialing(http) node --> 127.0.0.1:nodeHttpPort
STEP: Dialing from node. command:for i in $(seq 1 39); do curl -s --connect-timeout 1 http://127.0.0.1:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:15:11.037: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 39); do curl -s --connect-timeout 1 http://127.0.0.1:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:15:51.434: INFO: stderr: ""
Mar 30 15:15:51.434: INFO: stdout: 3
STEP: dialing(udp) node1 --> node2:nodeUdpPort
STEP: Dialing from node. command:for i in $(seq 1 39); do echo 'hostName' | timeout -t 3 nc -w 1 -u 104.197.91.190 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:15:51.434: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 39); do echo 'hostName' | timeout -t 3 nc -w 1 -u 104.197.91.190 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:17:10.755: INFO: stderr: ""
Mar 30 15:17:10.755: INFO: stdout: 3
STEP: dialing(http) node1 --> node2:nodeHttpPort
STEP: Dialing from node. command:for i in $(seq 1 39); do curl -s --connect-timeout 1 http://104.197.91.190:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:17:10.755: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 39); do curl -s --connect-timeout 1 http://104.197.91.190:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:17:51.132: INFO: stderr: ""
Mar 30 15:17:51.132: INFO: stdout: 3
STEP: checking kube-proxy URLs
STEP: Getting kube-proxy self URL /healthz
Mar 30 15:17:51.132: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -s --connect-timeout 1 http://localhost:10249/healthz'
Mar 30 15:17:52.237: INFO: stderr: ""
Mar 30 15:17:52.237: INFO: stdout: ok
STEP: Getting kube-proxy self URL /proxyMode
Mar 30 15:17:52.237: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -s --connect-timeout 1 http://localhost:10249/proxyMode'
Mar 30 15:17:53.410: INFO: stderr: ""
Mar 30 15:17:53.410: INFO: stdout: iptables
Mar 30 15:17:53.470: INFO: Waiting for pod netserver-0 to disappear
Mar 30 15:17:53.512: INFO: Pod netserver-0 no longer exists
Mar 30 15:17:54.512: INFO: Waiting for amount of service:node-port-service endpoints to 2
STEP: Hit Test with Fewer Endpoints
STEP: Hitting endpoints from host and container
STEP: dialing(udp) endpointPodIP:endpointUdpPort from node1
STEP: Dialing from node. command:for i in $(seq 1 5); do echo 'hostName' | timeout -t 3 nc -w 1 -u 10.245.3.3 8081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:17:59.552: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 5); do echo 'hostName' | timeout -t 3 nc -w 1 -u 10.245.3.3 8081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:18:10.681: INFO: stderr: ""
Mar 30 15:18:10.681: INFO: stdout: 1
STEP: dialing(http) endpointPodIP:endpointHttpPort from node1
STEP: Dialing from node. command:for i in $(seq 1 5); do curl -s --connect-timeout 1 http://10.245.3.3:8080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:18:10.682: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 5); do curl -s --connect-timeout 1 http://10.245.3.3:8080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:18:16.814: INFO: stderr: ""
Mar 30 15:18:16.814: INFO: stdout: 1
STEP: dialing(udp) endpointPodIP:endpointUdpPort from test container
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=10.245.3.3&port=8081&tries=5'
Mar 30 15:18:16.814: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=10.245.3.3&port=8081&tries=5''
Mar 30 15:18:17.931: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 85 100 85 0 0 44902 0 --:--:-- --:--:-- --:--:-- 85000\n"
Mar 30 15:18:17.931: INFO: stdout: {"responses":["netserver-1","netserver-1","netserver-1","netserver-1","netserver-1"]}
STEP: dialing(http) endpointPodIP:endpointHttpPort from test container
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=10.245.3.3&port=8080&tries=5'
Mar 30 15:18:17.931: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=10.245.3.3&port=8080&tries=5''
Mar 30 15:18:19.033: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 85 100 85 0 0 29442 0 --:--:-- --:--:-- --:--:-- 42500\n"
Mar 30 15:18:19.033: INFO: stdout: {"responses":["netserver-1","netserver-1","netserver-1","netserver-1","netserver-1"]}
STEP: dialing(udp) endpointPodIP:endpointUdpPort from node1
STEP: Dialing from node. command:for i in $(seq 1 5); do echo 'hostName' | timeout -t 3 nc -w 1 -u 10.245.1.4 8081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:18:19.034: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 5); do echo 'hostName' | timeout -t 3 nc -w 1 -u 10.245.1.4 8081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:18:30.151: INFO: stderr: ""
Mar 30 15:18:30.151: INFO: stdout: 1
STEP: dialing(http) endpointPodIP:endpointHttpPort from node1
STEP: Dialing from node. command:for i in $(seq 1 5); do curl -s --connect-timeout 1 http://10.245.1.4:8080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:18:30.152: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 5); do curl -s --connect-timeout 1 http://10.245.1.4:8080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:18:36.288: INFO: stderr: ""
Mar 30 15:18:36.288: INFO: stdout: 1
STEP: dialing(udp) endpointPodIP:endpointUdpPort from test container
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=10.245.1.4&port=8081&tries=5'
Mar 30 15:18:36.289: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=10.245.1.4&port=8081&tries=5''
Mar 30 15:18:37.395: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 85 100 85 0 0 19105 0 --:--:-- --:--:-- --:--:-- 21250\n"
Mar 30 15:18:37.395: INFO: stdout: {"responses":["netserver-2","netserver-2","netserver-2","netserver-2","netserver-2"]}
STEP: dialing(http) endpointPodIP:endpointHttpPort from test container
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=10.245.1.4&port=8080&tries=5'
Mar 30 15:18:37.395: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=10.245.1.4&port=8080&tries=5''
Mar 30 15:18:38.544: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 85 100 85 0 0 14202 0 --:--:-- --:--:-- --:--:-- 17000\n"
Mar 30 15:18:38.545: INFO: stdout: {"responses":["netserver-2","netserver-2","netserver-2","netserver-2","netserver-2"]}
STEP: Hitting clusterIP from host and container
STEP: dialing(udp) node1 --> clusterIP:clusterUdpPort
STEP: Dialing from node. command:for i in $(seq 1 34); do echo 'hostName' | timeout -t 3 nc -w 1 -u 10.0.166.152 90; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:18:38.545: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 34); do echo 'hostName' | timeout -t 3 nc -w 1 -u 10.0.166.152 90; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:19:47.790: INFO: stderr: ""
Mar 30 15:19:47.790: INFO: stdout: 2
STEP: dialing(http) node1 --> clusterIP:clusterHttpPort
STEP: Dialing from node. command:for i in $(seq 1 34); do curl -s --connect-timeout 1 http://10.0.166.152:80/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:19:47.790: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 34); do curl -s --connect-timeout 1 http://10.0.166.152:80/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:20:23.190: INFO: stderr: ""
Mar 30 15:20:23.190: INFO: stdout: 2
STEP: dialing(udp) test container --> clusterIP:clusterUdpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=10.0.166.152&port=90&tries=34'
Mar 30 15:20:23.191: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=10.0.166.152&port=90&tries=34''
Mar 30 15:20:24.323: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 491 100 491 0 0 31671 0 --:--:-- --:--:-- --:--:-- 32733\n"
Mar 30 15:20:24.323: INFO: stdout: {"responses":["netserver-1","netserver-2","netserver-2","netserver-2","netserver-2","netserver-2","netserver-1","netserver-2","netserver-1","netserver-2","netserver-2","netserver-1","netserver-2","netserver-1","netserver-1","netserver-1","netserver-2","netserver-2","netserver-2","netserver-1","netserver-2","netserver-1","netserver-2","netserver-2","netserver-2","netserver-1","netserver-1","netserver-1","netserver-1","netserver-2","netserver-1","netserver-2","netserver-2","netserver-1"]}
STEP: dialing(http) test container --> clusterIP:clusterHttpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=10.0.166.152&port=80&tries=34'
Mar 30 15:20:24.323: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=10.0.166.152&port=80&tries=34''
Mar 30 15:20:25.448: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 491 100 491 0 0 19187 0 --:--:-- --:--:-- --:--:-- 19640\n"
Mar 30 15:20:25.448: INFO: stdout: {"responses":["netserver-1","netserver-1","netserver-2","netserver-1","netserver-1","netserver-2","netserver-2","netserver-2","netserver-1","netserver-1","netserver-1","netserver-2","netserver-2","netserver-2","netserver-1","netserver-2","netserver-1","netserver-1","netserver-2","netserver-1","netserver-1","netserver-1","netserver-2","netserver-1","netserver-1","netserver-1","netserver-2","netserver-2","netserver-1","netserver-2","netserver-2","netserver-2","netserver-2","netserver-2"]}
STEP: dialing(udp) endpoint container --> clusterIP:clusterUdpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.3:8080/dial?request=hostName&protocol=udp&host=10.0.166.152&port=90&tries=34'
Mar 30 15:20:25.449: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.3:8080/dial?request=hostName&protocol=udp&host=10.0.166.152&port=90&tries=34''
Mar 30 15:20:26.622: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 491 100 491 0 0 33401 0 --:--:-- --:--:-- --:--:-- 35071\n"
Mar 30 15:20:26.622: INFO: stdout: {"responses":["netserver-1","netserver-1","netserver-1","netserver-2","netserver-1","netserver-2","netserver-1","netserver-2","netserver-2","netserver-2","netserver-1","netserver-2","netserver-2","netserver-1","netserver-1","netserver-2","netserver-2","netserver-1","netserver-1","netserver-2","netserver-2","netserver-2","netserver-2","netserver-1","netserver-2","netserver-2","netserver-2","netserver-1","netserver-1","netserver-1","netserver-2","netserver-2","netserver-1","netserver-1"]}
STEP: dialing(http) endpoint container --> clusterIP:clusterHttpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.3:8080/dial?request=hostName&protocol=http&host=10.0.166.152&port=80&tries=34'
Mar 30 15:20:26.623: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.3:8080/dial?request=hostName&protocol=http&host=10.0.166.152&port=80&tries=34''
Mar 30 15:20:27.748: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 491 100 491 0 0 21226 0 --:--:-- --:--:-- --:--:-- 22318\n"
Mar 30 15:20:27.748: INFO: stdout: {"responses":["netserver-1","netserver-1","netserver-1","netserver-1","netserver-2","netserver-1","netserver-2","netserver-1","netserver-2","netserver-1","netserver-1","netserver-2","netserver-2","netserver-1","netserver-1","netserver-2","netserver-1","netserver-2","netserver-2","netserver-2","netserver-1","netserver-1","netserver-1","netserver-2","netserver-1","netserver-2","netserver-2","netserver-1","netserver-1","netserver-2","netserver-1","netserver-2","netserver-2","netserver-1"]}
STEP: Hitting nodePort from host and container
STEP: dialing(udp) node1 --> node1:nodeUdpPort
STEP: Dialing from node. command:for i in $(seq 1 34); do echo 'hostName' | timeout -t 3 nc -w 1 -u 23.236.56.214 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:20:27.749: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 34); do echo 'hostName' | timeout -t 3 nc -w 1 -u 23.236.56.214 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:21:37.045: INFO: stderr: ""
Mar 30 15:21:37.045: INFO: stdout: 2
STEP: dialing(http) node1 --> node1:nodeHttpPort
STEP: Dialing from node. command:for i in $(seq 1 34); do curl -s --connect-timeout 1 http://23.236.56.214:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:21:37.045: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 34); do curl -s --connect-timeout 1 http://23.236.56.214:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:22:12.420: INFO: stderr: ""
Mar 30 15:22:12.420: INFO: stdout: 2
STEP: dialing(udp) test container --> node1:nodeUdpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=23.236.56.214&port=32081&tries=34'
Mar 30 15:22:12.420: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=23.236.56.214&port=32081&tries=34''
Mar 30 15:22:13.576: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 491 100 491 0 0 10470 0 --:--:-- --:--:-- --:--:-- 10673\n"
Mar 30 15:22:13.576: INFO: stdout: {"responses":["netserver-2","netserver-2","netserver-1","netserver-2","netserver-1","netserver-2","netserver-1","netserver-2","netserver-2","netserver-1","netserver-2","netserver-1","netserver-2","netserver-1","netserver-2","netserver-1","netserver-1","netserver-1","netserver-1","netserver-2","netserver-1","netserver-2","netserver-2","netserver-2","netserver-2","netserver-1","netserver-2","netserver-2","netserver-1","netserver-1","netserver-2","netserver-1","netserver-1","netserver-1"]}
STEP: dialing(http) test container --> node1:nodeHttpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=23.236.56.214&port=32080&tries=34'
Mar 30 15:22:13.577: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=23.236.56.214&port=32080&tries=34''
Mar 30 15:22:14.729: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 491 100 491 0 0 6821 0 --:--:-- --:--:-- --:--:-- 6915\n"
Mar 30 15:22:14.729: INFO: stdout: {"responses":["netserver-2","netserver-1","netserver-1","netserver-1","netserver-2","netserver-1","netserver-2","netserver-1","netserver-2","netserver-1","netserver-1","netserver-2","netserver-1","netserver-2","netserver-1","netserver-2","netserver-1","netserver-2","netserver-1","netserver-2","netserver-2","netserver-1","netserver-2","netserver-1","netserver-2","netserver-1","netserver-2","netserver-2","netserver-1","netserver-2","netserver-2","netserver-2","netserver-2","netserver-1"]}
STEP: dialing(udp) endpoint container --> node1:nodeUdpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.3:8080/dial?request=hostName&protocol=udp&host=23.236.56.214&port=32081&tries=34'
Mar 30 15:22:14.730: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.3:8080/dial?request=hostName&protocol=udp&host=23.236.56.214&port=32081&tries=34''
Mar 30 15:22:15.821: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 491 100 491 0 0 10400 0 --:--:-- --:--:-- --:--:-- 10446\n"
Mar 30 15:22:15.821: INFO: stdout: {"responses":["netserver-2","netserver-2","netserver-2","netserver-2","netserver-1","netserver-2","netserver-1","netserver-2","netserver-1","netserver-1","netserver-1","netserver-2","netserver-2","netserver-1","netserver-2","netserver-2","netserver-2","netserver-2","netserver-2","netserver-2","netserver-1","netserver-2","netserver-2","netserver-2","netserver-1","netserver-1","netserver-2","netserver-2","netserver-2","netserver-2","netserver-1","netserver-1","netserver-1","netserver-2"]}
STEP: dialing(http) endpoint container --> node1:nodeHttpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.3:8080/dial?request=hostName&protocol=http&host=23.236.56.214&port=32080&tries=34'
Mar 30 15:22:15.821: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.3:8080/dial?request=hostName&protocol=http&host=23.236.56.214&port=32080&tries=34''
Mar 30 15:22:17.041: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 491 100 491 0 0 6776 0 --:--:-- --:--:-- --:--:-- 6819\n"
Mar 30 15:22:17.041: INFO: stdout: {"responses":["netserver-1","netserver-2","netserver-1","netserver-2","netserver-2","netserver-1","netserver-1","netserver-1","netserver-1","netserver-2","netserver-2","netserver-2","netserver-2","netserver-2","netserver-1","netserver-1","netserver-1","netserver-1","netserver-1","netserver-1","netserver-1","netserver-2","netserver-2","netserver-1","netserver-1","netserver-1","netserver-1","netserver-2","netserver-2","netserver-1","netserver-2","netserver-2","netserver-1","netserver-2"]}
STEP: dialing(udp) node --> 127.0.0.1:nodeUdpPort
STEP: Dialing from node. command:for i in $(seq 1 34); do echo 'hostName' | timeout -t 3 nc -w 1 -u 127.0.0.1 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:22:17.041: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 34); do echo 'hostName' | timeout -t 3 nc -w 1 -u 127.0.0.1 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:23:26.316: INFO: stderr: ""
Mar 30 15:23:26.317: INFO: stdout: 2
STEP: dialing(http) node --> 127.0.0.1:nodeHttpPort
STEP: Dialing from node. command:for i in $(seq 1 34); do curl -s --connect-timeout 1 http://127.0.0.1:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:23:26.317: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 34); do curl -s --connect-timeout 1 http://127.0.0.1:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:24:01.628: INFO: stderr: ""
Mar 30 15:24:01.628: INFO: stdout: 2
STEP: dialing(udp) node1 --> node2:nodeUdpPort
STEP: Dialing from node. command:for i in $(seq 1 34); do echo 'hostName' | timeout -t 3 nc -w 1 -u 104.197.91.190 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:24:01.628: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 34); do echo 'hostName' | timeout -t 3 nc -w 1 -u 104.197.91.190 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:25:10.849: INFO: stderr: ""
Mar 30 15:25:10.849: INFO: stdout: 2
STEP: dialing(http) node1 --> node2:nodeHttpPort
STEP: Dialing from node. command:for i in $(seq 1 34); do curl -s --connect-timeout 1 http://104.197.91.190:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:25:10.849: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 34); do curl -s --connect-timeout 1 http://104.197.91.190:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:25:46.171: INFO: stderr: ""
Mar 30 15:25:46.171: INFO: stdout: 2
STEP: checking kube-proxy URLs
STEP: Getting kube-proxy self URL /healthz
Mar 30 15:25:46.171: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -s --connect-timeout 1 http://localhost:10249/healthz'
Mar 30 15:25:47.348: INFO: stderr: ""
Mar 30 15:25:47.349: INFO: stdout: ok
STEP: Getting kube-proxy self URL /proxyMode
Mar 30 15:25:47.349: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -s --connect-timeout 1 http://localhost:10249/proxyMode'
Mar 30 15:25:48.509: INFO: stderr: ""
Mar 30 15:25:48.509: INFO: stdout: iptables
STEP: Deleting nodePortservice and ensuring that service cannot be hit
STEP: dialing(udp) node1 --> node1:nodeUdpPort
STEP: Dialing from node. command:for i in $(seq 1 30); do echo 'hostName' | timeout -t 3 nc -w 1 -u 23.236.56.214 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:26:03.570: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 30); do echo 'hostName' | timeout -t 3 nc -w 1 -u 23.236.56.214 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:28:04.792: INFO: stderr: "Terminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\n"
Mar 30 15:28:04.792: INFO: stdout: 0
STEP: dialing(http) node1 --> node1:nodeHttpPort
STEP: Dialing from node. command:for i in $(seq 1 30); do curl -s --connect-timeout 1 http://23.236.56.214:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:28:04.792: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 30); do curl -s --connect-timeout 1 http://23.236.56.214:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:28:36.073: INFO: stderr: ""
Mar 30 15:28:36.073: INFO: stdout: 0
STEP: dialing(udp) test container --> node1:nodeUdpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=23.236.56.214&port=32081&tries=30'
Mar 30 15:28:36.073: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=udp&host=23.236.56.214&port=32081&tries=30''
Mar 30 15:28:57.247: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:01 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:02 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:03 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:04 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:05 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:06 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:07 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:08 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:09 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:10 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:11 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:12 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:13 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:14 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:15 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:16 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:17 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:18 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:19 --:--:-- 0\r100 3620 0 3620 0 0 180 0 --:--:-- 0:00:20 --:--:-- 752\r100 3620 0 3620 0 0 180 0 --:--:-- 0:00:20 --:--:-- 950\n"
Mar 30 15:28:57.247: INFO: stdout: {"errors":["reading from udp connection failed. err:'read udp 10.245.3.4:37010-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:59425-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:34889-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:45066-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:38491-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:45901-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:59818-\u003e23.236.56.214:32081: i/o timeout'","reading from udp connection failed. err:'read udp 10.245.3.4:46144-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:51398-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:37575-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:57120-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:55811-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:58414-\u003e23.236.56.214:32081: i/o timeout'","reading from udp connection failed. err:'read udp 10.245.3.4:50300-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:48799-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:49609-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:37860-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:45816-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:36762-\u003e23.236.56.214:32081: i/o timeout'","reading from udp connection failed. err:'read udp 10.245.3.4:52208-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:60816-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:59094-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:59890-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:59397-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:47026-\u003e23.236.56.214:32081: i/o timeout'","reading from udp connection failed. err:'read udp 10.245.3.4:59791-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:36889-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:58868-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:49833-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.4:56730-\u003e23.236.56.214:32081: read: connection refused'"]}
STEP: dialing(http) test container --> node1:nodeHttpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=23.236.56.214&port=32080&tries=30'
Mar 30 15:28:57.248: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.4:8080/dial?request=hostName&protocol=http&host=23.236.56.214&port=32080&tries=30''
Mar 30 15:28:58.345: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 3132 0 3132 0 0 103k 0 --:--:-- --:--:-- --:--:-- 105k\n"
Mar 30 15:28:58.345: INFO: stdout: {"errors":["Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused"]}
STEP: dialing(udp) endpoint container --> node1:nodeUdpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.3:8080/dial?request=hostName&protocol=udp&host=23.236.56.214&port=32081&tries=30'
Mar 30 15:28:58.346: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.3:8080/dial?request=hostName&protocol=udp&host=23.236.56.214&port=32081&tries=30''
Mar 30 15:29:24.479: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:01 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:02 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:03 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:04 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:05 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:06 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:07 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:08 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:09 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:10 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:11 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:12 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:13 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:14 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:15 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:16 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:17 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:18 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:19 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:20 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:21 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:22 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:23 --:--:-- 0\r 0 0 0 0 0 0 0 0 --:--:-- 0:00:24 --:--:-- 0\r100 3607 0 3607 0 0 144 0 --:--:-- 0:00:25 --:--:-- 750\r100 3607 0 3607 0 0 144 0 --:--:-- 0:00:25 --:--:-- 948\n"
Mar 30 15:29:24.479: INFO: stdout: {"errors":["reading from udp connection failed. err:'read udp 10.245.3.3:56074-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:44297-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:41930-\u003e23.236.56.214:32081: i/o timeout'","reading from udp connection failed. err:'read udp 10.245.3.3:32857-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:33433-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:43560-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:51374-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:37697-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:55758-\u003e23.236.56.214:32081: i/o timeout'","reading from udp connection failed. err:'read udp 10.245.3.3:57488-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:57885-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:34536-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:53474-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:34670-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:33150-\u003e23.236.56.214:32081: i/o timeout'","reading from udp connection failed. err:'read udp 10.245.3.3:45376-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:56895-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:57452-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:34493-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:52742-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:55893-\u003e23.236.56.214:32081: i/o timeout'","reading from udp connection failed. err:'read udp 10.245.3.3:41605-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:43407-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:46217-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:52088-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:55317-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:58774-\u003e23.236.56.214:32081: i/o timeout'","reading from udp connection failed. err:'read udp 10.245.3.3:44260-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:42890-\u003e23.236.56.214:32081: read: connection refused'","reading from udp connection failed. err:'read udp 10.245.3.3:46234-\u003e23.236.56.214:32081: read: connection refused'"]}
STEP: dialing(http) endpoint container --> node1:nodeHttpPort
STEP: Dialing from container. Running command:curl -q 'http://10.245.3.3:8080/dial?request=hostName&protocol=http&host=23.236.56.214&port=32080&tries=30'
Mar 30 15:29:24.480: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -q 'http://10.245.3.3:8080/dial?request=hostName&protocol=http&host=23.236.56.214&port=32080&tries=30''
Mar 30 15:29:25.622: INFO: stderr: " % Total % Received % Xferd Average Speed Time Time Time Current\n Dload Upload Total Spent Left Speed\n\r 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\r100 3132 0 3132 0 0 100k 0 --:--:-- --:--:-- --:--:-- 101k\n"
Mar 30 15:29:25.622: INFO: stdout: {"errors":["Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused","Get http://23.236.56.214:32080/hostName: dial tcp 23.236.56.214:32080: getsockopt: connection refused"]}
STEP: dialing(udp) node --> 127.0.0.1:nodeUdpPort
STEP: Dialing from node. command:for i in $(seq 1 30); do echo 'hostName' | timeout -t 3 nc -w 1 -u 127.0.0.1 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:29:25.623: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 30); do echo 'hostName' | timeout -t 3 nc -w 1 -u 127.0.0.1 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:31:26.849: INFO: stderr: "Terminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\n"
Mar 30 15:31:26.849: INFO: stdout: 0
STEP: dialing(http) node --> 127.0.0.1:nodeHttpPort
STEP: Dialing from node. command:for i in $(seq 1 30); do curl -s --connect-timeout 1 http://127.0.0.1:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:31:26.849: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 30); do curl -s --connect-timeout 1 http://127.0.0.1:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:31:58.182: INFO: stderr: ""
Mar 30 15:31:58.183: INFO: stdout: 0
STEP: dialing(udp) node1 --> node2:nodeUdpPort
STEP: Dialing from node. command:for i in $(seq 1 30); do echo 'hostName' | timeout -t 3 nc -w 1 -u 104.197.91.190 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:31:58.183: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 30); do echo 'hostName' | timeout -t 3 nc -w 1 -u 104.197.91.190 32081; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:33:59.355: INFO: stderr: "Terminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\nTerminated\n"
Mar 30 15:33:59.356: INFO: stdout: 0
STEP: dialing(http) node1 --> node2:nodeHttpPort
STEP: Dialing from node. command:for i in $(seq 1 30); do curl -s --connect-timeout 1 http://104.197.91.190:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l
Mar 30 15:33:59.356: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c for i in $(seq 1 30); do curl -s --connect-timeout 1 http://104.197.91.190:32080/hostName; echo; sleep 1s; done | grep -v '^\s*$' |sort | uniq -c | wc -l'
Mar 30 15:34:30.618: INFO: stderr: ""
Mar 30 15:34:30.618: INFO: stdout: 0
STEP: checking kube-proxy URLs
STEP: Getting kube-proxy self URL /healthz
Mar 30 15:34:30.618: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -s --connect-timeout 1 http://localhost:10249/healthz'
Mar 30 15:34:31.754: INFO: stderr: ""
Mar 30 15:34:31.754: INFO: stdout: ok
STEP: Getting kube-proxy self URL /proxyMode
Mar 30 15:34:31.754: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-e2e-kubeproxy-pwksq host-test-container-pod -- /bin/sh -c curl -s --connect-timeout 1 http://localhost:10249/proxyMode'
Mar 30 15:34:32.845: INFO: stderr: ""
Mar 30 15:34:32.845: INFO: stdout: iptables
[AfterEach] [k8s.io] KubeProxy
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:102
Mar 30 15:34:32.845: INFO: Waiting up to 1m0s for all nodes to be ready
STEP: Destroying namespace "e2e-tests-e2e-kubeproxy-pwksq" for this suite.
• [SLOW TEST:1590.699 seconds]
[k8s.io] KubeProxy
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:420
should test kube-proxy [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/kubeproxy.go:106
------------------------------
SSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[k8s.io] Pods
should cap back-off at MaxContainerBackOff [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pods.go:1065
[BeforeEach] [k8s.io] Pods
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:101
STEP: Creating a kubernetes client
Mar 30 15:35:08.089: INFO: >>> testContext.KubeConfig: /usr/local/google/home/stclair/.kube/config
STEP: Building a namespace api object
Mar 30 15:35:08.140: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-pods-bl818
Mar 30 15:35:08.179: INFO: Service account default in ns e2e-tests-pods-bl818 with secrets found. (39.394566ms)
STEP: Waiting for a default service account to be provisioned in namespace
Mar 30 15:35:08.179: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-pods-bl818
Mar 30 15:35:08.218: INFO: Service account default in ns e2e-tests-pods-bl818 with secrets found. (39.200802ms)
[It] should cap back-off at MaxContainerBackOff [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pods.go:1065
STEP: submitting the pod to kubernetes
Mar 30 15:35:08.268: INFO: Waiting up to 5m0s for pod back-off-cap status to be running
Mar 30 15:35:08.326: INFO: Waiting for pod back-off-cap in namespace 'e2e-tests-pods-bl818' status to be 'running'(found phase: "Pending", readiness: false) (58.039471ms elapsed)
Mar 30 15:35:10.367: INFO: Found pod 'back-off-cap' on node 'e2e-test-stclair-minion-gyh0'
STEP: verifying the pod is in kubernetes
STEP: geting restart delay when capped
Mar 30 15:46:37.869: INFO: getRestartDelay: restartCount = 7, finishedAt=2016-03-30 15:41:29 -0700 PDT restartedAt=2016-03-30 15:46:36 -0700 PDT (5m7s)
Mar 30 15:51:49.765: INFO: getRestartDelay: restartCount = 8, finishedAt=2016-03-30 15:46:42 -0700 PDT restartedAt=2016-03-30 15:51:48 -0700 PDT (5m6s)
Mar 30 15:57:03.462: INFO: getRestartDelay: restartCount = 9, finishedAt=2016-03-30 15:51:53 -0700 PDT restartedAt=2016-03-30 15:57:02 -0700 PDT (5m9s)
STEP: getting restart delay after a capped delay
Mar 30 16:02:21.830: INFO: getRestartDelay: restartCount = 10, finishedAt=2016-03-30 15:57:07 -0700 PDT restartedAt=2016-03-30 16:02:19 -0700 PDT (5m12s)
STEP: deleting the pod
[AfterEach] [k8s.io] Pods
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:102
Mar 30 16:02:21.883: INFO: Waiting up to 1m0s for all nodes to be ready
STEP: Destroying namespace "e2e-tests-pods-bl818" for this suite.
• [SLOW TEST:1639.039 seconds]
[k8s.io] Pods
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:420
should cap back-off at MaxContainerBackOff [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pods.go:1065
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[k8s.io] Pods
should have monotonically increasing restart count [Conformance] [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pods.go:770
[BeforeEach] [k8s.io] Pods
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:101
STEP: Creating a kubernetes client
Mar 30 16:02:27.129: INFO: >>> testContext.KubeConfig: /usr/local/google/home/stclair/.kube/config
STEP: Building a namespace api object
Mar 30 16:02:27.180: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-pods-xvbfg
Mar 30 16:02:27.219: INFO: Service account default in ns e2e-tests-pods-xvbfg with secrets found. (38.886677ms)
STEP: Waiting for a default service account to be provisioned in namespace
Mar 30 16:02:27.219: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-pods-xvbfg
Mar 30 16:02:27.258: INFO: Service account default in ns e2e-tests-pods-xvbfg with secrets found. (39.213503ms)
[It] should have monotonically increasing restart count [Conformance] [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pods.go:770
STEP: Creating pod liveness-http in namespace e2e-tests-pods-xvbfg
Mar 30 16:02:27.305: INFO: Waiting up to 5m0s for pod liveness-http status to be !pending
Mar 30 16:02:27.344: INFO: Waiting for pod liveness-http in namespace 'e2e-tests-pods-xvbfg' status to be '!pending'(found phase: "Pending", readiness: false) (39.370497ms elapsed)
Mar 30 16:02:29.385: INFO: Saw pod 'liveness-http' in namespace 'e2e-tests-pods-xvbfg' out of pending state (found '"Running"')
Mar 30 16:02:29.386: INFO: Started pod liveness-http in namespace e2e-tests-pods-xvbfg
STEP: checking the pod's current state and verifying that restartCount is present
Mar 30 16:02:29.426: INFO: Initial restart count of pod liveness-http is 0
Mar 30 16:02:49.884: INFO: Restart count of pod e2e-tests-pods-xvbfg/liveness-http is now 1 (20.457818007s elapsed)
Mar 30 16:03:08.249: INFO: Restart count of pod e2e-tests-pods-xvbfg/liveness-http is now 2 (38.822873229s elapsed)
Mar 30 16:03:28.664: INFO: Restart count of pod e2e-tests-pods-xvbfg/liveness-http is now 3 (59.238032462s elapsed)
Mar 30 16:03:49.072: INFO: Restart count of pod e2e-tests-pods-xvbfg/liveness-http is now 4 (1m19.645914964s elapsed)
Mar 30 16:05:00.501: INFO: Restart count of pod e2e-tests-pods-xvbfg/liveness-http is now 5 (2m31.074864363s elapsed)
STEP: deleting the pod
[AfterEach] [k8s.io] Pods
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:102
Mar 30 16:05:00.561: INFO: Waiting up to 1m0s for all nodes to be ready
STEP: Destroying namespace "e2e-tests-pods-xvbfg" for this suite.
• [SLOW TEST:158.675 seconds]
[k8s.io] Pods
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:420
should have monotonically increasing restart count [Conformance] [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pods.go:770
------------------------------
SSSSSSSSSSSSSSSSSSSSSSSSS
------------------------------
[k8s.io] Garbage collector [Slow]
should handle the creation of 1000 pods
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/garbage_collector.go:79
[BeforeEach] [k8s.io] Garbage collector [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:101
STEP: Creating a kubernetes client
Mar 30 16:05:05.804: INFO: >>> testContext.KubeConfig: /usr/local/google/home/stclair/.kube/config
STEP: Building a namespace api object
Mar 30 16:05:05.853: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-garbage-collector-320xc
Mar 30 16:05:05.891: INFO: Service account default in ns e2e-tests-garbage-collector-320xc had 0 secrets, ignoring for 2s: <nil>
Mar 30 16:05:07.931: INFO: Service account default in ns e2e-tests-garbage-collector-320xc with secrets found. (2.078371512s)
STEP: Waiting for a default service account to be provisioned in namespace
Mar 30 16:05:07.931: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-garbage-collector-320xc
Mar 30 16:05:07.970: INFO: Service account default in ns e2e-tests-garbage-collector-320xc with secrets found. (39.186029ms)
[It] should handle the creation of 1000 pods
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/garbage_collector.go:79
Mar 30 16:05:12.526: INFO: count: 50
Mar 30 16:05:17.125: INFO: count: 100
Mar 30 16:05:21.692: INFO: count: 150
Mar 30 16:05:26.785: INFO: count: 200
Mar 30 16:05:31.370: INFO: count: 250
Mar 30 16:05:36.012: INFO: count: 300
Mar 30 16:05:40.926: INFO: count: 350
Mar 30 16:05:46.016: INFO: count: 400
Mar 30 16:05:50.814: INFO: count: 450
Mar 30 16:05:56.051: INFO: count: 500
Mar 30 16:06:00.830: INFO: count: 550
Mar 30 16:06:05.575: INFO: count: 600
Mar 30 16:06:10.767: INFO: count: 650
Mar 30 16:06:15.665: INFO: count: 700
Mar 30 16:06:21.245: INFO: count: 750
Mar 30 16:06:26.042: INFO: count: 800
Mar 30 16:06:30.611: INFO: count: 850
Mar 30 16:06:35.522: INFO: count: 900
Mar 30 16:06:40.565: INFO: count: 950
Mar 30 16:06:45.585: INFO: count: 1000
Mar 30 16:06:45.585: INFO: created: 1000
STEP: Waiting for gc controller to gc all but 100 pods
[AfterEach] [k8s.io] Garbage collector [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:102
Mar 30 16:07:45.763: INFO: Waiting up to 1m0s for all nodes to be ready
STEP: Destroying namespace "e2e-tests-garbage-collector-320xc" for this suite.
• [SLOW TEST:165.160 seconds]
[k8s.io] Garbage collector [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:420
should handle the creation of 1000 pods
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/garbage_collector.go:79
------------------------------
[k8s.io] Pod Disks
should schedule a pod w/two RW PDs both mounted to one container, write to PD, verify contents, delete pod, recreate pod, verify contents, and repeat in rapid succession [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pd.go:270
[BeforeEach] [k8s.io] Pod Disks
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:101
STEP: Creating a kubernetes client
Mar 30 16:07:50.965: INFO: >>> testContext.KubeConfig: /usr/local/google/home/stclair/.kube/config
STEP: Building a namespace api object
Mar 30 16:07:51.010: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-pod-disks-59fuk
Mar 30 16:07:51.049: INFO: Service account default in ns e2e-tests-pod-disks-59fuk with secrets found. (38.805763ms)
STEP: Waiting for a default service account to be provisioned in namespace
Mar 30 16:07:51.049: INFO: Waiting up to 2m0s for service account default to be provisioned in ns e2e-tests-pod-disks-59fuk
Mar 30 16:07:51.088: INFO: Service account default in ns e2e-tests-pod-disks-59fuk with secrets found. (38.985479ms)
[BeforeEach] [k8s.io] Pod Disks
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pd.go:68
[It] should schedule a pod w/two RW PDs both mounted to one container, write to PD, verify contents, delete pod, recreate pod, verify contents, and repeat in rapid succession [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pd.go:270
STEP: creating PD1
Mar 30 16:07:58.004: INFO: Successfully created a new PD: "e2e-39fd3802-f6cc-11e5-94b8-7446a0c373f1".
STEP: creating PD2
Mar 30 16:08:04.637: INFO: Successfully created a new PD: "e2e-3e15d0f6-f6cc-11e5-94b8-7446a0c373f1".
Mar 30 16:08:04.638: INFO: PD Read/Writer Iteration #0
STEP: submitting host0Pod to kubernetes
Mar 30 16:08:04.689: INFO: Waiting up to 15m0s for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 status to be running
Mar 30 16:08:04.729: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (40.288865ms elapsed)
Mar 30 16:08:06.771: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (2.081427729s elapsed)
Mar 30 16:08:08.811: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (4.122347293s elapsed)
Mar 30 16:08:10.852: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (6.163032389s elapsed)
Mar 30 16:08:12.893: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (8.20372528s elapsed)
Mar 30 16:08:14.933: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (10.24439543s elapsed)
Mar 30 16:08:16.974: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (12.285017447s elapsed)
Mar 30 16:08:19.015: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (14.3258158s elapsed)
Mar 30 16:08:21.056: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (16.366665619s elapsed)
Mar 30 16:08:23.096: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (18.407066516s elapsed)
Mar 30 16:08:25.137: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (20.447878816s elapsed)
Mar 30 16:08:27.180: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (22.491261805s elapsed)
Mar 30 16:08:29.221: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (24.532057998s elapsed)
Mar 30 16:08:31.262: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (26.573198833s elapsed)
Mar 30 16:08:33.303: INFO: Found pod 'pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1' on node 'e2e-test-stclair-minion-gxqg'
STEP: writing a file in the container
Mar 30 16:08:33.304: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- /bin/sh -c echo '956069655376734706' > '/testpd1/tracker0''
Mar 30 16:08:34.536: INFO: Wrote value: "956069655376734706" to PD1 ("e2e-39fd3802-f6cc-11e5-94b8-7446a0c373f1") from pod "pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1" container "mycontainer"
STEP: writing a file in the container
Mar 30 16:08:34.536: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- /bin/sh -c echo '9048846577931981377' > '/testpd2/tracker0''
Mar 30 16:08:35.649: INFO: Wrote value: "9048846577931981377" to PD2 ("e2e-3e15d0f6-f6cc-11e5-94b8-7446a0c373f1") from pod "pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1" container "mycontainer"
STEP: reading a file in the container
Mar 30 16:08:35.649: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd1/tracker0'
Mar 30 16:08:36.753: INFO: Read file "/testpd1/tracker0" with content: 956069655376734706
STEP: reading a file in the container
Mar 30 16:08:36.753: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd2/tracker0'
Mar 30 16:08:37.847: INFO: Read file "/testpd2/tracker0" with content: 9048846577931981377
STEP: deleting host0Pod
Mar 30 16:08:37.901: INFO: PD Read/Writer Iteration #1
STEP: submitting host0Pod to kubernetes
Mar 30 16:08:37.948: INFO: Waiting up to 15m0s for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 status to be running
Mar 30 16:08:37.988: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (40.10435ms elapsed)
Mar 30 16:08:40.029: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (2.081152658s elapsed)
Mar 30 16:08:42.070: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (4.122260674s elapsed)
Mar 30 16:08:44.111: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (6.163092447s elapsed)
Mar 30 16:08:46.160: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (8.212038156s elapsed)
Mar 30 16:08:48.203: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (10.254918886s elapsed)
Mar 30 16:08:50.244: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (12.295568584s elapsed)
Mar 30 16:08:52.285: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (14.336401453s elapsed)
Mar 30 16:08:54.325: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (16.376930666s elapsed)
Mar 30 16:08:56.365: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (18.417216378s elapsed)
Mar 30 16:08:58.406: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (20.457704745s elapsed)
Mar 30 16:09:00.447: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (22.498713446s elapsed)
Mar 30 16:09:02.488: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (24.539590963s elapsed)
Mar 30 16:09:04.528: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (26.580224324s elapsed)
Mar 30 16:09:06.569: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (28.621169287s elapsed)
Mar 30 16:09:08.610: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (30.662143295s elapsed)
Mar 30 16:09:10.650: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (32.702359029s elapsed)
Mar 30 16:09:12.690: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (34.74175792s elapsed)
Mar 30 16:09:14.730: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (36.781674701s elapsed)
Mar 30 16:09:16.770: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (38.821630421s elapsed)
Mar 30 16:09:18.814: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (40.865792166s elapsed)
Mar 30 16:09:20.860: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (42.911389628s elapsed)
Mar 30 16:09:22.904: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (44.955736435s elapsed)
Mar 30 16:09:24.944: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (46.995833122s elapsed)
Mar 30 16:09:26.985: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (49.036949733s elapsed)
Mar 30 16:09:29.025: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (51.077156278s elapsed)
Mar 30 16:09:31.066: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (53.117913808s elapsed)
Mar 30 16:09:33.106: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (55.157796573s elapsed)
Mar 30 16:09:35.147: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (57.198656685s elapsed)
Mar 30 16:09:37.187: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (59.239018647s elapsed)
Mar 30 16:09:39.228: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m1.280183567s elapsed)
Mar 30 16:09:41.270: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m3.32195566s elapsed)
Mar 30 16:09:43.316: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m5.368343785s elapsed)
Mar 30 16:09:45.358: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m7.409518913s elapsed)
Mar 30 16:09:47.399: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m9.450780337s elapsed)
Mar 30 16:09:49.440: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m11.491663658s elapsed)
Mar 30 16:09:51.480: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m13.531522698s elapsed)
Mar 30 16:09:53.520: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m15.572244143s elapsed)
Mar 30 16:09:55.561: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m17.613360318s elapsed)
Mar 30 16:09:57.603: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m19.655020492s elapsed)
Mar 30 16:09:59.644: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m21.696018415s elapsed)
Mar 30 16:10:01.684: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m23.73627339s elapsed)
Mar 30 16:10:03.725: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m25.77705928s elapsed)
Mar 30 16:10:05.766: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m27.817912604s elapsed)
Mar 30 16:10:07.807: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m29.858752845s elapsed)
Mar 30 16:10:09.847: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m31.89887586s elapsed)
Mar 30 16:10:11.887: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m33.93862825s elapsed)
Mar 30 16:10:13.927: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m35.979377215s elapsed)
Mar 30 16:10:15.968: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m38.020343055s elapsed)
Mar 30 16:10:18.011: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m40.062463058s elapsed)
Mar 30 16:10:20.051: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m42.102435688s elapsed)
Mar 30 16:10:22.091: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m44.142964124s elapsed)
Mar 30 16:10:24.132: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m46.184149576s elapsed)
Mar 30 16:10:26.173: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m48.225097158s elapsed)
Mar 30 16:10:28.214: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m50.265515058s elapsed)
Mar 30 16:10:30.254: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m52.30633899s elapsed)
Mar 30 16:10:32.295: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m54.347227878s elapsed)
Mar 30 16:10:34.336: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m56.388174337s elapsed)
Mar 30 16:10:36.377: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m58.428916714s elapsed)
Mar 30 16:10:38.417: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (2m0.46912248s elapsed)
Mar 30 16:10:40.463: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (2m2.514645603s elapsed)
Mar 30 16:10:42.505: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (2m4.556987815s elapsed)
Mar 30 16:10:44.547: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (2m6.598464875s elapsed)
Mar 30 16:10:46.588: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (2m8.639963826s elapsed)
Mar 30 16:10:48.629: INFO: Found pod 'pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1' on node 'e2e-test-stclair-minion-gxqg'
STEP: reading a file in the container
Mar 30 16:10:48.629: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd1/tracker0'
Mar 30 16:10:49.736: INFO: Read file "/testpd1/tracker0" with content: 956069655376734706
STEP: reading a file in the container
Mar 30 16:10:49.736: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd2/tracker0'
Mar 30 16:10:50.852: INFO: Read file "/testpd2/tracker0" with content: 9048846577931981377
STEP: writing a file in the container
Mar 30 16:10:50.853: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- /bin/sh -c echo '6351756574549843040' > '/testpd1/tracker1''
Mar 30 16:10:51.987: INFO: Wrote value: "6351756574549843040" to PD1 ("e2e-39fd3802-f6cc-11e5-94b8-7446a0c373f1") from pod "pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1" container "mycontainer"
STEP: writing a file in the container
Mar 30 16:10:51.988: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- /bin/sh -c echo '4908781075446465294' > '/testpd2/tracker1''
Mar 30 16:10:53.083: INFO: Wrote value: "4908781075446465294" to PD2 ("e2e-3e15d0f6-f6cc-11e5-94b8-7446a0c373f1") from pod "pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1" container "mycontainer"
STEP: reading a file in the container
Mar 30 16:10:53.083: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd1/tracker0'
Mar 30 16:10:54.199: INFO: Read file "/testpd1/tracker0" with content: 956069655376734706
STEP: reading a file in the container
Mar 30 16:10:54.199: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd2/tracker0'
Mar 30 16:10:55.326: INFO: Read file "/testpd2/tracker0" with content: 9048846577931981377
STEP: reading a file in the container
Mar 30 16:10:55.326: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd1/tracker1'
Mar 30 16:10:56.424: INFO: Read file "/testpd1/tracker1" with content: 6351756574549843040
STEP: reading a file in the container
Mar 30 16:10:56.424: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd2/tracker1'
Mar 30 16:10:57.539: INFO: Read file "/testpd2/tracker1" with content: 4908781075446465294
STEP: deleting host0Pod
Mar 30 16:10:57.596: INFO: PD Read/Writer Iteration #2
STEP: submitting host0Pod to kubernetes
Mar 30 16:10:57.644: INFO: Waiting up to 15m0s for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 status to be running
Mar 30 16:10:57.684: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (40.101197ms elapsed)
Mar 30 16:10:59.725: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (2.080760587s elapsed)
Mar 30 16:11:01.766: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (4.121598163s elapsed)
Mar 30 16:11:03.807: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (6.162728794s elapsed)
Mar 30 16:11:05.848: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (8.203944439s elapsed)
Mar 30 16:11:07.889: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (10.244952891s elapsed)
Mar 30 16:11:09.930: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (12.285809778s elapsed)
Mar 30 16:11:11.971: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (14.326464441s elapsed)
Mar 30 16:11:14.011: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (16.366570626s elapsed)
Mar 30 16:11:16.051: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (18.407063119s elapsed)
Mar 30 16:11:18.092: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (20.447838001s elapsed)
Mar 30 16:11:20.133: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (22.488451557s elapsed)
Mar 30 16:11:22.173: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (24.529148502s elapsed)
Mar 30 16:11:24.214: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (26.570027152s elapsed)
Mar 30 16:11:26.255: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (28.61095957s elapsed)
Mar 30 16:11:28.296: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (30.651815094s elapsed)
Mar 30 16:11:30.337: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (32.692755259s elapsed)
Mar 30 16:11:32.378: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (34.733730992s elapsed)
Mar 30 16:11:34.419: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (36.774462376s elapsed)
Mar 30 16:11:36.460: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (38.815442791s elapsed)
Mar 30 16:11:38.501: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (40.856366997s elapsed)
Mar 30 16:11:40.543: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (42.898659098s elapsed)
Mar 30 16:11:42.584: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (44.940108597s elapsed)
Mar 30 16:11:44.626: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (46.981225514s elapsed)
Mar 30 16:11:46.667: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (49.022327776s elapsed)
Mar 30 16:11:48.708: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (51.063740012s elapsed)
Mar 30 16:11:50.749: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (53.104657768s elapsed)
Mar 30 16:11:52.791: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (55.14712116s elapsed)
Mar 30 16:11:54.834: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (57.189312497s elapsed)
Mar 30 16:11:56.876: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (59.231452737s elapsed)
Mar 30 16:11:58.916: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m1.271866502s elapsed)
Mar 30 16:12:00.956: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m3.312034162s elapsed)
Mar 30 16:12:02.997: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m5.352814625s elapsed)
Mar 30 16:12:05.038: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m7.393633209s elapsed)
Mar 30 16:12:07.084: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m9.440176201s elapsed)
Mar 30 16:12:09.125: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m11.48113087s elapsed)
Mar 30 16:12:11.166: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m13.521303885s elapsed)
Mar 30 16:12:13.206: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m15.561506196s elapsed)
Mar 30 16:12:15.247: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m17.602549025s elapsed)
Mar 30 16:12:17.288: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m19.643826369s elapsed)
Mar 30 16:12:19.329: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m21.685061601s elapsed)
Mar 30 16:12:21.371: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m23.726369833s elapsed)
Mar 30 16:12:23.411: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m25.767108836s elapsed)
Mar 30 16:12:25.456: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m27.811927827s elapsed)
Mar 30 16:12:27.498: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m29.853515643s elapsed)
Mar 30 16:12:29.540: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m31.895289876s elapsed)
Mar 30 16:12:31.582: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m33.937443385s elapsed)
Mar 30 16:12:33.623: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m35.978869639s elapsed)
Mar 30 16:12:35.664: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m38.019592077s elapsed)
Mar 30 16:12:37.705: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m40.060650675s elapsed)
Mar 30 16:12:39.746: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m42.101661649s elapsed)
Mar 30 16:12:41.787: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m44.142540489s elapsed)
Mar 30 16:12:43.828: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m46.183377234s elapsed)
Mar 30 16:12:45.869: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m48.224348493s elapsed)
Mar 30 16:12:47.910: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m50.266086468s elapsed)
Mar 30 16:12:49.951: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m52.30693839s elapsed)
Mar 30 16:12:51.992: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m54.347859569s elapsed)
Mar 30 16:12:54.033: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m56.38852093s elapsed)
Mar 30 16:12:56.077: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (1m58.4331539s elapsed)
Mar 30 16:12:58.118: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (2m0.47392373s elapsed)
Mar 30 16:13:00.159: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (2m2.514656317s elapsed)
Mar 30 16:13:02.200: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (2m4.555242865s elapsed)
Mar 30 16:13:04.240: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (2m6.596125882s elapsed)
Mar 30 16:13:06.281: INFO: Waiting for pod pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 in namespace 'e2e-tests-pod-disks-59fuk' status to be 'running'(found phase: "Pending", readiness: false) (2m8.636527364s elapsed)
Mar 30 16:13:08.322: INFO: Found pod 'pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1' on node 'e2e-test-stclair-minion-gxqg'
STEP: reading a file in the container
Mar 30 16:13:08.322: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd2/tracker0'
Mar 30 16:13:09.449: INFO: Read file "/testpd2/tracker0" with content: 9048846577931981377
STEP: reading a file in the container
Mar 30 16:13:09.449: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd1/tracker1'
Mar 30 16:13:10.604: INFO: Read file "/testpd1/tracker1" with content: 6351756574549843040
STEP: reading a file in the container
Mar 30 16:13:10.604: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd2/tracker1'
Mar 30 16:13:11.709: INFO: Read file "/testpd2/tracker1" with content: 4908781075446465294
STEP: reading a file in the container
Mar 30 16:13:11.709: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd1/tracker0'
Mar 30 16:13:12.868: INFO: Read file "/testpd1/tracker0" with content: 956069655376734706
STEP: writing a file in the container
Mar 30 16:13:12.868: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- /bin/sh -c echo '4592646212752383971' > '/testpd1/tracker2''
Mar 30 16:13:13.976: INFO: Wrote value: "4592646212752383971" to PD1 ("e2e-39fd3802-f6cc-11e5-94b8-7446a0c373f1") from pod "pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1" container "mycontainer"
STEP: writing a file in the container
Mar 30 16:13:13.976: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- /bin/sh -c echo '4200012985925673556' > '/testpd2/tracker2''
Mar 30 16:13:15.129: INFO: Wrote value: "4200012985925673556" to PD2 ("e2e-3e15d0f6-f6cc-11e5-94b8-7446a0c373f1") from pod "pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1" container "mycontainer"
STEP: reading a file in the container
Mar 30 16:13:15.129: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd2/tracker0'
Mar 30 16:13:16.230: INFO: Read file "/testpd2/tracker0" with content: 9048846577931981377
STEP: reading a file in the container
Mar 30 16:13:16.231: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd1/tracker1'
Mar 30 16:13:17.340: INFO: Read file "/testpd1/tracker1" with content: 6351756574549843040
STEP: reading a file in the container
Mar 30 16:13:17.340: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd2/tracker1'
Mar 30 16:13:18.491: INFO: Read file "/testpd2/tracker1" with content: 4908781075446465294
STEP: reading a file in the container
Mar 30 16:13:18.492: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd1/tracker2'
Mar 30 16:13:19.636: INFO: Read file "/testpd1/tracker2" with content: 4592646212752383971
STEP: reading a file in the container
Mar 30 16:13:19.637: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd2/tracker2'
Mar 30 16:13:20.752: INFO: Read file "/testpd2/tracker2" with content: 4200012985925673556
STEP: reading a file in the container
Mar 30 16:13:20.752: INFO: Running '/usr/local/google/home/stclair/k8s/k8s-2/_output/dockerized/bin/linux/amd64/kubectl kubectl --server=https://130.211.129.119 --kubeconfig=/usr/local/google/home/stclair/.kube/config exec --namespace=e2e-tests-pod-disks-59fuk pd-test-4209fcaa-f6cc-11e5-94b8-7446a0c373f1 -c=mycontainer -- cat /testpd1/tracker0'
Mar 30 16:13:21.844: INFO: Read file "/testpd1/tracker0" with content: 956069655376734706
STEP: deleting host0Pod
STEP: cleaning up PD-RW test environment
STEP: Waiting for PD "e2e-39fd3802-f6cc-11e5-94b8-7446a0c373f1" to detach from "e2e-test-stclair-minion-gxqg"
Mar 30 16:13:32.198: INFO: GCE PD "e2e-39fd3802-f6cc-11e5-94b8-7446a0c373f1" appears to have successfully detached from "e2e-test-stclair-minion-gxqg".
STEP: Deleting PD "e2e-39fd3802-f6cc-11e5-94b8-7446a0c373f1"
Mar 30 16:13:36.025: INFO: Successfully deleted PD "e2e-39fd3802-f6cc-11e5-94b8-7446a0c373f1".
E0330 16:13:39.963323 9913 gce.go:415] GCE operation failed: googleapi: Error 400: Invalid value for field 'disk': 'e2e-3e15d0f6-f6cc-11e5-94b8-7446a0c373f1'.
STEP: Waiting for PD "e2e-3e15d0f6-f6cc-11e5-94b8-7446a0c373f1" to detach from "e2e-test-stclair-minion-gxqg"
Mar 30 16:13:40.157: INFO: GCE PD "e2e-3e15d0f6-f6cc-11e5-94b8-7446a0c373f1" appears to have successfully detached from "e2e-test-stclair-minion-gxqg".
STEP: Deleting PD "e2e-3e15d0f6-f6cc-11e5-94b8-7446a0c373f1"
Mar 30 16:13:44.011: INFO: Successfully deleted PD "e2e-3e15d0f6-f6cc-11e5-94b8-7446a0c373f1".
[AfterEach] [k8s.io] Pod Disks
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:102
Mar 30 16:13:44.012: INFO: Waiting up to 1m0s for all nodes to be ready
STEP: Destroying namespace "e2e-tests-pod-disks-59fuk" for this suite.
• [SLOW TEST:358.289 seconds]
[k8s.io] Pod Disks
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/framework.go:420
should schedule a pod w/two RW PDs both mounted to one container, write to PD, verify contents, delete pod, recreate pod, verify contents, and repeat in rapid succession [Slow]
/go/src/k8s.io/kubernetes/_output/dockerized/go/src/k8s.io/kubernetes/test/e2e/pd.go:270
------------------------------
SSSSSSSSS
Ran 9 of 268 Specs in 4780.946 seconds
SUCCESS! -- 9 Passed | 0 Failed | 0 Pending | 259 Skipped PASS
Ginkgo ran 1 suite in 1h19m41.433522781s
Test Suite Passed
2016/03/30 16:13:49 e2e.go:196: Step 'Ginkgo tests' finished in 1h19m43.058423997s
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment