"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2021/04/05 05:19:03 DEBUG : Creating backend with remote "TestB2:rclone-test-havakob9xewijor4vezevig0" 2021/04/05 05:19:04 DEBUG : Creating backend with remote "/tmp/rclone543299501" === 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-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" === RUN TestMultithreadCopy/{size:131071_streams:2} 2021/04/05 05:19:09 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64k 2021/04/05 05:19:09 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999k starting 2021/04/05 05:19:09 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k starting 2021/04/05 05:19:09 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999k finished 2021/04/05 05:19:09 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k finished 2021/04/05 05:19:09 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64k === RUN TestMultithreadCopy/{size:131072_streams:2} 2021/04/05 05:19:10 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64k 2021/04/05 05:19:10 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64k starting 2021/04/05 05:19:10 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k starting 2021/04/05 05:19:11 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k finished 2021/04/05 05:19:11 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64k finished 2021/04/05 05:19:11 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64k === RUN TestMultithreadCopy/{size:131073_streams:2} 2021/04/05 05:19:12 DEBUG : file1: Starting multi-thread copy with 2 parts of size 128k 2021/04/05 05:19:12 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 starting 2021/04/05 05:19:12 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128k starting 2021/04/05 05:19:12 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 finished 2021/04/05 05:19:12 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128k finished 2021/04/05 05:19:12 DEBUG : file1: Finished multi-thread copy with 2 parts of size 128k --- PASS: TestMultithreadCopy (9.61s) --- PASS: TestMultithreadCopy/{size:131071_streams:2} (5.52s) --- PASS: TestMultithreadCopy/{size:131072_streams:2} (1.84s) --- PASS: TestMultithreadCopy/{size:131073_streams:2} (1.58s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Seek === RUN TestReOpen/Seek/Basics === RUN TestReOpen/Seek/ErrorAtStart === RUN TestReOpen/Seek/WithErrors 2021/04/05 05:19:13 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2021/04/05 05:19:13 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2021/04/05 05:19:13 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Seek/TooManyErrors 2021/04/05 05:19:13 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2021/04/05 05:19:13 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2021/04/05 05:19:13 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2021/04/05 05:19:13 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/04/05 05:19:13 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2021/04/05 05:19:13 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2021/04/05 05:19:13 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Range/TooManyErrors 2021/04/05 05:19:13 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2021/04/05 05:19:13 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2021/04/05 05:19:13 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2021/04/05 05:19:13 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-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", 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 (7.27s) --- 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/04/05 05:19:20 DEBUG : Creating backend with remote "non-existent" 2021/04/05 05:19:20 DEBUG : Creating backend with remote "non-existent" 2021/04/05 05:19:20 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: Waiting for checks to finish 2021/04/05 05:19:20 ERROR : : error reading source directory: directory not found 2021/04/05 05:19:20 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 differences found 2021/04/05 05:19:20 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-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", 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 (13.38s) --- PASS: TestCheckDownload/1 (0.95s) --- PASS: TestCheckDownload/2 (0.29s) --- PASS: TestCheckDownload/3 (0.32s) --- PASS: TestCheckDownload/4 (0.46s) --- PASS: TestCheckDownload/5 (0.32s) --- PASS: TestCheckDownload/6 (0.38s) --- PASS: TestCheckDownload/7 (0.36s) === RUN TestCheckSizeOnly run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", 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 (6.10s) --- PASS: TestCheckSizeOnly/1 (0.14s) --- PASS: TestCheckSizeOnly/2 (0.14s) --- PASS: TestCheckSizeOnly/3 (0.14s) --- PASS: TestCheckSizeOnly/4 (0.14s) --- PASS: TestCheckSizeOnly/5 (0.14s) --- PASS: TestCheckSizeOnly/6 (0.14s) --- PASS: TestCheckSizeOnly/7 (0.14s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestDeduplicateInteractive run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.28s) === RUN TestDeduplicateSkip run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.28s) === RUN TestDeduplicateSizeOnly run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.28s) === RUN TestDeduplicateFirst run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.28s) === RUN TestDeduplicateNewest run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.28s) === RUN TestDeduplicateNewestByHash run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:19:44 INFO : B2 bucket rclone-test-havakob9xewijor4vezevig0: Looking for duplicate SHA-1 hashes using newest mode. 2021/04/05 05:19:44 NOTICE: e533ee87d5fefc71b0e8509afc62170b08d4a12e: Found 3 files with duplicate SHA-1 hashes 2021/04/05 05:19:45 INFO : one: Deleted 2021/04/05 05:19:45 INFO : also/one: Deleted 2021/04/05 05:19:45 NOTICE: e533ee87d5fefc71b0e8509afc62170b08d4a12e: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (5.43s) === RUN TestDeduplicateOldest run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", 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-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.29s) === RUN TestDeduplicateSmallest run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", 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-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", 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-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", 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-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:19:52 DEBUG : a.txt: Excluded 2021/04/05 05:19:53 DEBUG : sub dir/hello world: Excluded 2021/04/05 05:19:53 DEBUG : sub dir/hello world2: Excluded 2021/04/05 05:19:53 DEBUG : sub dir/hello world: Excluded 2021/04/05 05:19:53 DEBUG : sub dir/hello world2: Excluded 2021/04/05 05:19:53 DEBUG : sub dir/ignore dir: Excluded 2021/04/05 05:19:53 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:19:54 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (9.90s) === RUN TestMkdir run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:19:58 DEBUG : B2 bucket rclone-test-havakob9xewijor4vezevig0: Making directory 2021/04/05 05:19:58 DEBUG : B2 bucket rclone-test-havakob9xewijor4vezevig0: Making directory --- PASS: TestMkdir (0.42s) === RUN TestLsd run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" --- PASS: TestLsd (2.12s) === RUN TestLs run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" --- PASS: TestLs (5.54s) === RUN TestLsWithFilesFrom run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:20:08 DEBUG : empty space: Excluded 2021/04/05 05:20:08 DEBUG : Couldn't decode error response: EOF --- PASS: TestLsWithFilesFrom (3.56s) === RUN TestLsLong run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" --- PASS: TestLsLong (3.26s) === RUN TestHashSums run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:20:16 ERROR : potato2: Hash unsupported: hash type not supported 2021/04/05 05:20:16 ERROR : empty space: Hash unsupported: hash type not supported 2021/04/05 05:20:17 ERROR : potato2: Hash unsupported: hash type not supported 2021/04/05 05:20:17 ERROR : empty space: Hash unsupported: hash type not supported --- PASS: TestHashSums (5.36s) === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" --- PASS: TestCount (5.27s) === RUN TestDelete run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:20:25 DEBUG : Waiting for deletions to finish 2021/04/05 05:20:26 DEBUG : large: Excluded from sync (and deletion) 2021/04/05 05:20:27 INFO : small: Deleted 2021/04/05 05:20:27 INFO : medium: Deleted --- PASS: TestDelete (4.61s) === RUN TestRetry 2021/04/05 05:20:28 DEBUG : Received error: EOF - low level retry 1/5 2021/04/05 05:20:28 DEBUG : Received error: EOF - low level retry 2/5 2021/04/05 05:20:28 DEBUG : Received error: EOF - low level retry 1/5 2021/04/05 05:20:28 DEBUG : Received error: EOF - low level retry 2/5 2021/04/05 05:20:28 DEBUG : Received error: EOF - low level retry 3/5 2021/04/05 05:20:28 DEBUG : Received error: EOF - low level retry 4/5 2021/04/05 05:20:28 DEBUG : Received error: EOF - low level retry 5/5 --- PASS: TestRetry (0.00s) === RUN TestCat run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" --- PASS: TestCat (5.54s) === RUN TestPurge 2021/04/05 05:20:34 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/04/05 05:20:34 DEBUG : Creating backend with remote "TestB2:rclone-test-nehiqit3nejomuz5qotamey9" 2021/04/05 05:20:34 DEBUG : Creating backend with remote "/tmp/rclone708207207" run.go:176: Remote "B2 bucket rclone-test-nehiqit3nejomuz5qotamey9", Local "Local file system at /tmp/rclone708207207", Modify Window "1ms" 2021/04/05 05:20:36 DEBUG : A2: Making directory 2021/04/05 05:20:36 DEBUG : A1/B2: Making directory 2021/04/05 05:20:36 DEBUG : A1/B2/C2: Making directory 2021/04/05 05:20:36 DEBUG : A1/B1/C3: Making directory 2021/04/05 05:20:36 DEBUG : A3: Making directory 2021/04/05 05:20:36 DEBUG : A3/B3: Making directory 2021/04/05 05:20:36 DEBUG : A3/B3/C4: Making directory fstest.go:251: Filtering empty directory "A2" fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B2/C2" fstest.go:251: Filtering empty directory "A1/B1/C3" fstest.go:251: Filtering empty directory "A3" fstest.go:251: Filtering empty directory "A3/B3" fstest.go:251: Filtering empty directory "A3/B3/C4" 2021/04/05 05:20:37 DEBUG : A1/B1/C1/one: Deleting (id "4_zcfa2bd83709dab0b7f880e1b_f101732c38fb10f42_d20210405_m052035_c001_v0001144_t0029") fstest.go:251: Filtering empty directory "A2" fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B2/C2" fstest.go:251: Filtering empty directory "A3" fstest.go:251: Filtering empty directory "A3/B3" fstest.go:251: Filtering empty directory "A3/B3/C4" 2021/04/05 05:20:38 DEBUG : A1/two: Deleting (id "4_zcfa2bd83709dab0b7f880e1b_f101732c38fb10f55_d20210405_m052036_c001_v0001144_t0029") 2021/04/05 05:20:38 DEBUG : B2 bucket rclone-test-nehiqit3nejomuz5qotamey9: Purge remote 2021/04/05 05:20:38 purge failed: directory not found --- PASS: TestPurge (4.71s) === RUN TestRmdirsNoLeaveRoot run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:20:40 DEBUG : A2: Making directory 2021/04/05 05:20:40 DEBUG : A1/B2: Making directory 2021/04/05 05:20:40 DEBUG : A1/B2/C2: Making directory 2021/04/05 05:20:40 DEBUG : A1/B1/C3: Making directory 2021/04/05 05:20:40 DEBUG : A3: Making directory 2021/04/05 05:20:40 DEBUG : A3/B3: Making directory 2021/04/05 05:20:40 DEBUG : A3/B3/C4: Making directory fstest.go:251: Filtering empty directory "A2" fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B2/C2" fstest.go:251: Filtering empty directory "A1/B1/C3" fstest.go:251: Filtering empty directory "A3" fstest.go:251: Filtering empty directory "A3/B3" fstest.go:251: Filtering empty directory "A3/B3/C4" 2021/04/05 05:20:41 DEBUG : A3/B3/C4: Removing directory fstest.go:251: Filtering empty directory "A2" fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B2/C2" fstest.go:251: Filtering empty directory "A1/B1/C3" fstest.go:251: Filtering empty directory "A3" fstest.go:251: Filtering empty directory "A3/B3" --- PASS: TestRmdirsNoLeaveRoot (6.42s) === RUN TestRmdirsLeaveRoot run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:20:45 DEBUG : A1: Making directory 2021/04/05 05:20:45 DEBUG : A1/B1: Making directory 2021/04/05 05:20:45 DEBUG : A1/B1/C1: Making directory fstest.go:251: Filtering empty directory "A1" fstest.go:251: Filtering empty directory "A1/B1" fstest.go:251: Filtering empty directory "A1/B1/C1" fstest.go:251: Filtering empty directory "A1" --- PASS: TestRmdirsLeaveRoot (0.71s) === RUN TestRmdirsWithFilter run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:20:46 DEBUG : A1: Making directory 2021/04/05 05:20:46 DEBUG : A1/B1: Making directory 2021/04/05 05:20:46 DEBUG : A1/B1/C1: Making directory fstest.go:251: Filtering empty directory "A1" fstest.go:251: Filtering empty directory "A1/B1" fstest.go:251: Filtering empty directory "A1/B1/C1" fstest.go:251: Filtering empty directory "A1" --- PASS: TestRmdirsWithFilter (0.71s) === RUN TestCopyURL run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:20:50 DEBUG : filename.txt: File name found in url --- PASS: TestCopyURL (7.26s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:20:54 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:20:54 DEBUG : file1: Need to transfer - File not found at Destination 2021/04/05 05:20:54 DEBUG : file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/04/05 05:20:54 INFO : file1: Copied (new) to: sub/file2 2021/04/05 05:20:54 INFO : file1: Deleted 2021/04/05 05:20:55 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:20:55 DEBUG : file1: Unchanged skipping 2021/04/05 05:20:55 INFO : file1: Deleted 2021/04/05 05:20:55 DEBUG : B2 bucket rclone-test-havakob9xewijor4vezevig0: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (2.39s) === RUN TestCaseInsensitiveMoveFile run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.29s) === RUN TestMoveFileBackupDir run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:20:57 DEBUG : Creating backend with remote "TestB2:rclone-test-havakob9xewijor4vezevig0/backup" 2021/04/05 05:20:57 DEBUG : Config file has changed externaly - reloading 2021/04/05 05:20:58 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:20:58 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2021/04/05 05:20:58 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:01 DEBUG : dst/file1: SHA-1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2021/04/05 05:21:01 INFO : dst/file1: Copied (server-side copy) 2021/04/05 05:21:01 INFO : dst/file1: Deleted 2021/04/05 05:21:02 DEBUG : dst/file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/04/05 05:21:02 INFO : dst/file1: Copied (new) 2021/04/05 05:21:02 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (7.22s) === RUN TestCopyFile run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:21:04 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:04 DEBUG : file1: Need to transfer - File not found at Destination 2021/04/05 05:21:05 DEBUG : file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/04/05 05:21:05 INFO : file1: Copied (new) to: sub/file2 2021/04/05 05:21:05 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 05:21:05 DEBUG : file1: Unchanged skipping 2021/04/05 05:21:05 DEBUG : B2 bucket rclone-test-havakob9xewijor4vezevig0: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (4.60s) === RUN TestCopyFileBackupDir run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:21:09 DEBUG : Creating backend with remote "TestB2:rclone-test-havakob9xewijor4vezevig0/backup" 2021/04/05 05:21:10 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:10 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2021/04/05 05:21:10 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:12 DEBUG : dst/file1: SHA-1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2021/04/05 05:21:12 INFO : dst/file1: Copied (server-side copy) 2021/04/05 05:21:13 INFO : dst/file1: Deleted 2021/04/05 05:21:13 DEBUG : dst/file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/04/05 05:21:13 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (8.17s) === RUN TestCopyFileCompareDest run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:21:16 DEBUG : Creating backend with remote "TestB2:rclone-test-havakob9xewijor4vezevig0/dst" 2021/04/05 05:21:17 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:18 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:18 DEBUG : Creating backend with remote "TestB2:rclone-test-havakob9xewijor4vezevig0/CompareDest" 2021/04/05 05:21:18 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:18 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:18 DEBUG : one: Need to transfer - File not found at Destination 2021/04/05 05:21:21 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 05:21:21 INFO : one: Copied (new) 2021/04/05 05:21:21 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:21 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/04/05 05:21:22 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/04/05 05:21:22 INFO : one: Copied (replaced existing) 2021/04/05 05:21:24 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:21:24 DEBUG : one: Destination found in --compare-dest, skipping 2021/04/05 05:21:25 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:25 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:21:25 DEBUG : two: Destination found in --compare-dest, skipping 2021/04/05 05:21:25 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:26 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:21:26 DEBUG : two: Destination found in --compare-dest, skipping 2021/04/05 05:21:26 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:26 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/04/05 05:21:26 DEBUG : two: Need to transfer - File not found at Destination 2021/04/05 05:21:26 DEBUG : two: SHA-1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2021/04/05 05:21:26 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (14.13s) === RUN TestCopyFileCopyDest run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:21:30 DEBUG : Creating backend with remote "TestB2:rclone-test-havakob9xewijor4vezevig0/dst" 2021/04/05 05:21:31 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:31 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:31 DEBUG : Creating backend with remote "TestB2:rclone-test-havakob9xewijor4vezevig0/CopyDest" 2021/04/05 05:21:32 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:32 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:32 DEBUG : one: Need to transfer - File not found at Destination 2021/04/05 05:21:39 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 05:21:39 INFO : one: Copied (new) 2021/04/05 05:21:40 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:40 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/04/05 05:21:41 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/04/05 05:21:41 INFO : one: Copied (replaced existing) 2021/04/05 05:21:43 DEBUG : Creating backend with remote "TestB2:rclone-test-havakob9xewijor4vezevig0/BackupDir" 2021/04/05 05:21:44 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:44 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:21:44 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/04/05 05:21:44 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:46 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 05:21:46 INFO : one: Copied (server-side copy) 2021/04/05 05:21:46 INFO : one: Deleted 2021/04/05 05:21:47 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/04/05 05:21:47 INFO : one: Copied (server-side copy) 2021/04/05 05:21:47 DEBUG : one: Destination found in --copy-dest, using server-side copy 2021/04/05 05:21:48 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:48 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:21:49 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/04/05 05:21:49 INFO : two: Copied (server-side copy) 2021/04/05 05:21:49 DEBUG : two: Destination found in --copy-dest, using server-side copy 2021/04/05 05:21:50 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:21:50 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 05:21:50 DEBUG : two: Unchanged skipping 2021/04/05 05:21:51 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:21:51 DEBUG : three: Sizes differ (src 7 vs dst 5) 2021/04/05 05:21:51 DEBUG : three: Destination not found in --copy-dest 2021/04/05 05:21:51 DEBUG : three: Need to transfer - File not found at Destination 2021/04/05 05:21:52 DEBUG : three: SHA-1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2021/04/05 05:21:52 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (28.42s) === 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-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:22:03 DEBUG : A1/B2: Making directory 2021/04/05 05:22:03 DEBUG : A1/B1/C3: Making directory fstest.go:251: Filtering empty directory "A1/B2" fstest.go:251: Filtering empty directory "A1/B1/C3" 2021/04/05 05:22:04 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:22:04 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:22:04 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:22:04 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:22:04 DEBUG : A1/one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 05:22:04 INFO : A1/one: Copied (server-side copy) to: A2/one 2021/04/05 05:22:05 DEBUG : A1/B1/C2/five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/04/05 05:22:05 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2021/04/05 05:22:05 DEBUG : A1/B1/C1/four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/04/05 05:22:05 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2021/04/05 05:22:05 INFO : A1/one: Deleted 2021/04/05 05:22:05 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:22:05 INFO : A1/B1/C2/five: Deleted 2021/04/05 05:22:05 DEBUG : A1/two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/04/05 05:22:05 INFO : A1/two: Copied (server-side copy) to: A2/two 2021/04/05 05:22:06 INFO : A1/B1/C1/four: Deleted 2021/04/05 05:22:06 DEBUG : A1/B1/three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/04/05 05:22:06 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2021/04/05 05:22:06 INFO : A1/two: Deleted 2021/04/05 05:22:07 INFO : A1/B1/three: Deleted fstest.go:251: Filtering empty directory "A2/B2" fstest.go:251: Filtering empty directory "A2/B1/C3" 2021/04/05 05:22:07 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:22:07 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:22:07 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:22:07 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:22:08 DEBUG : A2/one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 05:22:08 INFO : A2/one: Copied (server-side copy) to: A3/one 2021/04/05 05:22:08 DEBUG : A2/B1/three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/04/05 05:22:08 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2021/04/05 05:22:08 DEBUG : A2/B1/C2/five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/04/05 05:22:08 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2021/04/05 05:22:08 INFO : A2/B1/three: Deleted 2021/04/05 05:22:08 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:22:08 INFO : A2/B1/C2/five: Deleted 2021/04/05 05:22:09 INFO : A2/one: Deleted 2021/04/05 05:22:09 DEBUG : A2/B1/C1/four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/04/05 05:22:09 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2021/04/05 05:22:09 DEBUG : A2/two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/04/05 05:22:09 INFO : A2/two: Copied (server-side copy) to: A3/two 2021/04/05 05:22:09 INFO : A2/B1/C1/four: Deleted 2021/04/05 05:22:11 INFO : A2/two: Deleted fstest.go:251: Filtering empty directory "A3/B2" fstest.go:251: Filtering empty directory "A3/B1/C3" --- PASS: TestDirMove (17.14s) === RUN TestGetFsInfo run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", 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-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:22:16 DEBUG : B2 bucket rclone-test-havakob9xewijor4vezevig0: File to upload is small (34 bytes), uploading instead of streaming 2021/04/05 05:22:17 DEBUG : no_checksum_small_file_from_pipe: SHA-1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2021/04/05 05:22:17 INFO : no_checksum_small_file_from_pipe: Copied (new) 2021/04/05 05:22:17 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/04/05 05:22:18 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-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:22:21 DEBUG : B2 bucket rclone-test-havakob9xewijor4vezevig0: File to upload is small (34 bytes), uploading instead of streaming 2021/04/05 05:22:22 DEBUG : with_checksum_small_file_from_pipe: SHA-1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2021/04/05 05:22:22 INFO : with_checksum_small_file_from_pipe: Copied (new) 2021/04/05 05:22:22 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/04/05 05:22:23 DEBUG : with_checksum_big_file_from_pipe: SHA-1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2021/04/05 05:22:23 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-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:22:25 DEBUG : B2 bucket rclone-test-havakob9xewijor4vezevig0: File to upload is small (34 bytes), uploading instead of streaming 2021/04/05 05:22:26 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2021/04/05 05:22:26 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/04/05 05:22:28 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-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:22:30 DEBUG : B2 bucket rclone-test-havakob9xewijor4vezevig0: File to upload is small (34 bytes), uploading instead of streaming 2021/04/05 05:22:31 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2021/04/05 05:22:31 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/04/05 05:22:32 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (17.82s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.11s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (3.39s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (5.39s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (3.92s) === RUN TestRcatSize run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:22:35 DEBUG : B2 bucket rclone-test-havakob9xewijor4vezevig0: File to upload is small (60 bytes), uploading instead of streaming 2021/04/05 05:22:35 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/04/05 05:22:35 INFO : potato2: Copied (new) --- PASS: TestRcatSize (2.61s) === RUN TestCopyFileMaxTransfer run.go:176: Remote "B2 bucket rclone-test-havakob9xewijor4vezevig0", Local "Local file system at /tmp/rclone543299501", Modify Window "1ms" 2021/04/05 05:22:37 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:22:37 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2021/04/05 05:22:38 DEBUG : TestCopyFileMaxTransfer/file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/04/05 05:22:38 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2021/04/05 05:22:38 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:22:38 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2021/04/05 05:22:38 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1157-07.backblaze.com/b2api/v1/b2_upload_file/7f425d73708dab0b7f880e1b/c001_v0001157_t0036": Max transfer limit reached as set by --max-transfer 2021/04/05 05:22:39 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:22:39 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2021/04/05 05:22:39 DEBUG : Couldn't decode error response: EOF 2021/04/05 05:22:39 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2021/04/05 05:22:40 DEBUG : TestCopyFileMaxTransfer/file4: SHA-1 = 88dbbb375ed8f39b8579e82faa796ea8108139c8 OK 2021/04/05 05:22:40 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (5.15s) === 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/04/05 05:22:42 DEBUG : B2 bucket rclone-test-havakob9xewijor4vezevig0: Purge remote 2021/04/05 05:22:42 DEBUG : A1/B1/C1/four: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1001bcb7c31048e5_d20210405_m052205_c001_v0001037_t0053") 2021/04/05 05:22:42 DEBUG : A1/B1/C1/four: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b910c16_d20210405_m052202_c001_v0001157_t0036") 2021/04/05 05:22:42 DEBUG : A1/B1/C1/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1094f229cfd5edd9_d20210405_m052042_c001_v0001113_t0059") 2021/04/05 05:22:42 DEBUG : A1/B1/C1/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90fc89_d20210405_m052039_c001_v0001157_t0036") 2021/04/05 05:22:42 DEBUG : A1/B1/C2/five: Deleting (id "4_z7f425d73708dab0b7f880e1b_f10961cc143bf3d84_d20210405_m052205_c001_v0001130_t0053") 2021/04/05 05:22:42 DEBUG : A1/B1/C2/five: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b910c3f_d20210405_m052202_c001_v0001157_t0036") 2021/04/05 05:22:42 DEBUG : A1/B1/three: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1101856a48624542_d20210405_m052206_c001_v0001145_t0002") 2021/04/05 05:22:42 DEBUG : A1/B1/three: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b910bdf_d20210405_m052200_c001_v0001157_t0036") 2021/04/05 05:22:42 DEBUG : A1/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1049d66d079c1bcf_d20210405_m052204_c001_v0001039_t0051") 2021/04/05 05:22:42 DEBUG : A1/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b910b98_d20210405_m052159_c001_v0001157_t0036") 2021/04/05 05:22:43 DEBUG : A1/two: Deleting (id "4_z7f425d73708dab0b7f880e1b_f10180c571b0da171_d20210405_m052205_c001_v0001154_t0004") 2021/04/05 05:22:43 DEBUG : A1/two: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b910bc0_d20210405_m052200_c001_v0001157_t0036") 2021/04/05 05:22:43 DEBUG : A1/two: Deleting (id "4_z7f425d73708dab0b7f880e1b_f104f2a95dec8cb8a_d20210405_m052042_c001_v0001005_t0016") 2021/04/05 05:22:43 DEBUG : A1/two: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90fcb9_d20210405_m052040_c001_v0001157_t0036") 2021/04/05 05:22:43 DEBUG : A2/B1/C1/four: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1184a2f910682798_d20210405_m052209_c001_v0001157_t0015") 2021/04/05 05:22:43 DEBUG : A2/B1/C1/four: Deleting (id "4_z7f425d73708dab0b7f880e1b_f112a5115c4b2c1dc_d20210405_m052204_c001_v0001044_t0052") 2021/04/05 05:22:43 DEBUG : A2/B1/C2/five: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1088d26c6b503a26_d20210405_m052208_c001_v0001145_t0015") 2021/04/05 05:22:43 DEBUG : A2/B1/C2/five: Deleting (id "4_z7f425d73708dab0b7f880e1b_f118ec4bcfbd2928b_d20210405_m052204_c001_v0001113_t0010") 2021/04/05 05:22:43 DEBUG : A2/B1/three: Deleting (id "4_z7f425d73708dab0b7f880e1b_f10002ed92990d0cc_d20210405_m052208_c001_v0001154_t0019") 2021/04/05 05:22:43 DEBUG : A2/B1/three: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107ee1d5dc88a68d_d20210405_m052205_c001_v0001019_t0021") 2021/04/05 05:22:43 DEBUG : A2/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f112be6cda00907a4_d20210405_m052208_c001_v0001091_t0034") 2021/04/05 05:22:43 DEBUG : A2/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1131e8833edad6d2_d20210405_m052204_c001_v0001154_t0008") 2021/04/05 05:22:43 DEBUG : A2/two: Deleting (id "4_z7f425d73708dab0b7f880e1b_f103c75e2fde2aa9c_d20210405_m052210_c001_v0001130_t0013") 2021/04/05 05:22:43 DEBUG : A2/two: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1106b53192607328_d20210405_m052204_c001_v0001144_t0024") 2021/04/05 05:22:43 DEBUG : A3/B1/C1/four: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1164fab026b42ca8_d20210405_m052211_c001_v0001157_t0027") 2021/04/05 05:22:43 DEBUG : A3/B1/C1/four: Deleting (id "4_z7f425d73708dab0b7f880e1b_f105e06eb929fbfd1_d20210405_m052208_c001_v0001157_t0026") 2021/04/05 05:22:43 DEBUG : A3/B1/C2/five: Deleting (id "4_z7f425d73708dab0b7f880e1b_f11251bc658051070_d20210405_m052212_c001_v0001145_t0027") 2021/04/05 05:22:43 DEBUG : A3/B1/C2/five: Deleting (id "4_z7f425d73708dab0b7f880e1b_f117820cf4221cdb7_d20210405_m052207_c001_v0001137_t0050") 2021/04/05 05:22:43 DEBUG : A3/B1/three: Deleting (id "4_z7f425d73708dab0b7f880e1b_f100dc26cf9ba249c_d20210405_m052212_c001_v0001113_t0052") 2021/04/05 05:22:44 DEBUG : A3/B1/three: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1022eb9687a091c7_d20210405_m052207_c001_v0001157_t0004") 2021/04/05 05:22:44 DEBUG : A3/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1064b2984a765fe2_d20210405_m052215_c001_v0001150_t0055") 2021/04/05 05:22:44 DEBUG : A3/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f100b0e08e5bb94c7_d20210405_m052207_c001_v0001157_t0023") 2021/04/05 05:22:44 DEBUG : A3/two: Deleting (id "4_z7f425d73708dab0b7f880e1b_f115ad13a246d3a36_d20210405_m052215_c001_v0001130_t0038") 2021/04/05 05:22:44 DEBUG : A3/two: Deleting (id "4_z7f425d73708dab0b7f880e1b_f114e2667fa8808c5_d20210405_m052208_c001_v0001008_t0038") 2021/04/05 05:22:44 DEBUG : BackupDir/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f100b0e08e5bb91d8_d20210405_m052153_c001_v0001157_t0030") 2021/04/05 05:22:44 DEBUG : BackupDir/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f10502ae676316710_d20210405_m052146_c001_v0001150_t0004") 2021/04/05 05:22:44 DEBUG : CompareDest/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1155c05a1e750f97_d20210405_m052127_c001_v0001128_t0052") 2021/04/05 05:22:44 DEBUG : CompareDest/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b9104b9_d20210405_m052123_c001_v0001157_t0036") 2021/04/05 05:22:44 DEBUG : CompareDest/two: Deleting (id "4_z7f425d73708dab0b7f880e1b_f11566e9fafd07f42_d20210405_m052128_c001_v0001040_t0009") 2021/04/05 05:22:44 DEBUG : CompareDest/two: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b9104f3_d20210405_m052124_c001_v0001157_t0036") 2021/04/05 05:22:44 DEBUG : CopyDest/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f10907ad9a0e7de14_d20210405_m052156_c001_v0001157_t0040") 2021/04/05 05:22:44 DEBUG : CopyDest/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b910870_d20210405_m052142_c001_v0001157_t0036") 2021/04/05 05:22:44 DEBUG : CopyDest/three: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1093bb5d7bb6cd9c_d20210405_m052156_c001_v0001093_t0021") 2021/04/05 05:22:44 DEBUG : CopyDest/three: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b9109da_d20210405_m052150_c001_v0001157_t0036") 2021/04/05 05:22:44 DEBUG : CopyDest/two: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1164e051aa23bc2c_d20210405_m052157_c001_v0001144_t0047") 2021/04/05 05:22:44 DEBUG : CopyDest/two: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b910962_d20210405_m052147_c001_v0001157_t0036") 2021/04/05 05:22:44 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f111e23f42dbfcc31_d20210405_m052240_c001_v0001157_t0046") 2021/04/05 05:22:44 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b911253_d20210405_m052237_c001_v0001157_t0036") 2021/04/05 05:22:44 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z7f425d73708dab0b7f880e1b_f11695f7fccc3a419_d20210405_m052241_c001_v0001136_t0040") 2021/04/05 05:22:44 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b9112b2_d20210405_m052239_c001_v0001157_t0036") 2021/04/05 05:22:44 DEBUG : a.txt: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1116e881abc24407_d20210405_m051954_c001_v0001150_t0023") 2021/04/05 05:22:44 DEBUG : a.txt: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f24f_d20210405_m051948_c001_v0001157_t0036") 2021/04/05 05:22:44 DEBUG : also/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f101bd2546005b011_d20210405_m051945_c001_v0001157_t0045") 2021/04/05 05:22:45 DEBUG : also/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f0f5_d20210405_m051942_c001_v0001157_t0036") 2021/04/05 05:22:45 DEBUG : another: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1019179ac5fb8e0d_d20210405_m051946_c001_v0001029_t0020") 2021/04/05 05:22:45 DEBUG : another: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f11e_d20210405_m051943_c001_v0001157_t0036") 2021/04/05 05:22:45 DEBUG : backup/dst/file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f10592ba3b262e979_d20210405_m052115_c001_v0001145_t0052") 2021/04/05 05:22:45 DEBUG : backup/dst/file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f118c6d31fe7d7e73_d20210405_m052112_c001_v0001153_t0017") 2021/04/05 05:22:45 DEBUG : backup/dst/file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f117e989a24416de9_d20210405_m052102_c001_v0001157_t0021") 2021/04/05 05:22:45 DEBUG : backup/dst/file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107194d8b7c71964_d20210405_m052100_c001_v0001013_t0023") 2021/04/05 05:22:45 DEBUG : dst/file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1144a29bd75f0d52_d20210405_m052116_c001_v0001038_t0053") 2021/04/05 05:22:45 DEBUG : dst/file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b9102ec_d20210405_m052113_c001_v0001157_t0036") 2021/04/05 05:22:45 DEBUG : dst/file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1164fab026b420df_d20210405_m052112_c001_v0001157_t0039") 2021/04/05 05:22:45 DEBUG : dst/file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b910205_d20210405_m052108_c001_v0001157_t0036") 2021/04/05 05:22:45 DEBUG : dst/file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f102631160a840c05_d20210405_m052103_c001_v0001144_t0034") 2021/04/05 05:22:45 DEBUG : dst/file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b9100ad_d20210405_m052102_c001_v0001157_t0036") 2021/04/05 05:22:45 DEBUG : dst/file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1063588bca8c9075_d20210405_m052101_c001_v0001154_t0022") 2021/04/05 05:22:45 DEBUG : dst/file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90ffc5_d20210405_m052056_c001_v0001157_t0036") 2021/04/05 05:22:45 DEBUG : dst/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f11340414ec5effb9_d20210405_m052157_c001_v0001153_t0022") 2021/04/05 05:22:45 DEBUG : dst/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f111aea8bb13f4bcb_d20210405_m052147_c001_v0001040_t0003") 2021/04/05 05:22:45 DEBUG : dst/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f10580ff3c2389945_d20210405_m052146_c001_v0001030_t0029") 2021/04/05 05:22:45 DEBUG : dst/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b91083b_d20210405_m052141_c001_v0001157_t0036") 2021/04/05 05:22:45 DEBUG : dst/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f101bd2546005c670_d20210405_m052140_c001_v0001157_t0002") 2021/04/05 05:22:45 DEBUG : dst/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f101bd2546005c552_d20210405_m052134_c001_v0001157_t0002") 2021/04/05 05:22:45 DEBUG : dst/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f113501e88efe2501_d20210405_m052129_c001_v0001150_t0054") 2021/04/05 05:22:45 DEBUG : dst/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b910487_d20210405_m052122_c001_v0001157_t0036") 2021/04/05 05:22:46 DEBUG : dst/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f110a53a9a8024947_d20210405_m052121_c001_v0001128_t0024") 2021/04/05 05:22:46 DEBUG : dst/one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f110a53a9a8024941_d20210405_m052120_c001_v0001128_t0024") 2021/04/05 05:22:46 DEBUG : dst/three: Deleting (id "4_z7f425d73708dab0b7f880e1b_f101bd2546005c967_d20210405_m052158_c001_v0001157_t0035") 2021/04/05 05:22:46 DEBUG : dst/three: Deleting (id "4_z7f425d73708dab0b7f880e1b_f101bd2546005c866_d20210405_m052152_c001_v0001157_t0002") 2021/04/05 05:22:46 DEBUG : dst/two: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1106b531926072bc_d20210405_m052158_c001_v0001144_t0058") 2021/04/05 05:22:46 DEBUG : dst/two: Deleting (id "4_z7f425d73708dab0b7f880e1b_f101bd2546005c7e8_d20210405_m052149_c001_v0001157_t0054") 2021/04/05 05:22:46 DEBUG : dst/two: Deleting (id "4_z7f425d73708dab0b7f880e1b_f105e06eb929fb7b8_d20210405_m052130_c001_v0001157_t0023") 2021/04/05 05:22:46 DEBUG : dst/two: Deleting (id "4_z7f425d73708dab0b7f880e1b_f110a53a9a8024951_d20210405_m052126_c001_v0001128_t0024") 2021/04/05 05:22:46 DEBUG : empty space: Deleting (id "4_z7f425d73708dab0b7f880e1b_f10302fa6e829c2e1_d20210405_m052021_c001_v0001145_t0031") 2021/04/05 05:22:46 DEBUG : empty space: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f898_d20210405_m052019_c001_v0001157_t0036") 2021/04/05 05:22:46 DEBUG : empty space: Deleting (id "4_z7f425d73708dab0b7f880e1b_f11021e019ba4b2cf_d20210405_m052017_c001_v0001039_t0009") 2021/04/05 05:22:46 DEBUG : empty space: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f7a1_d20210405_m052014_c001_v0001157_t0036") 2021/04/05 05:22:46 DEBUG : empty space: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1124bb5374c04a89_d20210405_m052012_c001_v0001036_t0050") 2021/04/05 05:22:46 DEBUG : empty space: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f703_d20210405_m052011_c001_v0001157_t0036") 2021/04/05 05:22:46 DEBUG : empty space: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1022eb9687a07b48_d20210405_m052009_c001_v0001157_t0030") 2021/04/05 05:22:46 DEBUG : empty space: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f626_d20210405_m052007_c001_v0001157_t0036") 2021/04/05 05:22:46 DEBUG : empty space: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1024b065d0a27853_d20210405_m052003_c001_v0001036_t0020") 2021/04/05 05:22:46 DEBUG : empty space: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f4f3_d20210405_m052001_c001_v0001157_t0036") 2021/04/05 05:22:46 DEBUG : empty space: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1050a4f7a2869461_d20210405_m051938_c001_v0001136_t0017") 2021/04/05 05:22:46 DEBUG : empty space: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90efb9_d20210405_m051936_c001_v0001157_t0036") 2021/04/05 05:22:46 DEBUG : empty space: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1114556bac287a40_d20210405_m051927_c001_v0001090_t0058") 2021/04/05 05:22:46 DEBUG : empty space: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90ed2e_d20210405_m051923_c001_v0001157_t0036") 2021/04/05 05:22:46 DEBUG : empty space: Deleting (id "4_z7f425d73708dab0b7f880e1b_f113e8cef18d406a9_d20210405_m051917_c001_v0001044_t0043") 2021/04/05 05:22:47 DEBUG : empty space: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90eb67_d20210405_m051914_c001_v0001157_t0036") 2021/04/05 05:22:47 DEBUG : file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f109dccb73073a7d9_d20210405_m052052_c001_v0001009_t0011") 2021/04/05 05:22:47 DEBUG : file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90fe44_d20210405_m052049_c001_v0001157_t0036") 2021/04/05 05:22:47 DEBUG : file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f100b0e08e5bb811a_d20210405_m052032_c001_v0001157_t0044") 2021/04/05 05:22:47 DEBUG : file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90fa72_d20210405_m052028_c001_v0001157_t0036") 2021/04/05 05:22:47 DEBUG : file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f11985ce906c3c571_d20210405_m051913_c001_v0001157_t0003") 2021/04/05 05:22:47 DEBUG : file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90eaa1_d20210405_m051911_c001_v0001157_t0036") 2021/04/05 05:22:47 DEBUG : file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90ea2e_d20210405_m051909_c001_v0001157_t0036") 2021/04/05 05:22:47 DEBUG : file1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90e984_d20210405_m051905_c001_v0001157_t0036") 2021/04/05 05:22:47 DEBUG : file2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f101bd2546005bd50_d20210405_m052052_c001_v0001157_t0015") 2021/04/05 05:22:47 DEBUG : file2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90fe95_d20210405_m052051_c001_v0001157_t0036") 2021/04/05 05:22:47 DEBUG : file2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f118a8f43af8a7a07_d20210405_m052033_c001_v0001144_t0058") 2021/04/05 05:22:47 DEBUG : file2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90fa94_d20210405_m052029_c001_v0001157_t0036") 2021/04/05 05:22:47 DEBUG : filename.txt: Deleting (id "4_z7f425d73708dab0b7f880e1b_f101f2fd6d80c2c2c_d20210405_m052053_c001_v0001008_t0011") 2021/04/05 05:22:47 DEBUG : filename.txt: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90fe7b_d20210405_m052050_c001_v0001157_t0036") 2021/04/05 05:22:47 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1157145d2679e679_d20210405_m052232_c001_v0001038_t0001") 2021/04/05 05:22:47 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b911135_d20210405_m052231_c001_v0001157_t0036") 2021/04/05 05:22:47 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z7f425d73708dab0b7f880e1b_f109eac0ece70672c_d20210405_m052229_c001_v0001017_t0043") 2021/04/05 05:22:47 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b911083_d20210405_m052228_c001_v0001157_t0036") 2021/04/05 05:22:47 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1022eb9687a096a2_d20210405_m052233_c001_v0001157_t0011") 2021/04/05 05:22:47 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b911113_d20210405_m052230_c001_v0001157_t0036") 2021/04/05 05:22:47 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z7f425d73708dab0b7f880e1b_f11504c38558466fc_d20210405_m052229_c001_v0001157_t0004") 2021/04/05 05:22:47 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b91104d_d20210405_m052225_c001_v0001157_t0036") 2021/04/05 05:22:48 DEBUG : large: Deleting (id "4_z7f425d73708dab0b7f880e1b_f113cb0659a76c159_d20210405_m052027_c001_v0001157_t0000") 2021/04/05 05:22:48 DEBUG : large: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f9c2_d20210405_m052025_c001_v0001157_t0036") 2021/04/05 05:22:48 DEBUG : medium: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1106b53192606c0d_d20210405_m052026_c001_v0001144_t0047") 2021/04/05 05:22:48 DEBUG : medium: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f9a2_d20210405_m052024_c001_v0001157_t0036") 2021/04/05 05:22:48 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z7f425d73708dab0b7f880e1b_f100a3a7afc2f49ac_d20210405_m052219_c001_v0001041_t0048") 2021/04/05 05:22:48 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b910f03_d20210405_m052218_c001_v0001157_t0036") 2021/04/05 05:22:48 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1184a2f9106829d6_d20210405_m052221_c001_v0001157_t0028") 2021/04/05 05:22:48 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b910ee5_d20210405_m052217_c001_v0001157_t0036") 2021/04/05 05:22:48 DEBUG : not-one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f118aad2177c2967b_d20210405_m051946_c001_v0001021_t0042") 2021/04/05 05:22:48 DEBUG : not-one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f144_d20210405_m051943_c001_v0001157_t0036") 2021/04/05 05:22:48 DEBUG : one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f11515246117fc0af_d20210405_m051944_c001_v0001032_t0024") 2021/04/05 05:22:48 DEBUG : one: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f0d7_d20210405_m051941_c001_v0001157_t0036") 2021/04/05 05:22:48 DEBUG : potato1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1145e05725b7a307_d20210405_m052236_c001_v0001157_t0015") 2021/04/05 05:22:48 DEBUG : potato1: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b9111d2_d20210405_m052234_c001_v0001157_t0036") 2021/04/05 05:22:48 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f11385871864798de_d20210405_m052236_c001_v0001131_t0048") 2021/04/05 05:22:48 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b9111e5_d20210405_m052235_c001_v0001157_t0036") 2021/04/05 05:22:48 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f11985ce906c3d4ae_d20210405_m052021_c001_v0001157_t0049") 2021/04/05 05:22:48 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f889_d20210405_m052019_c001_v0001157_t0036") 2021/04/05 05:22:48 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1019c63fa22daf44_d20210405_m052018_c001_v0001040_t0013") 2021/04/05 05:22:48 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f781_d20210405_m052013_c001_v0001157_t0036") 2021/04/05 05:22:48 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1022eb9687a07c1f_d20210405_m052012_c001_v0001157_t0000") 2021/04/05 05:22:48 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f6cb_d20210405_m052010_c001_v0001157_t0036") 2021/04/05 05:22:48 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f105e06eb929fa71d_d20210405_m052009_c001_v0001157_t0033") 2021/04/05 05:22:48 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f5f8_d20210405_m052006_c001_v0001157_t0036") 2021/04/05 05:22:48 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f104fa93941e460dc_d20210405_m052005_c001_v0001157_t0050") 2021/04/05 05:22:49 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f4d2_d20210405_m052001_c001_v0001157_t0036") 2021/04/05 05:22:49 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f11505eaf96274b88_d20210405_m051939_c001_v0001137_t0055") 2021/04/05 05:22:49 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90efe3_d20210405_m051936_c001_v0001157_t0036") 2021/04/05 05:22:49 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f101745d52aeb7c88_d20210405_m051930_c001_v0001041_t0004") 2021/04/05 05:22:49 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90ed64_d20210405_m051924_c001_v0001157_t0036") 2021/04/05 05:22:49 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f108bdb5639a1ffea_d20210405_m051918_c001_v0001033_t0041") 2021/04/05 05:22:49 DEBUG : potato2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90eb90_d20210405_m051915_c001_v0001157_t0036") 2021/04/05 05:22:49 DEBUG : remotepotato: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1089569858f6b1f9_d20210405_m051939_c001_v0001130_t0024") 2021/04/05 05:22:49 DEBUG : remotepotato: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f01f_d20210405_m051937_c001_v0001157_t0036") 2021/04/05 05:22:49 DEBUG : remotepotato: Deleting (id "4_z7f425d73708dab0b7f880e1b_f11072a4d80fad963_d20210405_m051930_c001_v0001009_t0028") 2021/04/05 05:22:49 DEBUG : remotepotato: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90edad_d20210405_m051925_c001_v0001157_t0036") 2021/04/05 05:22:49 DEBUG : remotepotato: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1184a2f91068045e_d20210405_m051919_c001_v0001157_t0004") 2021/04/05 05:22:49 DEBUG : remotepotato: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90ebd6_d20210405_m051916_c001_v0001157_t0036") 2021/04/05 05:22:49 DEBUG : rutabaga: Deleting (id "4_z7f425d73708dab0b7f880e1b_f104fa93941e45aab_d20210405_m051939_c001_v0001157_t0052") 2021/04/05 05:22:49 DEBUG : rutabaga: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90ef7f_d20210405_m051934_c001_v0001157_t0036") 2021/04/05 05:22:49 DEBUG : rutabaga: Deleting (id "4_z7f425d73708dab0b7f880e1b_f100b0e08e5bb735b_d20210405_m051931_c001_v0001157_t0004") 2021/04/05 05:22:49 DEBUG : rutabaga: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90ecbb_d20210405_m051921_c001_v0001157_t0036") 2021/04/05 05:22:49 DEBUG : rutabaga: Deleting (id "4_z7f425d73708dab0b7f880e1b_f100f5cf9dff73d40_d20210405_m051919_c001_v0001144_t0023") 2021/04/05 05:22:49 DEBUG : rutabaga: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90eb2d_d20210405_m051913_c001_v0001157_t0036") 2021/04/05 05:22:49 DEBUG : small: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1184a2f910681283_d20210405_m052026_c001_v0001157_t0019") 2021/04/05 05:22:49 DEBUG : small: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f987_d20210405_m052024_c001_v0001157_t0036") 2021/04/05 05:22:49 DEBUG : sub dir/hello world: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1054e90ea1362125_d20210405_m052000_c001_v0001092_t0025") 2021/04/05 05:22:49 DEBUG : sub dir/hello world: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f492_d20210405_m051959_c001_v0001157_t0036") 2021/04/05 05:22:49 DEBUG : sub dir/hello world: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1032d0253fb698cd_d20210405_m051954_c001_v0001144_t0021") 2021/04/05 05:22:50 DEBUG : sub dir/hello world: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f280_d20210405_m051949_c001_v0001157_t0036") 2021/04/05 05:22:50 DEBUG : sub dir/hello world2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f104fa93941e45e8b_d20210405_m051955_c001_v0001157_t0021") 2021/04/05 05:22:50 DEBUG : sub dir/hello world2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f2a4_d20210405_m051950_c001_v0001157_t0036") 2021/04/05 05:22:50 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z7f425d73708dab0b7f880e1b_f11251bc658050b84_d20210405_m051956_c001_v0001145_t0000") 2021/04/05 05:22:50 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f2c0_d20210405_m051950_c001_v0001157_t0036") 2021/04/05 05:22:50 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z7f425d73708dab0b7f880e1b_f113cb0659a76bbca_d20210405_m051956_c001_v0001157_t0055") 2021/04/05 05:22:50 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f2dc_d20210405_m051951_c001_v0001157_t0036") 2021/04/05 05:22:50 DEBUG : sub dir/potato3: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1165616ecb72f25d_d20210405_m052023_c001_v0001137_t0003") 2021/04/05 05:22:50 DEBUG : sub dir/potato3: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f8b9_d20210405_m052020_c001_v0001157_t0036") 2021/04/05 05:22:50 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z7f425d73708dab0b7f880e1b_f105fbfcdb59e975a_d20210405_m051957_c001_v0001128_t0011") 2021/04/05 05:22:50 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f306_d20210405_m051951_c001_v0001157_t0036") 2021/04/05 05:22:50 DEBUG : sub/file2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f1172c1165330f0e7_d20210405_m052105_c001_v0001038_t0000") 2021/04/05 05:22:50 DEBUG : sub/file2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b910144_d20210405_m052104_c001_v0001157_t0036") 2021/04/05 05:22:50 DEBUG : sub/file2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e3a2d3b834eeb_d20210405_m052055_c001_v0001029_t0006") 2021/04/05 05:22:50 DEBUG : sub/file2: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90ff4f_d20210405_m052054_c001_v0001157_t0036") 2021/04/05 05:22:50 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z7f425d73708dab0b7f880e1b_f106f3e442acc39cb_d20210405_m052224_c001_v0001113_t0013") 2021/04/05 05:22:50 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b910fd9_d20210405_m052222_c001_v0001157_t0036") 2021/04/05 05:22:50 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z7f425d73708dab0b7f880e1b_f117820cf4221ce45_d20210405_m052224_c001_v0001137_t0022") 2021/04/05 05:22:50 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b910fbb_d20210405_m052222_c001_v0001157_t0036") 2021/04/05 05:22:50 DEBUG : zend.txt: Deleting (id "4_z7f425d73708dab0b7f880e1b_f113cb0659a76bc0a_d20210405_m051957_c001_v0001157_t0002") 2021/04/05 05:22:50 DEBUG : zend.txt: Deleting (id "4_z7f425d73708dab0b7f880e1b_f107e6fea8b90f26c_d20210405_m051949_c001_v0001157_t0036") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 3m48.296854004s (try 1/5)