This job view page is being replaced by Spyglass soon. Check out the new job view.
PRandyzhangx: [WIP] test
ResultFAILURE
Tests 0 failed / 0 succeeded
Started2023-01-31 13:50
Elapsed2m29s
Revision6cab7b3d426d3de882fcfdd5adf2bd368c122d92
Refs 1705

No Test Failures!


Error lines from build-log.txt

... skipping 3 lines ...
+ make verify
go test -v -cover ./pkg/... ./test/utils/credentials
?   	sigs.k8s.io/azuredisk-csi-driver/pkg/azureconstants	[no test files]
=== RUN   TestRescanAllVolumes
--- PASS: TestRescanAllVolumes (0.00s)
=== RUN   TestFindDiskByLun
W0131 13:51:39.371576   11564 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/azure/scsi1/ failed, error: read /dev/disk/azure/scsi1/ error: bad dir
W0131 13:51:39.371697   11564 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/by-id/ failed, error: read /dev/disk/by-id/ error: bad dir
--- PASS: TestFindDiskByLun (0.00s)
=== RUN   TestStrFirstLetterToUpper
--- PASS: TestStrFirstLetterToUpper (0.00s)
=== RUN   TestNewDriverV1
--- PASS: TestNewDriverV1 (0.00s)
=== RUN   TestCheckDiskCapacity
... skipping 28 lines ...
Git Commit: N/A
Go Version: go1.19.5
Platform: linux/amd64
Topology Key: topology.disk.csi.azure.com/zone

Streaming logs below:
W0131 13:51:39.378346   11564 azure_disk_utils.go:230] get kubeconfig() failed with error: open /var/run/secrets/kubernetes.io/serviceaccount/token: no such file or directory
I0131 13:51:39.381879   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:39.381910   11564 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:39.381915   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0131 13:51:39.381921   11564 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:39.381926   11564 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:39.381931   11564 driver.go:81] Enabling controller service capability: SINGLE_NODE_MULTI_WRITER
... skipping 22 lines ...
Git Commit: N/A
Go Version: go1.19.5
Platform: linux/amd64
Topology Key: topology.disk.csi.azure.com/zone

Streaming logs below:
W0131 13:51:39.385972   11564 azure_disk_utils.go:230] get kubeconfig() failed with error: open /var/run/secrets/kubernetes.io/serviceaccount/token: no such file or directory
W0131 13:51:39.386005   11564 azure_disk_utils.go:189] load azure config from file(/etc/kubernetes/azure.json) failed with open /etc/kubernetes/azure.json: no such file or directory
I0131 13:51:39.388604   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:39.388639   11564 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:39.388646   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0131 13:51:39.388651   11564 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:39.388657   11564 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:39.388663   11564 driver.go:81] Enabling controller service capability: SINGLE_NODE_MULTI_WRITER
... skipping 22 lines ...
Git Commit: N/A
Go Version: go1.19.5
Platform: linux/amd64
Topology Key: topology.disk.csi.azure.com/zone

Streaming logs below:
W0131 13:51:39.392399   11564 azure_disk_utils.go:230] get kubeconfig() failed with error: open /var/run/secrets/kubernetes.io/serviceaccount/token: no such file or directory
I0131 13:51:39.395372   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:39.395403   11564 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:39.395425   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0131 13:51:39.395435   11564 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:39.395439   11564 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:39.395444   11564 driver.go:81] Enabling controller service capability: SINGLE_NODE_MULTI_WRITER
... skipping 13 lines ...
Git Commit: N/A
Go Version: go1.19.5
Platform: linux/amd64
Topology Key: topology.disk.csi.azure.com/zone

