kubernetes-sigs/azuredisk-csi-driver PR #166 pull-azuredisk-csi-driver-sanity #1179880985387339778
Recent runs || View in Spyglass
Recent runs || View in Spyglass
| Result | FAILURE |
| Tests | 0 failed / 0 succeeded |
| Started | |
| Elapsed | 23m48s |
| Revision | |
| Builder | 4dbb931c-e62a-11e9-918b-16773c0c7aed |
| Refs |
master:6b4d4209 166:e7264e0f |
| infra-commit | 5316362be |
| repo | github.com/kubernetes-sigs/azuredisk-csi-driver |
| repo-commit | 2de810f9504b359da5ad074573d8f3450ba10eeb |
| repos | {u'github.com/kubernetes-sigs/azuredisk-csi-driver': u'master:6b4d4209385f8a3b04a39f1970017a27f2667a9a,166:e7264e0f9abbf0764bb810470367384df2eed149'} |
... skipping 318 lines ... I1003 22:19:40.827] Running Suite: CSI Driver Test Suite I1003 22:19:40.827] ==================================== I1003 22:19:40.827] Random Seed: 1570141180 I1003 22:19:40.828] Will run 56 of 57 specs I1003 22:19:40.828] I1003 22:19:40.828] DeleteSnapshot [Controller Server] I1003 22:19:40.828] should fail when no snapshot id is provided I1003 22:19:40.828] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1439 I1003 22:19:40.828] STEP: connecting to CSI driver I1003 22:19:40.829] I1003 22:19:40.829152 8830 azure.go:31] AZURE_CREDENTIAL_FILE env var set as /tmp/azure.json I1003 22:19:40.830] I1003 22:19:40.829748 8830 azure_auth.go:99] azure: using client_id+client_secret to retrieve access token I1003 22:19:40.831] I1003 22:19:40.831012 8830 mount_linux.go:160] Detected OS without systemd I1003 22:19:40.831] I1003 22:19:40.831046 8830 driver.go:80] Enabling controller service capability: CREATE_DELETE_VOLUME ... skipping 8 lines ... I1003 22:19:42.631] I1003 22:19:42.631668 8830 utils.go:113] GRPC request: I1003 22:19:42.632] I1003 22:19:42.631694 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:19:42.632] I1003 22:19:42.631703 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:19:42.633] I1003 22:19:42.632764 8830 utils.go:112] GRPC call: /csi.v1.Controller/DeleteSnapshot I1003 22:19:42.636] I1003 22:19:42.632786 8830 utils.go:113] GRPC request: I1003 22:19:42.636] I1003 22:19:42.632795 8830 controllerserver.go:491] DeleteSnapshot called with request { map[] {} [] 0} I1003 22:19:42.637] E1003 22:19:42.632813 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Snapshot ID must be provided I1003 22:19:42.637] • I1003 22:19:42.638] ------------------------------ I1003 22:19:42.638] DeleteSnapshot [Controller Server] I1003 22:19:42.639] should succeed when an invalid snapshot id is used I1003 22:19:42.639] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1455 I1003 22:19:42.639] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock ... skipping 131 lines ... I1003 22:20:12.469] I1003 22:20:12.155947 8830 utils.go:113] GRPC request: I1003 22:20:12.469] I1003 22:20:12.155955 8830 nodeserver.go:281] Using default NodeGetInfo I1003 22:20:12.473] I1003 22:20:12.473549 8830 utils.go:119] GRPC response: node_id:"sanity-test-node" max_volumes_per_node:8 I1003 22:20:12.474] • I1003 22:20:12.475] ------------------------------ I1003 22:20:12.475] Node Service NodePublishVolume I1003 22:20:12.475] should fail when no volume id is provided I1003 22:20:12.475] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:158 I1003 22:20:12.475] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:20:12.476] STEP: creating mount and staging directories I1003 22:20:12.476] I1003 22:20:12.474582 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:20:12.476] I1003 22:20:12.474623 8830 utils.go:113] GRPC request: I1003 22:20:12.476] I1003 22:20:12.474633 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:20:12.477] I1003 22:20:12.474639 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:20:12.482] I1003 22:20:12.478373 8830 utils.go:112] GRPC call: /csi.v1.Node/NodeGetCapabilities I1003 22:20:12.482] I1003 22:20:12.478471 8830 utils.go:113] GRPC request: I1003 22:20:12.482] I1003 22:20:12.478504 8830 nodeserver.go:272] Using default NodeGetCapabilities I1003 22:20:12.482] I1003 22:20:12.478549 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:STAGE_UNSTAGE_VOLUME > > I1003 22:20:12.483] I1003 22:20:12.479396 8830 utils.go:112] GRPC call: /csi.v1.Node/NodePublishVolume I1003 22:20:12.483] I1003 22:20:12.479424 8830 utils.go:113] GRPC request: I1003 22:20:12.483] E1003 22:20:12.479433 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request I1003 22:20:12.483] • I1003 22:20:12.483] ------------------------------ I1003 22:20:12.484] Node Service NodePublishVolume I1003 22:20:12.484] should fail when no target path is provided I1003 22:20:12.484] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:172 I1003 22:20:12.484] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:20:12.484] STEP: creating mount and staging directories I1003 22:20:12.485] I1003 22:20:12.479819 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:20:12.485] I1003 22:20:12.479845 8830 utils.go:113] GRPC request: I1003 22:20:12.485] I1003 22:20:12.479854 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:20:12.485] I1003 22:20:12.479860 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:20:12.486] I1003 22:20:12.480180 8830 utils.go:112] GRPC call: /csi.v1.Node/NodeGetCapabilities I1003 22:20:12.486] I1003 22:20:12.480204 8830 utils.go:113] GRPC request: I1003 22:20:12.486] I1003 22:20:12.480211 8830 nodeserver.go:272] Using default NodeGetCapabilities I1003 22:20:12.486] I1003 22:20:12.480217 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:STAGE_UNSTAGE_VOLUME > > I1003 22:20:12.487] I1003 22:20:12.480569 8830 utils.go:112] GRPC call: /csi.v1.Node/NodePublishVolume I1003 22:20:12.487] I1003 22:20:12.480648 8830 utils.go:113] GRPC request: volume_id:"id" I1003 22:20:12.487] E1003 22:20:12.480684 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request I1003 22:20:12.487] • I1003 22:20:12.487] ------------------------------ I1003 22:20:12.488] Node Service NodePublishVolume I1003 22:20:12.488] should fail when no volume capability is provided I1003 22:20:12.488] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:187 I1003 22:20:12.488] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:20:12.489] STEP: creating mount and staging directories I1003 22:20:12.489] I1003 22:20:12.480970 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:20:12.489] I1003 22:20:12.480990 8830 utils.go:113] GRPC request: I1003 22:20:12.489] I1003 22:20:12.480997 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:20:12.494] I1003 22:20:12.481003 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:20:12.494] I1003 22:20:12.481348 8830 utils.go:112] GRPC call: /csi.v1.Node/NodeGetCapabilities I1003 22:20:12.494] I1003 22:20:12.481387 8830 utils.go:113] GRPC request: I1003 22:20:12.495] I1003 22:20:12.481395 8830 nodeserver.go:272] Using default NodeGetCapabilities I1003 22:20:12.495] I1003 22:20:12.481402 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:STAGE_UNSTAGE_VOLUME > > I1003 22:20:12.495] I1003 22:20:12.481891 8830 utils.go:112] GRPC call: /csi.v1.Node/NodePublishVolume I1003 22:20:12.495] I1003 22:20:12.481913 8830 utils.go:113] GRPC request: volume_id:"id" target_path:"/tmp/csi" I1003 22:20:12.496] E1003 22:20:12.481940 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Volume capability missing in request I1003 22:20:12.496] • I1003 22:20:12.496] ------------------------------ I1003 22:20:12.496] Node Service NodeUnpublishVolume I1003 22:20:12.496] should fail when no volume id is provided I1003 22:20:12.497] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:205 I1003 22:20:12.497] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:20:12.497] STEP: creating mount and staging directories I1003 22:20:12.497] I1003 22:20:12.482838 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:20:12.501] I1003 22:20:12.482874 8830 utils.go:113] GRPC request: I1003 22:20:12.501] I1003 22:20:12.482920 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:20:12.501] I1003 22:20:12.482942 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:20:12.501] I1003 22:20:12.483313 8830 utils.go:112] GRPC call: /csi.v1.Node/NodeGetCapabilities I1003 22:20:12.502] I1003 22:20:12.483358 8830 utils.go:113] GRPC request: I1003 22:20:12.502] I1003 22:20:12.483380 8830 nodeserver.go:272] Using default NodeGetCapabilities I1003 22:20:12.502] I1003 22:20:12.483402 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:STAGE_UNSTAGE_VOLUME > > I1003 22:20:12.502] I1003 22:20:12.483851 8830 utils.go:112] GRPC call: /csi.v1.Node/NodeUnpublishVolume I1003 22:20:12.502] I1003 22:20:12.483891 8830 utils.go:113] GRPC request: I1003 22:20:12.503] E1003 22:20:12.483915 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request I1003 22:20:12.503] • I1003 22:20:12.503] ------------------------------ I1003 22:20:12.503] Node Service NodeUnpublishVolume I1003 22:20:12.503] should fail when no target path is provided I1003 22:20:12.504] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:217 I1003 22:20:12.504] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:20:12.504] STEP: creating mount and staging directories I1003 22:20:12.504] I1003 22:20:12.484271 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:20:12.504] I1003 22:20:12.484307 8830 utils.go:113] GRPC request: I1003 22:20:12.505] I1003 22:20:12.484328 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:20:12.510] I1003 22:20:12.484348 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:20:12.510] I1003 22:20:12.484716 8830 utils.go:112] GRPC call: /csi.v1.Node/NodeGetCapabilities I1003 22:20:12.510] I1003 22:20:12.484761 8830 utils.go:113] GRPC request: I1003 22:20:12.510] I1003 22:20:12.484783 8830 nodeserver.go:272] Using default NodeGetCapabilities I1003 22:20:12.511] I1003 22:20:12.484803 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:STAGE_UNSTAGE_VOLUME > > I1003 22:20:12.511] I1003 22:20:12.485151 8830 utils.go:112] GRPC call: /csi.v1.Node/NodeUnpublishVolume I1003 22:20:12.511] I1003 22:20:12.485187 8830 utils.go:113] GRPC request: volume_id:"id" I1003 22:20:12.511] E1003 22:20:12.485209 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Target path missing in request I1003 22:20:12.511] • I1003 22:20:12.511] ------------------------------ I1003 22:20:12.512] Node Service NodeStageVolume I1003 22:20:12.512] should fail when no volume id is provided I1003 22:20:12.512] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:245 I1003 22:20:12.512] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:20:12.512] STEP: creating mount and staging directories I1003 22:20:12.512] I1003 22:20:12.485552 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:20:12.513] I1003 22:20:12.485588 8830 utils.go:113] GRPC request: I1003 22:20:12.513] I1003 22:20:12.485609 8830 controllerserver.go:415] Using default ControllerGetCapabilities ... skipping 2 lines ... I1003 22:20:12.515] I1003 22:20:12.486066 8830 utils.go:113] GRPC request: I1003 22:20:12.515] I1003 22:20:12.486088 8830 nodeserver.go:272] Using default NodeGetCapabilities I1003 22:20:12.515] I1003 22:20:12.486108 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:STAGE_UNSTAGE_VOLUME > > I1003 22:20:12.515] I1003 22:20:12.486641 8830 utils.go:112] GRPC call: /csi.v1.Node/NodeStageVolume I1003 22:20:12.516] I1003 22:20:12.486715 8830 utils.go:113] GRPC request: publish_context:<key:"device" value:"/dev/mock" > staging_target_path:"/tmp/csi" volume_capability:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > I1003 22:20:12.516] I1003 22:20:12.486744 8830 nodeserver.go:50] NodeStageVolume: called with args {VolumeId: PublishContext:map[device:/dev/mock] StagingTargetPath:/tmp/csi VolumeCapability:mount:<> access_mode:<mode:SINGLE_NODE_WRITER > Secrets:map[] VolumeContext:map[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0} I1003 22:20:12.516] E1003 22:20:12.486810 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Volume ID not provided I1003 22:20:12.517] • I1003 22:20:12.517] ------------------------------ I1003 22:20:12.517] Node Service NodeStageVolume I1003 22:20:12.517] should fail when no staging target path is provided I1003 22:20:12.518] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:271 I1003 22:20:12.518] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:20:12.518] STEP: creating mount and staging directories I1003 22:20:12.519] I1003 22:20:12.487166 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:20:12.519] I1003 22:20:12.487203 8830 utils.go:113] GRPC request: I1003 22:20:12.519] I1003 22:20:12.487224 8830 controllerserver.go:415] Using default ControllerGetCapabilities ... skipping 2 lines ... I1003 22:20:12.520] I1003 22:20:12.487605 8830 utils.go:113] GRPC request: I1003 22:20:12.520] I1003 22:20:12.487655 8830 nodeserver.go:272] Using default NodeGetCapabilities I1003 22:20:12.520] I1003 22:20:12.487681 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:STAGE_UNSTAGE_VOLUME > > I1003 22:20:12.520] I1003 22:20:12.488130 8830 utils.go:112] GRPC call: /csi.v1.Node/NodeStageVolume I1003 22:20:12.521] I1003 22:20:12.488166 8830 utils.go:113] GRPC request: volume_id:"id" publish_context:<key:"device" value:"/dev/mock" > volume_capability:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > I1003 22:20:12.521] I1003 22:20:12.488190 8830 nodeserver.go:50] NodeStageVolume: called with args {VolumeId:id PublishContext:map[device:/dev/mock] StagingTargetPath: VolumeCapability:mount:<> access_mode:<mode:SINGLE_NODE_WRITER > Secrets:map[] VolumeContext:map[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0} I1003 22:20:12.521] E1003 22:20:12.488240 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Staging target not provided I1003 22:20:12.522] • I1003 22:20:12.522] ------------------------------ I1003 22:20:12.522] Node Service NodeStageVolume I1003 22:20:12.522] should fail when no volume capability is provided I1003 22:20:12.522] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:297 I1003 22:20:12.522] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:20:12.523] STEP: creating mount and staging directories I1003 22:20:12.523] I1003 22:20:12.488605 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:20:12.523] I1003 22:20:12.488678 8830 utils.go:113] GRPC request: I1003 22:20:12.523] I1003 22:20:12.488704 8830 controllerserver.go:415] Using default ControllerGetCapabilities ... skipping 2 lines ... I1003 22:20:12.524] I1003 22:20:12.489135 8830 utils.go:113] GRPC request: I1003 22:20:12.524] I1003 22:20:12.489152 8830 nodeserver.go:272] Using default NodeGetCapabilities I1003 22:20:12.525] I1003 22:20:12.489169 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:STAGE_UNSTAGE_VOLUME > > I1003 22:20:12.525] I1003 22:20:12.489515 8830 utils.go:112] GRPC call: /csi.v1.Node/NodeStageVolume I1003 22:20:12.525] I1003 22:20:12.489554 8830 utils.go:113] GRPC request: volume_id:"id" publish_context:<key:"device" value:"/dev/mock" > staging_target_path:"/tmp/csi" I1003 22:20:12.525] I1003 22:20:12.489578 8830 nodeserver.go:50] NodeStageVolume: called with args {VolumeId:id PublishContext:map[device:/dev/mock] StagingTargetPath:/tmp/csi VolumeCapability:<nil> Secrets:map[] VolumeContext:map[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0} I1003 22:20:12.526] E1003 22:20:12.489611 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Volume capability not provided I1003 22:20:12.526] • I1003 22:20:12.526] ------------------------------ I1003 22:20:12.526] Node Service NodeUnstageVolume I1003 22:20:12.526] should fail when no volume id is provided I1003 22:20:12.526] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:324 I1003 22:20:12.527] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:20:12.527] STEP: creating mount and staging directories I1003 22:20:12.527] I1003 22:20:12.490003 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:20:12.527] I1003 22:20:12.490052 8830 utils.go:113] GRPC request: I1003 22:20:12.527] I1003 22:20:12.490076 8830 controllerserver.go:415] Using default ControllerGetCapabilities ... skipping 2 lines ... I1003 22:20:12.528] I1003 22:20:12.490418 8830 utils.go:113] GRPC request: I1003 22:20:12.528] I1003 22:20:12.490424 8830 nodeserver.go:272] Using default NodeGetCapabilities I1003 22:20:12.529] I1003 22:20:12.490430 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:STAGE_UNSTAGE_VOLUME > > I1003 22:20:12.529] I1003 22:20:12.490926 8830 utils.go:112] GRPC call: /csi.v1.Node/NodeUnstageVolume I1003 22:20:12.529] I1003 22:20:12.490947 8830 utils.go:113] GRPC request: staging_target_path:"/tmp/csi" I1003 22:20:12.529] I1003 22:20:12.490955 8830 nodeserver.go:146] NodeUnstageVolume: called with args {VolumeId: StagingTargetPath:/tmp/csi XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0} I1003 22:20:12.529] E1003 22:20:12.490980 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Volume ID not provided I1003 22:20:12.530] • I1003 22:20:12.530] ------------------------------ I1003 22:20:12.530] Node Service NodeUnstageVolume I1003 22:20:12.530] should fail when no staging target path is provided I1003 22:20:12.530] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/node.go:338 I1003 22:20:12.530] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:20:12.531] STEP: creating mount and staging directories I1003 22:20:12.531] I1003 22:20:12.491250 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:20:12.531] I1003 22:20:12.491271 8830 utils.go:113] GRPC request: I1003 22:20:12.531] I1003 22:20:12.491278 8830 controllerserver.go:415] Using default ControllerGetCapabilities ... skipping 2 lines ... I1003 22:20:12.532] I1003 22:20:12.491603 8830 utils.go:113] GRPC request: I1003 22:20:12.532] I1003 22:20:12.491610 8830 nodeserver.go:272] Using default NodeGetCapabilities I1003 22:20:12.532] I1003 22:20:12.491616 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:STAGE_UNSTAGE_VOLUME > > I1003 22:20:12.532] I1003 22:20:12.492163 8830 utils.go:112] GRPC call: /csi.v1.Node/NodeUnstageVolume I1003 22:20:12.533] I1003 22:20:12.492199 8830 utils.go:113] GRPC request: volume_id:"id" I1003 22:20:12.533] I1003 22:20:12.492242 8830 nodeserver.go:146] NodeUnstageVolume: called with args {VolumeId:id StagingTargetPath: XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0} I1003 22:20:12.533] E1003 22:20:12.492270 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Staging target not provided I1003 22:20:12.533] •S I1003 22:20:12.533] ------------------------------ I1003 22:20:12.533] ListSnapshots [Controller Server] I1003 22:20:12.534] should return appropriate values (no optional values added) I1003 22:20:12.534] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1186 I1003 22:20:12.534] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock ... skipping 403 lines ... I1003 22:23:39.088] I1003 22:23:39.088] ListVolumes not supported I1003 22:23:39.089] I1003 22:23:39.089] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:157 I1003 22:23:39.089] ------------------------------ I1003 22:23:39.089] Controller Service CreateVolume I1003 22:23:39.089] should fail when no name is provided I1003 22:23:39.089] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:186 I1003 22:23:39.090] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:23:39.090] STEP: creating mount and staging directories I1003 22:23:39.090] I1003 22:23:39.081100 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:23:39.090] I1003 22:23:39.081121 8830 utils.go:113] GRPC request: I1003 22:23:39.090] I1003 22:23:39.081127 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:23:39.090] I1003 22:23:39.081133 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:23:39.091] I1003 22:23:39.081521 8830 utils.go:112] GRPC call: /csi.v1.Controller/CreateVolume I1003 22:23:39.091] I1003 22:23:39.081545 8830 utils.go:113] GRPC request: I1003 22:23:39.091] E1003 22:23:39.081553 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Name must be provided I1003 22:23:39.091] • I1003 22:23:39.091] ------------------------------ I1003 22:23:39.091] Controller Service CreateVolume I1003 22:23:39.092] should fail when no volume capabilities are provided I1003 22:23:39.092] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:202 I1003 22:23:39.092] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:23:39.092] STEP: creating mount and staging directories I1003 22:23:39.092] I1003 22:23:39.081855 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:23:39.092] I1003 22:23:39.081870 8830 utils.go:113] GRPC request: I1003 22:23:39.092] I1003 22:23:39.081875 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:23:39.093] I1003 22:23:39.081879 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:23:39.093] I1003 22:23:39.082237 8830 utils.go:112] GRPC call: /csi.v1.Controller/CreateVolume I1003 22:23:39.093] I1003 22:23:39.082262 8830 utils.go:113] GRPC request: name:"sanity-controller-create-no-volume-capabilities-8A74F569-13D1D454" I1003 22:23:39.093] E1003 22:23:39.082269 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = CreateVolume Volume capabilities must be provided I1003 22:23:39.093] • I1003 22:23:39.094] ------------------------------ I1003 22:23:39.094] Controller Service CreateVolume I1003 22:23:39.094] should return appropriate values SingleNodeWriter NoCapacity Type:Mount I1003 22:23:39.094] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:220 I1003 22:23:39.094] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock ... skipping 57 lines ... I1003 22:24:13.868] CreateVolume I1003 22:24:13.869] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:179 I1003 22:24:13.869] should return appropriate values SingleNodeWriter WithCapacity 1Gi Type:Mount I1003 22:24:13.869] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:262 I1003 22:24:13.869] ------------------------------ I1003 22:24:13.870] Controller Service CreateVolume I1003 22:24:13.870] should not fail when requesting to create a volume with already existing name and same capacity. I1003 22:24:13.870] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:311 I1003 22:24:13.871] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:24:13.871] STEP: creating mount and staging directories I1003 22:24:13.871] I1003 22:24:13.020035 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:24:13.871] I1003 22:24:13.020061 8830 utils.go:113] GRPC request: I1003 22:24:13.872] I1003 22:24:13.020069 8830 controllerserver.go:415] Using default ControllerGetCapabilities ... skipping 23 lines ... I1003 22:24:30.080] I1003 22:24:30.080] • [SLOW TEST:17.055 seconds] I1003 22:24:30.080] Controller Service I1003 22:24:30.080] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44 I1003 22:24:30.081] CreateVolume I1003 22:24:30.081] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:179 I1003 22:24:30.081] should not fail when requesting to create a volume with already existing name and same capacity. I1003 22:24:30.081] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:311 I1003 22:24:30.081] ------------------------------ I1003 22:24:30.081] Controller Service CreateVolume I1003 22:24:30.081] should fail when requesting to create a volume with already existing name and different capacity. I1003 22:24:30.082] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:385 I1003 22:24:30.082] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:24:30.082] STEP: creating mount and staging directories I1003 22:24:30.082] I1003 22:24:30.079540 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:24:30.082] I1003 22:24:30.079560 8830 utils.go:113] GRPC request: I1003 22:24:30.082] I1003 22:24:30.079566 8830 controllerserver.go:415] Using default ControllerGetCapabilities ... skipping 5 lines ... I1003 22:24:30.173] I1003 22:24:30.172728 8830 azuredisk.go:244] azureDisk - creating new managed Name:sanity-controller-create-twice-different-8A74F569-13D1D454 StorageAccountType:Standard_LRS Size:10 I1003 22:24:31.008] I1003 22:24:31.008506 8830 azuredisk.go:348] azureDisk - created new MD Name:sanity-controller-create-twice-different-8A74F569-13D1D454 StorageAccountType:Standard_LRS Size:10 I1003 22:24:31.009] I1003 22:24:31.008540 8830 controllerserver.go:222] create azure disk(sanity-controller-create-twice-different-8A74F569-13D1D454) account type(Standard_LRS) rg(azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068) location() size(10) successfully I1003 22:24:31.009] I1003 22:24:31.008550 8830 utils.go:119] GRPC response: volume:<capacity_bytes:10737418240 volume_id:"/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/sanity-controller-create-twice-different-8A74F569-13D1D454" accessible_topology:<segments:<key:"topology.disk.csi.azure.com/zone" value:"" > > > I1003 22:24:31.009] I1003 22:24:31.009102 8830 utils.go:112] GRPC call: /csi.v1.Controller/CreateVolume I1003 22:24:31.009] I1003 22:24:31.009126 8830 utils.go:113] GRPC request: name:"sanity-controller-create-twice-different-8A74F569-13D1D454" capacity_range:<required_bytes:21474836480 limit_bytes:21474836480 > volume_capabilities:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > I1003 22:24:31.099] E1003 22:24:31.098170 8830 utils.go:117] GRPC error: rpc error: code = AlreadyExists desc = the request volume already exists, but its capacity(10) is different from (20) I1003 22:24:31.099] STEP: cleaning up deleting the volume I1003 22:24:31.100] I1003 22:24:31.098964 8830 utils.go:112] GRPC call: /csi.v1.Controller/DeleteVolume I1003 22:24:31.100] I1003 22:24:31.099005 8830 utils.go:113] GRPC request: volume_id:"/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/sanity-controller-create-twice-different-8A74F569-13D1D454" I1003 22:24:31.100] I1003 22:24:31.099020 8830 controllerserver.go:277] deleting azure disk(/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/sanity-controller-create-twice-different-8A74F569-13D1D454) I1003 22:24:36.582] I1003 22:24:36.581686 8830 azure_managedDiskController.go:195] azureDisk - deleted a managed disk: /subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/sanity-controller-create-twice-different-8A74F569-13D1D454 I1003 22:24:36.582] I1003 22:24:36.581752 8830 controllerserver.go:287] delete azure disk(/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/sanity-controller-create-twice-different-8A74F569-13D1D454) successfully I1003 22:24:36.582] I1003 22:24:36.581764 8830 utils.go:119] GRPC response: I1003 22:24:36.583] I1003 22:24:36.583] • [SLOW TEST:6.507 seconds] I1003 22:24:36.583] Controller Service I1003 22:24:36.583] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44 I1003 22:24:36.583] CreateVolume I1003 22:24:36.583] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:179 I1003 22:24:36.584] should fail when requesting to create a volume with already existing name and different capacity. I1003 22:24:36.584] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:385 I1003 22:24:36.584] ------------------------------ I1003 22:24:36.584] Controller Service CreateVolume I1003 22:24:36.584] should not fail when creating volume with maximum-length name I1003 22:24:36.584] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:460 I1003 22:24:36.585] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:24:36.585] STEP: creating mount and staging directories I1003 22:24:36.585] I1003 22:24:36.582487 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:24:36.585] I1003 22:24:36.582519 8830 utils.go:113] GRPC request: I1003 22:24:36.585] I1003 22:24:36.582527 8830 controllerserver.go:415] Using default ControllerGetCapabilities ... skipping 17 lines ... I1003 22:24:53.043] I1003 22:24:53.043] • [SLOW TEST:16.460 seconds] I1003 22:24:53.043] Controller Service I1003 22:24:53.043] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44 I1003 22:24:53.043] CreateVolume I1003 22:24:53.044] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:179 I1003 22:24:53.044] should not fail when creating volume with maximum-length name I1003 22:24:53.044] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:460 I1003 22:24:53.044] ------------------------------ I1003 22:24:53.044] Controller Service DeleteVolume I1003 22:24:53.044] should fail when no volume id is provided I1003 22:24:53.045] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:519 I1003 22:24:53.045] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:24:53.045] STEP: creating mount and staging directories I1003 22:24:53.045] I1003 22:24:53.042633 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:24:53.045] I1003 22:24:53.042676 8830 utils.go:113] GRPC request: I1003 22:24:53.045] I1003 22:24:53.042686 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:24:53.046] I1003 22:24:53.042707 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:24:53.046] I1003 22:24:53.043117 8830 utils.go:112] GRPC call: /csi.v1.Controller/DeleteVolume I1003 22:24:53.046] I1003 22:24:53.043150 8830 utils.go:113] GRPC request: I1003 22:24:53.046] E1003 22:24:53.043158 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request I1003 22:24:53.046] • I1003 22:24:53.046] ------------------------------ I1003 22:24:53.046] Controller Service DeleteVolume I1003 22:24:53.046] should succeed when an invalid volume id is used I1003 22:24:53.047] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:534 I1003 22:24:53.047] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:24:53.047] STEP: creating mount and staging directories I1003 22:24:53.047] I1003 22:24:53.043465 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:24:53.047] I1003 22:24:53.043494 8830 utils.go:113] GRPC request: I1003 22:24:53.047] I1003 22:24:53.043502 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:24:53.047] I1003 22:24:53.043508 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:24:53.048] I1003 22:24:53.043907 8830 utils.go:112] GRPC call: /csi.v1.Controller/DeleteVolume I1003 22:24:53.048] I1003 22:24:53.043935 8830 utils.go:113] GRPC request: volume_id:"reallyfakevolumeid" I1003 22:24:53.048] E1003 22:24:53.043950 8830 controllerserver.go:273] validateDiskURI(reallyfakevolumeid) in DeleteVolume failed with error: Inavlid DiskURI: reallyfakevolumeid, correct format: [/subscriptions/{sub-id}/resourcegroups/{group-name}/providers/microsoft.compute/disks/{disk-id}] I1003 22:24:53.048] I1003 22:24:53.043961 8830 utils.go:119] GRPC response: I1003 22:24:53.048] • I1003 22:24:53.048] ------------------------------ I1003 22:24:53.049] Controller Service DeleteVolume I1003 22:24:53.049] should return appropriate values (no optional values added) I1003 22:24:53.049] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:546 ... skipping 25 lines ... I1003 22:25:15.797] DeleteVolume I1003 22:25:15.798] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:512 I1003 22:25:15.798] should return appropriate values (no optional values added) I1003 22:25:15.798] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:546 I1003 22:25:15.798] ------------------------------ I1003 22:25:15.799] Controller Service ValidateVolumeCapabilities I1003 22:25:15.800] should fail when no volume id is provided I1003 22:25:15.802] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:592 I1003 22:25:15.802] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:25:15.802] STEP: creating mount and staging directories I1003 22:25:15.803] I1003 22:25:09.778782 8830 utils.go:112] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities I1003 22:25:15.803] I1003 22:25:09.778807 8830 utils.go:113] GRPC request: I1003 22:25:15.803] E1003 22:25:09.778815 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Volume ID missing in request I1003 22:25:15.803] • I1003 22:25:15.803] ------------------------------ I1003 22:25:15.804] Controller Service ValidateVolumeCapabilities I1003 22:25:15.804] should fail when no volume capabilities are provided I1003 22:25:15.804] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:604 I1003 22:25:15.804] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:25:15.805] STEP: creating mount and staging directories I1003 22:25:15.805] I1003 22:25:09.779196 8830 utils.go:112] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities I1003 22:25:15.805] I1003 22:25:09.779213 8830 utils.go:113] GRPC request: volume_id:"id" I1003 22:25:15.805] E1003 22:25:09.779218 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Volume capabilities missing in request I1003 22:25:15.805] • I1003 22:25:15.806] ------------------------------ I1003 22:25:15.806] Controller Service ValidateVolumeCapabilities I1003 22:25:15.806] should return appropriate values (no optional values added) I1003 22:25:15.806] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:618 I1003 22:25:15.806] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock ... skipping 24 lines ... I1003 22:25:26.311] ValidateVolumeCapabilities I1003 22:25:26.314] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:591 I1003 22:25:26.315] should return appropriate values (no optional values added) I1003 22:25:26.315] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:618 I1003 22:25:26.315] ------------------------------ I1003 22:25:26.315] Controller Service ValidateVolumeCapabilities I1003 22:25:26.315] should fail when the requested volume does not exist I1003 22:25:26.315] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:687 I1003 22:25:26.315] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:25:26.316] STEP: creating mount and staging directories I1003 22:25:26.316] I1003 22:25:26.310140 8830 utils.go:112] GRPC call: /csi.v1.Controller/ValidateVolumeCapabilities I1003 22:25:26.316] I1003 22:25:26.310162 8830 utils.go:113] GRPC request: volume_id:"some-vol-id" volume_capabilities:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > I1003 22:25:26.316] E1003 22:25:26.310179 8830 utils.go:117] GRPC error: rpc error: code = NotFound desc = Volume not found, failed with error: could not get disk name from some-vol-id, correct format: .*/subscriptions/(?:.*)/resourceGroups/(?:.*)/providers/Microsoft.Compute/disks/(.+) I1003 22:25:26.316] • I1003 22:25:26.316] ------------------------------ I1003 22:25:26.316] Controller Service ControllerPublishVolume I1003 22:25:26.317] should fail when no volume id is provided I1003 22:25:26.317] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:720 I1003 22:25:26.317] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:25:26.317] STEP: creating mount and staging directories I1003 22:25:26.317] I1003 22:25:26.310593 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:25:26.317] I1003 22:25:26.310621 8830 utils.go:113] GRPC request: I1003 22:25:26.317] I1003 22:25:26.310628 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:25:26.318] I1003 22:25:26.310635 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:25:26.318] I1003 22:25:26.311130 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerPublishVolume I1003 22:25:26.318] I1003 22:25:26.311157 8830 utils.go:113] GRPC request: I1003 22:25:26.318] I1003 22:25:26.311167 8830 controllerserver.go:294] ControllerPublishVolume: called with args {VolumeId: NodeId: VolumeCapability:<nil> Readonly:false Secrets:map[] VolumeContext:map[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0} I1003 22:25:26.318] E1003 22:25:26.311205 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Volume ID not provided I1003 22:25:26.318] • I1003 22:25:26.318] ------------------------------ I1003 22:25:26.319] Controller Service ControllerPublishVolume I1003 22:25:26.319] should fail when no node id is provided I1003 22:25:26.319] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:735 I1003 22:25:26.319] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:25:26.319] STEP: creating mount and staging directories I1003 22:25:26.319] I1003 22:25:26.311646 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:25:26.319] I1003 22:25:26.311672 8830 utils.go:113] GRPC request: I1003 22:25:26.319] I1003 22:25:26.311679 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:25:26.320] I1003 22:25:26.311685 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:25:26.320] I1003 22:25:26.313346 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerPublishVolume I1003 22:25:26.320] I1003 22:25:26.313363 8830 utils.go:113] GRPC request: volume_id:"id" I1003 22:25:26.320] I1003 22:25:26.313369 8830 controllerserver.go:294] ControllerPublishVolume: called with args {VolumeId:id NodeId: VolumeCapability:<nil> Readonly:false Secrets:map[] VolumeContext:map[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0} I1003 22:25:26.320] E1003 22:25:26.313381 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Volume capability not provided I1003 22:25:26.320] • I1003 22:25:26.320] ------------------------------ I1003 22:25:26.321] Controller Service ControllerPublishVolume I1003 22:25:26.321] should fail when no volume capability is provided I1003 22:25:26.321] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:751 I1003 22:25:26.321] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:25:26.321] STEP: creating mount and staging directories I1003 22:25:26.321] I1003 22:25:26.313646 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:25:26.321] I1003 22:25:26.313662 8830 utils.go:113] GRPC request: I1003 22:25:26.321] I1003 22:25:26.313667 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:25:26.322] I1003 22:25:26.313670 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:25:26.322] I1003 22:25:26.313984 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerPublishVolume I1003 22:25:26.322] I1003 22:25:26.314001 8830 utils.go:113] GRPC request: volume_id:"id" node_id:"fakenode" I1003 22:25:26.322] I1003 22:25:26.314007 8830 controllerserver.go:294] ControllerPublishVolume: called with args {VolumeId:id NodeId:fakenode VolumeCapability:<nil> Readonly:false Secrets:map[] VolumeContext:map[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0} I1003 22:25:26.322] E1003 22:25:26.314019 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Volume capability not provided I1003 22:25:26.322] • I1003 22:25:26.323] ------------------------------ I1003 22:25:26.323] Controller Service ControllerPublishVolume I1003 22:25:26.323] should return appropriate values (no optional values added) I1003 22:25:26.323] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:768 I1003 22:25:26.323] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock ... skipping 53 lines ... I1003 22:26:06.010] ControllerPublishVolume I1003 22:26:06.011] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:713 I1003 22:26:06.011] should return appropriate values (no optional values added) I1003 22:26:06.012] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:768 I1003 22:26:06.012] ------------------------------ I1003 22:26:06.012] Controller Service ControllerPublishVolume I1003 22:26:06.013] should fail when the volume does not exist I1003 22:26:06.013] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:857 I1003 22:26:06.013] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:26:06.014] STEP: creating mount and staging directories I1003 22:26:06.014] I1003 22:26:05.659346 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:26:06.014] I1003 22:26:05.659370 8830 utils.go:113] GRPC request: I1003 22:26:06.018] I1003 22:26:05.659376 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:26:06.018] I1003 22:26:05.659381 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:26:06.018] STEP: calling controller publish on a non-existent volume I1003 22:26:06.019] I1003 22:26:05.659989 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerPublishVolume I1003 22:26:06.019] I1003 22:26:05.660008 8830 utils.go:113] GRPC request: volume_id:"some-vol-id" node_id:"some-node-id" volume_capability:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > I1003 22:26:06.019] I1003 22:26:05.660016 8830 controllerserver.go:294] ControllerPublishVolume: called with args {VolumeId:some-vol-id NodeId:some-node-id VolumeCapability:mount:<> access_mode:<mode:SINGLE_NODE_WRITER > Readonly:false Secrets:map[] VolumeContext:map[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0} I1003 22:26:06.020] E1003 22:26:05.660065 8830 utils.go:117] GRPC error: rpc error: code = NotFound desc = Volume not found, failed with error: could not get disk name from some-vol-id, correct format: .*/subscriptions/(?:.*)/resourceGroups/(?:.*)/providers/Microsoft.Compute/disks/(.+) I1003 22:26:06.020] • I1003 22:26:06.020] ------------------------------ I1003 22:26:06.020] Controller Service ControllerPublishVolume I1003 22:26:06.020] should fail when the node does not exist I1003 22:26:06.021] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:886 I1003 22:26:06.021] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:26:06.021] STEP: creating mount and staging directories I1003 22:26:06.021] I1003 22:26:05.660429 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:26:06.021] I1003 22:26:05.660451 8830 utils.go:113] GRPC request: I1003 22:26:06.022] I1003 22:26:05.660459 8830 controllerserver.go:415] Using default ControllerGetCapabilities ... skipping 8 lines ... I1003 22:26:08.416] I1003 22:26:06.535940 8830 utils.go:119] GRPC response: volume:<volume_id:"/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/sanity-controller-wrong-node-8A74F569-13D1D454" accessible_topology:<segments:<key:"topology.disk.csi.azure.com/zone" value:"" > > > I1003 22:26:08.416] STEP: calling controllerpublish on that volume I1003 22:26:08.417] I1003 22:26:06.536512 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerPublishVolume I1003 22:26:08.417] I1003 22:26:06.536535 8830 utils.go:113] GRPC request: volume_id:"/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/sanity-controller-wrong-node-8A74F569-13D1D454" node_id:"some-fake-node-id" volume_capability:<mount:<> access_mode:<mode:SINGLE_NODE_WRITER > > I1003 22:26:08.418] I1003 22:26:06.536544 8830 controllerserver.go:294] ControllerPublishVolume: called with args {VolumeId:/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/sanity-controller-wrong-node-8A74F569-13D1D454 NodeId:some-fake-node-id VolumeCapability:mount:<> access_mode:<mode:SINGLE_NODE_WRITER > Readonly:false Secrets:map[] VolumeContext:map[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0} I1003 22:26:08.418] I1003 22:26:06.630766 8830 controllerserver.go:326] GetDiskLun returned: <nil>. Initiating attaching volume "/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/sanity-controller-wrong-node-8A74F569-13D1D454" to node "some-fake-node-id". I1003 22:26:08.419] I1003 22:26:06.752216 8830 azure_wrap.go:222] Virtual machine "some-fake-node-id" not found with message: "compute.VirtualMachinesClient#Get: Failure responding to request: StatusCode=404 -- Original Error: autorest/azure: Service returned an error. Status=404 Code=\"ResourceNotFound\" Message=\"The Resource 'Microsoft.Compute/virtualMachines/some-fake-node-id' under resource group 'azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068' was not found.\"" I1003 22:26:08.419] E1003 22:26:06.752252 8830 azure_controller_common.go:204] error of getting data disks for node "some-fake-node-id": instance not found I1003 22:26:08.420] E1003 22:26:06.752265 8830 utils.go:117] GRPC error: rpc error: code = NotFound desc = failed to get azure instance id for node "some-fake-node-id" (instance not found) I1003 22:26:08.420] STEP: cleaning up deleting the volume I1003 22:26:08.420] I1003 22:26:06.752722 8830 utils.go:112] GRPC call: /csi.v1.Controller/DeleteVolume I1003 22:26:08.420] I1003 22:26:06.752743 8830 utils.go:113] GRPC request: volume_id:"/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/sanity-controller-wrong-node-8A74F569-13D1D454" I1003 22:26:08.421] I1003 22:26:06.752754 8830 controllerserver.go:277] deleting azure disk(/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/sanity-controller-wrong-node-8A74F569-13D1D454) I1003 22:26:12.247] I1003 22:26:12.247656 8830 azure_managedDiskController.go:195] azureDisk - deleted a managed disk: /subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/sanity-controller-wrong-node-8A74F569-13D1D454 I1003 22:26:12.248] I1003 22:26:12.247705 8830 controllerserver.go:287] delete azure disk(/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/sanity-controller-wrong-node-8A74F569-13D1D454) successfully I1003 22:26:12.248] I1003 22:26:12.247717 8830 utils.go:119] GRPC response: I1003 22:26:12.248] I1003 22:26:12.249] • [SLOW TEST:6.588 seconds] I1003 22:26:12.249] Controller Service I1003 22:26:12.249] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44 I1003 22:26:12.249] ControllerPublishVolume I1003 22:26:12.249] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:713 I1003 22:26:12.249] should fail when the node does not exist I1003 22:26:12.250] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:886 I1003 22:26:12.250] ------------------------------ I1003 22:26:12.250] Controller Service ControllerPublishVolume I1003 22:26:12.250] should fail when the volume is already published but is incompatible I1003 22:26:12.250] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:956 I1003 22:26:12.251] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:26:12.251] STEP: creating mount and staging directories I1003 22:26:12.251] I1003 22:26:12.248260 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:26:12.251] I1003 22:26:12.248282 8830 utils.go:113] GRPC request: I1003 22:26:12.251] I1003 22:26:12.248290 8830 controllerserver.go:415] Using default ControllerGetCapabilities ... skipping 5 lines ... I1003 22:26:12.252] I1003 22:26:12.253] S [SKIPPING] [0.002 seconds] I1003 22:26:12.253] Controller Service I1003 22:26:12.253] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44 I1003 22:26:12.253] ControllerPublishVolume I1003 22:26:12.253] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:713 I1003 22:26:12.253] should fail when the volume is already published but is incompatible [It] I1003 22:26:12.253] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:956 I1003 22:26:12.253] I1003 22:26:12.254] ControllerPublishVolume.readonly field not supported I1003 22:26:12.254] I1003 22:26:12.254] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:958 I1003 22:26:12.254] ------------------------------ I1003 22:26:12.254] Controller Service ControllerUnpublishVolume I1003 22:26:12.254] should fail when no volume id is provided I1003 22:26:12.254] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1066 I1003 22:26:12.254] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:26:12.255] STEP: creating mount and staging directories I1003 22:26:12.255] I1003 22:26:12.250204 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:26:12.255] I1003 22:26:12.250232 8830 utils.go:113] GRPC request: I1003 22:26:12.255] I1003 22:26:12.250240 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:26:12.255] I1003 22:26:12.250246 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:26:12.255] I1003 22:26:12.250743 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerUnpublishVolume I1003 22:26:12.256] I1003 22:26:12.250766 8830 utils.go:113] GRPC request: I1003 22:26:12.256] I1003 22:26:12.250776 8830 controllerserver.go:360] ControllerUnpublishVolume: called with args {VolumeId: NodeId: Secrets:map[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0} I1003 22:26:12.256] E1003 22:26:12.250795 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Volume ID not provided I1003 22:26:12.256] • I1003 22:26:12.256] ------------------------------ I1003 22:26:12.256] Controller Service ControllerUnpublishVolume I1003 22:26:12.256] should return appropriate values (no optional values added) I1003 22:26:12.257] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1081 I1003 22:26:12.257] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock ... skipping 52 lines ... I1003 22:26:51.783] ControllerUnpublishVolume I1003 22:26:51.783] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1059 I1003 22:26:51.783] should return appropriate values (no optional values added) I1003 22:26:51.783] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1081 I1003 22:26:51.784] ------------------------------ I1003 22:26:51.784] CreateSnapshot [Controller Server] I1003 22:26:51.784] should fail when no name is provided I1003 22:26:51.784] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1502 I1003 22:26:51.784] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:26:51.784] STEP: creating mount and staging directories I1003 22:26:51.785] I1003 22:26:51.653236 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:26:51.785] I1003 22:26:51.653270 8830 utils.go:113] GRPC request: I1003 22:26:51.785] I1003 22:26:51.653278 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:26:51.785] I1003 22:26:51.653285 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:26:51.786] I1003 22:26:51.653691 8830 utils.go:112] GRPC call: /csi.v1.Controller/CreateSnapshot I1003 22:26:51.786] I1003 22:26:51.653710 8830 utils.go:113] GRPC request: source_volume_id:"testId" I1003 22:26:51.786] I1003 22:26:51.653717 8830 controllerserver.go:439] CreateSnapshot called with request {testId map[] map[] {} [] 0} I1003 22:26:51.787] E1003 22:26:51.653758 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = Snapshot name must be provided I1003 22:26:51.787] • I1003 22:26:51.787] ------------------------------ I1003 22:26:51.787] CreateSnapshot [Controller Server] I1003 22:26:51.787] should fail when no source volume id is provided I1003 22:26:51.787] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1519 I1003 22:26:51.788] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:26:51.788] STEP: creating mount and staging directories I1003 22:26:51.788] I1003 22:26:51.654136 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:26:51.788] I1003 22:26:51.654154 8830 utils.go:113] GRPC request: I1003 22:26:51.788] I1003 22:26:51.654158 8830 controllerserver.go:415] Using default ControllerGetCapabilities I1003 22:26:51.789] I1003 22:26:51.654162 8830 utils.go:119] GRPC response: capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:CREATE_DELETE_SNAPSHOT > > capabilities:<rpc:<type:LIST_SNAPSHOTS > > I1003 22:26:51.789] I1003 22:26:51.654486 8830 utils.go:112] GRPC call: /csi.v1.Controller/CreateSnapshot I1003 22:26:51.789] I1003 22:26:51.654509 8830 utils.go:113] GRPC request: name:"name" I1003 22:26:51.789] I1003 22:26:51.654518 8830 controllerserver.go:439] CreateSnapshot called with request { name map[] map[] {} [] 0} I1003 22:26:51.790] E1003 22:26:51.654531 8830 utils.go:117] GRPC error: rpc error: code = InvalidArgument desc = CreateSnapshot Source Volume ID must be provided I1003 22:26:51.790] • I1003 22:26:51.790] ------------------------------ I1003 22:26:51.790] CreateSnapshot [Controller Server] I1003 22:26:51.790] should not fail when requesting to create a snapshot with already existing name and same SourceVolumeId. I1003 22:26:51.791] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1536 I1003 22:26:51.791] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:26:51.791] STEP: creating mount and staging directories I1003 22:26:51.791] I1003 22:26:51.654834 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:26:51.791] I1003 22:26:51.654846 8830 utils.go:113] GRPC request: I1003 22:26:51.792] I1003 22:26:51.654850 8830 controllerserver.go:415] Using default ControllerGetCapabilities ... skipping 34 lines ... I1003 22:27:14.861] I1003 22:27:14.860747 8830 controllerserver.go:287] delete azure disk(/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/CreateSnapshot-volume-1) successfully I1003 22:27:14.862] I1003 22:27:14.860755 8830 utils.go:119] GRPC response: I1003 22:27:14.862] I1003 22:27:14.862] • [SLOW TEST:23.206 seconds] I1003 22:27:14.862] CreateSnapshot [Controller Server] I1003 22:27:14.862] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44 I1003 22:27:14.863] should not fail when requesting to create a snapshot with already existing name and same SourceVolumeId. I1003 22:27:14.863] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1536 I1003 22:27:14.863] ------------------------------ I1003 22:27:14.863] CreateSnapshot [Controller Server] I1003 22:27:14.864] should fail when requesting to create a snapshot with already existing name and different SourceVolumeId. I1003 22:27:14.864] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1566 I1003 22:27:14.864] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:27:14.864] STEP: creating mount and staging directories I1003 22:27:14.865] I1003 22:27:14.861411 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:27:14.865] I1003 22:27:14.861433 8830 utils.go:113] GRPC request: I1003 22:27:14.865] I1003 22:27:14.861442 8830 controllerserver.go:415] Using default ControllerGetCapabilities ... skipping 22 lines ... I1003 22:27:27.747] I1003 22:27:27.642723 8830 utils.go:119] GRPC response: volume:<capacity_bytes:10737418240 volume_id:"/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/CreateSnapshot-volume-3" accessible_topology:<segments:<key:"topology.disk.csi.azure.com/zone" value:"" > > > I1003 22:27:27.747] STEP: creating a snapshot with the same name but different volume source id I1003 22:27:27.748] I1003 22:27:27.643259 8830 utils.go:112] GRPC call: /csi.v1.Controller/CreateSnapshot I1003 22:27:27.748] I1003 22:27:27.643284 8830 utils.go:113] GRPC request: source_volume_id:"/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/CreateSnapshot-volume-3" name:"CreateSnapshot-snapshot-2" I1003 22:27:27.748] I1003 22:27:27.643295 8830 controllerserver.go:439] CreateSnapshot called with request {/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/CreateSnapshot-volume-3 CreateSnapshot-snapshot-2 map[] map[] {} [] 0} I1003 22:27:27.749] I1003 22:27:27.643316 8830 controllerserver.go:463] begin to create snapshot(CreateSnapshot-snapshot-2) under rg(azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068) I1003 22:27:27.918] E1003 22:27:27.913436 8830 utils.go:117] GRPC error: rpc error: code = AlreadyExists desc = request snapshot(CreateSnapshot-snapshot-2) under rg(azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068) already exists, but the SourceVolumeId is different, error details: compute.SnapshotsClient#CreateOrUpdate: Failure sending request: StatusCode=400 -- Original Error: Code="BadRequest" Message="Changing property 'sourceResourceId' is not allowed for existing disk 'CreateSnapshot-snapshot-2'." I1003 22:27:27.918] STEP: cleaning up deleting the snapshot I1003 22:27:27.926] I1003 22:27:27.918163 8830 utils.go:112] GRPC call: /csi.v1.Controller/DeleteSnapshot I1003 22:27:27.926] I1003 22:27:27.918190 8830 utils.go:113] GRPC request: snapshot_id:"/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/snapshots/CreateSnapshot-snapshot-2" I1003 22:27:27.927] I1003 22:27:27.918204 8830 controllerserver.go:491] DeleteSnapshot called with request {/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/snapshots/CreateSnapshot-snapshot-2 map[] {} [] 0} I1003 22:27:27.927] I1003 22:27:27.918262 8830 controllerserver.go:503] begin to delete snapshot(CreateSnapshot-snapshot-2) under rg(azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068) I1003 22:27:33.490] I1003 22:27:33.489452 8830 controllerserver.go:512] delete snapshot(CreateSnapshot-snapshot-2) under rg(azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068) successfully ... skipping 6 lines ... I1003 22:27:42.096] I1003 22:27:38.966603 8830 controllerserver.go:287] delete azure disk(/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/CreateSnapshot-volume-2) successfully I1003 22:27:42.097] I1003 22:27:38.966615 8830 utils.go:119] GRPC response: I1003 22:27:42.097] I1003 22:27:42.097] • [SLOW TEST:24.106 seconds] I1003 22:27:42.098] CreateSnapshot [Controller Server] I1003 22:27:42.098] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44 I1003 22:27:42.098] should fail when requesting to create a snapshot with already existing name and different SourceVolumeId. I1003 22:27:42.099] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1566 I1003 22:27:42.099] ------------------------------ I1003 22:27:42.099] CreateSnapshot [Controller Server] I1003 22:27:42.099] should not fail when creating snapshot with maximum-length name I1003 22:27:42.099] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1601 I1003 22:27:42.100] STEP: reusing connection to CSI driver at unix:///tmp/csi.sock I1003 22:27:42.100] STEP: creating mount and staging directories I1003 22:27:42.100] I1003 22:27:38.967189 8830 utils.go:112] GRPC call: /csi.v1.Controller/ControllerGetCapabilities I1003 22:27:42.100] I1003 22:27:38.967215 8830 utils.go:113] GRPC request: I1003 22:27:42.100] I1003 22:27:38.967224 8830 controllerserver.go:415] Using default ControllerGetCapabilities ... skipping 36 lines ... I1003 22:28:24.334] I1003 22:28:22.395654 8830 controllerserver.go:287] delete azure disk(/subscriptions/940f88ce-a64b-4e73-a258-9931349b9789/resourceGroups/azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068/providers/Microsoft.Compute/disks/CreateSnapshot-volume-3) successfully I1003 22:28:24.334] I1003 22:28:22.395665 8830 utils.go:119] GRPC response: I1003 22:28:24.334] I1003 22:28:24.335] • [SLOW TEST:43.429 seconds] I1003 22:28:24.335] CreateSnapshot [Controller Server] I1003 22:28:24.335] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/tests.go:44 I1003 22:28:24.335] should not fail when creating snapshot with maximum-length name I1003 22:28:24.335] /go/src/github.com/kubernetes-sigs/azuredisk-csi-driver/vendor/github.com/kubernetes-csi/csi-test/pkg/sanity/controller.go:1601 I1003 22:28:24.336] ------------------------------ I1003 22:28:24.336] I1003 22:28:24.336] Ran 53 of 57 Specs in 521.574 seconds I1003 22:28:24.336] SUCCESS! -- 53 Passed | 0 Failed | 0 Pending | 4 Skipped I1003 22:28:24.336] PASS I1003 22:28:24.337] pkill -f azurediskplugin I1003 22:28:24.337] Deleting CSI sanity test binary I1003 22:28:24.380] test/sanity/run-test.sh: line 19: 8830 Terminated _output/azurediskplugin --endpoint "$endpoint" --nodeid "$nodeid" -v=5 I1003 22:28:24.382] 2019/10/03 22:28:24 Deleting resource group azuredisk-csi-driver-test-d9c4cd20-e62a-11e9-ae8d-f2967c2ab068 I1003 22:32:13.055] panic: test timed out after 20m0s ... skipping 86 lines ... I1003 22:32:13.107] I1003 22:32:13.107] goroutine 119 [select]: I1003 22:32:13.107] net/http.(*persistConn).writeLoop(0xc0002b6360) I1003 22:32:13.108] /usr/local/go/src/net/http/transport.go:1958 +0x113 I1003 22:32:13.108] created by net/http.(*Transport).dialConn I1003 22:32:13.108] /usr/local/go/src/net/http/transport.go:1358 +0xb0d I1003 22:32:13.108] FAIL github.com/kubernetes-sigs/azuredisk-csi-driver/test/sanity 1200.013s I1003 22:32:13.128] Makefile:51: recipe for target 'sanity-test' failed W1003 22:32:13.182] make: *** [sanity-test] Error 1 W1003 22:32:13.182] Traceback (most recent call last): W1003 22:32:13.182] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 50, in <module> W1003 22:32:13.182] main(ARGS.env, ARGS.cmd + ARGS.args) W1003 22:32:13.182] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 41, in main W1003 22:32:13.182] check(*cmd) W1003 22:32:13.183] File "/workspace/./test-infra/jenkins/../scenarios/execute.py", line 30, in check W1003 22:32:13.183] subprocess.check_call(cmd) W1003 22:32:13.184] File "/usr/lib/python2.7/subprocess.py", line 186, in check_call W1003 22:32:13.184] raise CalledProcessError(retcode, cmd) W1003 22:32:13.184] subprocess.CalledProcessError: Command '('make', 'sanity-test')' returned non-zero exit status 2 E1003 22:32:13.184] Command failed I1003 22:32:13.185] process 457 exited with code 1 after 22.8m E1003 22:32:13.185] FAIL: pull-azuredisk-csi-driver-sanity I1003 22:32:13.185] Call: gcloud auth activate-service-account --key-file=/etc/service-account/service-account.json W1003 22:32:44.761] Activated service account credentials for: [kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com] I1003 22:32:44.845] process 8858 exited with code 0 after 0.5m I1003 22:32:44.846] Call: gcloud config get-value account I1003 22:32:45.117] process 8870 exited with code 0 after 0.0m I1003 22:32:45.117] Will upload results to gs://kubernetes-upstream/pr-logs using kubernetes-upstream@kubernetes-upstream.iam.gserviceaccount.com ... skipping 28 lines ...