"./operations.test -test.v -test.timeout 1h0m0s -remote TestDrive: -verbose -fast-list" - Starting (try 1/5) 2021/02/08 05:21:31 DEBUG : Creating backend with remote "TestDrive:rclone-test-sixucin6ramokel2qudalib1" 2021/02/08 05:21:32 DEBUG : Creating backend with remote "/tmp/rclone365471012" === RUN TestDoMultiThreadCopy --- PASS: TestDoMultiThreadCopy (0.00s) === RUN TestMultithreadCalculateChunks === RUN TestMultithreadCalculateChunks/{size:1_streams:10_wantPartSize:65536_wantStreams:1} === RUN TestMultithreadCalculateChunks/{size:1048576_streams:1_wantPartSize:1048576_wantStreams:1} === RUN TestMultithreadCalculateChunks/{size:1048576_streams:2_wantPartSize:524288_wantStreams:2} === RUN TestMultithreadCalculateChunks/{size:1048577_streams:2_wantPartSize:589824_wantStreams:2} === RUN TestMultithreadCalculateChunks/{size:1048575_streams:2_wantPartSize:524288_wantStreams:2} --- PASS: TestMultithreadCalculateChunks (0.00s) --- PASS: TestMultithreadCalculateChunks/{size:1_streams:10_wantPartSize:65536_wantStreams:1} (0.00s) --- PASS: TestMultithreadCalculateChunks/{size:1048576_streams:1_wantPartSize:1048576_wantStreams:1} (0.00s) --- PASS: TestMultithreadCalculateChunks/{size:1048576_streams:2_wantPartSize:524288_wantStreams:2} (0.00s) --- PASS: TestMultithreadCalculateChunks/{size:1048577_streams:2_wantPartSize:589824_wantStreams:2} (0.00s) --- PASS: TestMultithreadCalculateChunks/{size:1048575_streams:2_wantPartSize:524288_wantStreams:2} (0.00s) === RUN TestMultithreadCopy run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" === RUN TestMultithreadCopy/{size:131071_streams:2} 2021/02/08 05:21:35 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64k 2021/02/08 05:21:35 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999k starting 2021/02/08 05:21:35 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k starting 2021/02/08 05:21:36 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999k finished 2021/02/08 05:21:36 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k finished 2021/02/08 05:21:36 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64k === RUN TestMultithreadCopy/{size:131072_streams:2} 2021/02/08 05:21:38 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64k 2021/02/08 05:21:38 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64k starting 2021/02/08 05:21:38 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k starting 2021/02/08 05:21:39 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64k finished 2021/02/08 05:21:39 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k finished 2021/02/08 05:21:39 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64k === RUN TestMultithreadCopy/{size:131073_streams:2} 2021/02/08 05:21:41 DEBUG : file1: Starting multi-thread copy with 2 parts of size 128k 2021/02/08 05:21:41 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 starting 2021/02/08 05:21:41 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128k starting 2021/02/08 05:21:41 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128k finished 2021/02/08 05:21:41 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 finished 2021/02/08 05:21:41 DEBUG : file1: Finished multi-thread copy with 2 parts of size 128k --- PASS: TestMultithreadCopy (10.53s) --- PASS: TestMultithreadCopy/{size:131071_streams:2} (4.15s) --- PASS: TestMultithreadCopy/{size:131072_streams:2} (2.60s) --- PASS: TestMultithreadCopy/{size:131073_streams:2} (2.66s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Seek === RUN TestReOpen/Seek/Basics === RUN TestReOpen/Seek/ErrorAtStart === RUN TestReOpen/Seek/WithErrors 2021/02/08 05:21:43 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2021/02/08 05:21:43 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2021/02/08 05:21:43 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Seek/TooManyErrors 2021/02/08 05:21:43 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2021/02/08 05:21:43 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2021/02/08 05:21:43 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2021/02/08 05:21:43 DEBUG : potato: Reopen failed after 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Range === RUN TestReOpen/Range/Basics === RUN TestReOpen/Range/ErrorAtStart === RUN TestReOpen/Range/WithErrors 2021/02/08 05:21:43 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2021/02/08 05:21:43 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2021/02/08 05:21:43 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Range/TooManyErrors 2021/02/08 05:21:43 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2021/02/08 05:21:43 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2021/02/08 05:21:43 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2021/02/08 05:21:43 DEBUG : potato: Reopen failed after 6 bytes read: failed to reopen: too many retries --- PASS: TestReOpen (0.00s) --- PASS: TestReOpen/Seek (0.00s) --- PASS: TestReOpen/Seek/Basics (0.00s) --- PASS: TestReOpen/Seek/ErrorAtStart (0.00s) --- PASS: TestReOpen/Seek/WithErrors (0.00s) --- PASS: TestReOpen/Seek/TooManyErrors (0.00s) --- PASS: TestReOpen/Range (0.00s) --- PASS: TestReOpen/Range/Basics (0.00s) --- PASS: TestReOpen/Range/ErrorAtStart (0.00s) --- PASS: TestReOpen/Range/WithErrors (0.00s) --- PASS: TestReOpen/Range/TooManyErrors (0.00s) === RUN TestCheck run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" === RUN TestCheck/1 === RUN TestCheck/2 === RUN TestCheck/3 === RUN TestCheck/4 === RUN TestCheck/5 === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (12.18s) --- PASS: TestCheck/1 (0.23s) --- PASS: TestCheck/2 (1.25s) --- PASS: TestCheck/3 (0.25s) --- PASS: TestCheck/4 (0.27s) --- PASS: TestCheck/5 (0.24s) --- PASS: TestCheck/6 (0.27s) --- PASS: TestCheck/7 (0.26s) === RUN TestCheckFsError 2021/02/08 05:21:55 DEBUG : Creating backend with remote "non-existent" 2021/02/08 05:21:55 DEBUG : Creating backend with remote "non-existent" 2021/02/08 05:21:55 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: Waiting for checks to finish 2021/02/08 05:21:55 ERROR : : error reading source directory: directory not found 2021/02/08 05:21:55 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 differences found 2021/02/08 05:21:55 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (15.43s) --- PASS: TestCheckDownload/1 (0.97s) --- PASS: TestCheckDownload/2 (0.90s) --- PASS: TestCheckDownload/3 (0.86s) --- PASS: TestCheckDownload/4 (0.77s) --- PASS: TestCheckDownload/5 (0.72s) --- PASS: TestCheckDownload/6 (0.74s) --- PASS: TestCheckDownload/7 (0.73s) === RUN TestCheckSizeOnly run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (11.21s) --- PASS: TestCheckSizeOnly/1 (0.23s) --- PASS: TestCheckSizeOnly/2 (0.22s) --- PASS: TestCheckSizeOnly/3 (0.28s) --- PASS: TestCheckSizeOnly/4 (0.26s) --- PASS: TestCheckSizeOnly/5 (0.22s) --- PASS: TestCheckSizeOnly/6 (0.23s) --- PASS: TestCheckSizeOnly/7 (0.30s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestDeduplicateInteractive run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:22:25 INFO : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': Looking for duplicate names using interactive mode. 2021/02/08 05:22:26 NOTICE: one: Found 3 files with duplicate names 2021/02/08 05:22:26 NOTICE: one: Deleting 2/3 identical duplicates (MD5 0f6d3e59c45e54d0f2cb30fb594620a3) 2021/02/08 05:22:26 INFO : one: Deleted 2021/02/08 05:22:26 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/02/08 05:22:26 DEBUG : pacer: Rate limited, increasing sleep to 1.200104683s 2021/02/08 05:22:27 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:22:27 INFO : one: Deleted 2021/02/08 05:22:27 NOTICE: one: All duplicates removed --- PASS: TestDeduplicateInteractive (7.67s) === RUN TestDeduplicateSkip run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:22:33 INFO : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': Looking for duplicate names using skip mode. 2021/02/08 05:22:33 NOTICE: one: Found 3 files with duplicate names 2021/02/08 05:22:33 NOTICE: one: Deleting 1/2 identical duplicates (MD5 0f6d3e59c45e54d0f2cb30fb594620a3) 2021/02/08 05:22:34 INFO : one: Deleted 2021/02/08 05:22:34 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSkip (6.58s) === RUN TestDeduplicateSizeOnly run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:22:39 INFO : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': Looking for duplicate names using skip mode. 2021/02/08 05:22:40 NOTICE: one: Found 3 files with duplicate names 2021/02/08 05:22:40 NOTICE: one: Deleting 1/2 identical duplicates (size 11) 2021/02/08 05:22:40 INFO : one: Deleted 2021/02/08 05:22:40 NOTICE: one: Skipping 2 files with duplicate names 2021/02/08 05:22: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/02/08 05:22:40 DEBUG : pacer: Rate limited, increasing sleep to 1.950231739s 2021/02/08 05:22:41 DEBUG : pacer: Reducing sleep to 0s --- PASS: TestDeduplicateSizeOnly (8.51s) === RUN TestDeduplicateFirst run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:22:48 INFO : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': Looking for duplicate names using first mode. 2021/02/08 05:22:48 NOTICE: one: Found 3 files with duplicate names 2021/02/08 05:22:49 INFO : one: Deleted 2021/02/08 05:22:49 INFO : one: Deleted 2021/02/08 05:22:49 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateFirst (6.45s) === RUN TestDeduplicateNewest run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:22:54 INFO : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': Looking for duplicate names using newest mode. 2021/02/08 05:22:55 NOTICE: one: Found 3 files with duplicate names 2021/02/08 05:22:55 INFO : one: Deleted 2021/02/08 05:22:56 INFO : one: Deleted 2021/02/08 05:22:56 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateNewest (6.67s) === RUN TestDeduplicateNewestByHash run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:22:57 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/02/08 05:22:57 DEBUG : pacer: Rate limited, increasing sleep to 1.760803203s 2021/02/08 05:22:57 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/02/08 05:22:57 DEBUG : pacer: Rate limited, increasing sleep to 2.778069416s 2021/02/08 05:22:59 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:23:09 INFO : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': Looking for duplicate MD5 hashes using newest mode. 2021/02/08 05:23:09 NOTICE: 34f10581897d1de028eb82e0da16ed39: Found 3 files with duplicate MD5 hashes 2021/02/08 05:23:10 INFO : one: Deleted 2021/02/08 05:23:10 INFO : also/one: Deleted 2021/02/08 05:23:10 NOTICE: 34f10581897d1de028eb82e0da16ed39: Deleted 2 extra copies 2021/02/08 05:23:13 DEBUG : also: Rmdir: contains trashed file: "one" --- PASS: TestDeduplicateNewestByHash (16.56s) === RUN TestDeduplicateOldest run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:23:18 INFO : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': Looking for duplicate names using oldest mode. 2021/02/08 05:23:18 NOTICE: one: Found 3 files with duplicate names 2021/02/08 05:23:19 INFO : one: Deleted 2021/02/08 05:23:19 INFO : one: Deleted 2021/02/08 05:23:19 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateOldest (6.78s) === RUN TestDeduplicateLargest run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:23:24 INFO : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': Looking for duplicate names using largest mode. 2021/02/08 05:23:25 NOTICE: one: Found 3 files with duplicate names 2021/02/08 05:23:25 INFO : one: Deleted 2021/02/08 05:23:26 INFO : one: Deleted 2021/02/08 05:23:26 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateLargest (6.72s) === RUN TestDeduplicateSmallest run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:23:31 INFO : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': Looking for duplicate names using smallest mode. 2021/02/08 05:23:31 NOTICE: one: Found 3 files with duplicate names 2021/02/08 05:23:32 INFO : one: Deleted 2021/02/08 05:23:33 INFO : one: Deleted 2021/02/08 05:23:33 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateSmallest (6.83s) === RUN TestDeduplicateRename run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:23:40 INFO : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': Looking for duplicate names using rename mode. 2021/02/08 05:23:40 NOTICE: one.txt: Found 3 files with duplicate names 2021/02/08 05:23:42 INFO : one-2.txt: renamed from: one.txt 2021/02/08 05:23:43 INFO : one-3.txt: renamed from: one.txt 2021/02/08 05:23:44 INFO : one-4.txt: renamed from: one.txt --- PASS: TestDeduplicateRename (13.36s) === RUN TestMergeDirs run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:24:01 INFO : dupe2: merging "two.txt" 2021/02/08 05:24:02 INFO : dupe2: removing empty directory 2021/02/08 05:24:03 INFO : dupe3: merging "three.txt" 2021/02/08 05:24:04 INFO : dupe3: removing empty directory 2021/02/08 05:24:13 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/02/08 05:24:13 DEBUG : pacer: Rate limited, increasing sleep to 1.486936745s 2021/02/08 05:24:13 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:24:15 DEBUG : dupe1: Rmdir: contains trashed file: "three.txt" 2021/02/08 05:24:15 DEBUG : dupe1: Rmdir: contains trashed file: "two.txt" 2021/02/08 05:24:15 DEBUG : dupe1: Rmdir: contains trashed file: "one.txt" --- PASS: TestMergeDirs (28.38s) === RUN TestListDirSorted run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:24:31 DEBUG : a.txt: Excluded 2021/02/08 05:24:32 DEBUG : sub dir/hello world: Excluded 2021/02/08 05:24:32 DEBUG : sub dir/hello world2: Excluded 2021/02/08 05:24:32 DEBUG : sub dir/ignore dir: Excluded 2021/02/08 05:24:32 DEBUG : sub dir/hello world: Excluded 2021/02/08 05:24:32 DEBUG : sub dir/hello world2: Excluded 2021/02/08 05:24:33 DEBUG : sub dir/ignore dir: Excluded 2021/02/08 05:24:39 DEBUG : sub dir/sub sub dir: Rmdir: contains trashed file: "hello world3" 2021/02/08 05:24:40 DEBUG : sub dir/ignore dir: Rmdir: contains trashed file: "should be ignored" 2021/02/08 05:24:40 DEBUG : sub dir/ignore dir: Rmdir: contains trashed file: ".ignore" 2021/02/08 05:24:41 DEBUG : sub dir: Rmdir: contains trashed file: "sub sub dir" 2021/02/08 05:24:41 DEBUG : sub dir: Rmdir: contains trashed file: "ignore dir" 2021/02/08 05:24:41 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" 2021/02/08 05:24:41 DEBUG : sub dir: Rmdir: contains trashed file: "hello world2" --- PASS: TestListDirSorted (25.57s) === RUN TestMkdir run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:24:41 DEBUG : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': Making directory 2021/02/08 05:24:42 DEBUG : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': Making directory --- PASS: TestMkdir (0.68s) === RUN TestLsd run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:24:47 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestLsd (5.48s) === RUN TestLs run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" --- PASS: TestLs (5.04s) === RUN TestLsWithFilesFrom run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:24:56 DEBUG : empty space: Excluded from sync (and deletion) --- PASS: TestLsWithFilesFrom (4.94s) === RUN TestLsLong run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" --- PASS: TestLsLong (4.76s) === RUN TestHashSums run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:25:08 ERROR : potato2: Hash unsupported: hash type not supported 2021/02/08 05:25:08 ERROR : empty space: Hash unsupported: hash type not supported 2021/02/08 05:25:08 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/02/08 05:25:08 DEBUG : pacer: Rate limited, increasing sleep to 1.704973465s 2021/02/08 05:25:09 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:25:11 ERROR : potato2: Hash unsupported: hash type not supported 2021/02/08 05:25:11 ERROR : empty space: Hash unsupported: hash type not supported 2021/02/08 05:25:12 ERROR : potato2: Hash unsupported: hash type not supported 2021/02/08 05:25:12 ERROR : empty space: Hash unsupported: hash type not supported --- PASS: TestHashSums (12.99s) === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:25:18 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/02/08 05:25:18 DEBUG : pacer: Rate limited, increasing sleep to 1.347983122s 2021/02/08 05:25:19 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/02/08 05:25:19 DEBUG : pacer: Rate limited, increasing sleep to 2.754741684s 2021/02/08 05:25:20 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:25:28 DEBUG : sub dir: Rmdir: contains trashed file: "potato3" --- PASS: TestCount (14.16s) === RUN TestDelete run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:25:35 DEBUG : Waiting for deletions to finish 2021/02/08 05:25:35 DEBUG : large: Excluded from sync (and deletion) 2021/02/08 05:25:36 INFO : medium: Deleted 2021/02/08 05:25:36 INFO : small: Deleted 2021/02/08 05:25:36 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/02/08 05:25:36 DEBUG : pacer: Rate limited, increasing sleep to 1.269373241s 2021/02/08 05:25:36 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/02/08 05:25:36 DEBUG : pacer: Rate limited, increasing sleep to 2.887317237s 2021/02/08 05:25:38 DEBUG : pacer: Reducing sleep to 0s --- PASS: TestDelete (11.15s) === RUN TestRetry 2021/02/08 05:25:41 DEBUG : Received error: EOF - low level retry 1/5 2021/02/08 05:25:41 DEBUG : Received error: EOF - low level retry 2/5 2021/02/08 05:25:41 DEBUG : Received error: EOF - low level retry 1/5 2021/02/08 05:25:41 DEBUG : Received error: EOF - low level retry 2/5 2021/02/08 05:25:41 DEBUG : Received error: EOF - low level retry 3/5 2021/02/08 05:25:41 DEBUG : Received error: EOF - low level retry 4/5 2021/02/08 05:25:41 DEBUG : Received error: EOF - low level retry 5/5 --- PASS: TestRetry (0.00s) === RUN TestCat run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" --- PASS: TestCat (9.07s) === RUN TestPurge 2021/02/08 05:25:50 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/02/08 05:25:50 DEBUG : Creating backend with remote "TestDrive:rclone-test-cesijab6dopetag1nasehup9" 2021/02/08 05:25:50 DEBUG : Creating backend with remote "/tmp/rclone237678070" run.go:176: Remote "Google drive root 'rclone-test-cesijab6dopetag1nasehup9'", Local "Local file system at /tmp/rclone237678070", Modify Window "1ms" 2021/02/08 05:25:56 DEBUG : A2: Making directory 2021/02/08 05:25:57 DEBUG : A1/B2: Making directory 2021/02/08 05:25:58 DEBUG : A1/B2/C2: Making directory 2021/02/08 05:25:59 DEBUG : A1/B1/C3: Making directory 2021/02/08 05:26:00 DEBUG : A3: Making directory 2021/02/08 05:26:01 DEBUG : A3/B3: Making directory 2021/02/08 05:26:03 DEBUG : A3/B3/C4: Making directory 2021/02/08 05:26:09 DEBUG : Google drive root 'rclone-test-cesijab6dopetag1nasehup9': Purge remote 2021/02/08 05:26:09 purge failed: directory not found --- PASS: TestPurge (19.17s) === RUN TestRmdirsNoLeaveRoot run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:26:14 DEBUG : A2: Making directory 2021/02/08 05:26:15 DEBUG : A1/B2: Making directory 2021/02/08 05:26:16 DEBUG : A1/B2/C2: Making directory 2021/02/08 05:26:17 DEBUG : A1/B1/C3: Making directory 2021/02/08 05:26:18 DEBUG : A3: Making directory 2021/02/08 05:26:19 DEBUG : A3/B3: Making directory 2021/02/08 05:26:20 DEBUG : A3/B3/C4: Making directory 2021/02/08 05:26:24 DEBUG : A3/B3/C4: Removing directory 2021/02/08 05:26:26 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/02/08 05:26:26 DEBUG : pacer: Rate limited, increasing sleep to 1.463264604s 2021/02/08 05:26:26 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/02/08 05:26:26 DEBUG : pacer: Rate limited, increasing sleep to 2.659283772s 2021/02/08 05:26:26 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:26:28 DEBUG : pacer: low level retry 3/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/02/08 05:26:28 DEBUG : pacer: Rate limited, increasing sleep to 1.298521717s 2021/02/08 05:26:28 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/02/08 05:26:28 DEBUG : pacer: Rate limited, increasing sleep to 2.335395739s 2021/02/08 05:26:28 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/02/08 05:26:28 DEBUG : pacer: Rate limited, increasing sleep to 4.998716392s 2021/02/08 05:26:28 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/02/08 05:26:28 DEBUG : pacer: Rate limited, increasing sleep to 8.453686163s 2021/02/08 05:26:28 DEBUG : pacer: low level retry 4/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/02/08 05:26:28 DEBUG : pacer: Rate limited, increasing sleep to 16.804132948s 2021/02/08 05:26:29 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:26:46 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/02/08 05:26:46 DEBUG : pacer: Rate limited, increasing sleep to 1.652925435s 2021/02/08 05:26:46 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/02/08 05:26:46 DEBUG : pacer: Rate limited, increasing sleep to 2.125857011s 2021/02/08 05:26:46 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/02/08 05:26:46 DEBUG : pacer: Rate limited, increasing sleep to 4.409566625s 2021/02/08 05:26:48 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/02/08 05:26:48 DEBUG : pacer: Rate limited, increasing sleep to 8.010527374s 2021/02/08 05:26:53 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:27:01 DEBUG : A3/B3: Removing directory 2021/02/08 05:27:01 DEBUG : A3/B3: Rmdir: contains trashed file: "C4" 2021/02/08 05:27:02 DEBUG : A3: Removing directory 2021/02/08 05:27:02 DEBUG : A3: Rmdir: contains trashed file: "B3" 2021/02/08 05:27:02 DEBUG : A2: Removing directory 2021/02/08 05:27:04 DEBUG : A1/B2/C2: Removing directory 2021/02/08 05:27:04 DEBUG : A1/B2: Removing directory 2021/02/08 05:27:05 DEBUG : A1/B2: Rmdir: contains trashed file: "C2" 2021/02/08 05:27:05 DEBUG : A1/B1/C3: Removing directory 2021/02/08 05:27: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/02/08 05:27:06 DEBUG : pacer: Rate limited, increasing sleep to 1.988038865s 2021/02/08 05:27:06 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/02/08 05:27:06 DEBUG : pacer: Rate limited, increasing sleep to 2.865953758s 2021/02/08 05:27:08 DEBUG : pacer: low level retry 3/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/02/08 05:27:08 DEBUG : pacer: Rate limited, increasing sleep to 4.889867162s 2021/02/08 05:27:12 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:27:19 DEBUG : A1/B1/C1: Rmdir: contains trashed file: "one" 2021/02/08 05:27:20 DEBUG : A1/B1: Rmdir: contains trashed file: "C3" 2021/02/08 05:27:20 DEBUG : A1/B1: Rmdir: contains trashed file: "C1" 2021/02/08 05:27:21 DEBUG : A1: Rmdir: contains trashed file: "B2" 2021/02/08 05:27:21 DEBUG : A1: Rmdir: contains trashed file: "B1" 2021/02/08 05:27:21 DEBUG : A1: Rmdir: contains trashed file: "two" --- PASS: TestRmdirsNoLeaveRoot (73.12s) === RUN TestRmdirsLeaveRoot run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:27:22 DEBUG : A1: Making directory 2021/02/08 05:27:23 DEBUG : A1/B1: Making directory 2021/02/08 05:27:25 DEBUG : A1/B1/C1: Making directory 2021/02/08 05:27:26 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/02/08 05:27:26 DEBUG : pacer: Rate limited, increasing sleep to 1.818738717s 2021/02/08 05:27:26 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/02/08 05:27:26 DEBUG : pacer: Rate limited, increasing sleep to 2.717075833s 2021/02/08 05:27:28 DEBUG : pacer: low level retry 3/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/02/08 05:27:28 DEBUG : pacer: Rate limited, increasing sleep to 4.283894556s 2021/02/08 05:27:31 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:27:36 DEBUG : A1/B1/C1: Removing directory 2021/02/08 05:27:37 DEBUG : A1/B1: Removing directory 2021/02/08 05:27:37 DEBUG : A1/B1: Rmdir: contains trashed file: "C1" 2021/02/08 05:27:39 DEBUG : A1: Rmdir: contains trashed file: "B1" --- PASS: TestRmdirsLeaveRoot (18.31s) === RUN TestCopyURL run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:27:46 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/02/08 05:27:46 DEBUG : pacer: Rate limited, increasing sleep to 1.887760329s 2021/02/08 05:27:46 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/02/08 05:27:46 DEBUG : pacer: Rate limited, increasing sleep to 2.293383028s 2021/02/08 05:27:48 DEBUG : pacer: low level retry 3/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/02/08 05:27:48 DEBUG : pacer: Rate limited, increasing sleep to 4.652978368s 2021/02/08 05:27:50 DEBUG : pacer: Reducing sleep to 0s --- PASS: TestCopyURL (15.68s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:27:56 DEBUG : file1: Need to transfer - File not found at Destination 2021/02/08 05:27:59 DEBUG : file1: MD5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2021/02/08 05:27:59 INFO : file1: Copied (new) to: sub/file2 2021/02/08 05:27:59 INFO : file1: Deleted 2021/02/08 05:28:00 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/02/08 05:28:00 DEBUG : file1: Unchanged skipping 2021/02/08 05:28:00 INFO : file1: Deleted 2021/02/08 05:28:00 DEBUG : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': don't need to copy/move sub/file2, it is already at target location 2021/02/08 05:28:02 DEBUG : sub: Rmdir: contains trashed file: "file2" --- PASS: TestMoveFile (6.73s) === RUN TestCaseInsensitiveMoveFile run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.44s) === RUN TestMoveFileBackupDir run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:28:07 DEBUG : Creating backend with remote "TestDrive:rclone-test-sixucin6ramokel2qudalib1/backup" 2021/02/08 05:28:07 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/02/08 05:28:07 DEBUG : pacer: Rate limited, increasing sleep to 1.158359597s 2021/02/08 05:28:07 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/02/08 05:28:07 DEBUG : pacer: Rate limited, increasing sleep to 2.59921866s 2021/02/08 05:28:08 DEBUG : pacer: low level retry 3/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/02/08 05:28:08 DEBUG : pacer: Rate limited, increasing sleep to 4.565302375s 2021/02/08 05:28:11 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:28:16 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2021/02/08 05:28:19 INFO : dst/file1: Moved (server-side) 2021/02/08 05:28:21 DEBUG : dst/file1: MD5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2021/02/08 05:28:21 INFO : dst/file1: Copied (new) 2021/02/08 05:28:21 INFO : dst/file1: Deleted 2021/02/08 05:28:24 DEBUG : dst: Rmdir: contains trashed file: "file1" 2021/02/08 05:28:25 DEBUG : backup/dst: Rmdir: contains trashed file: "file1" 2021/02/08 05:28:26 DEBUG : backup: Rmdir: contains trashed file: "dst" --- PASS: TestMoveFileBackupDir (23.29s) === RUN TestCopyFile run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:28:27 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/02/08 05:28:27 DEBUG : pacer: Rate limited, increasing sleep to 1.062458721s 2021/02/08 05:28:27 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/02/08 05:28:27 DEBUG : pacer: Rate limited, increasing sleep to 2.615011516s 2021/02/08 05:28:28 DEBUG : pacer: low level retry 3/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/02/08 05:28:28 DEBUG : pacer: Rate limited, increasing sleep to 4.503576789s 2021/02/08 05:28:30 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:28:30 DEBUG : file1: Need to transfer - File not found at Destination 2021/02/08 05:28:38 DEBUG : file1: MD5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2021/02/08 05:28:38 INFO : file1: Copied (new) to: sub/file2 2021/02/08 05:28:38 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/02/08 05:28:38 DEBUG : file1: Unchanged skipping 2021/02/08 05:28:39 DEBUG : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': don't need to copy/move sub/file2, it is already at target location 2021/02/08 05:28:41 DEBUG : sub: Rmdir: contains trashed file: "file2" --- PASS: TestCopyFile (15.22s) === RUN TestCopyFileBackupDir run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:28:45 DEBUG : Creating backend with remote "TestDrive:rclone-test-sixucin6ramokel2qudalib1/backup" 2021/02/08 05:28:46 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2021/02/08 05:28:46 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/02/08 05:28:46 DEBUG : pacer: Rate limited, increasing sleep to 1.981908347s 2021/02/08 05:28:46 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/02/08 05:28:46 DEBUG : pacer: Rate limited, increasing sleep to 2.836754621s 2021/02/08 05:28:48 DEBUG : pacer: low level retry 3/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/02/08 05:28:48 DEBUG : pacer: Rate limited, increasing sleep to 4.592510129s 2021/02/08 05:28:51 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:28:58 INFO : dst/file1: Moved (server-side) 2021/02/08 05:29:00 DEBUG : dst/file1: MD5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2021/02/08 05:29:00 INFO : dst/file1: Copied (new) 2021/02/08 05:29:03 DEBUG : dst: Rmdir: contains trashed file: "file1" 2021/02/08 05:29:04 DEBUG : backup/dst: Rmdir: contains trashed file: "file1" 2021/02/08 05:29:05 DEBUG : backup: Rmdir: contains trashed file: "dst" 2021/02/08 05:29: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/02/08 05:29:06 DEBUG : pacer: Rate limited, increasing sleep to 1.338998651s 2021/02/08 05:29:06 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/02/08 05:29:06 DEBUG : pacer: Rate limited, increasing sleep to 2.598205671s 2021/02/08 05:29:07 DEBUG : pacer: low level retry 3/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/02/08 05:29:07 DEBUG : pacer: Rate limited, increasing sleep to 4.43629132s 2021/02/08 05:29:10 DEBUG : pacer: Reducing sleep to 0s --- PASS: TestCopyFileBackupDir (28.21s) === RUN TestCopyFileCompareDest run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:29:10 DEBUG : Creating backend with remote "TestDrive:rclone-test-sixucin6ramokel2qudalib1/dst" 2021/02/08 05:29:11 DEBUG : Creating backend with remote "TestDrive:rclone-test-sixucin6ramokel2qudalib1/CompareDest" 2021/02/08 05:29:12 DEBUG : one: Need to transfer - File not found at Destination 2021/02/08 05:29:15 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2021/02/08 05:29:15 INFO : one: Copied (new) 2021/02/08 05:29:16 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/02/08 05:29:17 DEBUG : one: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2021/02/08 05:29:17 INFO : one: Copied (replaced existing) 2021/02/08 05:29: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/02/08 05:29:21 DEBUG : pacer: Rate limited, increasing sleep to 1.912009349s 2021/02/08 05:29:22 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/02/08 05:29:22 DEBUG : pacer: Rate limited, increasing sleep to 2.218319255s 2021/02/08 05:29:24 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:29:26 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/02/08 05:29:26 DEBUG : pacer: Rate limited, increasing sleep to 1.782146004s 2021/02/08 05:29:26 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/02/08 05:29:26 DEBUG : pacer: Rate limited, increasing sleep to 2.170550878s 2021/02/08 05:29:28 DEBUG : pacer: low level retry 3/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/02/08 05:29:28 DEBUG : pacer: Rate limited, increasing sleep to 4.045598822s 2021/02/08 05:29:30 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:29:34 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/02/08 05:29:34 DEBUG : one: Destination found in --compare-dest, skipping 2021/02/08 05:29:37 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/02/08 05:29:37 DEBUG : two: Destination found in --compare-dest, skipping 2021/02/08 05:29:38 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/02/08 05:29:38 DEBUG : pacer: Rate limited, increasing sleep to 1.517802527s 2021/02/08 05:29:38 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/02/08 05:29:38 DEBUG : pacer: Rate limited, increasing sleep to 2.949310077s 2021/02/08 05:29:40 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:29:40 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/02/08 05:29:40 DEBUG : two: Destination found in --compare-dest, skipping 2021/02/08 05:29:43 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/02/08 05:29:43 DEBUG : two: Need to transfer - File not found at Destination 2021/02/08 05:29:44 DEBUG : two: MD5 = 2379e4ce8c3380e996ab0509f17069ad OK 2021/02/08 05:29:44 INFO : two: Copied (new) 2021/02/08 05:29:47 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/02/08 05:29:47 DEBUG : pacer: Rate limited, increasing sleep to 1.862146324s 2021/02/08 05:29:48 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/02/08 05:29:48 DEBUG : pacer: Rate limited, increasing sleep to 2.601268481s 2021/02/08 05:29:50 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:29:52 DEBUG : dst: Rmdir: contains trashed file: "two" 2021/02/08 05:29:52 DEBUG : dst: Rmdir: contains trashed file: "one" 2021/02/08 05:29:53 DEBUG : CompareDest: Rmdir: contains trashed file: "one" 2021/02/08 05:29:53 DEBUG : CompareDest: Rmdir: contains trashed file: "two" --- PASS: TestCopyFileCompareDest (44.30s) === RUN TestCopyFileCopyDest run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:29:54 DEBUG : Creating backend with remote "TestDrive:rclone-test-sixucin6ramokel2qudalib1/dst" 2021/02/08 05:29:55 DEBUG : Creating backend with remote "TestDrive:rclone-test-sixucin6ramokel2qudalib1/CopyDest" 2021/02/08 05:29:56 DEBUG : one: Need to transfer - File not found at Destination 2021/02/08 05:29:59 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2021/02/08 05:29:59 INFO : one: Copied (new) 2021/02/08 05:30:00 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/02/08 05:30:01 DEBUG : one: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2021/02/08 05:30:01 INFO : one: Copied (replaced existing) 2021/02/08 05:30:06 DEBUG : Creating backend with remote "TestDrive:rclone-test-sixucin6ramokel2qudalib1/BackupDir" 2021/02/08 05:30:07 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/02/08 05:30:07 DEBUG : pacer: Rate limited, increasing sleep to 1.759876432s 2021/02/08 05:30:07 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/02/08 05:30:07 DEBUG : pacer: Rate limited, increasing sleep to 2.422604599s 2021/02/08 05:30:09 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:30:09 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/02/08 05:30:09 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/02/08 05:30:13 INFO : one: Moved (server-side) 2021/02/08 05:30:15 DEBUG : one: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2021/02/08 05:30:15 INFO : one: Copied (server-side copy) 2021/02/08 05:30:15 DEBUG : one: Destination found in --copy-dest, using server-side copy 2021/02/08 05:30:18 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/02/08 05:30:19 DEBUG : two: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2021/02/08 05:30:19 INFO : two: Copied (server-side copy) 2021/02/08 05:30:19 DEBUG : two: Destination found in --copy-dest, using server-side copy 2021/02/08 05:30:20 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/02/08 05:30:20 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/02/08 05:30:20 DEBUG : two: Unchanged skipping 2021/02/08 05:30:23 DEBUG : three: Sizes differ (src 7 vs dst 5) 2021/02/08 05:30:23 DEBUG : three: Destination not found in --copy-dest 2021/02/08 05:30:23 DEBUG : three: Need to transfer - File not found at Destination 2021/02/08 05:30:23 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/02/08 05:30:23 DEBUG : pacer: Rate limited, increasing sleep to 1.058536608s 2021/02/08 05:30:24 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:30:26 DEBUG : three: MD5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2021/02/08 05:30:26 INFO : three: Copied (new) 2021/02/08 05:30:26 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/02/08 05:30:26 DEBUG : pacer: Rate limited, increasing sleep to 1.067986731s 2021/02/08 05:30:26 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/02/08 05:30:26 DEBUG : pacer: Rate limited, increasing sleep to 2.120046055s 2021/02/08 05:30:27 DEBUG : pacer: low level retry 3/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/02/08 05:30:27 DEBUG : pacer: Rate limited, increasing sleep to 4.421684479s 2021/02/08 05:30:29 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:30:39 DEBUG : dst: Rmdir: contains trashed file: "three" 2021/02/08 05:30:39 DEBUG : dst: Rmdir: contains trashed file: "two" 2021/02/08 05:30:39 DEBUG : dst: Rmdir: contains trashed file: "one" 2021/02/08 05:30:40 DEBUG : CopyDest: Rmdir: contains trashed file: "two" 2021/02/08 05:30:40 DEBUG : CopyDest: Rmdir: contains trashed file: "three" 2021/02/08 05:30:40 DEBUG : CopyDest: Rmdir: contains trashed file: "one" 2021/02/08 05:30:41 DEBUG : BackupDir: Rmdir: contains trashed file: "one" --- PASS: TestCopyFileCopyDest (48.07s) === RUN TestSameConfig --- PASS: TestSameConfig (0.00s) === RUN TestSame --- PASS: TestSame (0.00s) === RUN TestOverlapping --- PASS: TestOverlapping (0.00s) === RUN TestListFormat --- PASS: TestListFormat (0.00s) === RUN TestDirMove run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:30:46 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/02/08 05:30:46 DEBUG : pacer: Rate limited, increasing sleep to 1.64166552s 2021/02/08 05:30:46 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/02/08 05:30:46 DEBUG : pacer: Rate limited, increasing sleep to 2.489360099s 2021/02/08 05:30:48 DEBUG : pacer: low level retry 3/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/02/08 05:30:48 DEBUG : pacer: Rate limited, increasing sleep to 4.747036103s 2021/02/08 05:30:50 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:31:03 DEBUG : A1/B2: Making directory 2021/02/08 05:31:04 DEBUG : A1/B1/C3: Making directory 2021/02/08 05:31: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/02/08 05:31:06 DEBUG : pacer: Rate limited, increasing sleep to 1.698262519s 2021/02/08 05:31:06 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/02/08 05:31:06 DEBUG : pacer: Rate limited, increasing sleep to 2.949553517s 2021/02/08 05:31:08 DEBUG : pacer: low level retry 3/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/02/08 05:31:08 DEBUG : pacer: Rate limited, increasing sleep to 4.159567346s 2021/02/08 05:31:11 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:31: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/02/08 05:31:16 DEBUG : pacer: Rate limited, increasing sleep to 1.779406704s 2021/02/08 05:31:17 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:31:26 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2021/02/08 05:31:26 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five 2021/02/08 05:31:27 INFO : A2/one: Moved (server-side) to: A3/one 2021/02/08 05:31:27 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/02/08 05:31:27 DEBUG : pacer: Rate limited, increasing sleep to 1.1626826s 2021/02/08 05:31:27 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:31:27 INFO : A2/two: Moved (server-side) to: A3/two 2021/02/08 05:31:28 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2021/02/08 05:31:28 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/02/08 05:31:28 DEBUG : pacer: Rate limited, increasing sleep to 1.40137327s 2021/02/08 05:31:28 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/02/08 05:31:28 DEBUG : pacer: Rate limited, increasing sleep to 2.447671465s 2021/02/08 05:31:31 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:31:37 DEBUG : A2/B1: Rmdir: contains trashed file: "C3" 2021/02/08 05:31:37 DEBUG : A2/B1: Rmdir: contains trashed file: "C2" 2021/02/08 05:31:37 DEBUG : A2/B1: Rmdir: contains trashed file: "C1" 2021/02/08 05:31:38 DEBUG : A2: Rmdir: contains trashed file: "B2" 2021/02/08 05:31:38 DEBUG : A2: Rmdir: contains trashed file: "B1" 2021/02/08 05:31:41 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/02/08 05:31:41 DEBUG : pacer: Rate limited, increasing sleep to 1.702323447s 2021/02/08 05:31:41 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:31:46 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/02/08 05:31:46 DEBUG : pacer: Rate limited, increasing sleep to 1.278005111s 2021/02/08 05:31:46 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/02/08 05:31:46 DEBUG : pacer: Rate limited, increasing sleep to 2.088677519s 2021/02/08 05:31:48 DEBUG : pacer: low level retry 3/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/02/08 05:31:48 DEBUG : pacer: Rate limited, increasing sleep to 4.887302992s 2021/02/08 05:31:50 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:31:57 DEBUG : A3/B1/C2: Rmdir: contains trashed file: "five" 2021/02/08 05:31:58 DEBUG : A3/B1/C1: Rmdir: contains trashed file: "four" 2021/02/08 05:31:59 DEBUG : A3/B1: Rmdir: contains trashed file: "C3" 2021/02/08 05:31:59 DEBUG : A3/B1: Rmdir: contains trashed file: "C2" 2021/02/08 05:31:59 DEBUG : A3/B1: Rmdir: contains trashed file: "C1" 2021/02/08 05:31:59 DEBUG : A3/B1: Rmdir: contains trashed file: "three" 2021/02/08 05:32:00 DEBUG : A3: Rmdir: contains trashed file: "B2" 2021/02/08 05:32:00 DEBUG : A3: Rmdir: contains trashed file: "B1" 2021/02/08 05:32:00 DEBUG : A3: Rmdir: contains trashed file: "two" 2021/02/08 05:32:00 DEBUG : A3: Rmdir: contains trashed file: "one" --- PASS: TestDirMove (78.30s) === RUN TestGetFsInfo run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" --- PASS: TestGetFsInfo (0.43s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false === CONT TestRcat run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:32:01 DEBUG : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': File to upload is small (34 bytes), uploading instead of streaming 2021/02/08 05:32:03 DEBUG : no_checksum_small_file_from_pipe: MD5 = 4c762bc6ec18f21b23fcae426c7863b5 OK 2021/02/08 05:32:03 INFO : no_checksum_small_file_from_pipe: Copied (new) 2021/02/08 05:32:03 DEBUG : no_checksum_big_file_from_pipe: Sending chunk 0 length 102401 2021/02/08 05:32:04 DEBUG : no_checksum_big_file_from_pipe: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) === RUN TestRcat/withChecksum=true,ignoreChecksum=false === CONT TestRcat run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:32:06 DEBUG : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': File to upload is small (34 bytes), uploading instead of streaming 2021/02/08 05:32:07 DEBUG : with_checksum_small_file_from_pipe: MD5 = 4c762bc6ec18f21b23fcae426c7863b5 OK 2021/02/08 05:32:07 INFO : with_checksum_small_file_from_pipe: Copied (new) 2021/02/08 05:32:08 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/02/08 05:32:08 DEBUG : pacer: Rate limited, increasing sleep to 1.024010924s 2021/02/08 05:32:08 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/02/08 05:32:08 DEBUG : pacer: Rate limited, increasing sleep to 2.556478102s 2021/02/08 05:32:09 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:32:11 DEBUG : with_checksum_big_file_from_pipe: Sending chunk 0 length 102401 2021/02/08 05:32:12 DEBUG : with_checksum_big_file_from_pipe: MD5 = fffc7956ba9a7b58a63c01b6ce1ddc45 OK 2021/02/08 05:32:12 DEBUG : with_checksum_big_file_from_pipe: Size and MD5 of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true === CONT TestRcat run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:32:15 DEBUG : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': File to upload is small (34 bytes), uploading instead of streaming 2021/02/08 05:32:16 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2021/02/08 05:32:16 DEBUG : ignore_checksum_big_file_from_pipe: Sending chunk 0 length 102401 2021/02/08 05:32:17 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/02/08 05:32:18 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/02/08 05:32:18 DEBUG : pacer: Rate limited, increasing sleep to 1.809333606s 2021/02/08 05:32:18 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/02/08 05:32:18 DEBUG : pacer: Rate limited, increasing sleep to 2.516550978s 2021/02/08 05:32:20 DEBUG : pacer: Reducing sleep to 0s === RUN TestRcat/withChecksum=true,ignoreChecksum=true === CONT TestRcat run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:32:24 DEBUG : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': File to upload is small (34 bytes), uploading instead of streaming 2021/02/08 05:32:25 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2021/02/08 05:32:25 DEBUG : ignore_checksum_big_file_from_pipe: Sending chunk 0 length 102401 2021/02/08 05:32:42 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (43.07s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.05s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (8.32s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (9.15s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (20.55s) === RUN TestRcatSize run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:32:46 DEBUG : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': File to upload is small (60 bytes), uploading instead of streaming 2021/02/08 05:32:47 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2021/02/08 05:32:47 INFO : potato2: Copied (new) 2021/02/08 05:32:49 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/02/08 05:32:49 DEBUG : pacer: Rate limited, increasing sleep to 1.81433336s 2021/02/08 05:32:49 DEBUG : pacer: Reducing sleep to 0s --- PASS: TestRcatSize (6.58s) === RUN TestCopyFileMaxTransfer run.go:176: Remote "Google drive root 'rclone-test-sixucin6ramokel2qudalib1'", Local "Local file system at /tmp/rclone365471012", Modify Window "1ms" 2021/02/08 05:32:51 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2021/02/08 05:32:53 DEBUG : TestCopyFileMaxTransfer/file1: MD5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2021/02/08 05:32:53 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2021/02/08 05:32:54 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2021/02/08 05:32:55 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Max transfer limit reached as set by --max-transfer 2021/02/08 05:32:56 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2021/02/08 05:32:56 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2021/02/08 05:32:58 DEBUG : TestCopyFileMaxTransfer/file4: MD5 = cf96071acf9ee19f4189f6fb6e685591 OK 2021/02/08 05:32:58 INFO : TestCopyFileMaxTransfer/file4: Copied (new) 2021/02/08 05:32:58 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/02/08 05:32:58 DEBUG : pacer: Rate limited, increasing sleep to 1.8857406s 2021/02/08 05:32:58 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/02/08 05:32:58 DEBUG : pacer: Rate limited, increasing sleep to 2.659151304s 2021/02/08 05:33:00 DEBUG : pacer: Reducing sleep to 0s 2021/02/08 05:33:04 DEBUG : TestCopyFileMaxTransfer: Rmdir: contains trashed file: "file4" 2021/02/08 05:33:04 DEBUG : TestCopyFileMaxTransfer: Rmdir: contains trashed file: "file1" --- PASS: TestCopyFileMaxTransfer (14.64s) === RUN TestRcAbout rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcAbout (0.00s) === RUN TestRcCleanup rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcCleanup (0.00s) === RUN TestRcCopyfile rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcCopyfile (0.00s) === RUN TestRcCopyurl rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcCopyurl (0.00s) === RUN TestRcDelete rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcDelete (0.00s) === RUN TestRcDeletefile rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcDeletefile (0.00s) === RUN TestRcList rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcMkdir rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:25: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:25: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) PASS 2021/02/08 05:33:05 DEBUG : Google drive root 'rclone-test-sixucin6ramokel2qudalib1': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestDrive: -verbose -fast-list" - Finished OK in 11m35.201785177s (try 1/5)