Streaming logs below:
W0131 13:51:39.396231   11564 azure_disk_utils.go:230] get kubeconfig() failed with error: open /var/run/secrets/kubernetes.io/serviceaccount/token: no such file or directory
E0131 13:51:39.864718   11564 util.go:138] Send.sendRequest: error in parsing response body string "": unexpected end of JSON input, Skip retrying regional host
E0131 13:51:39.864888   11564 azure_standard.go:587] as.GetInstanceTypeByNodeName() failed: as.getVirtualMachine() err=Retriable: false, RetryAfter: 0s, HTTPStatusCode: 400, RawError: azure.BearerAuthorizer#WithAuthorization: Failed to refresh the Token for request to https://management.azure.com/subscriptions/12345/resourceGroups/rg1/providers/Microsoft.Compute/virtualMachines/?%24expand=instanceView&api-version=2022-03-01: StatusCode=400 -- Original Error: adal: Refresh request failed. Status Code = '400'. Response body: {"error":"invalid_request","error_description":"AADSTS90002: Tenant '1234' not found. Check to make sure you have the correct tenant ID and are signing into the correct cloud. Check with your subscription administrator, this may happen if there are no active subscriptions for the tenant.\r\nTrace ID: 65e0d805-9a5f-4a0f-a8f2-2c2b90252c00\r\nCorrelation ID: fa883b2f-16aa-4233-bf35-ad2df582bf5b\r\nTimestamp: 2023-01-31 13:51:39Z","error_codes":[90002],"timestamp":"2023-01-31 13:51:39Z","trace_id":"65e0d805-9a5f-4a0f-a8f2-2c2b90252c00","correlation_id":"fa883b2f-16aa-4233-bf35-ad2df582bf5b","error_uri":"https://login.microsoftonline.com/error?code=90002"} Endpoint https://login.microsoftonline.com/1234/oauth2/token
W0131 13:51:39.864971   11564 azuredisk.go:224] Failed to get node info. Error: NewNodeInfo: Failed to get instance type from Azure cloud provider, nodeName: , error: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 400, RawError: azure.BearerAuthorizer#WithAuthorization: Failed to refresh the Token for request to https://management.azure.com/subscriptions/12345/resourceGroups/rg1/providers/Microsoft.Compute/virtualMachines/?%24expand=instanceView&api-version=2022-03-01: StatusCode=400 -- Original Error: adal: Refresh request failed. Status Code = '400'. Response body: {"error":"invalid_request","error_description":"AADSTS90002: Tenant '1234' not found. Check to make sure you have the correct tenant ID and are signing into the correct cloud. Check with your subscription administrator, this may happen if there are no active subscriptions for the tenant.\r\nTrace ID: 65e0d805-9a5f-4a0f-a8f2-2c2b90252c00\r\nCorrelation ID: fa883b2f-16aa-4233-bf35-ad2df582bf5b\r\nTimestamp: 2023-01-31 13:51:39Z","error_codes":[90002],"timestamp":"2023-01-31 13:51:39Z","trace_id":"65e0d805-9a5f-4a0f-a8f2-2c2b90252c00","correlation_id":"fa883b2f-16aa-4233-bf35-ad2df582bf5b","error_uri":"https://login.microsoftonline.com/error?code=90002"} Endpoint https://login.microsoftonline.com/1234/oauth2/token
I0131 13:51:39.869024   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:39.869053   11564 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:39.869059   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0131 13:51:39.869063   11564 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:39.869067   11564 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:39.869072   11564 driver.go:81] Enabling controller service capability: SINGLE_NODE_MULTI_WRITER
... skipping 259 lines ...
I0131 13:51:39.941048   11564 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I0131 13:51:39.941052   11564 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:39.941057   11564 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:39.941061   11564 driver.go:81] Enabling controller service capability: LIST_VOLUMES
I0131 13:51:39.941064   11564 driver.go:81] Enabling controller service capability: LIST_VOLUMES_PUBLISHED_NODES
I0131 13:51:39.941069   11564 driver.go:100] Enabling volume access mode: SINGLE_NODE_WRITER
E0131 13:51:39.941236   11564 controllerserver.go:302] validateDiskURI(123) in DeleteVolume failed with error: invalid DiskURI: 123, correct format: [/subscriptions/{sub-id}/resourcegroups/{group-name}/providers/microsoft.compute/disks/{disk-id}]
--- PASS: TestDeleteVolume (0.00s)
=== RUN   TestControllerGetVolume
I0131 13:51:39.944107   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:39.944136   11564 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:39.944141   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0131 13:51:39.944145   11564 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
... skipping 54 lines ...
I0131 13:51:39.959577   11564 driver.go:81] Enabling controller service capability: LIST_VOLUMES
I0131 13:51:39.959589   11564 driver.go:81] Enabling controller service capability: LIST_VOLUMES_PUBLISHED_NODES
I0131 13:51:39.959604   11564 driver.go:100] Enabling volume access mode: SINGLE_NODE_WRITER
=== RUN   TestControllerPublishVolume/diskName_error
=== RUN   TestControllerPublishVolume/NodeID_missing
=== RUN   TestControllerPublishVolume/failed_provisioning_state
W0131 13:51:39.960147   11564 controllerserver.go:392] VM(unit-test-node) is in failed state, update VM first
=== RUN   TestControllerPublishVolume/Volume_already_attached_success
I0131 13:51:39.963508   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:39.963615   11564 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:39.963635   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0131 13:51:39.963650   11564 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I0131 13:51:39.963666   11564 driver.go:81] Enabling controller service capability: CLONE_VOLUME
... skipping 671 lines ...
I0131 13:51:40.150233   11564 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I0131 13:51:40.150237   11564 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:40.150247   11564 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:40.150253   11564 driver.go:81] Enabling controller service capability: LIST_VOLUMES
I0131 13:51:40.150260   11564 driver.go:81] Enabling controller service capability: LIST_VOLUMES_PUBLISHED_NODES
I0131 13:51:40.150265   11564 driver.go:100] Enabling volume access mode: SINGLE_NODE_WRITER
E0131 13:51:40.150383   11564 nodeserver.go:558] mkdir failed on target: /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk/azuredisk.go (mkdir /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk/azuredisk.go: not a directory)
--- PASS: TestEnsureMountPoint (0.00s)
=== RUN   TestNodeGetInfo
=== RUN   TestNodeGetInfo/[Success]_Get_node_information_for_existing_VM
I0131 13:51:40.153491   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:40.153521   11564 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:40.153526   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
... skipping 11 lines ...
I0131 13:51:40.156796   11564 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:40.156800   11564 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:40.156804   11564 driver.go:81] Enabling controller service capability: LIST_VOLUMES
I0131 13:51:40.156807   11564 driver.go:81] Enabling controller service capability: LIST_VOLUMES_PUBLISHED_NODES
I0131 13:51:40.156812   11564 driver.go:100] Enabling volume access mode: SINGLE_NODE_WRITER
W0131 13:51:40.156908   11564 azure_wrap.go:77] Unable to find node 280a925a-a16e-11ed-96cb-b2726b2b8e75: instance not found
W0131 13:51:40.156927   11564 nodeserver.go:339] get zone(fakeNodeID) failed with: instance not found, fall back to get zone from node labels
--- PASS: TestNodeGetInfo (0.01s)
    --- PASS: TestNodeGetInfo/[Success]_Get_node_information_for_existing_VM (0.00s)
    --- PASS: TestNodeGetInfo/[Failure]_Get_node_information_for_non-existing_VM (0.00s)
