"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Starting (try 1/5) 2021/04/05 06:08:39 DEBUG : Creating backend with remote "TestB2:rclone-test-sexawuk7vuwuloy5quzudoj4" 2021/04/05 06:08:40 DEBUG : Creating backend with remote "/tmp/rclone123740401" === 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" === RUN TestMultithreadCopy/{size:131071_streams:2} 2021/04/05 06:08:43 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64k 2021/04/05 06:08:43 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999k starting 2021/04/05 06:08:43 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k starting 2021/04/05 06:08:43 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999k finished 2021/04/05 06:08:44 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k finished 2021/04/05 06:08:44 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64k === RUN TestMultithreadCopy/{size:131072_streams:2} 2021/04/05 06:08:45 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64k 2021/04/05 06:08:45 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64k starting 2021/04/05 06:08:45 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k starting 2021/04/05 06:08:45 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k finished 2021/04/05 06:08:45 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64k finished 2021/04/05 06:08:45 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64k === RUN TestMultithreadCopy/{size:131073_streams:2} 2021/04/05 06:08:46 DEBUG : file1: Starting multi-thread copy with 2 parts of size 128k 2021/04/05 06:08:46 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 starting 2021/04/05 06:08:46 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128k starting 2021/04/05 06:08:46 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 finished 2021/04/05 06:08:47 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128k finished 2021/04/05 06:08:47 DEBUG : file1: Finished multi-thread copy with 2 parts of size 128k --- PASS: TestMultithreadCopy (7.99s) --- PASS: TestMultithreadCopy/{size:131071_streams:2} (3.97s) --- PASS: TestMultithreadCopy/{size:131072_streams:2} (1.35s) --- PASS: TestMultithreadCopy/{size:131073_streams:2} (1.51s) === 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 06:08:48 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2021/04/05 06:08:48 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2021/04/05 06:08:48 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Seek/TooManyErrors 2021/04/05 06:08:48 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2021/04/05 06:08:48 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2021/04/05 06:08:48 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2021/04/05 06:08:48 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 06:08:48 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2021/04/05 06:08:48 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2021/04/05 06:08:48 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Range/TooManyErrors 2021/04/05 06:08:48 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2021/04/05 06:08:48 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2021/04/05 06:08:48 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2021/04/05 06:08:48 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", 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 (5.88s) --- PASS: TestCheck/1 (0.14s) --- PASS: TestCheck/2 (0.14s) --- PASS: TestCheck/3 (0.14s) --- PASS: TestCheck/4 (0.14s) --- PASS: TestCheck/5 (0.14s) --- PASS: TestCheck/6 (0.14s) --- PASS: TestCheck/7 (0.14s) === RUN TestCheckFsError 2021/04/05 06:08:54 DEBUG : Creating backend with remote "non-existent" 2021/04/05 06:08:54 DEBUG : Creating backend with remote "non-existent" 2021/04/05 06:08:54 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 06:08:54 ERROR : : error reading source directory: directory not found 2021/04/05 06:08:54 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 differences found 2021/04/05 06:08:54 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", 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 (8.24s) --- PASS: TestCheckDownload/1 (0.73s) --- PASS: TestCheckDownload/2 (0.28s) --- PASS: TestCheckDownload/3 (0.29s) --- PASS: TestCheckDownload/4 (0.56s) --- PASS: TestCheckDownload/5 (0.34s) --- PASS: TestCheckDownload/6 (0.34s) --- PASS: TestCheckDownload/7 (0.29s) === RUN TestCheckSizeOnly run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", 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 (8.26s) --- PASS: TestCheckSizeOnly/1 (0.14s) --- PASS: TestCheckSizeOnly/2 (0.14s) --- PASS: TestCheckSizeOnly/3 (0.14s) --- PASS: TestCheckSizeOnly/4 (0.14s) --- PASS: TestCheckSizeOnly/5 (0.16s) --- 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:09:15 INFO : B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4: Looking for duplicate SHA-1 hashes using newest mode. 2021/04/05 06:09:15 NOTICE: bda607277dd519122a68ba2a5f6c6bd269b50b1d: Found 3 files with duplicate SHA-1 hashes 2021/04/05 06:09:15 INFO : one: Deleted 2021/04/05 06:09:16 INFO : also/one: Deleted 2021/04/05 06:09:16 NOTICE: bda607277dd519122a68ba2a5f6c6bd269b50b1d: Deleted 2 extra copies --- PASS: TestDeduplicateNewestByHash (5.86s) === RUN TestDeduplicateOldest run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" dedupe_test.go:24: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.30s) === RUN TestDeduplicateLargest run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:09:24 DEBUG : a.txt: Excluded 2021/04/05 06:09:24 DEBUG : sub dir/hello world: Excluded 2021/04/05 06:09:24 DEBUG : sub dir/hello world2: Excluded 2021/04/05 06:09:24 DEBUG : sub dir/hello world: Excluded 2021/04/05 06:09:24 DEBUG : sub dir/hello world2: Excluded 2021/04/05 06:09:25 DEBUG : sub dir/ignore dir: Excluded 2021/04/05 06:09:25 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:09:25 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (10.79s) === RUN TestMkdir run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:09:30 DEBUG : B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4: Making directory 2021/04/05 06:09:30 DEBUG : B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4: Making directory --- PASS: TestMkdir (0.42s) === RUN TestLsd run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" --- PASS: TestLsd (2.27s) === RUN TestLs run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" --- PASS: TestLs (2.82s) === RUN TestLsWithFilesFrom run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:09:37 DEBUG : empty space: Excluded from sync (and deletion) 2021/04/05 06:09:38 DEBUG : Couldn't decode error response: EOF --- PASS: TestLsWithFilesFrom (4.30s) === RUN TestLsLong run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" --- PASS: TestLsLong (5.45s) === RUN TestHashSums run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:09:48 ERROR : potato2: Hash unsupported: hash type not supported 2021/04/05 06:09:48 ERROR : empty space: Hash unsupported: hash type not supported 2021/04/05 06:09:48 ERROR : potato2: Hash unsupported: hash type not supported 2021/04/05 06:09:48 ERROR : empty space: Hash unsupported: hash type not supported --- PASS: TestHashSums (4.86s) === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" --- PASS: TestCount (3.83s) === RUN TestDelete run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:09:56 DEBUG : Waiting for deletions to finish 2021/04/05 06:09:56 DEBUG : large: Excluded from sync (and deletion) 2021/04/05 06:09:56 INFO : medium: Deleted 2021/04/05 06:09:57 INFO : small: Deleted --- PASS: TestDelete (3.57s) === RUN TestRetry 2021/04/05 06:09:57 DEBUG : Received error: EOF - low level retry 1/5 2021/04/05 06:09:57 DEBUG : Received error: EOF - low level retry 2/5 2021/04/05 06:09:57 DEBUG : Received error: EOF - low level retry 1/5 2021/04/05 06:09:57 DEBUG : Received error: EOF - low level retry 2/5 2021/04/05 06:09:57 DEBUG : Received error: EOF - low level retry 3/5 2021/04/05 06:09:57 DEBUG : Received error: EOF - low level retry 4/5 2021/04/05 06:09:57 DEBUG : Received error: EOF - low level retry 5/5 --- PASS: TestRetry (0.00s) === RUN TestCat run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" --- PASS: TestCat (5.08s) === RUN TestPurge 2021/04/05 06:10:02 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/04/05 06:10:02 DEBUG : Creating backend with remote "TestB2:rclone-test-dogavuj6ceyahuf2dubogex8" 2021/04/05 06:10:03 DEBUG : Creating backend with remote "/tmp/rclone879881291" run.go:176: Remote "B2 bucket rclone-test-dogavuj6ceyahuf2dubogex8", Local "Local file system at /tmp/rclone879881291", Modify Window "1ms" 2021/04/05 06:10:04 DEBUG : A2: Making directory 2021/04/05 06:10:04 DEBUG : A1/B2: Making directory 2021/04/05 06:10:04 DEBUG : A1/B2/C2: Making directory 2021/04/05 06:10:04 DEBUG : A1/B1/C3: Making directory 2021/04/05 06:10:04 DEBUG : A3: Making directory 2021/04/05 06:10:04 DEBUG : A3/B3: Making directory 2021/04/05 06:10:04 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 06:10:05 DEBUG : A1/B1/C1/one: Deleting (id "4_z8f320d43a07dab0b7f880e1b_f111b55b333f6ea55_d20210405_m061004_c001_v0001134_t0037") 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 06:10:05 DEBUG : A1/two: Deleting (id "4_z8f320d43a07dab0b7f880e1b_f111b55b333f6ea57_d20210405_m061004_c001_v0001134_t0037") 2021/04/05 06:10:06 DEBUG : B2 bucket rclone-test-dogavuj6ceyahuf2dubogex8: Purge remote 2021/04/05 06:10:06 purge failed: directory not found --- PASS: TestPurge (3.92s) === RUN TestRmdirsNoLeaveRoot run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:10:07 DEBUG : A2: Making directory 2021/04/05 06:10:07 DEBUG : A1/B2: Making directory 2021/04/05 06:10:07 DEBUG : A1/B2/C2: Making directory 2021/04/05 06:10:07 DEBUG : A1/B1/C3: Making directory 2021/04/05 06:10:07 DEBUG : A3: Making directory 2021/04/05 06:10:07 DEBUG : A3/B3: Making directory 2021/04/05 06:10:07 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 06:10:08 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 (3.66s) === RUN TestRmdirsLeaveRoot run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:10:10 DEBUG : A1: Making directory 2021/04/05 06:10:10 DEBUG : A1/B1: Making directory 2021/04/05 06:10:10 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.72s) === RUN TestRmdirsWithFilter run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:10:11 DEBUG : A1: Making directory 2021/04/05 06:10:11 DEBUG : A1/B1: Making directory 2021/04/05 06:10:11 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:10:13 DEBUG : filename.txt: File name found in url --- PASS: TestCopyURL (5.26s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:10:17 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:17 DEBUG : file1: Need to transfer - File not found at Destination 2021/04/05 06:10:17 DEBUG : file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/04/05 06:10:17 INFO : file1: Copied (new) to: sub/file2 2021/04/05 06:10:17 INFO : file1: Deleted 2021/04/05 06:10:18 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 06:10:18 DEBUG : file1: Unchanged skipping 2021/04/05 06:10:18 INFO : file1: Deleted 2021/04/05 06:10:18 DEBUG : B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (1.82s) === RUN TestCaseInsensitiveMoveFile run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.28s) === RUN TestMoveFileBackupDir run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:10:20 DEBUG : Creating backend with remote "TestB2:rclone-test-sexawuk7vuwuloy5quzudoj4/backup" 2021/04/05 06:10:21 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:21 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2021/04/05 06:10:21 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:23 DEBUG : dst/file1: SHA-1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2021/04/05 06:10:23 INFO : dst/file1: Copied (server-side copy) 2021/04/05 06:10:23 INFO : dst/file1: Deleted 2021/04/05 06:10:24 DEBUG : dst/file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/04/05 06:10:24 INFO : dst/file1: Copied (new) 2021/04/05 06:10:24 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (6.41s) === RUN TestCopyFile run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:10:26 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:26 DEBUG : file1: Need to transfer - File not found at Destination 2021/04/05 06:10:26 DEBUG : file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/04/05 06:10:26 INFO : file1: Copied (new) to: sub/file2 2021/04/05 06:10:27 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2021/04/05 06:10:27 DEBUG : file1: Unchanged skipping 2021/04/05 06:10:27 DEBUG : B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (2.59s) === RUN TestCopyFileBackupDir run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:10:29 DEBUG : Creating backend with remote "TestB2:rclone-test-sexawuk7vuwuloy5quzudoj4/backup" 2021/04/05 06:10:30 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:30 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2021/04/05 06:10:30 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:32 DEBUG : dst/file1: SHA-1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2021/04/05 06:10:32 INFO : dst/file1: Copied (server-side copy) 2021/04/05 06:10:32 INFO : dst/file1: Deleted 2021/04/05 06:10:33 DEBUG : dst/file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/04/05 06:10:33 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (7.21s) === RUN TestCopyFileCompareDest run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:10:35 DEBUG : Creating backend with remote "TestB2:rclone-test-sexawuk7vuwuloy5quzudoj4/dst" 2021/04/05 06:10:36 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:36 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:36 DEBUG : Creating backend with remote "TestB2:rclone-test-sexawuk7vuwuloy5quzudoj4/CompareDest" 2021/04/05 06:10:37 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:37 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:37 DEBUG : one: Need to transfer - File not found at Destination 2021/04/05 06:10:37 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_create_bucket": EOF) 2021/04/05 06:10:37 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2021/04/05 06:10:37 DEBUG : pacer: Reducing sleep to 10ms 2021/04/05 06:10:39 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 06:10:39 INFO : one: Copied (new) 2021/04/05 06:10:40 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:40 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/04/05 06:10:40 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/04/05 06:10:40 INFO : one: Copied (replaced existing) 2021/04/05 06:10:42 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 06:10:42 DEBUG : one: Destination found in --compare-dest, skipping 2021/04/05 06:10:44 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:44 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 06:10:44 DEBUG : two: Destination found in --compare-dest, skipping 2021/04/05 06:10:44 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:44 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 06:10:44 DEBUG : two: Destination found in --compare-dest, skipping 2021/04/05 06:10:45 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:45 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/04/05 06:10:45 DEBUG : two: Need to transfer - File not found at Destination 2021/04/05 06:10:45 DEBUG : two: SHA-1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2021/04/05 06:10:45 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (15.22s) === RUN TestCopyFileCopyDest run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:10:50 DEBUG : Creating backend with remote "TestB2:rclone-test-sexawuk7vuwuloy5quzudoj4/dst" 2021/04/05 06:10:51 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:52 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:52 DEBUG : Creating backend with remote "TestB2:rclone-test-sexawuk7vuwuloy5quzudoj4/CopyDest" 2021/04/05 06:10:52 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:52 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:52 DEBUG : one: Need to transfer - File not found at Destination 2021/04/05 06:10:54 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 06:10:54 INFO : one: Copied (new) 2021/04/05 06:10:55 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:55 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/04/05 06:10:55 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/04/05 06:10:55 INFO : one: Copied (replaced existing) 2021/04/05 06:10:57 DEBUG : Creating backend with remote "TestB2:rclone-test-sexawuk7vuwuloy5quzudoj4/BackupDir" 2021/04/05 06:10:58 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:10:58 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 06:10:58 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/04/05 06:10:58 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:00 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 06:11:00 INFO : one: Copied (server-side copy) 2021/04/05 06:11:01 INFO : one: Deleted 2021/04/05 06:11:02 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2021/04/05 06:11:02 INFO : one: Copied (server-side copy) 2021/04/05 06:11:02 DEBUG : one: Destination found in --copy-dest, using server-side copy 2021/04/05 06:11:05 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:05 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 06:11:06 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/04/05 06:11:06 INFO : two: Copied (server-side copy) 2021/04/05 06:11:06 DEBUG : two: Destination found in --copy-dest, using server-side copy 2021/04/05 06:11:06 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 06:11:06 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2021/04/05 06:11:06 DEBUG : two: Unchanged skipping 2021/04/05 06:11:07 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:08 DEBUG : three: Sizes differ (src 7 vs dst 5) 2021/04/05 06:11:08 DEBUG : three: Destination not found in --copy-dest 2021/04/05 06:11:08 DEBUG : three: Need to transfer - File not found at Destination 2021/04/05 06:11:08 DEBUG : three: SHA-1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2021/04/05 06:11:08 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (23.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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:11:17 DEBUG : A1/B2: Making directory 2021/04/05 06:11:17 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 06:11:18 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:18 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:18 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:18 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:19 DEBUG : A1/one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 06:11:19 INFO : A1/one: Copied (server-side copy) to: A2/one 2021/04/05 06:11:19 INFO : A1/one: Deleted 2021/04/05 06:11:19 DEBUG : A1/B1/C2/five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/04/05 06:11:19 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2021/04/05 06:11:19 DEBUG : A1/two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/04/05 06:11:19 INFO : A1/two: Copied (server-side copy) to: A2/two 2021/04/05 06:11:19 DEBUG : A1/B1/C1/four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/04/05 06:11:19 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2021/04/05 06:11:19 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:20 INFO : A1/B1/C2/five: Deleted 2021/04/05 06:11:20 INFO : A1/B1/C1/four: Deleted 2021/04/05 06:11:20 DEBUG : A1/B1/three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/04/05 06:11:20 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2021/04/05 06:11:21 INFO : A1/B1/three: Deleted 2021/04/05 06:11:21 INFO : A1/two: Deleted fstest.go:251: Filtering empty directory "A2/B2" fstest.go:251: Filtering empty directory "A2/B1/C3" 2021/04/05 06:11:21 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:21 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:21 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:21 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:22 DEBUG : A2/one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2021/04/05 06:11:22 INFO : A2/one: Copied (server-side copy) to: A3/one 2021/04/05 06:11:22 DEBUG : A2/B1/C1/four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2021/04/05 06:11:22 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2021/04/05 06:11:22 DEBUG : A2/two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2021/04/05 06:11:22 INFO : A2/two: Copied (server-side copy) to: A3/two 2021/04/05 06:11:23 DEBUG : A2/B1/three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2021/04/05 06:11:23 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2021/04/05 06:11:23 INFO : A2/one: Deleted 2021/04/05 06:11:23 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:23 INFO : A2/B1/three: Deleted 2021/04/05 06:11:23 INFO : A2/B1/C1/four: Deleted 2021/04/05 06:11:23 DEBUG : A2/B1/C2/five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2021/04/05 06:11:23 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2021/04/05 06:11:24 INFO : A2/B1/C2/five: Deleted 2021/04/05 06:11:26 INFO : A2/two: Deleted fstest.go:251: Filtering empty directory "A3/B2" fstest.go:251: Filtering empty directory "A3/B1/C3" --- PASS: TestDirMove (15.46s) === RUN TestGetFsInfo run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" --- PASS: TestGetFsInfo (0.29s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false === CONT TestRcat run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:11:30 DEBUG : B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4: File to upload is small (34 bytes), uploading instead of streaming 2021/04/05 06:11:31 DEBUG : no_checksum_small_file_from_pipe: SHA-1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2021/04/05 06:11:31 INFO : no_checksum_small_file_from_pipe: Copied (new) 2021/04/05 06:11:31 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/04/05 06:11:32 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:11:34 DEBUG : B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4: File to upload is small (34 bytes), uploading instead of streaming 2021/04/05 06:11:34 DEBUG : with_checksum_small_file_from_pipe: SHA-1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2021/04/05 06:11:34 INFO : with_checksum_small_file_from_pipe: Copied (new) 2021/04/05 06:11:34 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/04/05 06:11:35 DEBUG : with_checksum_big_file_from_pipe: SHA-1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2021/04/05 06:11:35 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:11:36 DEBUG : B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4: File to upload is small (34 bytes), uploading instead of streaming 2021/04/05 06:11:37 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2021/04/05 06:11:37 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/04/05 06:11:38 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-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:11:40 DEBUG : B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4: File to upload is small (34 bytes), uploading instead of streaming 2021/04/05 06:11:41 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2021/04/05 06:11:41 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2021/04/05 06:11:42 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (13.64s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (4.01s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.88s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (3.62s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (3.14s) === RUN TestRcatSize run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:11:44 DEBUG : B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4: File to upload is small (60 bytes), uploading instead of streaming 2021/04/05 06:11:44 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2021/04/05 06:11:44 INFO : potato2: Copied (new) --- PASS: TestRcatSize (3.29s) === RUN TestCopyFileMaxTransfer run.go:176: Remote "B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4", Local "Local file system at /tmp/rclone123740401", Modify Window "1ms" 2021/04/05 06:11:47 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:47 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2021/04/05 06:11:47 DEBUG : TestCopyFileMaxTransfer/file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2021/04/05 06:11:47 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2021/04/05 06:11:48 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:48 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2021/04/05 06:11:48 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1157-11.backblaze.com/b2api/v1/b2_upload_file/8f02cd13a05dab0b7f880e1b/c001_v0001157_t0036": Max transfer limit reached as set by --max-transfer 2021/04/05 06:11:48 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:48 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2021/04/05 06:11:48 DEBUG : Couldn't decode error response: EOF 2021/04/05 06:11:48 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2021/04/05 06:11:49 DEBUG : TestCopyFileMaxTransfer/file4: SHA-1 = 94fca4bb0d1f10d29e5a3ddc70d867293695a2d0 OK 2021/04/05 06:11:49 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (5.74s) === 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 06:11:52 DEBUG : B2 bucket rclone-test-sexawuk7vuwuloy5quzudoj4: Purge remote 2021/04/05 06:11:53 DEBUG : A1/B1/C1/four: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1049809f7a582293_d20210405_m061119_c001_v0001145_t0029") 2021/04/05 06:11:53 DEBUG : A1/B1/C1/four: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17937_d20210405_m061116_c001_v0001157_t0036") 2021/04/05 06:11:53 DEBUG : A1/B1/C1/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f117e989a24432dbc_d20210405_m061008_c001_v0001157_t0047") 2021/04/05 06:11:53 DEBUG : A1/B1/C1/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc1712f_d20210405_m061007_c001_v0001157_t0036") 2021/04/05 06:11:53 DEBUG : A1/B1/C2/five: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f106102c5816cfb05_d20210405_m061119_c001_v0001157_t0025") 2021/04/05 06:11:53 DEBUG : A1/B1/C2/five: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17951_d20210405_m061117_c001_v0001157_t0036") 2021/04/05 06:11:53 DEBUG : A1/B1/three: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f115fa591fef6d0a1_d20210405_m061120_c001_v0001153_t0011") 2021/04/05 06:11:53 DEBUG : A1/B1/three: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17929_d20210405_m061116_c001_v0001157_t0036") 2021/04/05 06:11:53 DEBUG : A1/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f10093f1bcd5df65d_d20210405_m061119_c001_v0001136_t0000") 2021/04/05 06:11:53 DEBUG : A1/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17903_d20210405_m061114_c001_v0001157_t0036") 2021/04/05 06:11:53 DEBUG : A1/two: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f113a849b347ec71f_d20210405_m061119_c001_v0001113_t0041") 2021/04/05 06:11:53 DEBUG : A1/two: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17917_d20210405_m061115_c001_v0001157_t0036") 2021/04/05 06:11:53 DEBUG : A1/two: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f113faf65114be727_d20210405_m061009_c001_v0001144_t0005") 2021/04/05 06:11:53 DEBUG : A1/two: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17142_d20210405_m061007_c001_v0001157_t0036") 2021/04/05 06:11:53 DEBUG : A2/B1/C1/four: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1151b9e82aa79b2f_d20210405_m061122_c001_v0001144_t0029") 2021/04/05 06:11:53 DEBUG : A2/B1/C1/four: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1025840e3047dbee_d20210405_m061119_c001_v0001005_t0034") 2021/04/05 06:11:53 DEBUG : A2/B1/C2/five: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1199d08ae083ed38_d20210405_m061124_c001_v0001145_t0014") 2021/04/05 06:11:53 DEBUG : A2/B1/C2/five: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1019179ac5fba6c9_d20210405_m061119_c001_v0001029_t0036") 2021/04/05 06:11:53 DEBUG : A2/B1/three: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1145a505a2b422fb_d20210405_m061123_c001_v0001154_t0016") 2021/04/05 06:11:53 DEBUG : A2/B1/three: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1145e05725b9d8ed_d20210405_m061120_c001_v0001157_t0041") 2021/04/05 06:11:53 DEBUG : A2/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f11708b469f1277aa_d20210405_m061122_c001_v0001128_t0037") 2021/04/05 06:11:53 DEBUG : A2/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f107e6fea8b92dbfc_d20210405_m061118_c001_v0001157_t0022") 2021/04/05 06:11:54 DEBUG : A2/two: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f115695fcdfe9b9ac_d20210405_m061122_c001_v0001134_t0049") 2021/04/05 06:11:54 DEBUG : A2/two: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f10907ad9a0e9dd5b_d20210405_m061119_c001_v0001157_t0017") 2021/04/05 06:11:54 DEBUG : A3/B1/C1/four: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f100f5cf9dff7fa3f_d20210405_m061127_c001_v0001144_t0003") 2021/04/05 06:11:54 DEBUG : A3/B1/C1/four: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1084ff527d847ef9_d20210405_m061122_c001_v0001157_t0031") 2021/04/05 06:11:54 DEBUG : A3/B1/C2/five: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f104fa93941e68877_d20210405_m061128_c001_v0001157_t0013") 2021/04/05 06:11:54 DEBUG : A3/B1/C2/five: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1184a2f9106a11e9_d20210405_m061123_c001_v0001157_t0034") 2021/04/05 06:11:54 DEBUG : A3/B1/three: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f112a5d91ca89aed8_d20210405_m061128_c001_v0001136_t0043") 2021/04/05 06:11:54 DEBUG : A3/B1/three: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f10180c571b0deaa2_d20210405_m061122_c001_v0001154_t0023") 2021/04/05 06:11:54 DEBUG : A3/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f100d2b07576199d3_d20210405_m061129_c001_v0001153_t0004") 2021/04/05 06:11:54 DEBUG : A3/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f102efcfd74eee2fd_d20210405_m061122_c001_v0001038_t0022") 2021/04/05 06:11:54 DEBUG : A3/two: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f115fa591fef6d0bc_d20210405_m061129_c001_v0001153_t0012") 2021/04/05 06:11:54 DEBUG : A3/two: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1145e05725b9d947_d20210405_m061122_c001_v0001157_t0015") 2021/04/05 06:11:54 DEBUG : BackupDir/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1050a4f7a286b7b1_d20210405_m061109_c001_v0001136_t0015") 2021/04/05 06:11:54 DEBUG : BackupDir/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f108fe17c597bb417_d20210405_m061100_c001_v0001000_t0027") 2021/04/05 06:11:54 DEBUG : CompareDest/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1164e051aa2457c1_d20210405_m061046_c001_v0001144_t0046") 2021/04/05 06:11:54 DEBUG : CompareDest/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc1757d_d20210405_m061042_c001_v0001157_t0036") 2021/04/05 06:11:54 DEBUG : CompareDest/two: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f108c0d49a8b2ac50_d20210405_m061047_c001_v0001091_t0023") 2021/04/05 06:11:54 DEBUG : CompareDest/two: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17590_d20210405_m061043_c001_v0001157_t0036") 2021/04/05 06:11:54 DEBUG : CopyDest/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f101bd25460078680_d20210405_m061109_c001_v0001157_t0002") 2021/04/05 06:11:54 DEBUG : CopyDest/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc1770e_d20210405_m061056_c001_v0001157_t0036") 2021/04/05 06:11:54 DEBUG : CopyDest/three: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f103f89391b786971_d20210405_m061110_c001_v0001157_t0028") 2021/04/05 06:11:54 DEBUG : CopyDest/three: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc1782d_d20210405_m061107_c001_v0001157_t0036") 2021/04/05 06:11:55 DEBUG : CopyDest/two: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1184a2f9106a1053_d20210405_m061110_c001_v0001157_t0041") 2021/04/05 06:11:55 DEBUG : CopyDest/two: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc177e0_d20210405_m061104_c001_v0001157_t0036") 2021/04/05 06:11:55 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f10901a3dc4c34c5e_d20210405_m061150_c001_v0001131_t0023") 2021/04/05 06:11:55 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17c9d_d20210405_m061147_c001_v0001157_t0036") 2021/04/05 06:11:55 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1168384111a35d7b_d20210405_m061150_c001_v0001044_t0037") 2021/04/05 06:11:55 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17ccf_d20210405_m061149_c001_v0001157_t0036") 2021/04/05 06:11:55 DEBUG : a.txt: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f103f89391b785cf3_d20210405_m060926_c001_v0001157_t0035") 2021/04/05 06:11:55 DEBUG : a.txt: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16b31_d20210405_m060919_c001_v0001157_t0036") 2021/04/05 06:11:55 DEBUG : also/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1022eb9687a23b0b_d20210405_m060916_c001_v0001157_t0045") 2021/04/05 06:11:55 DEBUG : also/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16a57_d20210405_m060913_c001_v0001157_t0036") 2021/04/05 06:11:55 DEBUG : another: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f11297cd67b0e390e_d20210405_m060916_c001_v0001157_t0019") 2021/04/05 06:11:55 DEBUG : another: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16a7e_d20210405_m060914_c001_v0001157_t0036") 2021/04/05 06:11:55 DEBUG : backup/dst/file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f101732c38fb1d9c4_d20210405_m061033_c001_v0001144_t0036") 2021/04/05 06:11:55 DEBUG : backup/dst/file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f113cb0659a78a432_d20210405_m061031_c001_v0001157_t0030") 2021/04/05 06:11:55 DEBUG : backup/dst/file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1184a2f9106a0a56_d20210405_m061024_c001_v0001157_t0036") 2021/04/05 06:11:55 DEBUG : backup/dst/file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f10792c5697852d6a_d20210405_m061022_c001_v0001144_t0050") 2021/04/05 06:11:55 DEBUG : dst/file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f114ac7d1fd637d25_d20210405_m061034_c001_v0001009_t0008") 2021/04/05 06:11:55 DEBUG : dst/file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc1743c_d20210405_m061033_c001_v0001157_t0036") 2021/04/05 06:11:55 DEBUG : dst/file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f119aba5bf9ebcdd8_d20210405_m061032_c001_v0001150_t0031") 2021/04/05 06:11:55 DEBUG : dst/file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc173a5_d20210405_m061028_c001_v0001157_t0036") 2021/04/05 06:11:55 DEBUG : dst/file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f113501e88efe7c0a_d20210405_m061025_c001_v0001150_t0007") 2021/04/05 06:11:55 DEBUG : dst/file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17336_d20210405_m061024_c001_v0001157_t0036") 2021/04/05 06:11:55 DEBUG : dst/file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f100269104d765d72_d20210405_m061023_c001_v0001130_t0002") 2021/04/05 06:11:55 DEBUG : dst/file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc172b0_d20210405_m061019_c001_v0001157_t0036") 2021/04/05 06:11:56 DEBUG : dst/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f10804e29d1b9b60b_d20210405_m061111_c001_v0001032_t0043") 2021/04/05 06:11:56 DEBUG : dst/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111c9c143e0a1483_d20210405_m061102_c001_v0001144_t0032") 2021/04/05 06:11:56 DEBUG : dst/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f105e06eb92a1cac6_d20210405_m061101_c001_v0001157_t0026") 2021/04/05 06:11:56 DEBUG : dst/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17702_d20210405_m061056_c001_v0001157_t0036") 2021/04/05 06:11:56 DEBUG : dst/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f108943771c098b4e_d20210405_m061055_c001_v0001092_t0000") 2021/04/05 06:11:56 DEBUG : dst/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f108943771c098b4a_d20210405_m061054_c001_v0001092_t0000") 2021/04/05 06:11:56 DEBUG : dst/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1097fedafe0ef0f2_d20210405_m061049_c001_v0001013_t0018") 2021/04/05 06:11:56 DEBUG : dst/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17568_d20210405_m061041_c001_v0001157_t0036") 2021/04/05 06:11:56 DEBUG : dst/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f10302fa6e82a08c0_d20210405_m061040_c001_v0001145_t0019") 2021/04/05 06:11:56 DEBUG : dst/one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f10302fa6e82a08ba_d20210405_m061039_c001_v0001145_t0019") 2021/04/05 06:11:56 DEBUG : dst/three: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f11345bb4d0388a78_d20210405_m061112_c001_v0001128_t0033") 2021/04/05 06:11:56 DEBUG : dst/three: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f108943771c098b58_d20210405_m061108_c001_v0001092_t0000") 2021/04/05 06:11:56 DEBUG : dst/two: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1022eb9687a24857_d20210405_m061112_c001_v0001157_t0025") 2021/04/05 06:11:56 DEBUG : dst/two: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1113cd4588411603_d20210405_m061105_c001_v0001005_t0027") 2021/04/05 06:11:56 DEBUG : dst/two: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f11504c3855868e9f_d20210405_m061049_c001_v0001157_t0003") 2021/04/05 06:11:56 DEBUG : dst/two: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f10302fa6e82a08d3_d20210405_m061045_c001_v0001145_t0019") 2021/04/05 06:11:56 DEBUG : empty space: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1100e5c1d944192f_d20210405_m060952_c001_v0001157_t0023") 2021/04/05 06:11:56 DEBUG : empty space: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16f68_d20210405_m060950_c001_v0001157_t0036") 2021/04/05 06:11:56 DEBUG : empty space: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16f37_d20210405_m060949_c001_v0001157_t0016") 2021/04/05 06:11:56 DEBUG : empty space: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16eba_d20210405_m060946_c001_v0001157_t0036") 2021/04/05 06:11:56 DEBUG : empty space: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f10438681c9bca3fe_d20210405_m060941_c001_v0001038_t0057") 2021/04/05 06:11:56 DEBUG : empty space: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16e07_d20210405_m060940_c001_v0001157_t0036") 2021/04/05 06:11:56 DEBUG : empty space: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f108851d07af6c7ee_d20210405_m060938_c001_v0001042_t0011") 2021/04/05 06:11:56 DEBUG : empty space: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16d82_d20210405_m060936_c001_v0001157_t0036") 2021/04/05 06:11:57 DEBUG : empty space: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f105fa00fae4aedbe_d20210405_m060934_c001_v0001090_t0051") 2021/04/05 06:11:57 DEBUG : empty space: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16d2a_d20210405_m060933_c001_v0001157_t0036") 2021/04/05 06:11:57 DEBUG : empty space: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1126946d3d94c645_d20210405_m060906_c001_v0001131_t0007") 2021/04/05 06:11:57 DEBUG : empty space: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc1691c_d20210405_m060903_c001_v0001157_t0036") 2021/04/05 06:11:57 DEBUG : empty space: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f100b0e08e5bd9708_d20210405_m060900_c001_v0001157_t0017") 2021/04/05 06:11:57 DEBUG : empty space: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16813_d20210405_m060855_c001_v0001157_t0036") 2021/04/05 06:11:57 DEBUG : empty space: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f110c424cf9ede5d6_d20210405_m060852_c001_v0001030_t0020") 2021/04/05 06:11:57 DEBUG : empty space: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16755_d20210405_m060849_c001_v0001157_t0036") 2021/04/05 06:11:57 DEBUG : file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f107841314d41a318_d20210405_m061014_c001_v0001130_t0027") 2021/04/05 06:11:57 DEBUG : file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc171bf_d20210405_m061011_c001_v0001157_t0036") 2021/04/05 06:11:57 DEBUG : file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f10907ad9a0e9d22a_d20210405_m061001_c001_v0001157_t0017") 2021/04/05 06:11:57 DEBUG : file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc1703b_d20210405_m060957_c001_v0001157_t0036") 2021/04/05 06:11:57 DEBUG : file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f113faf65114be321_d20210405_m060847_c001_v0001144_t0051") 2021/04/05 06:11:57 DEBUG : file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc166d7_d20210405_m060845_c001_v0001157_t0036") 2021/04/05 06:11:57 DEBUG : file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc166a8_d20210405_m060844_c001_v0001157_t0036") 2021/04/05 06:11:57 DEBUG : file1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16661_d20210405_m060841_c001_v0001157_t0036") 2021/04/05 06:11:57 DEBUG : file2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f11985ce906c60f33_d20210405_m061015_c001_v0001157_t0019") 2021/04/05 06:11:57 DEBUG : file2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17208_d20210405_m061013_c001_v0001157_t0036") 2021/04/05 06:11:57 DEBUG : file2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f103f89391b786197_d20210405_m061002_c001_v0001157_t0020") 2021/04/05 06:11:57 DEBUG : file2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17052_d20210405_m060958_c001_v0001157_t0036") 2021/04/05 06:11:57 DEBUG : filename.txt: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1016fa77539670b0_d20210405_m061015_c001_v0001042_t0059") 2021/04/05 06:11:57 DEBUG : filename.txt: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc171d5_d20210405_m061013_c001_v0001157_t0036") 2021/04/05 06:11:57 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f104e6f47aea20bc4_d20210405_m061142_c001_v0001022_t0050") 2021/04/05 06:11:57 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17bf3_d20210405_m061141_c001_v0001157_t0036") 2021/04/05 06:11:57 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f103f89391b786c85_d20210405_m061138_c001_v0001157_t0048") 2021/04/05 06:11:58 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17b7f_d20210405_m061137_c001_v0001157_t0036") 2021/04/05 06:11:58 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1172a23f5011e02d_d20210405_m061143_c001_v0001021_t0023") 2021/04/05 06:11:58 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17bdb_d20210405_m061140_c001_v0001157_t0036") 2021/04/05 06:11:58 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1185689f349a605e_d20210405_m061139_c001_v0001038_t0014") 2021/04/05 06:11:58 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17b69_d20210405_m061137_c001_v0001157_t0036") 2021/04/05 06:11:58 DEBUG : large: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f114095c51ff83ab4_d20210405_m060957_c001_v0001150_t0030") 2021/04/05 06:11:58 DEBUG : large: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16ffd_d20210405_m060955_c001_v0001157_t0036") 2021/04/05 06:11:58 DEBUG : medium: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1181f4243d4e6adc_d20210405_m060956_c001_v0001145_t0050") 2021/04/05 06:11:58 DEBUG : medium: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16fec_d20210405_m060954_c001_v0001157_t0036") 2021/04/05 06:11:58 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f103f89391b786be3_d20210405_m061132_c001_v0001157_t0009") 2021/04/05 06:11:58 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17ace_d20210405_m061131_c001_v0001157_t0036") 2021/04/05 06:11:58 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1084ff527d848061_d20210405_m061133_c001_v0001157_t0053") 2021/04/05 06:11:58 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17aba_d20210405_m061130_c001_v0001157_t0036") 2021/04/05 06:11:58 DEBUG : not-one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f106102c5816cec9e_d20210405_m060917_c001_v0001157_t0007") 2021/04/05 06:11:58 DEBUG : not-one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16a99_d20210405_m060914_c001_v0001157_t0036") 2021/04/05 06:11:58 DEBUG : one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f105fa00fae4aedaf_d20210405_m060915_c001_v0001090_t0050") 2021/04/05 06:11:58 DEBUG : one: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16a37_d20210405_m060912_c001_v0001157_t0036") 2021/04/05 06:11:58 DEBUG : potato1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f114ca3c764fef14d_d20210405_m061145_c001_v0001144_t0034") 2021/04/05 06:11:58 DEBUG : potato1: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17c3b_d20210405_m061143_c001_v0001157_t0036") 2021/04/05 06:11:58 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f101bd25460078a33_d20210405_m061146_c001_v0001157_t0004") 2021/04/05 06:11:58 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17c48_d20210405_m061144_c001_v0001157_t0036") 2021/04/05 06:11:58 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1084ff527d8471f1_d20210405_m060953_c001_v0001157_t0027") 2021/04/05 06:11:58 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16f52_d20210405_m060950_c001_v0001157_t0036") 2021/04/05 06:11:58 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f104611d50c1544c9_d20210405_m060949_c001_v0001134_t0040") 2021/04/05 06:11:59 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16eaf_d20210405_m060945_c001_v0001157_t0036") 2021/04/05 06:11:59 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f10907ad9a0e9cf8d_d20210405_m060944_c001_v0001157_t0019") 2021/04/05 06:11:59 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16def_d20210405_m060940_c001_v0001157_t0036") 2021/04/05 06:11:59 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1020ad9329c590f3_d20210405_m060939_c001_v0001150_t0019") 2021/04/05 06:11:59 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16d68_d20210405_m060936_c001_v0001157_t0036") 2021/04/05 06:11:59 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1088d26c6b50be14_d20210405_m060935_c001_v0001145_t0001") 2021/04/05 06:11:59 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16d0a_d20210405_m060932_c001_v0001157_t0036") 2021/04/05 06:11:59 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f117e989a244327ba_d20210405_m060908_c001_v0001157_t0040") 2021/04/05 06:11:59 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc1693c_d20210405_m060904_c001_v0001157_t0036") 2021/04/05 06:11:59 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f101bd2546007786e_d20210405_m060900_c001_v0001157_t0055") 2021/04/05 06:11:59 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16837_d20210405_m060856_c001_v0001157_t0036") 2021/04/05 06:11:59 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f103f89391b785846_d20210405_m060852_c001_v0001157_t0024") 2021/04/05 06:11:59 DEBUG : potato2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc1676e_d20210405_m060850_c001_v0001157_t0036") 2021/04/05 06:11:59 DEBUG : remotepotato: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1022eb9687a23a45_d20210405_m060909_c001_v0001157_t0046") 2021/04/05 06:11:59 DEBUG : remotepotato: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc1696b_d20210405_m060905_c001_v0001157_t0036") 2021/04/05 06:11:59 DEBUG : remotepotato: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1145e05725b9c2a4_d20210405_m060901_c001_v0001157_t0005") 2021/04/05 06:11:59 DEBUG : remotepotato: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16875_d20210405_m060858_c001_v0001157_t0036") 2021/04/05 06:11:59 DEBUG : remotepotato: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1009281f9519ef4a_d20210405_m060853_c001_v0001150_t0041") 2021/04/05 06:11:59 DEBUG : remotepotato: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc1678d_d20210405_m060851_c001_v0001157_t0036") 2021/04/05 06:11:59 DEBUG : rutabaga: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f107a9d41824031e5_d20210405_m060910_c001_v0001113_t0031") 2021/04/05 06:11:59 DEBUG : rutabaga: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc168ff_d20210405_m060902_c001_v0001157_t0036") 2021/04/05 06:12:00 DEBUG : rutabaga: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f104fa93941e67058_d20210405_m060901_c001_v0001157_t0009") 2021/04/05 06:12:00 DEBUG : rutabaga: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc167e4_d20210405_m060854_c001_v0001157_t0036") 2021/04/05 06:12:00 DEBUG : rutabaga: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1100e5c1d9441261_d20210405_m060853_c001_v0001157_t0018") 2021/04/05 06:12:00 DEBUG : rutabaga: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc1673b_d20210405_m060848_c001_v0001157_t0036") 2021/04/05 06:12:00 DEBUG : small: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1181f4243d4e6ae1_d20210405_m060956_c001_v0001145_t0042") 2021/04/05 06:12:00 DEBUG : small: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16fd6_d20210405_m060954_c001_v0001157_t0036") 2021/04/05 06:12:00 DEBUG : sub dir/hello world: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f11504c385586843a_d20210405_m060932_c001_v0001157_t0047") 2021/04/05 06:12:00 DEBUG : sub dir/hello world: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16cd1_d20210405_m060931_c001_v0001157_t0036") 2021/04/05 06:12:00 DEBUG : sub dir/hello world: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f10014f622917ab6b_d20210405_m060927_c001_v0001025_t0010") 2021/04/05 06:12:00 DEBUG : sub dir/hello world: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16b51_d20210405_m060920_c001_v0001157_t0036") 2021/04/05 06:12:00 DEBUG : sub dir/hello world2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f102efcfd74eee25f_d20210405_m060927_c001_v0001038_t0048") 2021/04/05 06:12:00 DEBUG : sub dir/hello world2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16b6f_d20210405_m060921_c001_v0001157_t0036") 2021/04/05 06:12:00 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f11487e8a668968b0_d20210405_m060927_c001_v0001091_t0045") 2021/04/05 06:12:00 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16b7b_d20210405_m060922_c001_v0001157_t0036") 2021/04/05 06:12:00 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f104482e40b1c3fc2_d20210405_m060928_c001_v0001144_t0019") 2021/04/05 06:12:00 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16b9c_d20210405_m060922_c001_v0001157_t0036") 2021/04/05 06:12:00 DEBUG : sub dir/potato3: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1184a2f9106a051a_d20210405_m060953_c001_v0001157_t0031") 2021/04/05 06:12:00 DEBUG : sub dir/potato3: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16f80_d20210405_m060951_c001_v0001157_t0036") 2021/04/05 06:12:00 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f1022eb9687a23cb2_d20210405_m060929_c001_v0001157_t0021") 2021/04/05 06:12:00 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16bb4_d20210405_m060923_c001_v0001157_t0036") 2021/04/05 06:12:00 DEBUG : sub/file2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f10907ad9a0e9d62b_d20210405_m061027_c001_v0001157_t0052") 2021/04/05 06:12:00 DEBUG : sub/file2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17377_d20210405_m061026_c001_v0001157_t0036") 2021/04/05 06:12:00 DEBUG : sub/file2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f10395c9fd575d77a_d20210405_m061018_c001_v0001012_t0027") 2021/04/05 06:12:00 DEBUG : sub/file2: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc1726e_d20210405_m061017_c001_v0001157_t0036") 2021/04/05 06:12:00 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f10592ba3b2632882_d20210405_m061135_c001_v0001145_t0003") 2021/04/05 06:12:01 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17b2b_d20210405_m061134_c001_v0001157_t0036") 2021/04/05 06:12:01 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f11890b1f88faaf3f_d20210405_m061136_c001_v0001136_t0037") 2021/04/05 06:12:01 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc17b19_d20210405_m061134_c001_v0001157_t0036") 2021/04/05 06:12:01 DEBUG : zend.txt: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f114095c51ff839af_d20210405_m060929_c001_v0001150_t0004") 2021/04/05 06:12:01 DEBUG : zend.txt: Deleting (id "4_z8f02cd13a05dab0b7f880e1b_f111e23f42dc16b4a_d20210405_m060920_c001_v0001157_t0036") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list" - Finished OK in 3m22.91472061s (try 1/5)