sigs.k8s.io/azuredisk-csi-driver PR #180 chewong-pull-azuredisk-csi-driver-e2e-single-az #1192575885362860032
Recent runs || View in Spyglass
Recent runs || View in Spyglass
error during make e2e-test: exit status 2
from junit_runner.xml
Filter through log files
Check APIReachability
Deferred TearDown
DumpClusterLogs
IsUp
TearDown
TearDown Previous
Timeout
Up
kubectl version
list nodes
test setup
... skipping 241 lines ... I1107 23:06:56.388] pod/tiller-deploy-54c96cb5df-8qgzd condition met I1107 23:06:56.389] helm version I1107 23:06:56.438] Client: &version.Version{SemVer:"v2.11.0", GitCommit:"2e55dbe1fdb5fdb96b75ff144a339489417b146b", GitTreeState:"clean"} I1107 23:06:57.471] Server: &version.Version{SemVer:"v2.11.0", GitCommit:"2e55dbe1fdb5fdb96b75ff144a339489417b146b", GitTreeState:"clean"} I1107 23:06:57.473] # Only build and push the image if it does not exist in the registry I1107 23:06:57.474] docker pull k8sprowinternal.azurecr.io/azuredisk-csi:e2e-759e34c20ae61fd6b5aeacace7b768eca105f4a8 || make azuredisk-container push I1107 23:06:57.872] Error response from daemon: manifest for k8sprowinternal.azurecr.io/azuredisk-csi:e2e-759e34c20ae61fd6b5aeacace7b768eca105f4a8 not found: manifest unknown: manifest unknown I1107 23:06:57.876] make[2]: Entering directory '/go/src/sigs.k8s.io/azuredisk-csi-driver' I1107 23:06:57.880] if [ ! -d ./vendor ]; then dep ensure -vendor-only; fi I1107 23:06:57.881] CGO_ENABLED=0 GOOS=linux go build -a -ldflags "-X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.driverVersion=e2e-759e34c20ae61fd6b5aeacace7b768eca105f4a8 -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.gitCommit=759e34c20ae61fd6b5aeacace7b768eca105f4a8 -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.buildDate=2019-11-07T23:06:57Z -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.DriverName=disk.csi.azure.com -X sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk.topologyKey=topology.disk.csi.azure.com/zone -extldflags "-static"" -o _output/azurediskplugin ./pkg/azurediskplugin I1107 23:07:58.043] docker build --no-cache -t k8sprowinternal.azurecr.io/azuredisk-csi:e2e-759e34c20ae61fd6b5aeacace7b768eca105f4a8 -f ./pkg/azurediskplugin/Dockerfile . I1107 23:08:01.741] Sending build context to Docker daemon 214.4MB I1107 23:08:01.798] Step 1/6 : FROM aksrepos.azurecr.io/fundamental/base-ubuntu:v0.0.5 ... skipping 211 lines ... I1107 23:10:41.164] Nov 7 23:10:41.163: INFO: PersistentVolumeClaim pvc-jn4fd found but phase is Pending instead of Bound. I1107 23:10:43.237] Nov 7 23:10:43.236: INFO: PersistentVolumeClaim pvc-jn4fd found and phase=Bound (12.510784691s) I1107 23:10:43.237] STEP: checking the PVC I1107 23:10:43.309] STEP: validating provisioned PV I1107 23:10:43.381] STEP: checking the PV I1107 23:10:43.381] STEP: deploying the pod I1107 23:10:43.455] STEP: checking that the pods command exits with no error I1107 23:10:43.455] Nov 7 23:10:43.454: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-9j9pv" in namespace "azuredisk-869" to be "success or failure" I1107 23:10:43.532] Nov 7 23:10:43.532: INFO: Pod "azuredisk-volume-tester-9j9pv": Phase="Pending", Reason="", readiness=false. Elapsed: 77.582515ms I1107 23:10:45.605] Nov 7 23:10:45.605: INFO: Pod "azuredisk-volume-tester-9j9pv": Phase="Pending", Reason="", readiness=false. Elapsed: 2.150409343s I1107 23:10:47.678] Nov 7 23:10:47.678: INFO: Pod "azuredisk-volume-tester-9j9pv": Phase="Pending", Reason="", readiness=false. Elapsed: 4.223095768s I1107 23:10:49.751] Nov 7 23:10:49.751: INFO: Pod "azuredisk-volume-tester-9j9pv": Phase="Pending", Reason="", readiness=false. Elapsed: 6.296251495s I1107 23:10:51.824] Nov 7 23:10:51.824: INFO: Pod "azuredisk-volume-tester-9j9pv": Phase="Pending", Reason="", readiness=false. Elapsed: 8.369186619s ... skipping 84 lines ... I1107 23:12:55.869] Nov 7 23:12:55.868: INFO: PersistentVolumeClaim pvc-hzjk7 found but phase is Pending instead of Bound. I1107 23:12:57.947] Nov 7 23:12:57.946: INFO: PersistentVolumeClaim pvc-hzjk7 found and phase=Bound (10.481558895s) I1107 23:12:57.947] STEP: checking the PVC I1107 23:12:58.024] STEP: validating provisioned PV I1107 23:12:58.102] STEP: checking the PV I1107 23:12:58.102] STEP: deploying the pod I1107 23:12:58.183] STEP: checking that the pods command exits with no error I1107 23:12:58.184] Nov 7 23:12:58.183: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-qxhvz" in namespace "azuredisk-3611" to be "success or failure" I1107 23:12:58.261] Nov 7 23:12:58.261: INFO: Pod "azuredisk-volume-tester-qxhvz": Phase="Pending", Reason="", readiness=false. Elapsed: 77.33451ms I1107 23:13:00.339] Nov 7 23:13:00.339: INFO: Pod "azuredisk-volume-tester-qxhvz": Phase="Pending", Reason="", readiness=false. Elapsed: 2.155458781s I1107 23:13:02.417] Nov 7 23:13:02.417: INFO: Pod "azuredisk-volume-tester-qxhvz": Phase="Pending", Reason="", readiness=false. Elapsed: 4.233627552s I1107 23:13:04.499] Nov 7 23:13:04.498: INFO: Pod "azuredisk-volume-tester-qxhvz": Phase="Pending", Reason="", readiness=false. Elapsed: 6.315191348s I1107 23:13:06.577] Nov 7 23:13:06.577: INFO: Pod "azuredisk-volume-tester-qxhvz": Phase="Pending", Reason="", readiness=false. Elapsed: 8.393475716s ... skipping 182 lines ... I1107 23:23:30.584] Nov 7 23:23:30.584: INFO: PersistentVolumeClaim pvc-m9472 found but phase is Pending instead of Bound. I1107 23:23:32.657] Nov 7 23:23:32.656: INFO: PersistentVolumeClaim pvc-m9472 found and phase=Bound (10.434469243s) I1107 23:23:32.657] STEP: checking the PVC I1107 23:23:32.728] STEP: validating provisioned PV I1107 23:23:32.801] STEP: checking the PV I1107 23:23:32.801] STEP: deploying the pod I1107 23:23:32.880] STEP: checking that the pods command exits with an error I1107 23:23:32.880] Nov 7 23:23:32.880: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-wlbfh" in namespace "azuredisk-8841" to be "Error status code" I1107 23:23:32.952] Nov 7 23:23:32.952: INFO: Pod "azuredisk-volume-tester-wlbfh": Phase="Pending", Reason="", readiness=false. Elapsed: 72.436363ms I1107 23:23:35.025] Nov 7 23:23:35.025: INFO: Pod "azuredisk-volume-tester-wlbfh": Phase="Pending", Reason="", readiness=false. Elapsed: 2.145321861s I1107 23:23:37.099] Nov 7 23:23:37.099: INFO: Pod "azuredisk-volume-tester-wlbfh": Phase="Pending", Reason="", readiness=false. Elapsed: 4.219286668s I1107 23:23:39.173] Nov 7 23:23:39.172: INFO: Pod "azuredisk-volume-tester-wlbfh": Phase="Pending", Reason="", readiness=false. Elapsed: 6.292553268s I1107 23:23:41.246] Nov 7 23:23:41.245: INFO: Pod "azuredisk-volume-tester-wlbfh": Phase="Pending", Reason="", readiness=false. Elapsed: 8.365429366s I1107 23:23:43.319] Nov 7 23:23:43.318: INFO: Pod "azuredisk-volume-tester-wlbfh": Phase="Pending", Reason="", readiness=false. Elapsed: 10.438531264s ... skipping 24 lines ... I1107 23:24:35.162] Nov 7 23:24:35.162: INFO: Pod "azuredisk-volume-tester-wlbfh": Phase="Pending", Reason="", readiness=false. Elapsed: 1m2.281854237s I1107 23:24:37.240] Nov 7 23:24:37.240: INFO: Pod "azuredisk-volume-tester-wlbfh": Phase="Pending", Reason="", readiness=false. Elapsed: 1m4.359759863s I1107 23:24:39.318] Nov 7 23:24:39.318: INFO: Pod "azuredisk-volume-tester-wlbfh": Phase="Pending", Reason="", readiness=false. Elapsed: 1m6.438343695s I1107 23:24:41.397] Nov 7 23:24:41.397: INFO: Pod "azuredisk-volume-tester-wlbfh": Phase="Pending", Reason="", readiness=false. Elapsed: 1m8.516733925s I1107 23:24:43.476] Nov 7 23:24:43.476: INFO: Pod "azuredisk-volume-tester-wlbfh": Phase="Pending", Reason="", readiness=false. Elapsed: 1m10.595957461s I1107 23:24:45.558] Nov 7 23:24:45.558: INFO: Pod "azuredisk-volume-tester-wlbfh": Phase="Pending", Reason="", readiness=false. Elapsed: 1m12.677848917s I1107 23:24:47.637] Nov 7 23:24:47.637: INFO: Pod "azuredisk-volume-tester-wlbfh": Phase="Failed", Reason="", readiness=false. Elapsed: 1m14.757000752s I1107 23:24:47.637] STEP: Saw pod failure I1107 23:24:47.638] Nov 7 23:24:47.637: INFO: Pod "azuredisk-volume-tester-wlbfh" satisfied condition "Error status code" I1107 23:24:47.638] STEP: checking that pod logs contain expected message I1107 23:24:47.721] Nov 7 23:24:47.721: INFO: deleting Pod "azuredisk-8841"/"azuredisk-volume-tester-wlbfh" I1107 23:24:47.803] Nov 7 23:24:47.803: INFO: Pod azuredisk-volume-tester-wlbfh has the following logs: touch: /mnt/test-1/data: Read-only file system I1107 23:24:47.803] I1107 23:24:47.803] STEP: Deleting pod azuredisk-volume-tester-wlbfh in namespace azuredisk-8841 I1107 23:24:47.910] Nov 7 23:24:47.910: INFO: deleting PVC "azuredisk-8841"/"pvc-m9472" ... skipping 346 lines ... I1107 23:36:57.794] Nov 7 23:36:57.793: INFO: Waiting up to 5m0s for PersistentVolumeClaims [pvc-sqrlv] to have phase Bound I1107 23:36:57.865] Nov 7 23:36:57.865: INFO: PersistentVolumeClaim pvc-sqrlv found and phase=Bound (71.937017ms) I1107 23:36:57.866] STEP: checking the PVC I1107 23:36:57.938] STEP: validating provisioned PV I1107 23:36:58.010] STEP: checking the PV I1107 23:36:58.010] STEP: deploying the pod I1107 23:36:58.086] STEP: checking that the pods command exits with an error I1107 23:36:58.087] Nov 7 23:36:58.086: INFO: Waiting up to 15m0s for pod "azuredisk-volume-tester-4dsxq" in namespace "azuredisk-5490" to be "Error status code" I1107 23:36:58.162] Nov 7 23:36:58.161: INFO: Pod "azuredisk-volume-tester-4dsxq": Phase="Pending", Reason="", readiness=false. Elapsed: 75.001939ms I1107 23:37:00.234] Nov 7 23:37:00.234: INFO: Pod "azuredisk-volume-tester-4dsxq": Phase="Pending", Reason="", readiness=false. Elapsed: 2.147849049s I1107 23:37:02.307] Nov 7 23:37:02.307: INFO: Pod "azuredisk-volume-tester-4dsxq": Phase="Pending", Reason="", readiness=false. Elapsed: 4.220783862s I1107 23:37:04.386] Nov 7 23:37:04.382: INFO: Pod "azuredisk-volume-tester-4dsxq": Phase="Pending", Reason="", readiness=false. Elapsed: 6.295672393s I1107 23:37:06.455] Nov 7 23:37:06.455: INFO: Pod "azuredisk-volume-tester-4dsxq": Phase="Pending", Reason="", readiness=false. Elapsed: 8.368294911s I1107 23:37:08.528] Nov 7 23:37:08.527: INFO: Pod "azuredisk-volume-tester-4dsxq": Phase="Pending", Reason="", readiness=false. Elapsed: 10.440947933s ... skipping 422 lines ... I1107 23:51:46.965] Nov 7 23:51:46.962: INFO: Pod "azuredisk-volume-tester-4dsxq": Phase="Pending", Reason="", readiness=false. Elapsed: 14m48.876013764s I1107 23:51:49.042] Nov 7 23:51:49.038: INFO: Pod "azuredisk-volume-tester-4dsxq": Phase="Pending", Reason="", readiness=false. Elapsed: 14m50.951452888s I1107 23:51:51.118] Nov 7 23:51:51.115: INFO: Pod "azuredisk-volume-tester-4dsxq": Phase="Pending", Reason="", readiness=false. Elapsed: 14m53.028645525s I1107 23:51:53.190] Nov 7 23:51:53.190: INFO: Pod "azuredisk-volume-tester-4dsxq": Phase="Pending", Reason="", readiness=false. Elapsed: 14m55.103430745s I1107 23:51:55.266] Nov 7 23:51:55.266: INFO: Pod "azuredisk-volume-tester-4dsxq": Phase="Pending", Reason="", readiness=false. Elapsed: 14m57.179533976s I1107 23:51:57.346] Nov 7 23:51:57.342: INFO: Pod "azuredisk-volume-tester-4dsxq": Phase="Pending", Reason="", readiness=false. Elapsed: 14m59.255989009s I1107 23:51:59.346] Nov 7 23:51:59.345: INFO: Unexpected error occurred: Gave up after waiting 15m0s for pod "azuredisk-volume-tester-4dsxq" to be "Error status code" I1107 23:51:59.346] Nov 7 23:51:59.345: INFO: deleting Pod "azuredisk-5490"/"azuredisk-volume-tester-4dsxq" I1107 23:51:59.426] Nov 7 23:51:59.424: INFO: Error getting logs for pod azuredisk-volume-tester-4dsxq: the server rejected our request for an unknown reason (get pods azuredisk-volume-tester-4dsxq) I1107 23:51:59.426] STEP: Deleting pod azuredisk-volume-tester-4dsxq in namespace azuredisk-5490 I1107 23:51:59.500] Nov 7 23:51:59.498: INFO: deleting PVC "azuredisk-5490"/"pvc-sqrlv" I1107 23:51:59.501] Nov 7 23:51:59.498: INFO: Deleting PersistentVolumeClaim "pvc-sqrlv" I1107 23:51:59.574] Nov 7 23:51:59.572: INFO: Waiting up to 5m0s for PersistentVolumeClaim azuredisk-5490 to be removed I1107 23:51:59.646] Nov 7 23:51:59.646: INFO: Claim "azuredisk-5490" in namespace "pvc-sqrlv" doesn't exist in the system I1107 23:51:59.646] STEP: deleting PV "azuredisk-5490-disk.csi.azure.com-preprovsioned-pv-qld48" ... skipping 7 lines ... I1107 23:52:20.099] Nov 7 23:52:20.099: INFO: PersistentVolume azuredisk-5490-disk.csi.azure.com-preprovsioned-pv-qld48 found and phase=Released (20.377237558s) I1107 23:52:25.172] Nov 7 23:52:25.172: INFO: PersistentVolume azuredisk-5490-disk.csi.azure.com-preprovsioned-pv-qld48 found and phase=Released (25.449992269s) I1107 23:52:30.245] Nov 7 23:52:30.244: INFO: PersistentVolume azuredisk-5490-disk.csi.azure.com-preprovsioned-pv-qld48 was removed I1107 23:52:30.245] STEP: Collecting events from namespace "azuredisk-5490". I1107 23:52:30.318] STEP: Found 5 events. I1107 23:52:30.319] Nov 7 23:52:30.318: INFO: At 2019-11-07 23:36:58 +0000 UTC - event for azuredisk-volume-tester-4dsxq: {default-scheduler } Scheduled: Successfully assigned azuredisk-5490/azuredisk-volume-tester-4dsxq to k8s-agentpool1-33686023-1 I1107 23:52:30.319] Nov 7 23:52:30.318: INFO: At 2019-11-07 23:37:13 +0000 UTC - event for azuredisk-volume-tester-4dsxq: {attachdetach-controller } FailedAttachVolume: AttachVolume.Attach failed for volume "azuredisk-5490-disk.csi.azure.com-preprovsioned-pv-qld48" : attachment timeout for volume /subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/kubetest-b280c4a7-01b1-11ea-b14a-024223255246/providers/Microsoft.Compute/disks/pre-provisioned-readOnly I1107 23:52:30.319] Nov 7 23:52:30.318: INFO: At 2019-11-07 23:38:59 +0000 UTC - event for azuredisk-volume-tester-4dsxq: {attachdetach-controller } SuccessfulAttachVolume: AttachVolume.Attach succeeded for volume "azuredisk-5490-disk.csi.azure.com-preprovsioned-pv-qld48" I1107 23:52:30.320] Nov 7 23:52:30.318: INFO: At 2019-11-07 23:39:01 +0000 UTC - event for azuredisk-volume-tester-4dsxq: {kubelet k8s-agentpool1-33686023-1} FailedMount: Unable to mount volumes for pod "azuredisk-volume-tester-4dsxq_azuredisk-5490(4cd6baf9-7ba3-417a-92b7-0826cdc47d76)": timeout expired waiting for volumes to attach or mount for pod "azuredisk-5490"/"azuredisk-volume-tester-4dsxq". list of unmounted volumes=[test-volume-1]. list of unattached volumes=[test-volume-1 default-token-xjbhw] I1107 23:52:30.320] Nov 7 23:52:30.318: INFO: At 2019-11-07 23:39:09 +0000 UTC - event for azuredisk-volume-tester-4dsxq: {kubelet k8s-agentpool1-33686023-1} FailedMount: MountVolume.MountDevice failed for volume "azuredisk-5490-disk.csi.azure.com-preprovsioned-pv-qld48" : rpc error: code = Internal desc = Failed to find disk and lun 0. azureDisk - findDiskByLun(0) failed within timeout I1107 23:52:30.391] Nov 7 23:52:30.390: INFO: POD NODE PHASE GRACE CONDITIONS I1107 23:52:30.391] Nov 7 23:52:30.390: INFO: I1107 23:52:30.606] Nov 7 23:52:30.606: INFO: I1107 23:52:30.607] Logging node info for node k8s-agentpool1-33686023-0 I1107 23:52:30.683] Nov 7 23:52:30.678: INFO: Node Info: &Node{ObjectMeta:k8s_io_apimachinery_pkg_apis_meta_v1.ObjectMeta{Name:k8s-agentpool1-33686023-0,GenerateName:,Namespace:,SelfLink:/api/v1/nodes/k8s-agentpool1-33686023-0,UID:d085a298-9974-43ae-8ed0-392cb1c46d0f,ResourceVersion:6738,Generation:0,CreationTimestamp:2019-11-07 23:05:41 +0000 UTC,DeletionTimestamp:<nil>,DeletionGracePeriodSeconds:nil,Labels:map[string]string{agentpool: agentpool1,beta.kubernetes.io/arch: amd64,beta.kubernetes.io/instance-type: Standard_D4s_v3,beta.kubernetes.io/os: linux,failure-domain.beta.kubernetes.io/region: eastus2,failure-domain.beta.kubernetes.io/zone: 1,kubernetes.azure.com/cluster: kubetest-b280c4a7-01b1-11ea-b14a-024223255246,kubernetes.azure.com/role: agent,kubernetes.io/arch: amd64,kubernetes.io/hostname: k8s-agentpool1-33686023-0,kubernetes.io/os: linux,kubernetes.io/role: agent,node-role.kubernetes.io/agent: ,storageprofile: managed,storagetier: Premium_LRS,},Annotations:map[string]string{csi.volume.kubernetes.io/nodeid: {"disk.csi.azure.com":"k8s-agentpool1-33686023-0"},node.alpha.kubernetes.io/ttl: 0,volumes.kubernetes.io/controller-managed-attach-detach: true,},OwnerReferences:[],Finalizers:[],ClusterName:,Initializers:nil,ManagedFields:[],},Spec:NodeSpec{PodCIDR:10.244.1.0/24,DoNotUse_ExternalID:,ProviderID:azure:///subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/kubetest-b280c4a7-01b1-11ea-b14a-024223255246/providers/Microsoft.Compute/virtualMachines/k8s-agentpool1-33686023-0,Unschedulable:false,Taints:[],ConfigSource:nil,},Status:NodeStatus{Capacity:ResourceList{attachable-volumes-csi-disk.csi.azure.com: {{8 0} {<nil>} 8 DecimalSI},cpu: {{4 0} {<nil>} 4 DecimalSI},ephemeral-storage: {{31036776448 0} {<nil>} BinarySI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{16797044736 0} {<nil>} 16403364Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Allocatable:ResourceList{attachable-volumes-csi-disk.csi.azure.com: {{8 0} {<nil>} 8 DecimalSI},cpu: {{4 0} {<nil>} 4 DecimalSI},ephemeral-storage: {{27933098757 0} {<nil>} 27933098757 DecimalSI},hugepages-1Gi: {{0 0} {<nil>} 0 DecimalSI},hugepages-2Mi: {{0 0} {<nil>} 0 DecimalSI},memory: {{16010612736 0} {<nil>} 15635364Ki BinarySI},pods: {{110 0} {<nil>} 110 DecimalSI},},Phase:,Conditions:[{NetworkUnavailable False 2019-11-07 23:06:29 +0000 UTC 2019-11-07 23:06:29 +0000 UTC RouteCreated RouteController created a route} {MemoryPressure False 2019-11-07 23:51:58 +0000 UTC 2019-11-07 23:05:32 +0000 UTC KubeletHasSufficientMemory kubelet has sufficient memory available} {DiskPressure False 2019-11-07 23:51:58 +0000 UTC 2019-11-07 23:05:32 +0000 UTC KubeletHasNoDiskPressure kubelet has no disk pressure} {PIDPressure False 2019-11-07 23:51:58 +0000 UTC 2019-11-07 23:05:32 +0000 UTC KubeletHasSufficientPID kubelet has sufficient PID available} {Ready True 2019-11-07 23:51:58 +0000 UTC 2019-11-07 23:06:00 +0000 UTC KubeletReady kubelet is posting ready status. AppArmor enabled}],Addresses:[{InternalIP 10.240.0.5} {Hostname k8s-agentpool1-33686023-0}],DaemonEndpoints:NodeDaemonEndpoints{KubeletEndpoint:DaemonEndpoint{Port:10250,},},NodeInfo:NodeSystemInfo{MachineID:670947c793044cfab43548499bea9509,SystemUUID:2BCAE8A9-2ABC-834B-9C47-40604E0A1479,BootID:688eddc9-3dd8-407e-b697-b5b233ea018d,KernelVersion:4.15.0-1061-azure,OSImage:Ubuntu 16.04.6 LTS,ContainerRuntimeVersion:docker://3.0.6,KubeletVersion:v1.15.0,KubeProxyVersion:v1.15.0,OperatingSystem:linux,Architecture:amd64,},Images:[{[gcrio.azureedge.net/google_containers/hyperkube-amd64@sha256:7f66ed6bd11edd309dad15db1dc906f89035cc9d9ae705c65c98264c775b9a8f gcrio.azureedge.net/google_containers/hyperkube-amd64:v1.15.0] 593352254} {[k8sprowinternal.azurecr.io/azuredisk-csi@sha256:4d1702070348b324556602fe72eefc465d6808133da293a0fb5d31793bb8b667 k8sprowinternal.azurecr.io/azuredisk-csi:e2e-759e34c20ae61fd6b5aeacace7b768eca105f4a8] 286951557} {[k8s.gcr.io/kubernetes-dashboard-amd64@sha256:0ae6b69432e78069c5ce2bcde0fe409c5c4d6f0f4d9cd50a17974fea38898747 k8s.gcr.io/kubernetes-dashboard-amd64:v1.10.1] 121711221} {[quay.io/k8scsi/csi-provisioner@sha256:d657c839dce87324fe2b677302913f9386f885f8746be7bea0ced5b0844e3433 quay.io/k8scsi/csi-provisioner:v1.3.1] 50596998} {[k8s.gcr.io/ip-masq-agent-amd64@sha256:269e0fb9d53fd37f7a135d6a55ea265a67279ba218aa148323f015cf70167340 k8s.gcr.io/ip-masq-agent-amd64:v2.3.0] 50144412} {[quay.io/k8scsi/csi-attacher@sha256:26fccd7a99d973845df1193b46ebdcc6ab8dc5f6e6be319750c471fce1742d13 quay.io/k8scsi/csi-attacher:v1.2.0] 46226754} {[quay.io/k8scsi/csi-cluster-driver-registrar@sha256:fafd75ae5442f192cfa8c2e792903aee30d5884b62e802e4464b0a895d21e3ef quay.io/k8scsi/csi-cluster-driver-registrar:v1.0.1] 45874691} {[quay.io/k8scsi/csi-snapshotter@sha256:a49e0da1af6f2bf717e41ba1eee8b5e6a1cbd66a709dd92cc43fe475fe2589eb quay.io/k8scsi/csi-snapshotter:v1.1.0] 44325352} {[k8s.gcr.io/metrics-server-amd64@sha256:49a9f12f7067d11f42c803dbe61ed2c1299959ad85cb315b25ff7eef8e6b8892 k8s.gcr.io/metrics-server-amd64:v0.2.1] 42541759} {[mcr.microsoft.com/k8s/flexvolume/keyvault-flexvolume@sha256:4fd30d43947d4a54fc89ead7985beecfd3c9b2a93a0655a373b1608ab90bd5af mcr.microsoft.com/k8s/flexvolume/keyvault-flexvolume:v0.0.7] 22909487} {[quay.io/k8scsi/livenessprobe@sha256:8c9c8a0f330acc2e558ae6624309447d02522e76aa35af23fa19b88abf4381cb quay.io/k8scsi/livenessprobe:v1.0.2] 17415381} {[quay.io/k8scsi/csi-node-driver-registrar@sha256:13daf82fb99e951a4bff8ae5fc7c17c3a8fe7130be6400990d8f6076c32d4599 quay.io/k8scsi/csi-node-driver-registrar:v1.1.0] 15815995} {[quay.io/k8scsi/livenessprobe@sha256:dde617756e0f602adc566ab71fd885f1dad451ad3fb063ac991c95a2ff47aea5 quay.io/k8scsi/livenessprobe:v1.1.0] 14967303} {[mcr.microsoft.com/k8s/flexvolume/blobfuse-flexvolume@sha256:23d8c6033f02a1ecad05127ebdc931bb871264228661bc122704b0974e4d9fdd mcr.microsoft.com/k8s/flexvolume/blobfuse-flexvolume:1.0.8] 1159025} {[k8s.gcr.io/pause-amd64@sha256:59eec8837a4d942cc19a52b8c09ea75121acc38114a2c68b98983ce9356b8610 k8s.gcr.io/pause-amd64:3.1] 742472}],VolumesInUse:[],VolumesAttached:[],Config:nil,},} I1107 23:52:30.683] Nov 7 23:52:30.679: INFO: ... skipping 81 lines ... I1107 23:52:49.606] • Failure [964.260 seconds] I1107 23:52:49.606] [azuredisk-csi-e2e] [single-az] Pre-Provisioned I1107 23:52:49.606] /go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/pre_provisioning.go:36 I1107 23:52:49.606] [env] should use a pre-provisioned volume and mount it as readOnly in a pod [It] I1107 23:52:49.606] /go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/pre_provisioning.go:66 I1107 23:52:49.607] I1107 23:52:49.607] Unexpected error: I1107 23:52:49.607] <*errors.errorString | 0xc00020e620>: { I1107 23:52:49.607] s: "Gave up after waiting 15m0s for pod \"azuredisk-volume-tester-4dsxq\" to be \"Error status code\"", I1107 23:52:49.607] } I1107 23:52:49.607] Gave up after waiting 15m0s for pod "azuredisk-volume-tester-4dsxq" to be "Error status code" I1107 23:52:49.607] occurred I1107 23:52:49.607] I1107 23:52:49.607] /go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/testsuites/testsuites.go:553 I1107 23:52:49.607] ------------------------------ I1107 23:52:49.608] [azuredisk-csi-e2e] [single-az] Pre-Provisioned I1107 23:52:49.608] [env] should use a pre-provisioned volume and retain PV with reclaimPolicy "Retain" ... skipping 44 lines ... I1107 23:53:24.858] make[1]: Leaving directory '/go/src/sigs.k8s.io/azuredisk-csi-driver' I1107 23:53:24.858] 2019/11/07 23:53:24 Azure Disk CSI Driver uninstalled I1107 23:53:24.858] I1107 23:53:24.858] I1107 23:53:24.859] Summarizing 1 Failure: I1107 23:53:24.859] I1107 23:53:24.859] [Fail] [azuredisk-csi-e2e] [single-az] Pre-Provisioned [It] [env] should use a pre-provisioned volume and mount it as readOnly in a pod I1107 23:53:24.859] /go/src/sigs.k8s.io/azuredisk-csi-driver/test/e2e/testsuites/testsuites.go:553 I1107 23:53:24.859] I1107 23:53:24.859] Ran 9 of 16 Specs in 2793.924 seconds I1107 23:53:24.859] FAIL! -- 8 Passed | 1 Failed | 0 Pending | 7 Skipped I1107 23:53:24.860] --- FAIL: TestE2E (2793.92s) I1107 23:53:24.860] FAIL I1107 23:53:24.861] FAIL sigs.k8s.io/azuredisk-csi-driver/test/e2e 2793.976s W1107 23:53:24.992] make: *** [Makefile:65: e2e-test] Error 1 W1107 23:53:24.992] 2019/11/07 23:53:24 process.go:155: Step 'make e2e-test' finished in 47m23.544141629s W1107 23:53:24.993] 2019/11/07 23:53:24 azure.go:912: Skippng DumpClusterLogs due to CCM not being enabled. W1107 23:53:24.993] 2019/11/07 23:53:24 azure.go:906: Deleting resource group: kubetest-b280c4a7-01b1-11ea-b14a-024223255246. W1108 00:02:34.026] 2019/11/08 00:02:34 process.go:96: Saved XML output to /workspace/_artifacts/junit_runner.xml. W1108 00:02:34.027] 2019/11/08 00:02:34 process.go:153: Running: bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}" W1108 00:02:34.680] 2019/11/08 00:02:34 process.go:155: Step 'bash -c . hack/lib/version.sh && KUBE_ROOT=. kube::version::get_version_vars && echo "${KUBE_GIT_VERSION-}"' finished in 653.416553ms W1108 00:02:34.680] 2019/11/08 00:02:34 main.go:319: Something went wrong: encountered 1 errors: [error during make e2e-test: exit status 2] W1108 00:02:34.685] Traceback (most recent call last): W1108 00:02:34.685] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 778, in <module> W1108 00:02:34.685] main(parse_args()) W1108 00:02:34.685] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 626, in main W1108 00:02:34.686] mode.start(runner_args) W1108 00:02:34.686] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 262, in start W1108 00:02:34.686] check_env(env, self.command, *args) W1108 00:02:34.686] File "/workspace/./test-infra/jenkins/../scenarios/kubernetes_e2e.py", line 111, in check_env W1108 00:02:34.686] subprocess.check_call(cmd, env=env) W1108 00:02:34.687] File "/usr/lib/python2.7/subprocess.py", line 190, in check_call W1108 00:02:34.687] raise CalledProcessError(retcode, cmd) W1108 00:02:34.688] subprocess.CalledProcessError: Command '('kubetest', '--dump=/workspace/_artifacts', '--gcp-service-account=/etc/service-account/service-account.json', '--up', '--down', '--test', '--deployment=acsengine', '--provider=skeleton', '--cluster=bootstrap-e2e', '--gcp-network=bootstrap-e2e', '--ginkgo-parallel=1', '--acsengine-agentpoolcount=2', '--acsengine-admin-username=azureuser', '--acsengine-creds=/etc/azure-cred/credentials', '--acsengine-orchestratorRelease=1.16', '--acsengine-mastervmsize=Standard_DS2_v2', '--acsengine-agentvmsize=Standard_D4s_v3', '--acsengine-location=eastus2', '--acsengine-public-key=/etc/azure-ssh/azure-ssh-pub', '--acsengine-template-url=https://raw.githubusercontent.com/kubernetes-sigs/cloud-provider-azure/master/tests/k8s-azure/manifest/linux.json', '--acsengine-download-url=https://github.com/Azure/aks-engine/releases/download/v0.39.1/aks-engine-v0.39.1-linux-amd64.tar.gz', '--test-azure-disk-csi-driver=True', '--timeout=420m')' returned non-zero exit status 1 E1108 00:02:34.694] Command failed I1108 00:02:34.694] process 685 exited with code 1 after 67.1m E1108 00:02:34.695] FAIL: chewong-pull-azuredisk-csi-driver-e2e-single-az I1108 00:02:34.695] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1108 00:02:35.523] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1108 00:02:35.555] process 18141 exited with code 0 after 0.0m I1108 00:02:35.555] Call: gcloud config get-value account I1108 00:02:35.810] process 18153 exited with code 0 after 0.0m I1108 00:02:35.811] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com I1108 00:02:35.811] Upload result and artifacts... I1108 00:02:35.811] Gubernator results at https://gubernator.k8s.io/build/kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azuredisk-csi-driver/180/chewong-pull-azuredisk-csi-driver-e2e-single-az/1192575885362860032 I1108 00:02:35.811] Call: gsutil ls gs://kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azuredisk-csi-driver/180/chewong-pull-azuredisk-csi-driver-e2e-single-az/1192575885362860032/artifacts W1108 00:02:37.022] CommandException: One or more URLs matched no objects. E1108 00:02:37.097] Command failed I1108 00:02:37.097] process 18165 exited with code 1 after 0.0m W1108 00:02:37.097] Remote dir gs://kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azuredisk-csi-driver/180/chewong-pull-azuredisk-csi-driver-e2e-single-az/1192575885362860032/artifacts not exist yet I1108 00:02:37.097] Call: gsutil -m -q -o GSUtil:use_magicfile=True cp -r -c -z log,txt,xml /workspace/_artifacts gs://kubernetes-upstream/pr-logs/pull/sigs.k8s.io_azuredisk-csi-driver/180/chewong-pull-azuredisk-csi-driver-e2e-single-az/1192575885362860032/artifacts I1108 00:02:38.612] process 18309 exited with code 0 after 0.0m I1108 00:02:38.612] Call: git rev-parse HEAD I1108 00:02:38.616] process 18838 exited with code 0 after 0.0m ... skipping 21 lines ...