=== RUN   TestNodeGetVolumeStats
I0131 13:51:40.159925   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:40.159956   11564 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
... skipping 12 lines ...
I0131 13:51:40.163445   11564 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I0131 13:51:40.163453   11564 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:40.163458   11564 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:40.163461   11564 driver.go:81] Enabling controller service capability: LIST_VOLUMES
I0131 13:51:40.163465   11564 driver.go:81] Enabling controller service capability: LIST_VOLUMES_PUBLISHED_NODES
I0131 13:51:40.163470   11564 driver.go:100] Enabling volume access mode: SINGLE_NODE_WRITER
W0131 13:51:40.163761   11564 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/azure/scsi1/ failed, error: read /dev/disk/azure/scsi1/ error: bad dir
W0131 13:51:40.163787   11564 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/by-id/ failed, error: read /dev/disk/by-id/ error: bad dir
E0131 13:51:40.163828   11564 nodeserver.go:169] NodeStageVolume: could not determine if volume vol_1 needs to be resized: Failed readonly device check. Expected 1 or 0, got '16106127360'
W0131 13:51:40.163852   11564 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/azure/scsi1/ failed, error: read /dev/disk/azure/scsi1/ error: bad dir
W0131 13:51:40.163859   11564 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/by-id/ failed, error: read /dev/disk/by-id/ error: bad dir
W0131 13:51:40.163896   11564 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/azure/scsi1/ failed, error: read /dev/disk/azure/scsi1/ error: bad dir
W0131 13:51:40.163905   11564 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/by-id/ failed, error: read /dev/disk/by-id/ error: bad dir
W0131 13:51:40.163955   11564 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/azure/scsi1/ failed, error: read /dev/disk/azure/scsi1/ error: bad dir
W0131 13:51:40.163972   11564 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/by-id/ failed, error: read /dev/disk/by-id/ error: bad dir
I0131 13:51:40.164002   11564 mount_linux.go:529] Disk "/dev/sdd" appears to be unformatted, attempting to format as type: "ext4" with options: [-F -m0 /dev/sdd]
I0131 13:51:40.164014   11564 mount_linux.go:539] Disk successfully formatted (mkfs): ext4 - /dev/sdd /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk/source_test
E0131 13:51:40.164028   11564 nodeserver.go:169] NodeStageVolume: could not determine if volume vol_1 needs to be resized: Failed readonly device check. Expected 1 or 0, got ''
W0131 13:51:40.164065   11564 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/azure/scsi1/ failed, error: read /dev/disk/azure/scsi1/ error: bad dir
W0131 13:51:40.164078   11564 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/by-id/ failed, error: read /dev/disk/by-id/ error: bad dir
W0131 13:51:40.164107   11564 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/azure/scsi1/ failed, error: read /dev/disk/azure/scsi1/ error: bad dir
W0131 13:51:40.164129   11564 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/by-id/ failed, error: read /dev/disk/by-id/ error: bad dir
E0131 13:51:40.164165   11564 nodeserver.go:169] NodeStageVolume: could not determine if volume vol_1 needs to be resized: Failed readonly device check. Expected 1 or 0, got '16106127360'
--- PASS: TestNodeStageVolume (0.00s)
=== RUN   TestNodeUnstageVolume
I0131 13:51:40.167254   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:40.167286   11564 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:40.167291   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0131 13:51:40.167295   11564 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
... skipping 11 lines ...
I0131 13:51:40.170590   11564 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I0131 13:51:40.170594   11564 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:40.170599   11564 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:40.170603   11564 driver.go:81] Enabling controller service capability: LIST_VOLUMES
I0131 13:51:40.170606   11564 driver.go:81] Enabling controller service capability: LIST_VOLUMES_PUBLISHED_NODES
I0131 13:51:40.170611   11564 driver.go:100] Enabling volume access mode: SINGLE_NODE_WRITER
E0131 13:51:40.170836   11564 nodeserver.go:558] mkdir failed on target: /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk/azuredisk.go (mkdir /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk/azuredisk.go: not a directory)
--- PASS: TestNodePublishVolume (0.00s)
=== RUN   TestNodeUnpublishVolume
I0131 13:51:40.173947   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:40.173976   11564 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:40.173980   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0131 13:51:40.173984   11564 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
... skipping 33 lines ...
I0131 13:51:40.188780   11564 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I0131 13:51:40.188794   11564 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:40.188823   11564 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:40.188850   11564 driver.go:81] Enabling controller service capability: LIST_VOLUMES
I0131 13:51:40.188866   11564 driver.go:81] Enabling controller service capability: LIST_VOLUMES_PUBLISHED_NODES
I0131 13:51:40.188889   11564 driver.go:100] Enabling volume access mode: SINGLE_NODE_WRITER
E0131 13:51:40.189523   11564 nodeserver.go:558] mkdir failed on target: /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk/test (mkdir /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk/test: not a directory)
--- PASS: TestEnsureBlockTargetFile (0.00s)
=== RUN   TestMakeDir
--- PASS: TestMakeDir (0.00s)
=== RUN   TestGetDevicePathWithLUN
I0131 13:51:40.192890   11564 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:40.192970   11564 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
... skipping 44 lines ...
--- PASS: TestCheckDiskName (0.00s)
=== RUN   TestGetCachingMode
--- PASS: TestGetCachingMode (0.00s)
=== RUN   TestGetKubeConfig
--- PASS: TestGetKubeConfig (0.00s)
=== RUN   TestGetCloudProvider
W0131 13:51:37.634112   11555 azure_disk_utils.go:189] load azure config from file(/etc/kubernetes/azure.json) failed with open /etc/kubernetes/azure.json: no such file or directory
W0131 13:51:37.634289   11555 azure_disk_utils.go:230] get kubeconfig(/home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/pkg/azureutils/empty-kube-config) failed with error: invalid configuration: no configuration has been provided, try setting KUBERNETES_MASTER environment variable
W0131 13:51:37.634435   11555 azure_disk_utils.go:230] get kubeconfig() failed with error: open /var/run/secrets/kubernetes.io/serviceaccount/token: no such file or directory
--- PASS: TestGetCloudProvider (0.00s)
=== RUN   TestGetDiskLUN
--- PASS: TestGetDiskLUN (0.00s)
=== RUN   TestGetDiskName
--- PASS: TestGetDiskName (0.00s)
=== RUN   TestGetFStype
... skipping 426 lines ...
coverage: 78.0% of statements
ok  	sigs.k8s.io/azuredisk-csi-driver/test/utils/credentials	0.007s	coverage: 78.0% of statements
go test -v -cover -tags azurediskv2 ./pkg/azuredisk --temp-use-driver-v2
=== RUN   TestRescanAllVolumes
--- PASS: TestRescanAllVolumes (0.00s)
=== RUN   TestFindDiskByLun
W0131 13:51:45.893821   11933 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/azure/scsi1/ failed, error: read /dev/disk/azure/scsi1/ error: bad dir
W0131 13:51:45.893934   11933 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/by-id/ failed, error: read /dev/disk/by-id/ error: bad dir
--- PASS: TestFindDiskByLun (0.00s)
=== RUN   TestStrFirstLetterToUpper
--- PASS: TestStrFirstLetterToUpper (0.00s)
=== RUN   TestNewDriverV1
--- PASS: TestNewDriverV1 (0.00s)
=== RUN   TestCheckDiskCapacity
... skipping 30 lines ...
Git Commit: N/A
Go Version: go1.19.5
Platform: linux/amd64
Topology Key: topology.disk.csi.azure.com/zone

