"./vfs.test -test.v -test.timeout 1h0m0s -remote TestCompressDrive: -verbose" - Starting (try 2/5) 2021/05/09 06:07:04 DEBUG : Creating backend with remote "TestCompressDrive:rclone-test-feziteb5bunojen5jaxitas1" 2021/05/09 06:07:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/05/09 06:07:05 DEBUG : Creating backend with remote "/tmp/rclone243623217" === RUN TestDirHandleMethods run.go:176: Remote "Compressed: TestCompressDrive:rclone-test-feziteb5bunojen5jaxitas1", Local "Local file system at /tmp/rclone243623217", Modify Window "1ms" 2021/05/09 06:07:06 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: User Rate Limit Exceeded. Rate of requests for user exceed configured project quota. You may consider re-evaluating expected per-user traffic to the API and adjust project quota limits accordingly. You may monitor aggregate quota usage and adjust limits in the API Console: https://console.developers.google.com/apis/api/drive.googleapis.com/quotas?project=847840889997, userRateLimitExceeded) 2021/05/09 06:07:06 DEBUG : pacer: Rate limited, increasing sleep to 1.915779982s 2021/05/09 06:07:07 DEBUG : pacer: Reducing sleep to 0s 2021/05/09 06:07:11 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: User Rate Limit Exceeded. Rate of requests for user exceed configured project quota. You may consider re-evaluating expected per-user traffic to the API and adjust project quota limits accordingly. You may monitor aggregate quota usage and adjust limits in the API Console: https://console.developers.google.com/apis/api/drive.googleapis.com/quotas?project=847840889997, userRateLimitExceeded) 2021/05/09 06:07:11 DEBUG : pacer: Rate limited, increasing sleep to 1.324187536s 2021/05/09 06:07:11 DEBUG : pacer: low level retry 2/10 (error googleapi: Error 403: User Rate Limit Exceeded. Rate of requests for user exceed configured project quota. You may consider re-evaluating expected per-user traffic to the API and adjust project quota limits accordingly. You may monitor aggregate quota usage and adjust limits in the API Console: https://console.developers.google.com/apis/api/drive.googleapis.com/quotas?project=847840889997, userRateLimitExceeded) 2021/05/09 06:07:11 DEBUG : pacer: Rate limited, increasing sleep to 2.505818635s 2021/05/09 06:07:13 DEBUG : pacer: Reducing sleep to 0s 2021/05/09 06:07:15 DEBUG : WaitForWriters: timeout=10s 2021/05/09 06:07:15 DEBUG : dir: Looking for writers 2021/05/09 06:07:15 DEBUG : : Looking for writers 2021/05/09 06:07:15 DEBUG : dir: reading active writers 2021/05/09 06:07:15 DEBUG : >WaitForWriters: 2021/05/09 06:07:16 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: User Rate Limit Exceeded. Rate of requests for user exceed configured project quota. You may consider re-evaluating expected per-user traffic to the API and adjust project quota limits accordingly. You may monitor aggregate quota usage and adjust limits in the API Console: https://console.developers.google.com/apis/api/drive.googleapis.com/quotas?project=847840889997, userRateLimitExceeded) 2021/05/09 06:07:16 DEBUG : pacer: Rate limited, increasing sleep to 1.057682007s 2021/05/09 06:07:16 DEBUG : pacer: low level retry 2/10 (error googleapi: Error 403: User Rate Limit Exceeded. Rate of requests for user exceed configured project quota. You may consider re-evaluating expected per-user traffic to the API and adjust project quota limits accordingly. You may monitor aggregate quota usage and adjust limits in the API Console: https://console.developers.google.com/apis/api/drive.googleapis.com/quotas?project=847840889997, userRateLimitExceeded) 2021/05/09 06:07:16 DEBUG : pacer: Rate limited, increasing sleep to 2.033298589s 2021/05/09 06:07:18 DEBUG : pacer: Reducing sleep to 0s 2021/05/09 06:07:20 DEBUG : dir: Rmdir: contains trashed file: "file1.DgAAAAAAAAA.gz" 2021/05/09 06:07:20 DEBUG : dir: Rmdir: contains trashed file: "file1.json" --- PASS: TestDirHandleMethods (15.26s) === RUN TestDirHandleReaddir run.go:176: Remote "Compressed: TestCompressDrive:rclone-test-feziteb5bunojen5jaxitas1", Local "Local file system at /tmp/rclone243623217", Modify Window "1ms" 2021/05/09 06:07:21 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: User Rate Limit Exceeded. Rate of requests for user exceed configured project quota. You may consider re-evaluating expected per-user traffic to the API and adjust project quota limits accordingly. You may monitor aggregate quota usage and adjust limits in the API Console: https://console.developers.google.com/apis/api/drive.googleapis.com/quotas?project=847840889997, userRateLimitExceeded) 2021/05/09 06:07:21 DEBUG : pacer: Rate limited, increasing sleep to 1.248865878s 2021/05/09 06:07:21 DEBUG : pacer: Reducing sleep to 0s 2021/05/09 06:07:27 DEBUG : pacer: low level retry 1/1 (error googleapi: Error 403: User Rate Limit Exceeded. Rate of requests for user exceed configured project quota. You may consider re-evaluating expected per-user traffic to the API and adjust project quota limits accordingly. You may monitor aggregate quota usage and adjust limits in the API Console: https://console.developers.google.com/apis/api/drive.googleapis.com/quotas?project=847840889997, userRateLimitExceeded) 2021/05/09 06:07:27 DEBUG : pacer: Rate limited, increasing sleep to 1.444179147s 2021/05/09 06:07:27 DEBUG : WaitForWriters: timeout=10s 2021/05/09 06:07:27 DEBUG : : Looking for writers 2021/05/09 06:07:27 DEBUG : >WaitForWriters: 2021/05/09 06:07:27 DEBUG : pacer: Reducing sleep to 0s run.go:121: removing file "dir/file2" failed - try 1/3: object not found run.go:121: removing file "dir/file2" failed - try 2/3: object not found run.go:121: removing file "dir/file2" failed - try 3/3: object not found run.go:124: removing file "dir/file2" failed: object not found 2021/05/09 06:07:33 DEBUG : dir: Rmdir: contains file: "file2.DwAAAAAAAAA.gz" run.go:121: removing dir "dir" failed - try 1/3: directory not empty 2021/05/09 06:07:34 DEBUG : dir: Rmdir: contains file: "file2.DwAAAAAAAAA.gz" run.go:121: removing dir "dir" failed - try 2/3: directory not empty 2021/05/09 06:07:35 DEBUG : dir: Rmdir: contains file: "file2.DwAAAAAAAAA.gz" run.go:121: removing dir "dir" failed - try 3/3: directory not empty run.go:124: removing dir "dir" failed: directory not empty fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:302: Flushing the directory cache 2021/05/09 06:07:40 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: User Rate Limit Exceeded. Rate of requests for user exceed configured project quota. You may consider re-evaluating expected per-user traffic to the API and adjust project quota limits accordingly. You may monitor aggregate quota usage and adjust limits in the API Console: https://console.developers.google.com/apis/api/drive.googleapis.com/quotas?project=847840889997, userRateLimitExceeded) 2021/05/09 06:07:40 DEBUG : pacer: Rate limited, increasing sleep to 1.671807574s 2021/05/09 06:07:41 DEBUG : pacer: low level retry 2/10 (error googleapi: Error 403: User Rate Limit Exceeded. Rate of requests for user exceed configured project quota. You may consider re-evaluating expected per-user traffic to the API and adjust project quota limits accordingly. You may monitor aggregate quota usage and adjust limits in the API Console: https://console.developers.google.com/apis/api/drive.googleapis.com/quotas?project=847840889997, userRateLimitExceeded) 2021/05/09 06:07:41 DEBUG : pacer: Rate limited, increasing sleep to 2.779250931s 2021/05/09 06:07:42 DEBUG : pacer: Reducing sleep to 0s fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Flushing the directory cache fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 run.go:169 run.go:339 vfs_test.go:54 panic.go:969 panic.go:212 signal_unix.go:720 compress.go:629 compress.go:658 compress.go:687 run.go:272 run.go:289 dir_handle_test.go:46 Error: Should be true Test: TestDirHandleReaddir Messages: listing wrong, want got dir/file2 (15) fstest.go:192: Error Trace: fstest.go:192 fstest.go:309 fstest.go:339 run.go:169 run.go:339 vfs_test.go:54 panic.go:969 panic.go:212 signal_unix.go:720 compress.go:629 compress.go:658 compress.go:687 run.go:272 run.go:289 dir_handle_test.go:46 Error: Should be true Test: TestDirHandleReaddir Messages: Unexpected file "dir/file2" fstest.go:328: Error Trace: fstest.go:328 fstest.go:339 run.go:169 run.go:339 vfs_test.go:54 panic.go:969 panic.go:212 signal_unix.go:720 compress.go:629 compress.go:658 compress.go:687 run.go:272 run.go:289 dir_handle_test.go:46 Error: Not equal: expected: []string{} actual : []string{"dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=3) "dir" } Test: TestDirHandleReaddir Messages: directories --- FAIL: TestDirHandleReaddir (29.03s) panic: runtime error: invalid memory address or nil pointer dereference [recovered] panic: runtime error: invalid memory address or nil pointer dereference [signal SIGSEGV: segmentation violation code=0x1 addr=0x40 pc=0xcc414f] goroutine 107 [running]: testing.tRunner.func1.1(0x19d89a0, 0x2b1b860) /usr/local/go/src/testing/testing.go:1072 +0x30d testing.tRunner.func1(0xc0000fc480) /usr/local/go/src/testing/testing.go:1075 +0x41a panic(0x19d89a0, 0x2b1b860) /usr/local/go/src/runtime/panic.go:969 +0x1b9 github.com/rclone/rclone/backend/compress.(*Fs).putMetadata(0xc0000d4700, 0x1fc9360, 0xc00011e010, 0xc000284480, 0x1fd91e0, 0xc0005b60a0, 0x0, 0x0, 0x0, 0xc0000f5b50, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/compress/compress.go:629 +0x24f github.com/rclone/rclone/backend/compress.(*Fs).putWithCustomFunctions(0xc0000d4700, 0x1fc9360, 0xc00011e010, 0x1f96a80, 0xc0005ea3e0, 0x1fd91e0, 0xc0005b60a0, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/compress/compress.go:658 +0x19f github.com/rclone/rclone/backend/compress.(*Fs).Put(0xc0000d4700, 0x1fc9360, 0xc00011e010, 0x1f93a60, 0xc0004d0210, 0x1fd91e0, 0xc0005b60a0, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/compress/compress.go:687 +0x38d github.com/rclone/rclone/fstest.(*Run).WriteObjectTo(0xc000af56e0, 0x1fc9360, 0xc00011e010, 0x1fded00, 0xc0000d4700, 0x1cbd75a, 0x9, 0x1cc7a9d, 0xf, 0x75bcd15, ...) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:272 +0x364 github.com/rclone/rclone/fstest.(*Run).WriteObject(...) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:289 github.com/rclone/rclone/vfs.TestDirHandleReaddir(0xc0000fc480) /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_handle_test.go:46 +0x274 testing.tRunner(0xc0000fc480, 0x1d6bd48) /usr/local/go/src/testing/testing.go:1123 +0xef created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1168 +0x2b3 "./vfs.test -test.v -test.timeout 1h0m0s -remote TestCompressDrive: -verbose" - Finished ERROR in 45.247176085s (try 2/5): exit status 2: Failed [TestDirHandleReaddir]