"./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) 2020/11/03 05:04:28 DEBUG : Creating backend with remote "TestB2:rclone-test-cuwivix2liraron1mavovoy0" 2020/11/03 05:04:29 DEBUG : Creating backend with remote "/tmp/rclone710848303" === 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-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" === RUN TestMultithreadCopy/{size:131071_streams:2} 2020/11/03 05:04:32 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64k 2020/11/03 05:04:32 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999k starting 2020/11/03 05:04:32 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k starting 2020/11/03 05:04:33 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999k finished 2020/11/03 05:04:33 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k finished 2020/11/03 05:04:33 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64k === RUN TestMultithreadCopy/{size:131072_streams:2} 2020/11/03 05:04:34 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64k 2020/11/03 05:04:34 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64k starting 2020/11/03 05:04:34 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k starting 2020/11/03 05:04:34 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64k finished 2020/11/03 05:04:35 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k finished 2020/11/03 05:04:35 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64k === RUN TestMultithreadCopy/{size:131073_streams:2} 2020/11/03 05:04:36 DEBUG : file1: Starting multi-thread copy with 2 parts of size 128k 2020/11/03 05:04:36 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 starting 2020/11/03 05:04:36 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128k starting 2020/11/03 05:04:36 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 finished 2020/11/03 05:04:36 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128k finished 2020/11/03 05:04:36 DEBUG : file1: Finished multi-thread copy with 2 parts of size 128k --- PASS: TestMultithreadCopy (7.44s) --- PASS: TestMultithreadCopy/{size:131071_streams:2} (3.71s) --- PASS: TestMultithreadCopy/{size:131072_streams:2} (1.80s) --- PASS: TestMultithreadCopy/{size:131073_streams:2} (1.36s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Seek === RUN TestReOpen/Seek/Basics === RUN TestReOpen/Seek/ErrorAtStart === RUN TestReOpen/Seek/WithErrors 2020/11/03 05:04:37 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2020/11/03 05:04:37 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2020/11/03 05:04:37 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Seek/TooManyErrors 2020/11/03 05:04:37 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2020/11/03 05:04:37 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2020/11/03 05:04:37 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2020/11/03 05:04:37 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 2020/11/03 05:04:37 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2020/11/03 05:04:37 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2020/11/03 05:04:37 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Range/TooManyErrors 2020/11/03 05:04:37 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2020/11/03 05:04:37 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2020/11/03 05:04:37 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2020/11/03 05:04:37 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-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" === RUN TestCheck/1 === RUN TestCheck/2 === RUN TestCheck/3 === RUN TestCheck/4 === RUN TestCheck/5 === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (8.24s) --- PASS: TestCheck/1 (0.15s) --- PASS: TestCheck/2 (0.16s) --- PASS: TestCheck/3 (0.15s) --- PASS: TestCheck/4 (0.15s) --- PASS: TestCheck/5 (0.16s) --- PASS: TestCheck/6 (0.15s) --- PASS: TestCheck/7 (0.15s) === RUN TestCheckFsError 2020/11/03 05:04:45 DEBUG : Creating backend with remote "non-existent" 2020/11/03 05:04:45 DEBUG : Creating backend with remote "non-existent" 2020/11/03 05:04:45 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: Waiting for checks to finish 2020/11/03 05:04:45 ERROR : : error reading source directory: directory not found 2020/11/03 05:04:45 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 2 differences found 2020/11/03 05:04:45 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-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", 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 (12.09s) --- PASS: TestCheckDownload/1 (0.71s) --- PASS: TestCheckDownload/2 (0.28s) --- PASS: TestCheckDownload/3 (0.29s) --- PASS: TestCheckDownload/4 (0.52s) --- PASS: TestCheckDownload/5 (0.29s) --- PASS: TestCheckDownload/6 (0.30s) --- PASS: TestCheckDownload/7 (0.29s) === RUN TestCheckSizeOnly run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", 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.91s) --- PASS: TestCheckSizeOnly/1 (0.15s) --- PASS: TestCheckSizeOnly/2 (0.15s) --- PASS: TestCheckSizeOnly/3 (0.14s) --- PASS: TestCheckSizeOnly/4 (0.15s) --- PASS: TestCheckSizeOnly/5 (0.15s) --- PASS: TestCheckSizeOnly/6 (0.15s) --- PASS: TestCheckSizeOnly/7 (0.15s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestDeduplicateInteractive run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (0.29s) === RUN TestDeduplicateSkip run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (0.29s) === RUN TestDeduplicateSizeOnly run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (0.29s) === RUN TestDeduplicateFirst run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (0.29s) === RUN TestDeduplicateNewest run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (0.29s) === RUN TestDeduplicateOldest run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (0.29s) === RUN TestDeduplicateLargest run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (0.29s) === RUN TestDeduplicateSmallest run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (0.29s) === RUN TestDeduplicateRename run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (0.29s) === RUN TestMergeDirs run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" dedupe_test.go:238: Can't merge directories --- SKIP: TestMergeDirs (0.29s) === RUN TestListDirSorted run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:05:11 DEBUG : a.txt: Excluded 2020/11/03 05:05:11 DEBUG : sub dir/hello world: Excluded 2020/11/03 05:05:11 DEBUG : sub dir/hello world2: Excluded 2020/11/03 05:05:12 DEBUG : sub dir/hello world: Excluded 2020/11/03 05:05:12 DEBUG : sub dir/hello world2: Excluded 2020/11/03 05:05:12 DEBUG : sub dir/ignore dir: Excluded 2020/11/03 05:05:12 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (13.23s) === RUN TestMkdir run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:05:20 DEBUG : B2 bucket rclone-test-cuwivix2liraron1mavovoy0: Making directory 2020/11/03 05:05:20 DEBUG : B2 bucket rclone-test-cuwivix2liraron1mavovoy0: Making directory --- PASS: TestMkdir (0.43s) === RUN TestLsd run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" --- PASS: TestLsd (4.52s) === RUN TestLs run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" --- PASS: TestLs (2.89s) === RUN TestLsWithFilesFrom run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:05:29 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (2.90s) === RUN TestLsLong run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" --- PASS: TestLsLong (4.67s) === RUN TestHashSums run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" --- PASS: TestHashSums (3.61s) === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" --- PASS: TestCount (5.55s) === RUN TestDelete run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:05:46 DEBUG : Waiting for deletions to finish 2020/11/03 05:05:46 DEBUG : large: Excluded from sync (and deletion) 2020/11/03 05:05:47 INFO : medium: Deleted 2020/11/03 05:05:47 INFO : small: Deleted --- PASS: TestDelete (3.34s) === RUN TestRetry 2020/11/03 05:05:48 DEBUG : Received error: EOF - low level retry 1/5 2020/11/03 05:05:48 DEBUG : Received error: EOF - low level retry 2/5 2020/11/03 05:05:48 DEBUG : Received error: EOF - low level retry 1/5 2020/11/03 05:05:48 DEBUG : Received error: EOF - low level retry 2/5 2020/11/03 05:05:48 DEBUG : Received error: EOF - low level retry 3/5 2020/11/03 05:05:48 DEBUG : Received error: EOF - low level retry 4/5 2020/11/03 05:05:48 DEBUG : Received error: EOF - low level retry 5/5 --- PASS: TestRetry (0.00s) === RUN TestCat run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" --- PASS: TestCat (4.71s) === RUN TestPurge 2020/11/03 05:05:53 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2020/11/03 05:05:53 DEBUG : Creating backend with remote "TestB2:rclone-test-zevoraw6votawuh1sesuriy0" 2020/11/03 05:05:53 DEBUG : Creating backend with remote "/tmp/rclone332003129" run.go:176: Remote "B2 bucket rclone-test-zevoraw6votawuh1sesuriy0", Local "Local file system at /tmp/rclone332003129", Modify Window "1ms" 2020/11/03 05:05:55 DEBUG : A2: Making directory 2020/11/03 05:05:55 DEBUG : A1/B2: Making directory 2020/11/03 05:05:55 DEBUG : A1/B2/C2: Making directory 2020/11/03 05:05:55 DEBUG : A1/B1/C3: Making directory 2020/11/03 05:05:55 DEBUG : A3: Making directory 2020/11/03 05:05:55 DEBUG : A3/B3: Making directory 2020/11/03 05:05:55 DEBUG : A3/B3/C4: Making directory fstest.go:247: Filtering empty directory "A2" fstest.go:247: Filtering empty directory "A1/B2" fstest.go:247: Filtering empty directory "A1/B2/C2" fstest.go:247: Filtering empty directory "A1/B1/C3" fstest.go:247: Filtering empty directory "A3" fstest.go:247: Filtering empty directory "A3/B3" fstest.go:247: Filtering empty directory "A3/B3/C4" 2020/11/03 05:05:57 DEBUG : A1/B1/C1/one: Deleting (id "4_z3fc24da370fd8bcb7f580e1b_f118e0807d6408ae0_d20201103_m050554_c001_v0001146_t0000") fstest.go:247: Filtering empty directory "A2" fstest.go:247: Filtering empty directory "A1/B2" fstest.go:247: Filtering empty directory "A1/B2/C2" fstest.go:247: Filtering empty directory "A3" fstest.go:247: Filtering empty directory "A3/B3" fstest.go:247: Filtering empty directory "A3/B3/C4" 2020/11/03 05:05:57 DEBUG : A1/two: Deleting (id "4_z3fc24da370fd8bcb7f580e1b_f118e0807d6408b04_d20201103_m050555_c001_v0001146_t0000") 2020/11/03 05:05:58 DEBUG : B2 bucket rclone-test-zevoraw6votawuh1sesuriy0: Purge remote 2020/11/03 05:05:58 purge failed: directory not found --- PASS: TestPurge (5.53s) === RUN TestRmdirsNoLeaveRoot run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:05:59 DEBUG : A2: Making directory 2020/11/03 05:05:59 DEBUG : A1/B2: Making directory 2020/11/03 05:05:59 DEBUG : A1/B2/C2: Making directory 2020/11/03 05:05:59 DEBUG : A1/B1/C3: Making directory 2020/11/03 05:05:59 DEBUG : A3: Making directory 2020/11/03 05:05:59 DEBUG : A3/B3: Making directory 2020/11/03 05:05:59 DEBUG : A3/B3/C4: Making directory fstest.go:247: Filtering empty directory "A2" fstest.go:247: Filtering empty directory "A1/B2" fstest.go:247: Filtering empty directory "A1/B2/C2" fstest.go:247: Filtering empty directory "A1/B1/C3" fstest.go:247: Filtering empty directory "A3" fstest.go:247: Filtering empty directory "A3/B3" fstest.go:247: Filtering empty directory "A3/B3/C4" 2020/11/03 05:06:00 DEBUG : A3/B3/C4: Removing directory fstest.go:247: Filtering empty directory "A2" fstest.go:247: Filtering empty directory "A1/B2" fstest.go:247: Filtering empty directory "A1/B2/C2" fstest.go:247: Filtering empty directory "A1/B1/C3" fstest.go:247: Filtering empty directory "A3" fstest.go:247: Filtering empty directory "A3/B3" --- PASS: TestRmdirsNoLeaveRoot (3.57s) === RUN TestRmdirsLeaveRoot run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:06:02 DEBUG : A1: Making directory 2020/11/03 05:06:02 DEBUG : A1/B1: Making directory 2020/11/03 05:06:02 DEBUG : A1/B1/C1: Making directory fstest.go:247: Filtering empty directory "A1" fstest.go:247: Filtering empty directory "A1/B1" fstest.go:247: Filtering empty directory "A1/B1/C1" fstest.go:247: Filtering empty directory "A1" --- PASS: TestRmdirsLeaveRoot (0.70s) === RUN TestCopyURL run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" --- PASS: TestCopyURL (8.74s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:06:11 DEBUG : file1: Need to transfer - File not found at Destination 2020/11/03 05:06:12 DEBUG : file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2020/11/03 05:06:12 INFO : file1: Copied (new) to: sub/file2 2020/11/03 05:06:12 INFO : file1: Deleted 2020/11/03 05:06:13 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/11/03 05:06:13 DEBUG : file1: Unchanged skipping 2020/11/03 05:06:13 INFO : file1: Deleted 2020/11/03 05:06:13 DEBUG : B2 bucket rclone-test-cuwivix2liraron1mavovoy0: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (2.60s) === RUN TestCaseInsensitiveMoveFile run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.28s) === RUN TestMoveFileBackupDir run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:06:15 DEBUG : Creating backend with remote "TestB2:rclone-test-cuwivix2liraron1mavovoy0/backup" 2020/11/03 05:06:16 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2020/11/03 05:06:18 DEBUG : dst/file1: SHA-1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2020/11/03 05:06:18 INFO : dst/file1: Copied (server-side copy) 2020/11/03 05:06:19 INFO : dst/file1: Deleted 2020/11/03 05:06:31 DEBUG : dst/file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2020/11/03 05:06:31 INFO : dst/file1: Copied (new) 2020/11/03 05:06:31 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (18.41s) === RUN TestCopyFile run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:06:32 DEBUG : file1: Need to transfer - File not found at Destination 2020/11/03 05:06:33 DEBUG : file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2020/11/03 05:06:33 INFO : file1: Copied (new) to: sub/file2 2020/11/03 05:06:33 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2020/11/03 05:06:33 DEBUG : file1: Unchanged skipping 2020/11/03 05:06:34 DEBUG : B2 bucket rclone-test-cuwivix2liraron1mavovoy0: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (2.91s) === RUN TestCopyFileBackupDir run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:06:36 DEBUG : Creating backend with remote "TestB2:rclone-test-cuwivix2liraron1mavovoy0/backup" 2020/11/03 05:06:37 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2020/11/03 05:06:39 DEBUG : dst/file1: SHA-1 = 9d125cddbdc7323ccf9db2d2b67af177f781b5c6 OK 2020/11/03 05:06:39 INFO : dst/file1: Copied (server-side copy) 2020/11/03 05:06:40 INFO : dst/file1: Deleted 2020/11/03 05:06:41 DEBUG : dst/file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2020/11/03 05:06:41 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (27.72s) === RUN TestCopyFileCompareDest run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:07:03 DEBUG : Creating backend with remote "TestB2:rclone-test-cuwivix2liraron1mavovoy0/dst" 2020/11/03 05:07:05 DEBUG : Creating backend with remote "TestB2:rclone-test-cuwivix2liraron1mavovoy0/CompareDest" 2020/11/03 05:07:05 DEBUG : one: Need to transfer - File not found at Destination 2020/11/03 05:07:07 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/11/03 05:07:07 INFO : one: Copied (new) 2020/11/03 05:07:07 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/11/03 05:07:11 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2020/11/03 05:07:11 INFO : one: Copied (replaced existing) 2020/11/03 05:07:14 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/11/03 05:07:14 DEBUG : one: Destination found in --compare-dest, skipping 2020/11/03 05:07:15 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/11/03 05:07:15 DEBUG : two: Destination found in --compare-dest, skipping 2020/11/03 05:07:16 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/11/03 05:07:16 DEBUG : two: Destination found in --compare-dest, skipping 2020/11/03 05:07:16 DEBUG : two: Sizes differ (src 5 vs dst 3) 2020/11/03 05:07:16 DEBUG : two: Need to transfer - File not found at Destination 2020/11/03 05:07:16 DEBUG : two: Clearing upload URL because of error: Post "https://pod-000-1032-09.backblaze.com/b2api/v1/b2_upload_file/af820d3370ed8bcb7f580e1b/c001_v0001032_t0029": EOF 2020/11/03 05:07:16 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1032-09.backblaze.com/b2api/v1/b2_upload_file/af820d3370ed8bcb7f580e1b/c001_v0001032_t0029": EOF) 2020/11/03 05:07:16 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2020/11/03 05:07:16 DEBUG : two: Received error: Post "https://pod-000-1032-09.backblaze.com/b2api/v1/b2_upload_file/af820d3370ed8bcb7f580e1b/c001_v0001032_t0029": EOF - low level retry 1/10 2020/11/03 05:07:17 DEBUG : pacer: Reducing sleep to 10ms 2020/11/03 05:07:18 DEBUG : two: SHA-1 = a4be8877ad88f3109a6411985a1f359e10e2d930 OK 2020/11/03 05:07:18 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (16.74s) === RUN TestCopyFileCopyDest run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:07:20 DEBUG : Creating backend with remote "TestB2:rclone-test-cuwivix2liraron1mavovoy0/dst" 2020/11/03 05:07:21 DEBUG : Creating backend with remote "TestB2:rclone-test-cuwivix2liraron1mavovoy0/CopyDest" 2020/11/03 05:07:22 DEBUG : one: Need to transfer - File not found at Destination 2020/11/03 05:07:23 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/11/03 05:07:23 INFO : one: Copied (new) 2020/11/03 05:07:23 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/11/03 05:07:24 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2020/11/03 05:07:24 INFO : one: Copied (replaced existing) 2020/11/03 05:07:25 DEBUG : Creating backend with remote "TestB2:rclone-test-cuwivix2liraron1mavovoy0/BackupDir" 2020/11/03 05:07:26 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/11/03 05:07:26 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/11/03 05:07:27 DEBUG : one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/11/03 05:07:27 INFO : one: Copied (server-side copy) 2020/11/03 05:07:29 INFO : one: Deleted 2020/11/03 05:07:33 DEBUG : one: SHA-1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2020/11/03 05:07:33 INFO : one: Copied (server-side copy) 2020/11/03 05:07:33 DEBUG : one: Destination found in --copy-dest, using server-side copy 2020/11/03 05:07:35 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/11/03 05:07:36 DEBUG : two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2020/11/03 05:07:36 INFO : two: Copied (server-side copy) 2020/11/03 05:07:36 DEBUG : two: Destination found in --copy-dest, using server-side copy 2020/11/03 05:07:36 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/11/03 05:07:36 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2020/11/03 05:07:36 DEBUG : two: Unchanged skipping 2020/11/03 05:07:37 DEBUG : three: Sizes differ (src 7 vs dst 5) 2020/11/03 05:07:37 DEBUG : three: Destination not found in --copy-dest 2020/11/03 05:07:37 DEBUG : three: Need to transfer - File not found at Destination 2020/11/03 05:07:38 DEBUG : three: SHA-1 = 370e613335f3cf2f19b2a2d35a86402f55c659f8 OK 2020/11/03 05:07:38 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (31.35s) === 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-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:07:54 DEBUG : A1/B2: Making directory 2020/11/03 05:07:54 DEBUG : A1/B1/C3: Making directory fstest.go:247: Filtering empty directory "A1/B2" fstest.go:247: Filtering empty directory "A1/B1/C3" 2020/11/03 05:07:56 DEBUG : A1/B1/C2/five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2020/11/03 05:07:56 INFO : A1/B1/C2/five: Copied (server-side copy) to: A2/B1/C2/five 2020/11/03 05:07:56 INFO : A1/B1/C2/five: Deleted 2020/11/03 05:07:57 DEBUG : A1/B1/three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2020/11/03 05:07:57 INFO : A1/B1/three: Copied (server-side copy) to: A2/B1/three 2020/11/03 05:07:57 DEBUG : A1/two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2020/11/03 05:07:57 INFO : A1/two: Copied (server-side copy) to: A2/two 2020/11/03 05:07:58 INFO : A1/two: Deleted 2020/11/03 05:07:58 DEBUG : A1/one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/11/03 05:07:58 INFO : A1/one: Copied (server-side copy) to: A2/one 2020/11/03 05:07:58 INFO : A1/B1/three: Deleted 2020/11/03 05:07:58 DEBUG : A1/B1/C1/four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2020/11/03 05:07:58 INFO : A1/B1/C1/four: Copied (server-side copy) to: A2/B1/C1/four 2020/11/03 05:07:59 INFO : A1/B1/C1/four: Deleted 2020/11/03 05:07:59 INFO : A1/one: Deleted fstest.go:247: Filtering empty directory "A2/B2" fstest.go:247: Filtering empty directory "A2/B1/C3" 2020/11/03 05:08:00 DEBUG : A2/B1/three: SHA-1 = b802f384302cb24fbab0a44997e820bf2e8507bb OK 2020/11/03 05:08:00 INFO : A2/B1/three: Copied (server-side copy) to: A3/B1/three 2020/11/03 05:08:01 INFO : A2/B1/three: Deleted 2020/11/03 05:08:01 DEBUG : A2/one: SHA-1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2020/11/03 05:08:01 INFO : A2/one: Copied (server-side copy) to: A3/one 2020/11/03 05:08:01 DEBUG : A2/B1/C2/five: SHA-1 = 4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 OK 2020/11/03 05:08:01 INFO : A2/B1/C2/five: Copied (server-side copy) to: A3/B1/C2/five 2020/11/03 05:08:01 INFO : A2/one: Deleted 2020/11/03 05:08:01 DEBUG : A2/B1/C1/four: SHA-1 = 9f8f7eec5dea5ac43738721939c120318cbff1df OK 2020/11/03 05:08:01 INFO : A2/B1/C1/four: Copied (server-side copy) to: A3/B1/C1/four 2020/11/03 05:08:02 INFO : A2/B1/C1/four: Deleted 2020/11/03 05:08:02 DEBUG : A2/two: SHA-1 = ad782ecdac770fc6eb9a62e44f90873fb97fb26b OK 2020/11/03 05:08:02 INFO : A2/two: Copied (server-side copy) to: A3/two 2020/11/03 05:08:03 INFO : A2/two: Deleted 2020/11/03 05:08:07 INFO : A2/B1/C2/five: Deleted fstest.go:247: Filtering empty directory "A3/B2" fstest.go:247: Filtering empty directory "A3/B1/C3" --- PASS: TestDirMove (38.06s) === RUN TestGetFsInfo run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", 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-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:08:30 DEBUG : B2 bucket rclone-test-cuwivix2liraron1mavovoy0: File to upload is small (34 bytes), uploading instead of streaming 2020/11/03 05:08:31 DEBUG : no_checksum_small_file_from_pipe: SHA-1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2020/11/03 05:08:31 INFO : no_checksum_small_file_from_pipe: Copied (new) 2020/11/03 05:08:31 DEBUG : no_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2020/11/03 05:08:31 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-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:08:33 DEBUG : B2 bucket rclone-test-cuwivix2liraron1mavovoy0: File to upload is small (34 bytes), uploading instead of streaming 2020/11/03 05:08:34 DEBUG : with_checksum_small_file_from_pipe: SHA-1 = 4c3452689ee5e2b950454c46181b8b5e7bcfeca9 OK 2020/11/03 05:08:34 INFO : with_checksum_small_file_from_pipe: Copied (new) 2020/11/03 05:08:34 DEBUG : with_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2020/11/03 05:08:35 DEBUG : with_checksum_big_file_from_pipe: SHA-1 = d0080f73aa8b494570ad63df1beb25c01ea9eea2 OK 2020/11/03 05:08: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-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:08:38 DEBUG : B2 bucket rclone-test-cuwivix2liraron1mavovoy0: File to upload is small (34 bytes), uploading instead of streaming 2020/11/03 05:08:39 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2020/11/03 05:08:39 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2020/11/03 05:08:39 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-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:08:42 DEBUG : B2 bucket rclone-test-cuwivix2liraron1mavovoy0: File to upload is small (34 bytes), uploading instead of streaming 2020/11/03 05:08:43 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2020/11/03 05:08:43 DEBUG : ignore_checksum_big_file_from_pipe: File has 102401 bytes, which makes only one chunk. Using direct upload. 2020/11/03 05:08:44 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (16.42s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (3.92s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (4.46s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (4.50s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (3.54s) === RUN TestRcatSize run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:08:46 DEBUG : B2 bucket rclone-test-cuwivix2liraron1mavovoy0: File to upload is small (60 bytes), uploading instead of streaming 2020/11/03 05:08:47 DEBUG : potato2: SHA-1 = 9dc7f7d3279715991a22853f5981df582b7f9f6d OK 2020/11/03 05:08:47 INFO : potato2: Copied (new) --- PASS: TestRcatSize (2.34s) === RUN TestCopyFileMaxTransfer run.go:176: Remote "B2 bucket rclone-test-cuwivix2liraron1mavovoy0", Local "Local file system at /tmp/rclone710848303", Modify Window "1ms" 2020/11/03 05:08:48 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2020/11/03 05:08:49 DEBUG : TestCopyFileMaxTransfer/file1: SHA-1 = a379624177abc4679cafafa8eae1d73e1478aaa6 OK 2020/11/03 05:08:49 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2020/11/03 05:08:49 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2020/11/03 05:08:49 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Post "https://pod-000-1145-09.backblaze.com/b2api/v1/b2_upload_file/af820d3370ed8bcb7f580e1b/c001_v0001145_t0039": Max transfer limit reached as set by --max-transfer 2020/11/03 05:08:50 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2020/11/03 05:08:50 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2020/11/03 05:08:51 DEBUG : TestCopyFileMaxTransfer/file4: SHA-1 = 80076474c163ebab342111af4998a3a29adabe18 OK 2020/11/03 05:08:51 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (4.81s) === 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 2020/11/03 05:08:53 DEBUG : B2 bucket rclone-test-cuwivix2liraron1mavovoy0: Purge remote 2020/11/03 05:08:53 DEBUG : A1/B1/C1/four: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1008028587061d53_d20201103_m050758_c001_v0001145_t0009") 2020/11/03 05:08:53 DEBUG : A1/B1/C1/four: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e089fa0_d20201103_m050753_c001_v0001145_t0039") 2020/11/03 05:08:53 DEBUG : A1/B1/C1/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1041cf8670d33c26_d20201103_m050601_c001_v0001137_t0023") 2020/11/03 05:08:53 DEBUG : A1/B1/C1/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08915d_d20201103_m050559_c001_v0001145_t0039") 2020/11/03 05:08:53 DEBUG : A1/B1/C2/five: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f105c9eac5d1e967a_d20201103_m050756_c001_v0001021_t0015") 2020/11/03 05:08:53 DEBUG : A1/B1/C2/five: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e089fbf_d20201103_m050754_c001_v0001145_t0039") 2020/11/03 05:08:53 DEBUG : A1/B1/three: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f118e0807d6409ce9_d20201103_m050757_c001_v0001146_t0052") 2020/11/03 05:08:53 DEBUG : A1/B1/three: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e089f92_d20201103_m050753_c001_v0001145_t0039") 2020/11/03 05:08:53 DEBUG : A1/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1196d6240994f49a_d20201103_m050758_c001_v0001146_t0058") 2020/11/03 05:08:54 DEBUG : A1/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e089f6b_d20201103_m050752_c001_v0001145_t0039") 2020/11/03 05:08:54 DEBUG : A1/two: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1086f0905d57d178_d20201103_m050757_c001_v0001145_t0017") 2020/11/03 05:08:54 DEBUG : A1/two: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e089f7c_d20201103_m050752_c001_v0001145_t0039") 2020/11/03 05:08:54 DEBUG : A1/two: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f11694c15c3aa423b_d20201103_m050601_c001_v0001043_t0000") 2020/11/03 05:08:54 DEBUG : A1/two: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e089178_d20201103_m050559_c001_v0001145_t0039") 2020/11/03 05:08:54 DEBUG : A2/B1/C1/four: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1125094cabc4ac84_d20201103_m050801_c001_v0001145_t0048") 2020/11/03 05:08:54 DEBUG : A2/B1/C1/four: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1109cac7cb34d316_d20201103_m050757_c001_v0001146_t0019") 2020/11/03 05:08:54 DEBUG : A2/B1/C2/five: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f103ca503d7b6e5da_d20201103_m050801_c001_v0001015_t0026") 2020/11/03 05:08:54 DEBUG : A2/B1/C2/five: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1131bc4e28fb1a19_d20201103_m050755_c001_v0001145_t0028") 2020/11/03 05:08:54 DEBUG : A2/B1/three: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f10409440996ff117_d20201103_m050800_c001_v0001128_t0002") 2020/11/03 05:08:54 DEBUG : A2/B1/three: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f101ea00fa6e89673_d20201103_m050755_c001_v0001146_t0035") 2020/11/03 05:08:54 DEBUG : A2/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f11817bb607bf1e01_d20201103_m050801_c001_v0001136_t0028") 2020/11/03 05:08:54 DEBUG : A2/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f109d873def806fd9_d20201103_m050755_c001_v0001041_t0005") 2020/11/03 05:08:54 DEBUG : A2/two: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1158b23b0ba31d97_d20201103_m050803_c001_v0001145_t0045") 2020/11/03 05:08:54 DEBUG : A2/two: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f11731ab8ab508ade_d20201103_m050755_c001_v0001044_t0056") 2020/11/03 05:08:54 DEBUG : A3/B1/C1/four: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f101a90f0b3e88ed8_d20201103_m050807_c001_v0001144_t0011") 2020/11/03 05:08:55 DEBUG : A3/B1/C1/four: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f111cadd0653d8980_d20201103_m050800_c001_v0001145_t0009") 2020/11/03 05:08:55 DEBUG : A3/B1/C2/five: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f105fa5a48394bfb0_d20201103_m050808_c001_v0001146_t0010") 2020/11/03 05:08:55 DEBUG : A3/B1/C2/five: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1050f42dac5289e4_d20201103_m050800_c001_v0001145_t0026") 2020/11/03 05:08:55 DEBUG : A3/B1/three: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1133098d03142d5a_d20201103_m050809_c001_v0001029_t0033") 2020/11/03 05:08:55 DEBUG : A3/B1/three: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f111cadd0653d8977_d20201103_m050800_c001_v0001145_t0023") 2020/11/03 05:08:55 DEBUG : A3/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1078019d13775cba_d20201103_m050828_c001_v0001137_t0058") 2020/11/03 05:08:55 DEBUG : A3/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f112518a7cfaa29c6_d20201103_m050800_c001_v0001000_t0014") 2020/11/03 05:08:55 DEBUG : A3/two: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1085e8576d3e93ae_d20201103_m050829_c001_v0001128_t0007") 2020/11/03 05:08:55 DEBUG : A3/two: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f112a693ada2545b6_d20201103_m050801_c001_v0001146_t0042") 2020/11/03 05:08:55 DEBUG : BackupDir/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f101ea00fa6e89485_d20201103_m050739_c001_v0001146_t0040") 2020/11/03 05:08:55 DEBUG : BackupDir/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f110a8634b77fbe95_d20201103_m050726_c001_v0001145_t0021") 2020/11/03 05:08:55 DEBUG : CompareDest/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1141d50abaab4a1e_d20201103_m050718_c001_v0001130_t0044") 2020/11/03 05:08:55 DEBUG : CompareDest/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e089a43_d20201103_m050713_c001_v0001145_t0039") 2020/11/03 05:08:55 DEBUG : CompareDest/two: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f104b64c7a6b5428f_d20201103_m050718_c001_v0001092_t0022") 2020/11/03 05:08:55 DEBUG : CompareDest/two: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e089a7a_d20201103_m050714_c001_v0001145_t0039") 2020/11/03 05:08:55 DEBUG : CopyDest/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1061f64e3c736fa5_d20201103_m050747_c001_v0001130_t0032") 2020/11/03 05:08:55 DEBUG : CopyDest/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e089be7_d20201103_m050725_c001_v0001145_t0039") 2020/11/03 05:08:55 DEBUG : CopyDest/three: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f104e8a5be52289f8_d20201103_m050748_c001_v0001017_t0002") 2020/11/03 05:08:55 DEBUG : CopyDest/three: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e089d66_d20201103_m050736_c001_v0001145_t0039") 2020/11/03 05:08:55 DEBUG : CopyDest/two: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f11443af1803e72d8_d20201103_m050748_c001_v0001146_t0022") 2020/11/03 05:08:56 DEBUG : CopyDest/two: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e089d28_d20201103_m050734_c001_v0001145_t0039") 2020/11/03 05:08:56 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1061f64e3c73705e_d20201103_m050851_c001_v0001130_t0053") 2020/11/03 05:08:56 DEBUG : TestCopyFileMaxTransfer/file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08a643_d20201103_m050848_c001_v0001145_t0039") 2020/11/03 05:08:56 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1131bc4e28fb20a2_d20201103_m050852_c001_v0001145_t0037") 2020/11/03 05:08:56 DEBUG : TestCopyFileMaxTransfer/file4: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08a682_d20201103_m050850_c001_v0001145_t0039") 2020/11/03 05:08:56 DEBUG : a.txt: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f115a643211b80e85_d20201103_m050512_c001_v0001146_t0003") 2020/11/03 05:08:56 DEBUG : a.txt: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088a40_d20201103_m050508_c001_v0001145_t0039") 2020/11/03 05:08:56 DEBUG : backup/dst/file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f110a8634b77fb87e_d20201103_m050641_c001_v0001145_t0053") 2020/11/03 05:08:56 DEBUG : backup/dst/file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f113e7b054a072afe_d20201103_m050638_c001_v0001042_t0047") 2020/11/03 05:08:56 DEBUG : backup/dst/file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1012f447c2374b8d_d20201103_m050632_c001_v0001043_t0021") 2020/11/03 05:08:56 DEBUG : backup/dst/file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1069c2b7fc5da1ff_d20201103_m050617_c001_v0001146_t0037") 2020/11/03 05:08:56 DEBUG : dst/file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f119e6e934e6733df_d20201103_m050642_c001_v0001113_t0053") 2020/11/03 05:08:56 DEBUG : dst/file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e0895ca_d20201103_m050640_c001_v0001145_t0039") 2020/11/03 05:08:56 DEBUG : dst/file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f115a643211b81b20_d20201103_m050639_c001_v0001146_t0051") 2020/11/03 05:08:56 DEBUG : dst/file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e089508_d20201103_m050635_c001_v0001145_t0039") 2020/11/03 05:08:56 DEBUG : dst/file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1061f64e3c736ee0_d20201103_m050632_c001_v0001130_t0034") 2020/11/03 05:08:56 DEBUG : dst/file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e0893b2_d20201103_m050619_c001_v0001145_t0039") 2020/11/03 05:08:56 DEBUG : dst/file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f118e0807d6408e6c_d20201103_m050618_c001_v0001146_t0009") 2020/11/03 05:08:56 DEBUG : dst/file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08932b_d20201103_m050614_c001_v0001145_t0039") 2020/11/03 05:08:56 DEBUG : dst/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f111ff60f9f2abebf_d20201103_m050750_c001_v0001043_t0035") 2020/11/03 05:08:57 DEBUG : dst/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1109cac7cb34d021_d20201103_m050729_c001_v0001146_t0032") 2020/11/03 05:08:57 DEBUG : dst/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f10399a237a2e2108_d20201103_m050727_c001_v0001017_t0020") 2020/11/03 05:08:57 DEBUG : dst/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e089bdb_d20201103_m050724_c001_v0001145_t0039") 2020/11/03 05:08:57 DEBUG : dst/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f11617a4cdff50ac1_d20201103_m050723_c001_v0001144_t0038") 2020/11/03 05:08:57 DEBUG : dst/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f11617a4cdff50ab1_d20201103_m050722_c001_v0001144_t0038") 2020/11/03 05:08:57 DEBUG : dst/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f118fe3e48089c315_d20201103_m050719_c001_v0001145_t0023") 2020/11/03 05:08:57 DEBUG : dst/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e089a31_d20201103_m050712_c001_v0001145_t0039") 2020/11/03 05:08:57 DEBUG : dst/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f10951a4fa32687b2_d20201103_m050707_c001_v0001032_t0029") 2020/11/03 05:08:57 DEBUG : dst/one: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f10951a4fa32687a6_d20201103_m050706_c001_v0001032_t0029") 2020/11/03 05:08:57 DEBUG : dst/three: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f11564e239a7f1d43_d20201103_m050750_c001_v0001136_t0049") 2020/11/03 05:08:57 DEBUG : dst/three: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f11617a4cdff50b62_d20201103_m050738_c001_v0001144_t0038") 2020/11/03 05:08:57 DEBUG : dst/two: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1131bc4e28fb1972_d20201103_m050750_c001_v0001145_t0022") 2020/11/03 05:08:57 DEBUG : dst/two: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f11488808c1a3b16a_d20201103_m050735_c001_v0001128_t0015") 2020/11/03 05:08:57 DEBUG : dst/two: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f111f369355d5cb5d_d20201103_m050719_c001_v0001130_t0054") 2020/11/03 05:08:57 DEBUG : dst/two: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f117725db666d9def_d20201103_m050717_c001_v0001145_t0025") 2020/11/03 05:08:57 DEBUG : empty space: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f118e0807d64088b9_d20201103_m050541_c001_v0001146_t0015") 2020/11/03 05:08:57 DEBUG : empty space: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088ee5_d20201103_m050540_c001_v0001145_t0039") 2020/11/03 05:08:57 DEBUG : empty space: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1041cf8670d33b7c_d20201103_m050538_c001_v0001137_t0024") 2020/11/03 05:08:57 DEBUG : empty space: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088e63_d20201103_m050536_c001_v0001145_t0039") 2020/11/03 05:08:57 DEBUG : empty space: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1069c2b7fc5d9bcd_d20201103_m050532_c001_v0001146_t0041") 2020/11/03 05:08:57 DEBUG : empty space: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088da6_d20201103_m050531_c001_v0001145_t0039") 2020/11/03 05:08:57 DEBUG : empty space: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1158b23b0ba30c87_d20201103_m050530_c001_v0001145_t0045") 2020/11/03 05:08:58 DEBUG : empty space: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088d45_d20201103_m050528_c001_v0001145_t0039") 2020/11/03 05:08:58 DEBUG : empty space: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f109991324c7e0231_d20201103_m050527_c001_v0001136_t0056") 2020/11/03 05:08:58 DEBUG : empty space: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088ce5_d20201103_m050525_c001_v0001145_t0039") 2020/11/03 05:08:58 DEBUG : empty space: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f105fa5a48394a90a_d20201103_m050501_c001_v0001146_t0051") 2020/11/03 05:08:58 DEBUG : empty space: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08891e_d20201103_m050458_c001_v0001145_t0039") 2020/11/03 05:08:58 DEBUG : empty space: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f117337056dcd244d_d20201103_m050450_c001_v0001146_t0003") 2020/11/03 05:08:58 DEBUG : empty space: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08879c_d20201103_m050446_c001_v0001145_t0039") 2020/11/03 05:08:58 DEBUG : empty space: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f101d10b459aae015_d20201103_m050442_c001_v0001145_t0037") 2020/11/03 05:08:58 DEBUG : empty space: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e0886c7_d20201103_m050439_c001_v0001145_t0039") 2020/11/03 05:08:58 DEBUG : file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f118e0807d6408c84_d20201103_m050605_c001_v0001146_t0005") 2020/11/03 05:08:58 DEBUG : file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e0891ef_d20201103_m050603_c001_v0001145_t0039") 2020/11/03 05:08:58 DEBUG : file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f103475bf9606db83_d20201103_m050551_c001_v0001137_t0000") 2020/11/03 05:08:58 DEBUG : file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08900f_d20201103_m050548_c001_v0001145_t0039") 2020/11/03 05:08:58 DEBUG : file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f11564f0c34c63a46_d20201103_m050436_c001_v0001130_t0037") 2020/11/03 05:08:58 DEBUG : file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088622_d20201103_m050435_c001_v0001145_t0039") 2020/11/03 05:08:58 DEBUG : file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e0885eb_d20201103_m050433_c001_v0001145_t0039") 2020/11/03 05:08:58 DEBUG : file1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08858d_d20201103_m050431_c001_v0001145_t0039") 2020/11/03 05:08:58 DEBUG : file2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1137bff6bf2a60e2_d20201103_m050610_c001_v0001120_t0027") 2020/11/03 05:08:58 DEBUG : file2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e089210_d20201103_m050604_c001_v0001145_t0039") 2020/11/03 05:08:58 DEBUG : file2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f10287e53c9165402_d20201103_m050552_c001_v0001134_t0055") 2020/11/03 05:08:58 DEBUG : file2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08901e_d20201103_m050549_c001_v0001145_t0039") 2020/11/03 05:08:58 DEBUG : filename.txt: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f101924e539db28f0_d20201103_m050611_c001_v0001136_t0035") 2020/11/03 05:08:58 DEBUG : filename.txt: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e089201_d20201103_m050603_c001_v0001145_t0039") 2020/11/03 05:08:58 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f110a8634b77fc8cf_d20201103_m050844_c001_v0001145_t0053") 2020/11/03 05:08:58 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08a59d_d20201103_m050843_c001_v0001145_t0039") 2020/11/03 05:08:58 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f111cadd0653d8df0_d20201103_m050840_c001_v0001145_t0023") 2020/11/03 05:08:59 DEBUG : ignore_checksum_big_file_from_pipe: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08a51d_d20201103_m050839_c001_v0001145_t0039") 2020/11/03 05:08:59 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f101924e539db2dcf_d20201103_m050845_c001_v0001136_t0032") 2020/11/03 05:08:59 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08a58a_d20201103_m050842_c001_v0001145_t0039") 2020/11/03 05:08:59 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f113b7ec7cfd60ae1_d20201103_m050841_c001_v0001091_t0026") 2020/11/03 05:08:59 DEBUG : ignore_checksum_small_file_from_pipe: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08a50a_d20201103_m050838_c001_v0001145_t0039") 2020/11/03 05:08:59 DEBUG : large: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1197d4d431dbc710_d20201103_m050547_c001_v0001136_t0058") 2020/11/03 05:08:59 DEBUG : large: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088fc6_d20201103_m050546_c001_v0001145_t0039") 2020/11/03 05:08:59 DEBUG : medium: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f11569c9a5a6fea8a_d20201103_m050546_c001_v0001029_t0035") 2020/11/03 05:08:59 DEBUG : medium: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088fb5_d20201103_m050545_c001_v0001145_t0039") 2020/11/03 05:08:59 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1118ac519668c2de_d20201103_m050832_c001_v0001004_t0010") 2020/11/03 05:08:59 DEBUG : no_checksum_big_file_from_pipe: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08a435_d20201103_m050831_c001_v0001145_t0039") 2020/11/03 05:08:59 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1037044104b83390_d20201103_m050833_c001_v0001145_t0051") 2020/11/03 05:08:59 DEBUG : no_checksum_small_file_from_pipe: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08a41d_d20201103_m050830_c001_v0001145_t0039") 2020/11/03 05:08:59 DEBUG : potato1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1067a2bb40bed726_d20201103_m050847_c001_v0001041_t0020") 2020/11/03 05:08:59 DEBUG : potato1: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08a5e7_d20201103_m050846_c001_v0001145_t0039") 2020/11/03 05:08:59 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1141ae0b7fd506eb_d20201103_m050848_c001_v0001145_t0047") 2020/11/03 05:08:59 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08a5f2_d20201103_m050847_c001_v0001145_t0039") 2020/11/03 05:08:59 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f116994cbb83d6630_d20201103_m050542_c001_v0001145_t0048") 2020/11/03 05:08:59 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088ec1_d20201103_m050539_c001_v0001145_t0039") 2020/11/03 05:08:59 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1086f0905d57c057_d20201103_m050538_c001_v0001145_t0019") 2020/11/03 05:08:59 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088e51_d20201103_m050535_c001_v0001145_t0039") 2020/11/03 05:08:59 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f101ea00fa6e88444_d20201103_m050534_c001_v0001146_t0017") 2020/11/03 05:08:59 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088d9a_d20201103_m050531_c001_v0001145_t0039") 2020/11/03 05:08:59 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f116b86c644e67ee3_d20201103_m050530_c001_v0001136_t0029") 2020/11/03 05:08:59 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088d3b_d20201103_m050528_c001_v0001145_t0039") 2020/11/03 05:09:00 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f111cadd0653d7725_d20201103_m050527_c001_v0001145_t0042") 2020/11/03 05:09:00 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088cd0_d20201103_m050525_c001_v0001145_t0039") 2020/11/03 05:09:00 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f114ac0fff635154c_d20201103_m050503_c001_v0001134_t0037") 2020/11/03 05:09:00 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088936_d20201103_m050459_c001_v0001145_t0039") 2020/11/03 05:09:00 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f104e07a944939bd9_d20201103_m050452_c001_v0001146_t0032") 2020/11/03 05:09:00 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e0887c4_d20201103_m050448_c001_v0001145_t0039") 2020/11/03 05:09:00 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1184a12a4f7780e9_d20201103_m050443_c001_v0001131_t0004") 2020/11/03 05:09:00 DEBUG : potato2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e0886da_d20201103_m050440_c001_v0001145_t0039") 2020/11/03 05:09:00 DEBUG : remotepotato: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f106e9ad5550a8d1a_d20201103_m050503_c001_v0001091_t0005") 2020/11/03 05:09:00 DEBUG : remotepotato: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08894a_d20201103_m050500_c001_v0001145_t0039") 2020/11/03 05:09:00 DEBUG : remotepotato: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f102360cccf700f95_d20201103_m050454_c001_v0001146_t0043") 2020/11/03 05:09:00 DEBUG : remotepotato: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088801_d20201103_m050449_c001_v0001145_t0039") 2020/11/03 05:09:00 DEBUG : remotepotato: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1118503f3d18cf21_d20201103_m050444_c001_v0001134_t0044") 2020/11/03 05:09:00 DEBUG : remotepotato: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e0886f8_d20201103_m050441_c001_v0001145_t0039") 2020/11/03 05:09:00 DEBUG : rutabaga: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1131bc4e28fb06e8_d20201103_m050503_c001_v0001145_t0042") 2020/11/03 05:09:00 DEBUG : rutabaga: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088907_d20201103_m050457_c001_v0001145_t0039") 2020/11/03 05:09:00 DEBUG : rutabaga: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f117337056dcd24e0_d20201103_m050455_c001_v0001146_t0023") 2020/11/03 05:09:00 DEBUG : rutabaga: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088779_d20201103_m050445_c001_v0001145_t0039") 2020/11/03 05:09:00 DEBUG : rutabaga: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f101ea00fa6e87ce2_d20201103_m050444_c001_v0001146_t0053") 2020/11/03 05:09:00 DEBUG : rutabaga: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088679_d20201103_m050437_c001_v0001145_t0039") 2020/11/03 05:09:00 DEBUG : small: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088fdc_d20201103_m050546_c001_v0001145_t0015") 2020/11/03 05:09:00 DEBUG : small: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088fa4_d20201103_m050545_c001_v0001145_t0039") 2020/11/03 05:09:00 DEBUG : sub dir/hello world: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f117425fbfa027b7d_d20201103_m050522_c001_v0001130_t0017") 2020/11/03 05:09:01 DEBUG : sub dir/hello world: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088c22_d20201103_m050521_c001_v0001145_t0039") 2020/11/03 05:09:01 DEBUG : sub dir/hello world: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f11975f5ce32d03c2_d20201103_m050514_c001_v0001144_t0017") 2020/11/03 05:09:01 DEBUG : sub dir/hello world: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088a61_d20201103_m050509_c001_v0001145_t0039") 2020/11/03 05:09:01 DEBUG : sub dir/hello world2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f117e2e1f3b36ae5f_d20201103_m050514_c001_v0001134_t0029") 2020/11/03 05:09:01 DEBUG : sub dir/hello world2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088a6a_d20201103_m050509_c001_v0001145_t0039") 2020/11/03 05:09:01 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088b4a_d20201103_m050515_c001_v0001145_t0036") 2020/11/03 05:09:01 DEBUG : sub dir/ignore dir/.ignore: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088a86_d20201103_m050509_c001_v0001145_t0039") 2020/11/03 05:09:01 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1115a4536755ae96_d20201103_m050516_c001_v0001137_t0010") 2020/11/03 05:09:01 DEBUG : sub dir/ignore dir/should be ignored: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088a92_d20201103_m050510_c001_v0001145_t0039") 2020/11/03 05:09:01 DEBUG : sub dir/potato3: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f118e0807d640890b_d20201103_m050543_c001_v0001146_t0006") 2020/11/03 05:09:01 DEBUG : sub dir/potato3: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088ef3_d20201103_m050540_c001_v0001145_t0039") 2020/11/03 05:09:01 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f101ea00fa6e881a1_d20201103_m050516_c001_v0001146_t0022") 2020/11/03 05:09:01 DEBUG : sub dir/sub sub dir/hello world3: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088aaa_d20201103_m050510_c001_v0001145_t0039") 2020/11/03 05:09:01 DEBUG : sub/file2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f118e0807d64090bc_d20201103_m050634_c001_v0001146_t0016") 2020/11/03 05:09:01 DEBUG : sub/file2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08949e_d20201103_m050633_c001_v0001145_t0039") 2020/11/03 05:09:01 DEBUG : sub/file2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f117e2e1f3b36afb7_d20201103_m050613_c001_v0001134_t0008") 2020/11/03 05:09:01 DEBUG : sub/file2: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e0892cd_d20201103_m050612_c001_v0001145_t0039") 2020/11/03 05:09:01 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f102171cb8c68d9b2_d20201103_m050835_c001_v0001145_t0058") 2020/11/03 05:09:01 DEBUG : with_checksum_big_file_from_pipe: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08a494_d20201103_m050834_c001_v0001145_t0039") 2020/11/03 05:09:01 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1092ef8d657f2d92_d20201103_m050837_c001_v0001038_t0058") 2020/11/03 05:09:01 DEBUG : with_checksum_small_file_from_pipe: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e08a488_d20201103_m050834_c001_v0001145_t0039") 2020/11/03 05:09:01 DEBUG : zend.txt: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f117337056dcd2824_d20201103_m050518_c001_v0001146_t0044") 2020/11/03 05:09:01 DEBUG : zend.txt: Deleting (id "4_zaf820d3370ed8bcb7f580e1b_f1093345b1e088a48_d20201103_m050508_c001_v0001145_t0039") "./operations.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 4m34.745032546s (try 1/5)