Streaming logs below:
W0131 13:51:45.901074   11933 azure_disk_utils.go:230] get kubeconfig() failed with error: open /var/run/secrets/kubernetes.io/serviceaccount/token: no such file or directory
I0131 13:51:45.904689   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:45.904719   11933 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:45.904726   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0131 13:51:45.904732   11933 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I0131 13:51:45.904737   11933 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:45.904823   11933 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
... skipping 26 lines ...
Git Commit: N/A
Go Version: go1.19.5
Platform: linux/amd64
Topology Key: topology.disk.csi.azure.com/zone

Streaming logs below:
W0131 13:51:45.908571   11933 azure_disk_utils.go:230] get kubeconfig() failed with error: open /var/run/secrets/kubernetes.io/serviceaccount/token: no such file or directory
W0131 13:51:45.908598   11933 azure_disk_utils.go:189] load azure config from file(/etc/kubernetes/azure.json) failed with open /etc/kubernetes/azure.json: no such file or directory
I0131 13:51:45.911461   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:45.911491   11933 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:45.911495   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0131 13:51:45.911500   11933 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I0131 13:51:45.911516   11933 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:45.911521   11933 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
... skipping 26 lines ...
Git Commit: N/A
Go Version: go1.19.5
Platform: linux/amd64
Topology Key: topology.disk.csi.azure.com/zone

