"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2021/01/04 05:29:06 DEBUG : Creating backend with remote "TestB2:rclone-test-radurin8zuzahox3tixanik6" 2021/01/04 05:29:07 DEBUG : Creating backend with remote "/tmp/rclone526761743" === 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 "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" === RUN TestMultithreadCopy/{size:131071_streams:2} 2021/01/04 05:29:13 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64k 2021/01/04 05:29:13 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999k starting 2021/01/04 05:29:13 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k starting 2021/01/04 05:29:13 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999k finished 2021/01/04 05:29:14 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k finished 2021/01/04 05:29:14 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64k === RUN TestMultithreadCopy/{size:131072_streams:2} 2021/01/04 05:29:15 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64k 2021/01/04 05:29:15 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64k starting 2021/01/04 05:29:15 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k starting 2021/01/04 05:29:15 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64k finished 2021/01/04 05:29:16 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k finished 2021/01/04 05:29:16 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64k === RUN TestMultithreadCopy/{size:131073_streams:2} 2021/01/04 05:29:17 DEBUG : file1: Starting multi-thread copy with 2 parts of size 128k 2021/01/04 05:29:17 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 starting 2021/01/04 05:29:17 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128k starting 2021/01/04 05:29:17 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 finished 2021/01/04 05:29:18 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128k finished 2021/01/04 05:29:18 DEBUG : file1: Finished multi-thread copy with 2 parts of size 128k --- PASS: TestMultithreadCopy (11.43s) --- PASS: TestMultithreadCopy/{size:131071_streams:2} (6.61s) --- PASS: TestMultithreadCopy/{size:131072_streams:2} (2.18s) --- PASS: TestMultithreadCopy/{size:131073_streams:2} (2.06s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Seek === RUN TestReOpen/Seek/Basics === RUN TestReOpen/Seek/ErrorAtStart === RUN TestReOpen/Seek/WithErrors 2021/01/04 05:29:18 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2021/01/04 05:29:18 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2021/01/04 05:29:18 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Seek/TooManyErrors 2021/01/04 05:29:18 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2021/01/04 05:29:18 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2021/01/04 05:29:18 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2021/01/04 05:29:18 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/01/04 05:29:18 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2021/01/04 05:29:18 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2021/01/04 05:29:18 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Range/TooManyErrors 2021/01/04 05:29:18 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2021/01/04 05:29:18 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2021/01/04 05:29:18 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2021/01/04 05:29:18 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 "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", 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 (8.41s) --- PASS: TestCheck/1 (0.14s) --- PASS: TestCheck/2 (0.14s) --- PASS: TestCheck/3 (0.14s) --- PASS: TestCheck/4 (0.15s) --- PASS: TestCheck/5 (0.14s) --- PASS: TestCheck/6 (0.14s) --- PASS: TestCheck/7 (0.14s) === RUN TestCheckFsError 2021/01/04 05:29:27 DEBUG : Creating backend with remote "non-existent" 2021/01/04 05:29:27 DEBUG : Creating backend with remote "non-existent" 2021/01/04 05:29:27 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: Waiting for checks to finish 2021/01/04 05:29:27 ERROR : : error reading source directory: directory not found 2021/01/04 05:29:27 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 differences found 2021/01/04 05:29:27 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 "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", 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 (25.39s) --- PASS: TestCheckDownload/1 (0.74s) --- PASS: TestCheckDownload/2 (0.28s) --- PASS: TestCheckDownload/3 (0.29s) --- PASS: TestCheckDownload/4 (0.43s) --- PASS: TestCheckDownload/5 (0.30s) --- PASS: TestCheckDownload/6 (0.73s) --- PASS: TestCheckDownload/7 (0.30s) === RUN TestCheckSizeOnly run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", 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 (32.51s) --- PASS: TestCheckSizeOnly/1 (0.14s) --- PASS: TestCheckSizeOnly/2 (0.15s) --- PASS: TestCheckSizeOnly/3 (0.15s) --- PASS: TestCheckSizeOnly/4 (0.15s) --- PASS: TestCheckSizeOnly/5 (0.15s) --- PASS: TestCheckSizeOnly/6 (0.15s) --- PASS: TestCheckSizeOnly/7 (0.15s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.01s) === RUN TestDeduplicateInteractive run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.29s) === RUN TestDeduplicateSkip run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.29s) === RUN TestDeduplicateSizeOnly run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.29s) === RUN TestDeduplicateFirst run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.29s) === RUN TestDeduplicateNewest run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.29s) === RUN TestDeduplicateNewestByHash run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:30:38 INFO : B2 bucket rclone-test-radurin8zuzahox3tixanik6: Looking for duplicate SHA-1 hashes using newest mode. 2021/01/04 05:30:38 NOTICE: 85da2b5de29892ab3ac8a1c82f5b214a6506b5f8: Found 3 files with duplicate SHA-1 hashes 2021/01/04 05:30:44 INFO : one: Deleted 2021/01/04 05:30:44 INFO : also/one: Deleted 2021/01/04 05:30:44 NOTICE: 85da2b5de29892ab3ac8a1c82f5b214a6506b5f8: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (20.37s) === RUN TestDeduplicateOldest run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.28s) === RUN TestDeduplicateLargest run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.28s) === RUN TestDeduplicateSmallest run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.28s) === RUN TestDeduplicateRename run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.28s) === RUN TestMergeDirs run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" dedupe_test.go:267: Can't merge directories --- SKIP: TestMergeDirs (0.28s) === RUN TestListDirSorted run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:30:59 DEBUG : a.txt: Excluded 2021/01/04 05:31:00 DEBUG : sub dir/hello world: Excluded 2021/01/04 05:31:00 DEBUG : sub dir/hello world2: Excluded 2021/01/04 05:31:00 DEBUG : sub dir/hello world: Excluded 2021/01/04 05:31:00 DEBUG : sub dir/hello world2: Excluded 2021/01/04 05:31:00 DEBUG : sub dir/ignore dir: Excluded 2021/01/04 05:31:00 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:31:01 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (20.98s) === RUN TestMkdir run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:31:09 DEBUG : B2 bucket rclone-test-radurin8zuzahox3tixanik6: Making directory 2021/01/04 05:31:09 DEBUG : B2 bucket rclone-test-radurin8zuzahox3tixanik6: Making directory --- PASS: TestMkdir (0.42s) === RUN TestLsd run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" --- PASS: TestLsd (3.06s) === RUN TestLs run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:31:17 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2021/01/04 05:31:17 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2021/01/04 05:31:18 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestLs (6.71s) === RUN TestLsWithFilesFrom run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:31:24 DEBUG : empty space: Excluded 2021/01/04 05:31:24 DEBUG : Couldn't decode error response: EOF --- PASS: TestLsWithFilesFrom (11.70s) === RUN TestLsLong run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" --- PASS: TestLsLong (13.67s) === RUN TestHashSums run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:31:53 ERROR : potato2: Hash unsupported: hash type not supported 2021/01/04 05:31:53 ERROR : empty space: Hash unsupported: hash type not supported 2021/01/04 05:31:54 ERROR : potato2: Hash unsupported: hash type not supported 2021/01/04 05:31:54 ERROR : empty space: Hash unsupported: hash type not supported --- PASS: TestHashSums (11.82s) === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" --- PASS: TestCount (26.71s) === RUN TestDelete run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:32:30 DEBUG : Waiting for deletions to finish 2021/01/04 05:32:30 DEBUG : large: Excluded from sync (and deletion) 2021/01/04 05:32:31 INFO : medium: Deleted 2021/01/04 05:32:43 INFO : small: Deleted --- PASS: TestDelete (20.40s) === RUN TestRetry 2021/01/04 05:32:43 DEBUG : Received error: EOF - low level retry 1/5 2021/01/04 05:32:43 DEBUG : Received error: EOF - low level retry 2/5 2021/01/04 05:32:43 DEBUG : Received error: EOF - low level retry 1/5 2021/01/04 05:32:43 DEBUG : Received error: EOF - low level retry 2/5 2021/01/04 05:32:43 DEBUG : Received error: EOF - low level retry 3/5 2021/01/04 05:32:43 DEBUG : Received error: EOF - low level retry 4/5 2021/01/04 05:32:43 DEBUG : Received error: EOF - low level retry 5/5 --- PASS: TestRetry (0.00s) === RUN TestCat run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" --- PASS: TestCat (6.37s) === RUN TestPurge 2021/01/04 05:32:50 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/01/04 05:32:50 DEBUG : Creating backend with remote "TestB2:rclone-test-wojiyef4betized6novanij0" 2021/01/04 05:32:50 DEBUG : Creating backend with remote "/tmp/rclone167469593" run.go:176: Remote "B2 bucket rclone-test-wojiyef4betized6novanij0", Local "Local file system at /tmp/rclone167469593", Modify Window "1ms" 2021/01/04 05:32:52 DEBUG : A2: Making directory 2021/01/04 05:32:52 DEBUG : A1/B2: Making directory 2021/01/04 05:32:52 DEBUG : A1/B2/C2: Making directory 2021/01/04 05:32:52 DEBUG : A1/B1/C3: Making directory 2021/01/04 05:32:52 DEBUG : A3: Making directory 2021/01/04 05:32:52 DEBUG : A3/B3: Making directory 2021/01/04 05:32:52 DEBUG : A3/B3/C4: Making directory fstest.go:249: Filtering empty directory "A2" fstest.go:249: Filtering empty directory "A1/B2" fstest.go:249: Filtering empty directory "A1/B2/C2" fstest.go:249: Filtering empty directory "A1/B1/C3" fstest.go:249: Filtering empty directory "A3" fstest.go:249: Filtering empty directory "A3/B3" fstest.go:249: Filtering empty directory "A3/B3/C4" 2021/01/04 05:32:53 DEBUG : A1/B1/C1/one: Deleting (id "4_zff723df3e02dcbbb7f680e1b_f10170bac205915e9_d20210104_m053252_c001_v0001038_t0041") fstest.go:249: Filtering empty directory "A2" fstest.go:249: Filtering empty directory "A1/B2" fstest.go:249: Filtering empty directory "A1/B2/C2" fstest.go:249: Filtering empty directory "A3" fstest.go:249: Filtering empty directory "A3/B3" fstest.go:249: Filtering empty directory "A3/B3/C4" 2021/01/04 05:32:53 DEBUG : A1/two: Deleting (id "4_zff723df3e02dcbbb7f680e1b_f10170bac205915eb_d20210104_m053252_c001_v0001038_t0041") 2021/01/04 05:32:54 DEBUG : B2 bucket rclone-test-wojiyef4betized6novanij0: Purge remote 2021/01/04 05:32:54 purge failed: directory not found --- PASS: TestPurge (4.16s) === RUN TestRmdirsNoLeaveRoot run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:32:56 DEBUG : A2: Making directory 2021/01/04 05:32:56 DEBUG : A1/B2: Making directory 2021/01/04 05:32:56 DEBUG : A1/B2/C2: Making directory 2021/01/04 05:32:56 DEBUG : A1/B1/C3: Making directory 2021/01/04 05:32:56 DEBUG : A3: Making directory 2021/01/04 05:32:56 DEBUG : A3/B3: Making directory 2021/01/04 05:32:56 DEBUG : A3/B3/C4: Making directory fstest.go:249: Filtering empty directory "A2" fstest.go:249: Filtering empty directory "A1/B2" fstest.go:249: Filtering empty directory "A1/B2/C2" fstest.go:249: Filtering empty directory "A1/B1/C3" fstest.go:249: Filtering empty directory "A3" fstest.go:249: Filtering empty directory "A3/B3" fstest.go:249: Filtering empty directory "A3/B3/C4" 2021/01/04 05:32:57 DEBUG : A3/B3/C4: Removing directory fstest.go:249: Filtering empty directory "A2" fstest.go:249: Filtering empty directory "A1/B2" fstest.go:249: Filtering empty directory "A1/B2/C2" fstest.go:249: Filtering empty directory "A1/B1/C3" fstest.go:249: Filtering empty directory "A3" fstest.go:249: Filtering empty directory "A3/B3" --- PASS: TestRmdirsNoLeaveRoot (20.65s) === RUN TestRmdirsLeaveRoot run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:33:15 DEBUG : A1: Making directory 2021/01/04 05:33:15 DEBUG : A1/B1: Making directory 2021/01/04 05:33:15 DEBUG : A1/B1/C1: Making directory fstest.go:249: Filtering empty directory "A1" fstest.go:249: Filtering empty directory "A1/B1" fstest.go:249: Filtering empty directory "A1/B1/C1" fstest.go:249: Filtering empty directory "A1" --- PASS: TestRmdirsLeaveRoot (0.70s) === RUN TestCopyURL run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" --- PASS: TestCopyURL (20.02s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:33:36 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:33:36 DEBUG : file1: Need to transfer - File not found at Destination 2021/01/04 05:33:41 DEBUG : file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/01/04 05:33:41 INFO : file1: Copied (new) to: sub/file2 2021/01/04 05:33:41 INFO : file1: Deleted 2021/01/04 05:33:42 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/01/04 05:33:42 DEBUG : file1: Unchanged skipping 2021/01/04 05:33:42 INFO : file1: Deleted 2021/01/04 05:33:42 DEBUG : B2 bucket rclone-test-radurin8zuzahox3tixanik6: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (7.41s) === RUN TestCaseInsensitiveMoveFile run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.28s) === RUN TestMoveFileBackupDir run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:33:48 DEBUG : Creating backend with remote "TestB2:rclone-test-radurin8zuzahox3tixanik6/backup" 2021/01/04 05:33:49 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:33:49 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2021/01/04 05:33:49 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:33:51 DEBUG : dst/file1: SHA-1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2021/01/04 05:33:51 INFO : dst/file1: Copied (server-side copy) 2021/01/04 05:33:54 INFO : dst/file1: Deleted 2021/01/04 05:33:54 DEBUG : dst/file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/01/04 05:33:55 INFO : dst/file1: Copied (new) 2021/01/04 05:33:55 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (14.29s) === RUN TestCopyFile run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:33:58 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:33:58 DEBUG : file1: Need to transfer - File not found at Destination 2021/01/04 05:34:02 DEBUG : file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/01/04 05:34:02 INFO : file1: Copied (new) to: sub/file2 2021/01/04 05:34:02 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/01/04 05:34:02 DEBUG : file1: Unchanged skipping 2021/01/04 05:34:02 DEBUG : B2 bucket rclone-test-radurin8zuzahox3tixanik6: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (6.04s) === RUN TestCopyFileBackupDir run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:34:05 DEBUG : Creating backend with remote "TestB2:rclone-test-radurin8zuzahox3tixanik6/backup" 2021/01/04 05:34:06 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:06 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2021/01/04 05:34:07 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:08 DEBUG : dst/file1: SHA-1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2021/01/04 05:34:08 INFO : dst/file1: Copied (server-side copy) 2021/01/04 05:34:12 INFO : dst/file1: Deleted 2021/01/04 05:34:15 DEBUG : dst/file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/01/04 05:34:15 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (16.36s) === RUN TestCopyFileCompareDest run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:34:20 DEBUG : Creating backend with remote "TestB2:rclone-test-radurin8zuzahox3tixanik6/dst" 2021/01/04 05:34:21 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:21 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:21 DEBUG : Creating backend with remote "TestB2:rclone-test-radurin8zuzahox3tixanik6/CompareDest" 2021/01/04 05:34:21 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:22 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:22 DEBUG : one: Need to transfer - File not found at Destination 2021/01/04 05:34:26 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/01/04 05:34:26 INFO : one: Copied (new) 2021/01/04 05:34:27 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:27 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/01/04 05:34:29 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/01/04 05:34:29 INFO : one: Copied (replaced existing) 2021/01/04 05:34:31 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/01/04 05:34:31 DEBUG : one: Destination found in --compare-dest, skipping 2021/01/04 05:34:33 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:33 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/01/04 05:34:33 DEBUG : two: Destination found in --compare-dest, skipping 2021/01/04 05:34:33 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:33 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/01/04 05:34:33 DEBUG : two: Destination found in --compare-dest, skipping 2021/01/04 05:34:34 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:34 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/01/04 05:34:34 DEBUG : two: Need to transfer - File not found at Destination 2021/01/04 05:34:34 DEBUG : two: Clearing upload URL because of error: Post "https://pod-000-1144-05.backblaze.com/b2api/v1/b2_upload_file/cf82dd83d0edcbbb7f680e1b/c001_v0001144_t0058": EOF 2021/01/04 05:34:34 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1144-05.backblaze.com/b2api/v1/b2_upload_file/cf82dd83d0edcbbb7f680e1b/c001_v0001144_t0058": EOF) 2021/01/04 05:34:34 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2021/01/04 05:34:34 DEBUG : two: Received error: Post "https://pod-000-1144-05.backblaze.com/b2api/v1/b2_upload_file/cf82dd83d0edcbbb7f680e1b/c001_v0001144_t0058": EOF - low level retry 1/10 2021/01/04 05:34:35 DEBUG : pacer: Reducing sleep to 10ms 2021/01/04 05:34:36 DEBUG : two: SHA-1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2021/01/04 05:34:36 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (23.35s) === RUN TestCopyFileCopyDest run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:34:43 DEBUG : Creating backend with remote "TestB2:rclone-test-radurin8zuzahox3tixanik6/dst" 2021/01/04 05:34:44 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:44 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:44 DEBUG : Creating backend with remote "TestB2:rclone-test-radurin8zuzahox3tixanik6/CopyDest" 2021/01/04 05:34:45 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:45 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:45 DEBUG : one: Need to transfer - File not found at Destination 2021/01/04 05:34:49 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/01/04 05:34:49 INFO : one: Copied (new) 2021/01/04 05:34:50 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:50 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/01/04 05:34:50 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/01/04 05:34:50 INFO : one: Copied (replaced existing) 2021/01/04 05:34:54 DEBUG : Creating backend with remote "TestB2:rclone-test-radurin8zuzahox3tixanik6/BackupDir" 2021/01/04 05:34:55 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:55 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/01/04 05:34:55 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/01/04 05:34:55 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:34:57 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/01/04 05:34:57 INFO : one: Copied (server-side copy) 2021/01/04 05:34:58 INFO : one: Deleted 2021/01/04 05:34:58 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/01/04 05:34:58 INFO : one: Copied (server-side copy) 2021/01/04 05:34:58 DEBUG : one: Destination found in --copy-dest, using server-side copy 2021/01/04 05:34:59 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:35:00 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/01/04 05:35:06 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/01/04 05:35:06 INFO : two: Copied (server-side copy) 2021/01/04 05:35:06 DEBUG : two: Destination found in --copy-dest, using server-side copy 2021/01/04 05:35:08 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/01/04 05:35:08 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/01/04 05:35:08 DEBUG : two: Unchanged skipping 2021/01/04 05:35:10 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:35:10 DEBUG : three: Sizes differ (src 7 vs dst 5) 2021/01/04 05:35:10 DEBUG : three: Destination not found in --copy-dest 2021/01/04 05:35:10 DEBUG : three: Need to transfer - File not found at Destination 2021/01/04 05:35:10 DEBUG : three: SHA-1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2021/01/04 05:35:10 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (32.58s) === 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 "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:35:24 DEBUG : A1/B2: Making directory 2021/01/04 05:35:24 DEBUG : A1/B1/C3: Making directory fstest.go:249: Filtering empty directory "A1/B2" fstest.go:249: Filtering empty directory "A1/B1/C3" 2021/01/04 05:35:25 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:35:25 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:35:25 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:35:25 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:35:26 DEBUG : A1/B1/three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/01/04 05:35:26 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2021/01/04 05:35:26 DEBUG : A1/one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/01/04 05:35:26 INFO : A1/one: Copied (server-side copy) to: A2/one 2021/01/04 05:35:26 DEBUG : A1/B1/C2/five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/01/04 05:35:26 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2021/01/04 05:35:26 INFO : A1/B1/three: Deleted 2021/01/04 05:35:26 INFO : A1/B1/C2/five: Deleted 2021/01/04 05:35:26 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:35:28 INFO : A1/one: Deleted 2021/01/04 05:35:32 DEBUG : A1/B1/C1/four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/01/04 05:35:32 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2021/01/04 05:35:34 INFO : A1/B1/C1/four: Deleted 2021/01/04 05:35:35 DEBUG : A1/two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/01/04 05:35:35 INFO : A1/two: Copied (server-side copy) to: A2/two 2021/01/04 05:35:35 INFO : A1/two: Deleted fstest.go:249: Filtering empty directory "A2/B2" fstest.go:249: Filtering empty directory "A2/B1/C3" 2021/01/04 05:35:36 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:35:36 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:35:36 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:35:36 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:35:37 DEBUG : A2/B1/C2/five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/01/04 05:35:37 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2021/01/04 05:35:37 DEBUG : A2/one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/01/04 05:35:37 INFO : A2/one: Copied (server-side copy) to: A3/one 2021/01/04 05:35:37 DEBUG : A2/B1/C1/four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/01/04 05:35:37 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2021/01/04 05:35:37 INFO : A2/B1/C2/five: Deleted 2021/01/04 05:35:37 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:35:37 DEBUG : A2/B1/three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/01/04 05:35:37 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2021/01/04 05:35:38 INFO : A2/B1/three: Deleted 2021/01/04 05:35:38 DEBUG : A2/two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/01/04 05:35:38 INFO : A2/two: Copied (server-side copy) to: A3/two 2021/01/04 05:35:38 INFO : A2/B1/C1/four: Deleted 2021/01/04 05:35:38 INFO : A2/one: Deleted 2021/01/04 05:35:41 INFO : A2/two: Deleted fstest.go:249: Filtering empty directory "A3/B2" fstest.go:249: Filtering empty directory "A3/B1/C3" --- PASS: TestDirMove (31.54s) === RUN TestGetFsInfo run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" --- PASS: TestGetFsInfo (0.28s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false === CONT TestRcat run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:35:48 DEBUG : B2 bucket rclone-test-radurin8zuzahox3tixanik6: File to upload is small (34 bytes), uploading instead of streaming 2021/01/04 05:35:50 DEBUG : no_checksum_small_file_from_pipe: SHA-1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2021/01/04 05:35:50 INFO : no_checksum_small_file_from_pipe: Copied (new) 2021/01/04 05:35:50 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/01/04 05:35:52 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 "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:35:53 DEBUG : B2 bucket rclone-test-radurin8zuzahox3tixanik6: File to upload is small (34 bytes), uploading instead of streaming 2021/01/04 05:35:55 DEBUG : with_checksum_small_file_from_pipe: SHA-1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2021/01/04 05:35:55 INFO : with_checksum_small_file_from_pipe: Copied (new) 2021/01/04 05:35:55 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/01/04 05:35:57 DEBUG : with_checksum_big_file_from_pipe: SHA-1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2021/01/04 05:35:57 DEBUG : with_checksum_big_file_from_pipe: Size and SHA-1 of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true === CONT TestRcat run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:35:58 DEBUG : B2 bucket rclone-test-radurin8zuzahox3tixanik6: File to upload is small (34 bytes), uploading instead of streaming 2021/01/04 05:36:04 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2021/01/04 05:36:04 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/01/04 05:36:07 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) === RUN TestRcat/withChecksum=true,ignoreChecksum=true === CONT TestRcat run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:36:14 DEBUG : B2 bucket rclone-test-radurin8zuzahox3tixanik6: File to upload is small (34 bytes), uploading instead of streaming 2021/01/04 05:36:15 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2021/01/04 05:36:15 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/01/04 05:36:17 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (30.87s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.42s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (4.86s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (16.13s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (4.45s) === RUN TestRcatSize run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:36:19 DEBUG : B2 bucket rclone-test-radurin8zuzahox3tixanik6: File to upload is small (60 bytes), uploading instead of streaming 2021/01/04 05:36:20 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/01/04 05:36:20 INFO : potato2: Copied (new) --- PASS: TestRcatSize (2.58s) === RUN TestCopyFileMaxTransfer run.go:176: Remote "B2 bucket rclone-test-radurin8zuzahox3tixanik6", Local "Local file system at /tmp/rclone526761743", Modify Window "1ms" 2021/01/04 05:36:21 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:36:21 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2021/01/04 05:36:23 DEBUG : TestCopyFileMaxTransfer/file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/01/04 05:36:23 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2021/01/04 05:36:24 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:36:24 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2021/01/04 05:36:24 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1144-06.backblaze.com/b2api/v1/b2_upload_file/cf82dd83d0edcbbb7f680e1b/c001_v0001144_t0020": Max transfer limit reached as set by --max-transfer 2021/01/04 05:36:24 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:36:24 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2021/01/04 05:36:24 DEBUG : Couldn't decode error response: EOF 2021/01/04 05:36:24 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2021/01/04 05:36:26 DEBUG : TestCopyFileMaxTransfer/file4: SHA-1 = a36627956fb4d320f8cf0b31587209d971b4df60 OK 2021/01/04 05:36:26 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (6.73s) === 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/01/04 05:36:28 DEBUG : B2 bucket rclone-test-radurin8zuzahox3tixanik6: Purge remote 2021/01/04 05:36:28 DEBUG : A1/B1/C1/four: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1013cdb41d832e46_d20210104_m053532_c001_v0001008_t0034") 2021/01/04 05:36:28 DEBUG : A1/B1/C1/four: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec0436_d20210104_m053521_c001_v0001144_t0020") 2021/01/04 05:36:28 DEBUG : A1/B1/C1/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f104110e915f1c15e_d20210104_m053258_c001_v0001004_t0029") 2021/01/04 05:36:28 DEBUG : A1/B1/C1/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebfb3a_d20210104_m053254_c001_v0001144_t0020") 2021/01/04 05:36:28 DEBUG : A1/B1/C2/five: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f107881f3a0aa8ede_d20210104_m053526_c001_v0001130_t0027") 2021/01/04 05:36:28 DEBUG : A1/B1/C2/five: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec0452_d20210104_m053523_c001_v0001144_t0020") 2021/01/04 05:36:28 DEBUG : A1/B1/three: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f119aa9ae5e17d219_d20210104_m053526_c001_v0001001_t0021") 2021/01/04 05:36:28 DEBUG : A1/B1/three: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec0416_d20210104_m053519_c001_v0001144_t0020") 2021/01/04 05:36:28 DEBUG : A1/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f11708d2553bce138_d20210104_m053526_c001_v0001144_t0035") 2021/01/04 05:36:28 DEBUG : A1/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec03d5_d20210104_m053516_c001_v0001144_t0020") 2021/01/04 05:36:28 DEBUG : A1/two: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f119ac2ad6ffc3a8c_d20210104_m053535_c001_v0001090_t0030") 2021/01/04 05:36:29 DEBUG : A1/two: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec03f7_d20210104_m053518_c001_v0001144_t0020") 2021/01/04 05:36:29 DEBUG : A1/two: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1153ba9ba0cbe21e_d20210104_m053314_c001_v0001146_t0057") 2021/01/04 05:36:29 DEBUG : A1/two: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebfb5f_d20210104_m053256_c001_v0001144_t0020") 2021/01/04 05:36:29 DEBUG : A2/B1/C1/four: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f118a45fdc46ac00f_d20210104_m053537_c001_v0001113_t0054") 2021/01/04 05:36:29 DEBUG : A2/B1/C1/four: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f102cadc44eb62794_d20210104_m053525_c001_v0001144_t0002") 2021/01/04 05:36:29 DEBUG : A2/B1/C2/five: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1074d6e26e791c65_d20210104_m053537_c001_v0001146_t0018") 2021/01/04 05:36:29 DEBUG : A2/B1/C2/five: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f116e83d7c5edd28a_d20210104_m053525_c001_v0001036_t0001") 2021/01/04 05:36:29 DEBUG : A2/B1/three: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f10571550449e90e2_d20210104_m053537_c001_v0001146_t0043") 2021/01/04 05:36:29 DEBUG : A2/B1/three: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f115173f1fa390188_d20210104_m053525_c001_v0001113_t0037") 2021/01/04 05:36:29 DEBUG : A2/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f11045fa1e45b528d_d20210104_m053537_c001_v0001113_t0013") 2021/01/04 05:36:29 DEBUG : A2/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f10957db8757930d8_d20210104_m053525_c001_v0001030_t0008") 2021/01/04 05:36:29 DEBUG : A2/two: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec0533_d20210104_m053538_c001_v0001144_t0006") 2021/01/04 05:36:29 DEBUG : A2/two: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f115e91eb78df846e_d20210104_m053534_c001_v0001144_t0035") 2021/01/04 05:36:29 DEBUG : A3/B1/C1/four: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f11729250e6e03fd4_d20210104_m053541_c001_v0001145_t0014") 2021/01/04 05:36:29 DEBUG : A3/B1/C1/four: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f116d1bddc17e5351_d20210104_m053536_c001_v0001040_t0029") 2021/01/04 05:36:29 DEBUG : A3/B1/C2/five: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f107315d7c6af9b14_d20210104_m053542_c001_v0001022_t0038") 2021/01/04 05:36:29 DEBUG : A3/B1/C2/five: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1142202222af9520_d20210104_m053536_c001_v0001146_t0045") 2021/01/04 05:36:29 DEBUG : A3/B1/three: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1141f809521a54e6_d20210104_m053542_c001_v0001025_t0005") 2021/01/04 05:36:29 DEBUG : A3/B1/three: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1006abccbf73a1ff_d20210104_m053537_c001_v0001120_t0041") 2021/01/04 05:36:29 DEBUG : A3/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f106ca57ac061efdc_d20210104_m053543_c001_v0001146_t0010") 2021/01/04 05:36:29 DEBUG : A3/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1071b9bcf6353a26_d20210104_m053537_c001_v0001021_t0010") 2021/01/04 05:36:29 DEBUG : A3/two: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1009011c27078156_d20210104_m053543_c001_v0001022_t0030") 2021/01/04 05:36:29 DEBUG : A3/two: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f111a027e13229d3a_d20210104_m053537_c001_v0001120_t0038") 2021/01/04 05:36:30 DEBUG : BackupDir/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f109de936b01a19ca_d20210104_m053511_c001_v0001137_t0025") 2021/01/04 05:36:30 DEBUG : BackupDir/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f118e59f4e121e6f1_d20210104_m053456_c001_v0001030_t0008") 2021/01/04 05:36:30 DEBUG : CompareDest/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1122376e8c24ae7a_d20210104_m053436_c001_v0001144_t0031") 2021/01/04 05:36:30 DEBUG : CompareDest/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec00f0_d20210104_m053430_c001_v0001144_t0020") 2021/01/04 05:36:30 DEBUG : CompareDest/two: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec0171_d20210104_m053438_c001_v0001144_t0001") 2021/01/04 05:36:30 DEBUG : CompareDest/two: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec0105_d20210104_m053432_c001_v0001144_t0020") 2021/01/04 05:36:30 DEBUG : CopyDest/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1152ab30dcf7520a_d20210104_m053511_c001_v0001128_t0041") 2021/01/04 05:36:30 DEBUG : CopyDest/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec026f_d20210104_m053453_c001_v0001144_t0020") 2021/01/04 05:36:30 DEBUG : CopyDest/three: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec0383_d20210104_m053511_c001_v0001144_t0003") 2021/01/04 05:36:30 DEBUG : CopyDest/three: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec0347_d20210104_m053508_c001_v0001144_t0020") 2021/01/04 05:36:30 DEBUG : CopyDest/two: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f116c1fad3afedae0_d20210104_m053514_c001_v0001042_t0056") 2021/01/04 05:36:30 DEBUG : CopyDest/two: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec02c7_d20210104_m053458_c001_v0001144_t0020") 2021/01/04 05:36:30 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1012ee85269e44da_d20210104_m053626_c001_v0001145_t0058") 2021/01/04 05:36:30 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec082b_d20210104_m053622_c001_v0001144_t0020") 2021/01/04 05:36:30 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f116930bf127c33e8_d20210104_m053627_c001_v0001137_t0021") 2021/01/04 05:36:30 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec085c_d20210104_m053625_c001_v0001144_t0020") 2021/01/04 05:36:30 DEBUG : a.txt: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f11839db5e81c3a8c_d20210104_m053101_c001_v0001144_t0004") 2021/01/04 05:36:30 DEBUG : a.txt: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf4b5_d20210104_m053048_c001_v0001144_t0020") 2021/01/04 05:36:30 DEBUG : also/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f107eca948e1a724c_d20210104_m053044_c001_v0001137_t0022") 2021/01/04 05:36:30 DEBUG : also/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf391_d20210104_m053031_c001_v0001144_t0020") 2021/01/04 05:36:30 DEBUG : another: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1028f349e1063fd7_d20210104_m053045_c001_v0001146_t0008") 2021/01/04 05:36:30 DEBUG : another: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf3a6_d20210104_m053032_c001_v0001144_t0020") 2021/01/04 05:36:31 DEBUG : backup/dst/file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f105f610a9120e85d_d20210104_m053416_c001_v0001144_t0049") 2021/01/04 05:36:31 DEBUG : backup/dst/file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f104830d87eb79997_d20210104_m053408_c001_v0001039_t0020") 2021/01/04 05:36:31 DEBUG : backup/dst/file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f11708d2553bcdbb6_d20210104_m053355_c001_v0001144_t0036") 2021/01/04 05:36:31 DEBUG : backup/dst/file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f118cbbeba1ee16ed_d20210104_m053351_c001_v0001120_t0044") 2021/01/04 05:36:31 DEBUG : dst/file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1033a75ee16a7800_d20210104_m053419_c001_v0001039_t0048") 2021/01/04 05:36:31 DEBUG : dst/file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebffef_d20210104_m053412_c001_v0001144_t0020") 2021/01/04 05:36:31 DEBUG : dst/file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1144b039ef7fe3b1_d20210104_m053408_c001_v0001144_t0026") 2021/01/04 05:36:31 DEBUG : dst/file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebff7f_d20210104_m053403_c001_v0001144_t0020") 2021/01/04 05:36:31 DEBUG : dst/file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f103c2c4384920071_d20210104_m053357_c001_v0001146_t0006") 2021/01/04 05:36:31 DEBUG : dst/file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebfefa_d20210104_m053354_c001_v0001144_t0020") 2021/01/04 05:36:31 DEBUG : dst/file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f105d15567caaf202_d20210104_m053351_c001_v0001026_t0032") 2021/01/04 05:36:31 DEBUG : dst/file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebfe3b_d20210104_m053343_c001_v0001144_t0020") 2021/01/04 05:36:31 DEBUG : dst/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1068c1607f9a6508_d20210104_m053514_c001_v0001043_t0001") 2021/01/04 05:36:31 DEBUG : dst/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f100087684edc5f2d_d20210104_m053458_c001_v0001015_t0037") 2021/01/04 05:36:31 DEBUG : dst/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f11903800f9786e8e_d20210104_m053457_c001_v0001146_t0001") 2021/01/04 05:36:31 DEBUG : dst/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec0242_d20210104_m053451_c001_v0001144_t0020") 2021/01/04 05:36:31 DEBUG : dst/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f11083582c0a955e8_d20210104_m053450_c001_v0001136_t0002") 2021/01/04 05:36:31 DEBUG : dst/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f11083582c0a955e0_d20210104_m053447_c001_v0001136_t0002") 2021/01/04 05:36:31 DEBUG : dst/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f115e91eb78df8176_d20210104_m053439_c001_v0001144_t0047") 2021/01/04 05:36:31 DEBUG : dst/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec00e6_d20210104_m053430_c001_v0001144_t0020") 2021/01/04 05:36:31 DEBUG : dst/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f105f610a9120e8f3_d20210104_m053427_c001_v0001144_t0058") 2021/01/04 05:36:31 DEBUG : dst/one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f105f610a9120e8c2_d20210104_m053423_c001_v0001144_t0058") 2021/01/04 05:36:31 DEBUG : dst/three: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f100f90e3941ad52f_d20210104_m053514_c001_v0001005_t0006") 2021/01/04 05:36:31 DEBUG : dst/three: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f11083582c0a95601_d20210104_m053510_c001_v0001136_t0002") 2021/01/04 05:36:31 DEBUG : dst/two: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f108b0bbcb0e4b1f9_d20210104_m053515_c001_v0001146_t0025") 2021/01/04 05:36:32 DEBUG : dst/two: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f102f20ffbf2f71d3_d20210104_m053500_c001_v0001008_t0000") 2021/01/04 05:36:32 DEBUG : dst/two: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f110d37847326ae78_d20210104_m053440_c001_v0001144_t0056") 2021/01/04 05:36:32 DEBUG : dst/two: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f116fcb330ce33dba_d20210104_m053435_c001_v0001120_t0026") 2021/01/04 05:36:32 DEBUG : empty space: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f10402b70fa0bbba4_d20210104_m053206_c001_v0001037_t0024") 2021/01/04 05:36:32 DEBUG : empty space: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf83c_d20210104_m053159_c001_v0001144_t0020") 2021/01/04 05:36:32 DEBUG : empty space: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f11903800f97861c8_d20210104_m053154_c001_v0001146_t0055") 2021/01/04 05:36:32 DEBUG : empty space: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf7b0_d20210104_m053149_c001_v0001144_t0020") 2021/01/04 05:36:32 DEBUG : empty space: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1002c41e80b1fedf_d20210104_m053138_c001_v0001128_t0009") 2021/01/04 05:36:32 DEBUG : empty space: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf712_d20210104_m053136_c001_v0001144_t0020") 2021/01/04 05:36:32 DEBUG : empty space: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f103505225ad961b0_d20210104_m053124_c001_v0001144_t0057") 2021/01/04 05:36:32 DEBUG : empty space: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf67d_d20210104_m053122_c001_v0001144_t0020") 2021/01/04 05:36:32 DEBUG : empty space: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1157bf1a01006364_d20210104_m053118_c001_v0001005_t0013") 2021/01/04 05:36:32 DEBUG : empty space: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf62b_d20210104_m053115_c001_v0001144_t0020") 2021/01/04 05:36:32 DEBUG : empty space: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f107f32b9f76ff78c_d20210104_m053009_c001_v0001013_t0004") 2021/01/04 05:36:32 DEBUG : empty space: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf1c0_d20210104_m052957_c001_v0001144_t0020") 2021/01/04 05:36:32 DEBUG : empty space: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f114c4c3b2f1bc67b_d20210104_m052943_c001_v0001011_t0028") 2021/01/04 05:36:32 DEBUG : empty space: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf052_d20210104_m052931_c001_v0001144_t0020") 2021/01/04 05:36:32 DEBUG : empty space: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f10199933457c52b2_d20210104_m052925_c001_v0001146_t0022") 2021/01/04 05:36:32 DEBUG : empty space: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebefb2_d20210104_m052921_c001_v0001144_t0020") 2021/01/04 05:36:32 DEBUG : file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f119c76c21596710a_d20210104_m053331_c001_v0001038_t0004") 2021/01/04 05:36:32 DEBUG : file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebfca8_d20210104_m053316_c001_v0001144_t0020") 2021/01/04 05:36:32 DEBUG : file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1113217ffaa5f585_d20210104_m053249_c001_v0001015_t0035") 2021/01/04 05:36:32 DEBUG : file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebfa7e_d20210104_m053244_c001_v0001144_t0020") 2021/01/04 05:36:32 DEBUG : file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f113b551a5f408fbf_d20210104_m052918_c001_v0001015_t0012") 2021/01/04 05:36:33 DEBUG : file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebef6f_d20210104_m052916_c001_v0001144_t0020") 2021/01/04 05:36:33 DEBUG : file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebef50_d20210104_m052914_c001_v0001144_t0020") 2021/01/04 05:36:33 DEBUG : file1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebef0d_d20210104_m052908_c001_v0001144_t0020") 2021/01/04 05:36:33 DEBUG : file2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1144b039ef7fe12e_d20210104_m053332_c001_v0001144_t0000") 2021/01/04 05:36:33 DEBUG : file2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebfd40_d20210104_m053329_c001_v0001144_t0020") 2021/01/04 05:36:33 DEBUG : file2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f11174e4a4067610b_d20210104_m053249_c001_v0001146_t0044") 2021/01/04 05:36:33 DEBUG : file2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebfa95_d20210104_m053245_c001_v0001144_t0020") 2021/01/04 05:36:33 DEBUG : filename.txt: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f105ab7827cd90e0d_d20210104_m053334_c001_v0001120_t0008") 2021/01/04 05:36:33 DEBUG : filename.txt: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebfd23_d20210104_m053327_c001_v0001144_t0020") 2021/01/04 05:36:33 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f108bfba8af729d2c_d20210104_m053617_c001_v0001120_t0003") 2021/01/04 05:36:33 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec07bd_d20210104_m053616_c001_v0001144_t0020") 2021/01/04 05:36:33 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f102735255fb7ff9b_d20210104_m053607_c001_v0001120_t0005") 2021/01/04 05:36:33 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec0701_d20210104_m053604_c001_v0001144_t0020") 2021/01/04 05:36:33 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1027efdc71e9faab_d20210104_m053618_c001_v0001145_t0002") 2021/01/04 05:36:33 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec07ab_d20210104_m053615_c001_v0001144_t0020") 2021/01/04 05:36:33 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f105f610a9120ee6a_d20210104_m053608_c001_v0001144_t0026") 2021/01/04 05:36:33 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec06c1_d20210104_m053558_c001_v0001144_t0020") 2021/01/04 05:36:33 DEBUG : large: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f108c39252ac5c590_d20210104_m053243_c001_v0001012_t0033") 2021/01/04 05:36:33 DEBUG : large: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf99b_d20210104_m053228_c001_v0001144_t0020") 2021/01/04 05:36:33 DEBUG : medium: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1036d549b24f070f_d20210104_m053230_c001_v0001018_t0034") 2021/01/04 05:36:33 DEBUG : medium: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf98a_d20210104_m053226_c001_v0001144_t0020") 2021/01/04 05:36:33 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f100a3c42fc110a30_d20210104_m053552_c001_v0001037_t0007") 2021/01/04 05:36:33 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec0612_d20210104_m053550_c001_v0001144_t0020") 2021/01/04 05:36:34 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f101b9119154b0238_d20210104_m053553_c001_v0001041_t0019") 2021/01/04 05:36:34 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec05db_d20210104_m053548_c001_v0001144_t0020") 2021/01/04 05:36:34 DEBUG : not-one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f110686d3f326283a_d20210104_m053045_c001_v0001040_t0043") 2021/01/04 05:36:34 DEBUG : not-one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf3d5_d20210104_m053034_c001_v0001144_t0020") 2021/01/04 05:36:34 DEBUG : one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1041ea66a7ee8b24_d20210104_m053038_c001_v0001031_t0010") 2021/01/04 05:36:34 DEBUG : one: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf35f_d20210104_m053027_c001_v0001144_t0020") 2021/01/04 05:36:34 DEBUG : potato1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f108b0bbcb0e4b675_d20210104_m053620_c001_v0001146_t0024") 2021/01/04 05:36:34 DEBUG : potato1: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec07f0_d20210104_m053618_c001_v0001144_t0020") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f10199933457c75bb_d20210104_m053621_c001_v0001146_t0035") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec07ff_d20210104_m053619_c001_v0001144_t0020") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f105fa2342c18c7de_d20210104_m053208_c001_v0001032_t0016") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf823_d20210104_m053157_c001_v0001144_t0020") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f105f610a9120e138_d20210104_m053155_c001_v0001144_t0012") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf781_d20210104_m053145_c001_v0001144_t0020") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f103505225ad96267_d20210104_m053139_c001_v0001144_t0004") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf6f0_d20210104_m053131_c001_v0001144_t0020") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f109e318760bffc07_d20210104_m053130_c001_v0001146_t0033") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf660_d20210104_m053120_c001_v0001144_t0020") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f101756156e680a6c_d20210104_m053119_c001_v0001136_t0044") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf60c_d20210104_m053112_c001_v0001144_t0020") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f108d8613657f4288_d20210104_m053010_c001_v0001144_t0016") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf204_d20210104_m053002_c001_v0001144_t0020") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f108909135dc1ee54_d20210104_m052943_c001_v0001136_t0018") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf06d_d20210104_m052933_c001_v0001144_t0020") 2021/01/04 05:36:34 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1048d21c27cab393_d20210104_m052926_c001_v0001131_t0029") 2021/01/04 05:36:35 DEBUG : potato2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebefc6_d20210104_m052922_c001_v0001144_t0020") 2021/01/04 05:36:35 DEBUG : remotepotato: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f102cadc44eb61836_d20210104_m053016_c001_v0001144_t0008") 2021/01/04 05:36:35 DEBUG : remotepotato: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf239_d20210104_m053006_c001_v0001144_t0020") 2021/01/04 05:36:35 DEBUG : remotepotato: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f11839db5e81c3607_d20210104_m052944_c001_v0001144_t0005") 2021/01/04 05:36:35 DEBUG : remotepotato: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf0a5_d20210104_m052936_c001_v0001144_t0020") 2021/01/04 05:36:35 DEBUG : remotepotato: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1153ba9ba0cbd544_d20210104_m052926_c001_v0001146_t0045") 2021/01/04 05:36:35 DEBUG : remotepotato: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebefe2_d20210104_m052923_c001_v0001144_t0020") 2021/01/04 05:36:35 DEBUG : rutabaga: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1142202222af8298_d20210104_m053023_c001_v0001146_t0047") 2021/01/04 05:36:35 DEBUG : rutabaga: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf191_d20210104_m052953_c001_v0001144_t0020") 2021/01/04 05:36:35 DEBUG : rutabaga: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f116a6894b2382d92_d20210104_m052952_c001_v0001032_t0019") 2021/01/04 05:36:35 DEBUG : rutabaga: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf00b_d20210104_m052927_c001_v0001144_t0020") 2021/01/04 05:36:35 DEBUG : rutabaga: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f11174e4a40674eb4_d20210104_m052926_c001_v0001146_t0047") 2021/01/04 05:36:35 DEBUG : rutabaga: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebef92_d20210104_m052918_c001_v0001144_t0020") 2021/01/04 05:36:35 DEBUG : small: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f11839db5e81c3ede_d20210104_m053231_c001_v0001144_t0037") 2021/01/04 05:36:35 DEBUG : small: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf961_d20210104_m053224_c001_v0001144_t0020") 2021/01/04 05:36:35 DEBUG : sub dir/hello world: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1122e96b3cf71e7a_d20210104_m053111_c001_v0001131_t0027") 2021/01/04 05:36:35 DEBUG : sub dir/hello world: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf5e3_d20210104_m053110_c001_v0001144_t0020") 2021/01/04 05:36:35 DEBUG : sub dir/hello world: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f117470dbd134708b_d20210104_m053105_c001_v0001036_t0057") 2021/01/04 05:36:35 DEBUG : sub dir/hello world: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf4e2_d20210104_m053052_c001_v0001144_t0020") 2021/01/04 05:36:35 DEBUG : sub dir/hello world2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f113da6dbb58288e6_d20210104_m053105_c001_v0001093_t0012") 2021/01/04 05:36:35 DEBUG : sub dir/hello world2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf4f3_d20210104_m053053_c001_v0001144_t0020") 2021/01/04 05:36:35 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f110d37847326a247_d20210104_m053106_c001_v0001144_t0020") 2021/01/04 05:36:35 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf50e_d20210104_m053055_c001_v0001144_t0020") 2021/01/04 05:36:35 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1156c19dbe9cc04f_d20210104_m053108_c001_v0001037_t0009") 2021/01/04 05:36:35 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf521_d20210104_m053056_c001_v0001144_t0020") 2021/01/04 05:36:36 DEBUG : sub dir/potato3: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f110fd8597a1a15e1_d20210104_m053223_c001_v0001146_t0048") 2021/01/04 05:36:36 DEBUG : sub dir/potato3: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf84f_d20210104_m053201_c001_v0001144_t0020") 2021/01/04 05:36:36 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f110f949817b6fd87_d20210104_m053108_c001_v0001092_t0018") 2021/01/04 05:36:36 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf54a_d20210104_m053058_c001_v0001144_t0020") 2021/01/04 05:36:36 DEBUG : sub/file2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f109cc505d983eecd_d20210104_m053403_c001_v0001120_t0048") 2021/01/04 05:36:36 DEBUG : sub/file2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebff32_d20210104_m053358_c001_v0001144_t0020") 2021/01/04 05:36:36 DEBUG : sub/file2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f11903800f9786933_d20210104_m053342_c001_v0001146_t0042") 2021/01/04 05:36:36 DEBUG : sub/file2: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebfdc7_d20210104_m053336_c001_v0001144_t0020") 2021/01/04 05:36:36 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1024046f9ad07577_d20210104_m053557_c001_v0001001_t0010") 2021/01/04 05:36:36 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec0676_d20210104_m053555_c001_v0001144_t0020") 2021/01/04 05:36:36 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1068786303cf0c20_d20210104_m053557_c001_v0001130_t0036") 2021/01/04 05:36:36 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ec0648_d20210104_m053553_c001_v0001144_t0020") 2021/01/04 05:36:36 DEBUG : zend.txt: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f108b0bbcb0e4a27b_d20210104_m053108_c001_v0001146_t0023") 2021/01/04 05:36:36 DEBUG : zend.txt: Deleting (id "4_zcf82dd83d0edcbbb7f680e1b_f1062b52f29ebf4d7_d20210104_m053051_c001_v0001144_t0020") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 7m30.634085838s (try 1/5)