Streaming logs below:
W0131 13:51:45.915051   11933 azure_disk_utils.go:230] get kubeconfig() failed with error: open /var/run/secrets/kubernetes.io/serviceaccount/token: no such file or directory
I0131 13:51:45.918014   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:45.918052   11933 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:45.918058   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0131 13:51:45.918062   11933 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I0131 13:51:45.918065   11933 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:45.918080   11933 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
... skipping 16 lines ...
Git Commit: N/A
Go Version: go1.19.5
Platform: linux/amd64
Topology Key: topology.disk.csi.azure.com/zone

Streaming logs below:
W0131 13:51:45.919148   11933 azure_disk_utils.go:230] get kubeconfig() failed with error: open /var/run/secrets/kubernetes.io/serviceaccount/token: no such file or directory
E0131 13:51:46.209163   11933 util.go:138] Send.sendRequest: error in parsing response body string "": unexpected end of JSON input, Skip retrying regional host
E0131 13:51:46.209309   11933 azure_standard.go:587] as.GetInstanceTypeByNodeName() failed: as.getVirtualMachine() err=Retriable: false, RetryAfter: 0s, HTTPStatusCode: 400, RawError: azure.BearerAuthorizer#WithAuthorization: Failed to refresh the Token for request to https://management.azure.com/subscriptions/12345/resourceGroups/rg1/providers/Microsoft.Compute/virtualMachines/?%24expand=instanceView&api-version=2022-03-01: StatusCode=400 -- Original Error: adal: Refresh request failed. Status Code = '400'. Response body: {"error":"invalid_request","error_description":"AADSTS90002: Tenant '1234' not found. Check to make sure you have the correct tenant ID and are signing into the correct cloud. Check with your subscription administrator, this may happen if there are no active subscriptions for the tenant.\r\nTrace ID: a63c6fb6-0965-487e-a894-6e2708778e00\r\nCorrelation ID: 0572c8e9-f7ad-48b8-a083-b68cb194dfc4\r\nTimestamp: 2023-01-31 13:51:46Z","error_codes":[90002],"timestamp":"2023-01-31 13:51:46Z","trace_id":"a63c6fb6-0965-487e-a894-6e2708778e00","correlation_id":"0572c8e9-f7ad-48b8-a083-b68cb194dfc4","error_uri":"https://login.microsoftonline.com/error?code=90002"} Endpoint https://login.microsoftonline.com/1234/oauth2/token
W0131 13:51:46.209329   11933 azuredisk.go:224] Failed to get node info. Error: NewNodeInfo: Failed to get instance type from Azure cloud provider, nodeName: , error: Retriable: false, RetryAfter: 0s, HTTPStatusCode: 400, RawError: azure.BearerAuthorizer#WithAuthorization: Failed to refresh the Token for request to https://management.azure.com/subscriptions/12345/resourceGroups/rg1/providers/Microsoft.Compute/virtualMachines/?%24expand=instanceView&api-version=2022-03-01: StatusCode=400 -- Original Error: adal: Refresh request failed. Status Code = '400'. Response body: {"error":"invalid_request","error_description":"AADSTS90002: Tenant '1234' not found. Check to make sure you have the correct tenant ID and are signing into the correct cloud. Check with your subscription administrator, this may happen if there are no active subscriptions for the tenant.\r\nTrace ID: a63c6fb6-0965-487e-a894-6e2708778e00\r\nCorrelation ID: 0572c8e9-f7ad-48b8-a083-b68cb194dfc4\r\nTimestamp: 2023-01-31 13:51:46Z","error_codes":[90002],"timestamp":"2023-01-31 13:51:46Z","trace_id":"a63c6fb6-0965-487e-a894-6e2708778e00","correlation_id":"0572c8e9-f7ad-48b8-a083-b68cb194dfc4","error_uri":"https://login.microsoftonline.com/error?code=90002"} Endpoint https://login.microsoftonline.com/1234/oauth2/token
I0131 13:51:46.212285   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:46.212332   11933 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:46.212337   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0131 13:51:46.212341   11933 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:46.212345   11933 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:46.212350   11933 driver.go:81] Enabling controller service capability: SINGLE_NODE_MULTI_WRITER
... skipping 255 lines ...
I0131 13:51:46.273962   11933 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I0131 13:51:46.273966   11933 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:46.273970   11933 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:46.273974   11933 driver.go:81] Enabling controller service capability: LIST_VOLUMES
I0131 13:51:46.273977   11933 driver.go:81] Enabling controller service capability: LIST_VOLUMES_PUBLISHED_NODES
I0131 13:51:46.273982   11933 driver.go:100] Enabling volume access mode: SINGLE_NODE_WRITER
E0131 13:51:46.274144   11933 controllerserver_v2.go:256] validateDiskURI(123) in DeleteVolume failed with error: invalid DiskURI: 123, correct format: [/subscriptions/{sub-id}/resourcegroups/{group-name}/providers/microsoft.compute/disks/{disk-id}]
--- PASS: TestDeleteVolume (0.00s)
=== RUN   TestControllerGetVolume
W0131 13:51:46.274209   11933 fake_azuredisk_v2.go:57] Using DriverV2
I0131 13:51:46.276871   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:46.276903   11933 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:46.276908   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
... skipping 60 lines ...
I0131 13:51:46.291520   11933 driver.go:81] Enabling controller service capability: LIST_VOLUMES
I0131 13:51:46.291523   11933 driver.go:81] Enabling controller service capability: LIST_VOLUMES_PUBLISHED_NODES
I0131 13:51:46.291527   11933 driver.go:100] Enabling volume access mode: SINGLE_NODE_WRITER
=== RUN   TestControllerPublishVolume/diskName_error
=== RUN   TestControllerPublishVolume/NodeID_missing
=== RUN   TestControllerPublishVolume/failed_provisioning_state
W0131 13:51:46.292193   11933 controllerserver_v2.go:341] VM(unit-test-node) is in failed state, update VM first
=== RUN   TestControllerPublishVolume/Volume_already_attached_success
W0131 13:51:46.292329   11933 fake_azuredisk_v2.go:57] Using DriverV2
I0131 13:51:46.295732   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:46.295762   11933 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:46.295769   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
I0131 13:51:46.295775   11933 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
... skipping 731 lines ...
I0131 13:51:46.495610   11933 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I0131 13:51:46.495616   11933 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:46.495640   11933 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:46.495648   11933 driver.go:81] Enabling controller service capability: LIST_VOLUMES
I0131 13:51:46.495653   11933 driver.go:81] Enabling controller service capability: LIST_VOLUMES_PUBLISHED_NODES
I0131 13:51:46.495660   11933 driver.go:100] Enabling volume access mode: SINGLE_NODE_WRITER
E0131 13:51:46.495841   11933 nodeserver_v2.go:543] mkdir failed on target: /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk/azuredisk.go (mkdir /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk/azuredisk.go: not a directory)
--- PASS: TestEnsureMountPoint (0.00s)
=== RUN   TestNodeGetInfo
=== RUN   TestNodeGetInfo/[Success]_Get_node_information_for_existing_VM
W0131 13:51:46.496301   11933 fake_azuredisk_v2.go:57] Using DriverV2
I0131 13:51:46.499131   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:46.499158   11933 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
... skipping 13 lines ...
I0131 13:51:46.502160   11933 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:46.502164   11933 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:46.502168   11933 driver.go:81] Enabling controller service capability: LIST_VOLUMES
I0131 13:51:46.502172   11933 driver.go:81] Enabling controller service capability: LIST_VOLUMES_PUBLISHED_NODES
I0131 13:51:46.502177   11933 driver.go:100] Enabling volume access mode: SINGLE_NODE_WRITER
W0131 13:51:46.502381   11933 azure_wrap.go:77] Unable to find node 280a925a-a16e-11ed-96cb-b2726b2b8e75: instance not found
W0131 13:51:46.502417   11933 nodeserver_v2.go:336] get zone(fakeNodeID) failed with: instance not found, fall back to get zone from node labels
--- PASS: TestNodeGetInfo (0.01s)
    --- PASS: TestNodeGetInfo/[Success]_Get_node_information_for_existing_VM (0.00s)
    --- PASS: TestNodeGetInfo/[Failure]_Get_node_information_for_non-existing_VM (0.00s)
=== RUN   TestNodeGetVolumeStats
W0131 13:51:46.502672   11933 fake_azuredisk_v2.go:57] Using DriverV2
I0131 13:51:46.505481   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
... skipping 14 lines ...
I0131 13:51:46.508927   11933 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I0131 13:51:46.508931   11933 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:46.508939   11933 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:46.508942   11933 driver.go:81] Enabling controller service capability: LIST_VOLUMES
I0131 13:51:46.508946   11933 driver.go:81] Enabling controller service capability: LIST_VOLUMES_PUBLISHED_NODES
I0131 13:51:46.508951   11933 driver.go:100] Enabling volume access mode: SINGLE_NODE_WRITER
W0131 13:51:46.509204   11933 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/azure/scsi1/ failed, error: read /dev/disk/azure/scsi1/ error: bad dir
W0131 13:51:46.509222   11933 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/by-id/ failed, error: read /dev/disk/by-id/ error: bad dir
E0131 13:51:46.509260   11933 nodeserver_v2.go:157] NodeStageVolume: could not determine if volume vol_1 needs to be resized: Failed readonly device check. Expected 1 or 0, got '16106127360'
W0131 13:51:46.509279   11933 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/azure/scsi1/ failed, error: read /dev/disk/azure/scsi1/ error: bad dir
W0131 13:51:46.509295   11933 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/by-id/ failed, error: read /dev/disk/by-id/ error: bad dir
W0131 13:51:46.509353   11933 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/azure/scsi1/ failed, error: read /dev/disk/azure/scsi1/ error: bad dir
W0131 13:51:46.509383   11933 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/by-id/ failed, error: read /dev/disk/by-id/ error: bad dir
W0131 13:51:46.509463   11933 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/azure/scsi1/ failed, error: read /dev/disk/azure/scsi1/ error: bad dir
W0131 13:51:46.509485   11933 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/by-id/ failed, error: read /dev/disk/by-id/ error: bad dir
I0131 13:51:46.509531   11933 mount_linux.go:529] Disk "/dev/sdd" appears to be unformatted, attempting to format as type: "ext4" with options: [-F -m0 /dev/sdd]
I0131 13:51:46.509558   11933 mount_linux.go:539] Disk successfully formatted (mkfs): ext4 - /dev/sdd /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk/source_test
E0131 13:51:46.509580   11933 nodeserver_v2.go:157] NodeStageVolume: could not determine if volume vol_1 needs to be resized: Failed readonly device check. Expected 1 or 0, got ''
W0131 13:51:46.509638   11933 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/azure/scsi1/ failed, error: read /dev/disk/azure/scsi1/ error: bad dir
W0131 13:51:46.509651   11933 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/by-id/ failed, error: read /dev/disk/by-id/ error: bad dir
W0131 13:51:46.509705   11933 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/azure/scsi1/ failed, error: read /dev/disk/azure/scsi1/ error: bad dir
W0131 13:51:46.509718   11933 azure_common_linux.go:188] azureDisk - getDiskLinkByDevName by sdd under /dev/disk/by-id/ failed, error: read /dev/disk/by-id/ error: bad dir
E0131 13:51:46.509766   11933 nodeserver_v2.go:157] NodeStageVolume: could not determine if volume vol_1 needs to be resized: Failed readonly device check. Expected 1 or 0, got '16106127360'
--- PASS: TestNodeStageVolume (0.00s)
=== RUN   TestNodeUnstageVolume
W0131 13:51:46.509941   11933 fake_azuredisk_v2.go:57] Using DriverV2
I0131 13:51:46.512908   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:46.513000   11933 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:46.513020   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
... skipping 13 lines ...
I0131 13:51:46.516470   11933 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I0131 13:51:46.516474   11933 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:46.516478   11933 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:46.516482   11933 driver.go:81] Enabling controller service capability: LIST_VOLUMES
I0131 13:51:46.516486   11933 driver.go:81] Enabling controller service capability: LIST_VOLUMES_PUBLISHED_NODES
I0131 13:51:46.516491   11933 driver.go:100] Enabling volume access mode: SINGLE_NODE_WRITER
E0131 13:51:46.516628   11933 nodeserver_v2.go:543] mkdir failed on target: /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk/azuredisk.go (mkdir /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk/azuredisk.go: not a directory)
--- PASS: TestNodePublishVolume (0.00s)
=== RUN   TestNodeUnpublishVolume
W0131 13:51:46.516908   11933 fake_azuredisk_v2.go:57] Using DriverV2
I0131 13:51:46.519659   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
I0131 13:51:46.519687   11933 driver.go:81] Enabling controller service capability: PUBLISH_UNPUBLISH_VOLUME
I0131 13:51:46.519692   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_SNAPSHOT
... skipping 37 lines ...
I0131 13:51:46.532476   11933 driver.go:81] Enabling controller service capability: LIST_SNAPSHOTS
I0131 13:51:46.532480   11933 driver.go:81] Enabling controller service capability: CLONE_VOLUME
I0131 13:51:46.532484   11933 driver.go:81] Enabling controller service capability: EXPAND_VOLUME
I0131 13:51:46.532488   11933 driver.go:81] Enabling controller service capability: LIST_VOLUMES
I0131 13:51:46.532492   11933 driver.go:81] Enabling controller service capability: LIST_VOLUMES_PUBLISHED_NODES
I0131 13:51:46.532496   11933 driver.go:100] Enabling volume access mode: SINGLE_NODE_WRITER
E0131 13:51:46.533308   11933 nodeserver_v2.go:543] mkdir failed on target: /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk/test (mkdir /home/prow/go/src/sigs.k8s.io/azuredisk-csi-driver/pkg/azuredisk/test: not a directory)
--- PASS: TestEnsureBlockTargetFile (0.00s)
=== RUN   TestMakeDir
--- PASS: TestMakeDir (0.00s)
=== RUN   TestGetDevicePathWithLUN
W0131 13:51:46.533650   11933 fake_azuredisk_v2.go:57] Using DriverV2
I0131 13:51:46.536421   11933 driver.go:81] Enabling controller service capability: CREATE_DELETE_VOLUME
... skipping 176 lines ...
go: downloading github.com/jmespath/go-jmespath/internal/testify v1.5.1
go mod vendor
diff --git a/vendor/sigs.k8s.io/cloud-provider-azure/pkg/provider/azure.go b/vendor/sigs.k8s.io/cloud-provider-azure/pkg/provider/azure.go
index 29513b97d..cb42b69b8 100644
--- a/vendor/sigs.k8s.io/cloud-provider-azure/pkg/provider/azure.go
+++ b/vendor/sigs.k8s.io/cloud-provider-azure/pkg/provider/azure.go
@@ -1035,6 +1035,12 @@ func initDiskControllers(az *Cloud) error {
 	logger := klogr.NewWithOptions(klogr.WithFormat(klogr.FormatKlog)).WithName("cloud-provider-azure").WithValues("type", "batch")
 
 	delayBeforeStart := ratelimitconfig.DefaultAttachDetachBatchInitialDelay
+	switch {
+	case az.Config.AttachDetachBatchInitialDelayInMillis < 0:
+		delayBeforeStart = 0
+	case az.Config.AttachDetachBatchInitialDelayInMillis > 0:
+		delayBeforeStart = time.Duration(az.Config.AttachDetachBatchInitialDelayInMillis) * time.Millisecond
+	}
 
 	processorOptions := []batch.ProcessorOption{
 		batch.WithVerboseLogLevel(3),

error
make: *** [Makefile:85: verify] Error 1
+ EXIT_VALUE=2
+ set +o xtrace