"./operations.test -test.v -test.timeout 30m0s -remote TestHubic: -verbose" - Starting (try 1/5) 2020/03/09 05:44:41 DEBUG : Hubic: Got swift credentials (expiry 2020-03-10 06:20:21 +0100 +0100 in 23h35m39.094058891s) === 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 TestMultithreadCopy/{size:131071_streams:2} 2020/03/09 05:44:42 INFO : Swift container rclone-test-zowemud3tamiyul5veyomos7: Container "rclone-test-zowemud3tamiyul5veyomos7" created 2020/03/09 05:44:44 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64k 2020/03/09 05:44:44 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999k starting 2020/03/09 05:44:44 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k starting 2020/03/09 05:44:45 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999k finished 2020/03/09 05:44:45 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k finished 2020/03/09 05:44:45 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64k === RUN TestMultithreadCopy/{size:131072_streams:2} 2020/03/09 05:44:57 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64k 2020/03/09 05:44:57 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64k starting 2020/03/09 05:44:57 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k starting 2020/03/09 05:44:57 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k finished 2020/03/09 05:44:57 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64k finished 2020/03/09 05:44:57 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64k === RUN TestMultithreadCopy/{size:131073_streams:2} 2020/03/09 05:44:59 DEBUG : file1: Starting multi-thread copy with 2 parts of size 128k 2020/03/09 05:44:59 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 starting 2020/03/09 05:44:59 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128k starting 2020/03/09 05:45:00 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128k finished 2020/03/09 05:45:01 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 finished 2020/03/09 05:45:01 DEBUG : file1: Finished multi-thread copy with 2 parts of size 128k --- PASS: TestMultithreadCopy (20.77s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" --- PASS: TestMultithreadCopy/{size:131071_streams:2} (3.26s) --- PASS: TestMultithreadCopy/{size:131072_streams:2} (12.41s) --- PASS: TestMultithreadCopy/{size:131073_streams:2} (3.81s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Seek === RUN TestReOpen/Seek/Basics === RUN TestReOpen/Seek/ErrorAtStart === RUN TestReOpen/Seek/WithErrors 2020/03/09 05:45:02 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2020/03/09 05:45:02 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2020/03/09 05:45:02 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Seek/TooManyErrors 2020/03/09 05:45:02 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2020/03/09 05:45:02 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2020/03/09 05:45:02 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2020/03/09 05:45:02 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/03/09 05:45:02 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2020/03/09 05:45:02 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2020/03/09 05:45:02 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Range/TooManyErrors 2020/03/09 05:45:02 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2020/03/09 05:45:02 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2020/03/09 05:45:02 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2020/03/09 05:45:02 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 TestDeduplicateInteractive --- SKIP: TestDeduplicateInteractive (0.11s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible === RUN TestDeduplicateSkip --- SKIP: TestDeduplicateSkip (0.11s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible === RUN TestDeduplicateFirst --- SKIP: TestDeduplicateFirst (0.10s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible === RUN TestDeduplicateNewest --- SKIP: TestDeduplicateNewest (0.11s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible === RUN TestDeduplicateOldest --- SKIP: TestDeduplicateOldest (0.11s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible === RUN TestDeduplicateLargest --- SKIP: TestDeduplicateLargest (0.11s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible === RUN TestDeduplicateSmallest --- SKIP: TestDeduplicateSmallest (0.17s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible === RUN TestDeduplicateRename --- SKIP: TestDeduplicateRename (0.15s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" dedupe_test.go:23: Can't test deduplicate - no duplicate files possible === RUN TestMergeDirs --- SKIP: TestMergeDirs (0.24s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" dedupe_test.go:217: Can't merge directories === RUN TestListDirSorted 2020/03/09 05:45:21 DEBUG : a.txt: Excluded 2020/03/09 05:45:21 DEBUG : sub dir/hello world: Excluded 2020/03/09 05:45:21 DEBUG : sub dir/hello world2: Excluded 2020/03/09 05:45:21 DEBUG : sub dir/hello world: Excluded 2020/03/09 05:45:21 DEBUG : sub dir/hello world2: Excluded 2020/03/09 05:45:21 DEBUG : sub dir/ignore dir: Excluded 2020/03/09 05:45:22 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (26.94s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestMkdir 2020/03/09 05:45:30 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: Making directory 2020/03/09 05:45:30 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: Making directory --- PASS: TestMkdir (0.16s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestLsd --- PASS: TestLsd (5.52s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestLs --- PASS: TestLs (7.46s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestLsWithFilesFrom 2020/03/09 05:45:50 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (9.57s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestLsLong --- PASS: TestLsLong (6.78s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestHashSums --- PASS: TestHashSums (6.14s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount --- PASS: TestCount (9.37s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestDelete 2020/03/09 05:46:25 DEBUG : Waiting for deletions to finish 2020/03/09 05:46:25 DEBUG : large: Excluded from sync (and deletion) 2020/03/09 05:46:27 INFO : small: Deleted 2020/03/09 05:46:27 INFO : medium: Deleted --- PASS: TestDelete (12.79s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestCheck 2020/03/09 05:46:32 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 1: Starting check test 2020/03/09 05:46:32 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 2: Starting check test 2020/03/09 05:46:35 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 3: Starting check test 2020/03/09 05:46:37 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 4: Starting check test 2020/03/09 05:46:37 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 5: Starting check test 2020/03/09 05:46:39 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 6: Starting check test 2020/03/09 05:46:40 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 7: Starting check test --- PASS: TestCheck (16.38s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestCheckFsError 2020/03/09 05:46:45 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: Waiting for checks to finish 2020/03/09 05:46:45 ERROR : : error reading source directory: directory not found 2020/03/09 05:46:45 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: 1 differences found --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload 2020/03/09 05:46:48 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 1: Starting check test 2020/03/09 05:46:48 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 2: Starting check test 2020/03/09 05:46:50 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 3: Starting check test 2020/03/09 05:46:52 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 4: Starting check test 2020/03/09 05:46:53 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 5: Starting check test 2020/03/09 05:46:57 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 6: Starting check test 2020/03/09 05:46:58 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 7: Starting check test --- PASS: TestCheckDownload (18.63s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestCheckSizeOnly 2020/03/09 05:47:10 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 1: Starting check test 2020/03/09 05:47:11 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 2: Starting check test 2020/03/09 05:47:13 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 3: Starting check test 2020/03/09 05:47:14 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 4: Starting check test 2020/03/09 05:47:14 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 5: Starting check test 2020/03/09 05:47:16 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 6: Starting check test 2020/03/09 05:47:16 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: 7: Starting check test --- PASS: TestCheckSizeOnly (17.64s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestCat --- PASS: TestCat (7.96s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestPurge 2020/03/09 05:47:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2020/03/09 05:47:29 DEBUG : Hubic: Got swift credentials (expiry 2020-03-10 06:20:21 +0100 +0100 in 23h32m51.60344972s) 2020/03/09 05:47:29 INFO : Swift container rclone-test-fuyofag3cayakiq7jokunuc1: Container "rclone-test-fuyofag3cayakiq7jokunuc1" created 2020/03/09 05:47:31 DEBUG : A2: Making directory 2020/03/09 05:47:31 DEBUG : A1/B2: Making directory 2020/03/09 05:47:31 DEBUG : A1/B2/C2: Making directory 2020/03/09 05:47:31 DEBUG : A1/B1/C3: Making directory 2020/03/09 05:47:31 DEBUG : A3: Making directory 2020/03/09 05:47:31 DEBUG : A3/B3: Making directory 2020/03/09 05:47:31 DEBUG : A3/B3/C4: Making directory 2020/03/09 05:47:40 DEBUG : Waiting for deletions to finish 2020/03/09 05:47:40 INFO : A1/B1/C1/one: Deleted 2020/03/09 05:47:41 DEBUG : A1/B1: Removing directory 2020/03/09 05:47:41 DEBUG : Waiting for deletions to finish 2020/03/09 05:47:42 INFO : A1/two: Deleted 2020/03/09 05:47:42 INFO : Swift container rclone-test-fuyofag3cayakiq7jokunuc1: Container "rclone-test-fuyofag3cayakiq7jokunuc1" removed 2020/03/09 05:47:43 DEBUG : Hubic Swift container rclone-test-fuyofag3cayakiq7jokunuc1: Purge remote 2020/03/09 05:47:43 DEBUG : Waiting for deletions to finish 2020/03/09 05:47:43 purge failed: directory not found --- PASS: TestPurge (14.26s) run.go:176: Remote "Hubic Swift container rclone-test-fuyofag3cayakiq7jokunuc1", Local "Local file system at /tmp/rclone088318667", Modify Window "1ns" fstest.go:241: Filtering empty directory "A2" fstest.go:241: Filtering empty directory "A1/B2" fstest.go:241: Filtering empty directory "A1/B2/C2" fstest.go:241: Filtering empty directory "A1/B1/C3" fstest.go:241: Filtering empty directory "A3" fstest.go:241: Filtering empty directory "A3/B3" fstest.go:241: Filtering empty directory "A3/B3/C4" fstest.go:241: Filtering empty directory "A2" fstest.go:241: Filtering empty directory "A1/B2" fstest.go:241: Filtering empty directory "A1/B2/C2" fstest.go:241: Filtering empty directory "A3" fstest.go:241: Filtering empty directory "A3/B3" fstest.go:241: Filtering empty directory "A3/B3/C4" === RUN TestRmdirsNoLeaveRoot 2020/03/09 05:47:44 DEBUG : A2: Making directory 2020/03/09 05:47:44 DEBUG : A1/B2: Making directory 2020/03/09 05:47:44 DEBUG : A1/B2/C2: Making directory 2020/03/09 05:47:44 DEBUG : A1/B1/C3: Making directory 2020/03/09 05:47:44 DEBUG : A3: Making directory 2020/03/09 05:47:44 DEBUG : A3/B3: Making directory 2020/03/09 05:47:44 DEBUG : A3/B3/C4: Making directory 2020/03/09 05:47:46 DEBUG : A3/B3/C4: Removing directory --- PASS: TestRmdirsNoLeaveRoot (6.01s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" fstest.go:241: Filtering empty directory "A2" fstest.go:241: Filtering empty directory "A1/B2" fstest.go:241: Filtering empty directory "A1/B2/C2" fstest.go:241: Filtering empty directory "A1/B1/C3" fstest.go:241: Filtering empty directory "A3" fstest.go:241: Filtering empty directory "A3/B3" fstest.go:241: Filtering empty directory "A3/B3/C4" fstest.go:241: Filtering empty directory "A2" fstest.go:241: Filtering empty directory "A1/B2" fstest.go:241: Filtering empty directory "A1/B2/C2" fstest.go:241: Filtering empty directory "A1/B1/C3" fstest.go:241: Filtering empty directory "A3" fstest.go:241: Filtering empty directory "A3/B3" === RUN TestRmdirsLeaveRoot 2020/03/09 05:47:49 DEBUG : A1: Making directory 2020/03/09 05:47:49 DEBUG : A1/B1: Making directory 2020/03/09 05:47:49 DEBUG : A1/B1/C1: Making directory --- PASS: TestRmdirsLeaveRoot (0.29s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" fstest.go:241: Filtering empty directory "A1" fstest.go:241: Filtering empty directory "A1/B1" fstest.go:241: Filtering empty directory "A1/B1/C1" fstest.go:241: Filtering empty directory "A1" === RUN TestCopyURL --- PASS: TestCopyURL (8.65s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile 2020/03/09 05:47:59 DEBUG : file1: Need to transfer - File not found at Destination 2020/03/09 05:48:01 INFO : file1: Copied (new) 2020/03/09 05:48:01 INFO : file1: Deleted 2020/03/09 05:48:01 DEBUG : file1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/03/09 05:48:01 DEBUG : file1: Unchanged skipping 2020/03/09 05:48:01 INFO : file1: Deleted 2020/03/09 05:48:01 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (11.78s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" fstest.go:289: Sleeping for 1s for list eventual consistency: 1/6 fstest.go:283: Sleeping for 6s just to make sure === RUN TestCaseInsensitiveMoveFile --- PASS: TestCaseInsensitiveMoveFile (0.17s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestMoveFileBackupDir 2020/03/09 05:48:13 DEBUG : Hubic: Got swift credentials (expiry 2020-03-10 06:20:21 +0100 +0100 in 23h32m7.844266886s) 2020/03/09 05:48:13 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2020/03/09 05:48:14 INFO : Swift container rclone-test-zowemud3tamiyul5veyomos7 path backup: Container "rclone-test-zowemud3tamiyul5veyomos7" created 2020/03/09 05:48:19 DEBUG : dst/file1: MD5 = 05164b153084ba910184c26e561a7c18 OK 2020/03/09 05:48:19 INFO : dst/file1: Copied (server side copy) 2020/03/09 05:48:20 INFO : dst/file1: Deleted 2020/03/09 05:48:21 INFO : dst/file1: Copied (new) 2020/03/09 05:48:21 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (15.58s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestCopyFile 2020/03/09 05:48:26 DEBUG : file1: Need to transfer - File not found at Destination 2020/03/09 05:48:27 INFO : file1: Copied (new) 2020/03/09 05:48:28 DEBUG : file1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/03/09 05:48:28 DEBUG : file1: Unchanged skipping 2020/03/09 05:48:28 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (4.01s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestCopyFileBackupDir 2020/03/09 05:48:31 DEBUG : Hubic: Got swift credentials (expiry 2020-03-10 06:20:21 +0100 +0100 in 23h31m49.2928653s) 2020/03/09 05:48:32 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2020/03/09 05:48:32 INFO : Swift container rclone-test-zowemud3tamiyul5veyomos7 path backup: Container "rclone-test-zowemud3tamiyul5veyomos7" created 2020/03/09 05:48:34 DEBUG : dst/file1: MD5 = 05164b153084ba910184c26e561a7c18 OK 2020/03/09 05:48:34 INFO : dst/file1: Copied (server side copy) 2020/03/09 05:48:35 INFO : dst/file1: Deleted 2020/03/09 05:48:36 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (9.14s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestCopyFileCompareDest 2020/03/09 05:48:39 DEBUG : Hubic: Got swift credentials (expiry 2020-03-10 06:20:21 +0100 +0100 in 23h31m41.751420866s) 2020/03/09 05:48:40 DEBUG : Hubic: Got swift credentials (expiry 2020-03-10 06:20:21 +0100 +0100 in 23h31m40.359239246s) 2020/03/09 05:48:41 DEBUG : one: Need to transfer - File not found at Destination 2020/03/09 05:48:41 INFO : Swift container rclone-test-zowemud3tamiyul5veyomos7 path dst: Container "rclone-test-zowemud3tamiyul5veyomos7" created 2020/03/09 05:48:43 INFO : one: Copied (new) 2020/03/09 05:48:44 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/03/09 05:48:46 INFO : one: Copied (replaced existing) 2020/03/09 05:48:49 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/03/09 05:48:49 DEBUG : one: Destination found in --compare-dest, skipping 2020/03/09 05:48:52 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/03/09 05:48:52 DEBUG : two: Destination found in --compare-dest, skipping 2020/03/09 05:48:52 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/03/09 05:48:52 DEBUG : two: Destination found in --compare-dest, skipping 2020/03/09 05:48:53 DEBUG : two: Sizes differ (src 5 vs dst 3) 2020/03/09 05:48:53 DEBUG : two: Need to transfer - File not found at Destination 2020/03/09 05:48:55 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (21.16s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestCopyFileCopyDest 2020/03/09 05:49:00 DEBUG : Hubic: Got swift credentials (expiry 2020-03-10 06:20:21 +0100 +0100 in 23h31m20.553242953s) 2020/03/09 05:49:01 DEBUG : Hubic: Got swift credentials (expiry 2020-03-10 06:20:21 +0100 +0100 in 23h31m19.500549332s) 2020/03/09 05:49:03 DEBUG : one: Need to transfer - File not found at Destination 2020/03/09 05:49:03 INFO : Swift container rclone-test-zowemud3tamiyul5veyomos7 path dst: Container "rclone-test-zowemud3tamiyul5veyomos7" created 2020/03/09 05:49:05 INFO : one: Copied (new) 2020/03/09 05:49:06 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/03/09 05:49:08 INFO : one: Copied (replaced existing) 2020/03/09 05:49:14 DEBUG : Hubic: Got swift credentials (expiry 2020-03-10 06:20:21 +0100 +0100 in 23h31m6.485790983s) 2020/03/09 05:49:15 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/03/09 05:49:15 DEBUG : one: Sizes differ (src 5 vs dst 3) 2020/03/09 05:49:15 INFO : Swift container rclone-test-zowemud3tamiyul5veyomos7 path BackupDir: Container "rclone-test-zowemud3tamiyul5veyomos7" created 2020/03/09 05:49:18 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/03/09 05:49:18 INFO : one: Copied (server side copy) 2020/03/09 05:49:18 INFO : one: Deleted 2020/03/09 05:49:20 DEBUG : one: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2020/03/09 05:49:20 INFO : one: Copied (server side copy) 2020/03/09 05:49:20 DEBUG : one: Destination found in --copy-dest, using server side copy 2020/03/09 05:49:23 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/03/09 05:49:25 DEBUG : two: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/03/09 05:49:25 INFO : two: Copied (server side copy) 2020/03/09 05:49:25 DEBUG : two: Destination found in --copy-dest, using server side copy 2020/03/09 05:49:25 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/03/09 05:49:25 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/03/09 05:49:25 DEBUG : two: Unchanged skipping 2020/03/09 05:49:29 DEBUG : three: Sizes differ (src 7 vs dst 5) 2020/03/09 05:49:29 DEBUG : three: Destination not found in --copy-dest 2020/03/09 05:49:29 DEBUG : three: Need to transfer - File not found at Destination 2020/03/09 05:49:30 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (39.91s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestSameConfig --- PASS: TestSameConfig (0.00s) === RUN TestSame --- PASS: TestSame (0.00s) === RUN TestOverlapping --- PASS: TestOverlapping (0.00s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestListFormat --- PASS: TestListFormat (0.00s) === RUN TestDirMove 2020/03/09 05:49:49 DEBUG : A1/B2: Making directory 2020/03/09 05:49:49 DEBUG : A1/B1/C3: Making directory 2020/03/09 05:49:52 DEBUG : A1/one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/03/09 05:49:52 INFO : A1/one: Copied (server side copy) 2020/03/09 05:49:52 DEBUG : A1/B1/C1/four: MD5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2020/03/09 05:49:52 INFO : A1/B1/C1/four: Copied (server side copy) 2020/03/09 05:49:53 DEBUG : A1/B1/C2/five: MD5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2020/03/09 05:49:53 INFO : A1/B1/C2/five: Copied (server side copy) 2020/03/09 05:49:53 INFO : A1/one: Deleted 2020/03/09 05:49:53 INFO : A1/B1/C1/four: Deleted 2020/03/09 05:49:53 INFO : A1/B1/C2/five: Deleted 2020/03/09 05:49:57 DEBUG : A1/B1/three: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/03/09 05:49:57 INFO : A1/B1/three: Copied (server side copy) 2020/03/09 05:49:57 INFO : A1/B1/three: Deleted 2020/03/09 05:49:58 DEBUG : A1/two: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/03/09 05:49:58 INFO : A1/two: Copied (server side copy) 2020/03/09 05:49:59 INFO : A1/two: Deleted 2020/03/09 05:50:02 DEBUG : A2/one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2020/03/09 05:50:02 INFO : A2/one: Copied (server side copy) 2020/03/09 05:50:02 DEBUG : A2/B1/C1/four: MD5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2020/03/09 05:50:02 INFO : A2/B1/C1/four: Copied (server side copy) 2020/03/09 05:50:02 INFO : A2/one: Deleted 2020/03/09 05:50:03 INFO : A2/B1/C1/four: Deleted 2020/03/09 05:50:03 DEBUG : A2/two: MD5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2020/03/09 05:50:03 INFO : A2/two: Copied (server side copy) 2020/03/09 05:50:04 INFO : A2/two: Deleted 2020/03/09 05:50:05 DEBUG : A2/B1/three: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2020/03/09 05:50:05 INFO : A2/B1/three: Copied (server side copy) 2020/03/09 05:50:05 INFO : A2/B1/three: Deleted 2020/03/09 05:50:07 DEBUG : A2/B1/C2/five: MD5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2020/03/09 05:50:07 INFO : A2/B1/C2/five: Copied (server side copy) 2020/03/09 05:50:08 INFO : A2/B1/C2/five: Deleted --- PASS: TestDirMove (34.67s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" fstest.go:241: Filtering empty directory "A1/B2" fstest.go:241: Filtering empty directory "A1/B1/C3" fstest.go:241: Filtering empty directory "A2/B2" fstest.go:241: Filtering empty directory "A2/B1/C3" fstest.go:241: Filtering empty directory "A3/B2" fstest.go:241: Filtering empty directory "A3/B1/C3" === RUN TestGetFsInfo --- PASS: TestGetFsInfo (0.12s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false 2020/03/09 05:50:15 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: File to upload is small (34 bytes), uploading instead of streaming 2020/03/09 05:50:16 INFO : no_checksum_small_file_from_pipe: Copied (new) 2020/03/09 05:50:18 DEBUG : no_checksum_big_file_from_pipe: Uploading segment file "no_checksum_big_file_from_pipe/1583733018.612068344/-1/00000000" into "rclone-test-zowemud3tamiyul5veyomos7_segments" 2020/03/09 05:50:20 DEBUG : no_checksum_big_file_from_pipe: Uploading segments into "rclone-test-zowemud3tamiyul5veyomos7_segments" seems done (EOF) 2020/03/09 05:50:24 DEBUG : no_checksum_big_file_from_pipe: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/03/09 05:50:24 DEBUG : no_checksum_big_file_from_pipe: Returning empty Md5sum for swift large object 2020/03/09 05:50:25 DEBUG : no_checksum_big_file_from_pipe: Removing segment file "no_checksum_big_file_from_pipe/1583733018.612068344/-1/00000000" in container "rclone-test-zowemud3tamiyul5veyomos7_segments" 2020/03/09 05:50:29 DEBUG : no_checksum_big_file_from_pipe: Removed empty container "rclone-test-zowemud3tamiyul5veyomos7_segments" === RUN TestRcat/withChecksum=true,ignoreChecksum=false 2020/03/09 05:50:30 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: File to upload is small (34 bytes), uploading instead of streaming 2020/03/09 05:50:32 INFO : with_checksum_small_file_from_pipe: Copied (new) 2020/03/09 05:50:32 DEBUG : with_checksum_big_file_from_pipe: Uploading segment file "with_checksum_big_file_from_pipe/1583733032.637851848/-1/00000000" into "rclone-test-zowemud3tamiyul5veyomos7_segments" 2020/03/09 05:50:36 DEBUG : with_checksum_big_file_from_pipe: Uploading segments into "rclone-test-zowemud3tamiyul5veyomos7_segments" seems done (EOF) 2020/03/09 05:50:42 DEBUG : with_checksum_big_file_from_pipe: Returning empty Md5sum for swift large object 2020/03/09 05:50:42 NOTICE: Swift container rclone-test-zowemud3tamiyul5veyomos7: --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2020/03/09 05:50:42 DEBUG : with_checksum_big_file_from_pipe: Size of src and dst objects identical 2020/03/09 05:50:42 DEBUG : with_checksum_big_file_from_pipe: Returning empty Md5sum for swift large object 2020/03/09 05:50:43 DEBUG : with_checksum_big_file_from_pipe: Removing segment file "with_checksum_big_file_from_pipe/1583733032.637851848/-1/00000000" in container "rclone-test-zowemud3tamiyul5veyomos7_segments" === RUN TestRcat/withChecksum=false,ignoreChecksum=true 2020/03/09 05:50:45 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: File to upload is small (34 bytes), uploading instead of streaming 2020/03/09 05:50:48 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2020/03/09 05:50:49 DEBUG : ignore_checksum_big_file_from_pipe: Uploading segment file "ignore_checksum_big_file_from_pipe/1583733049.531821396/-1/00000000" into "rclone-test-zowemud3tamiyul5veyomos7_segments" 2020/03/09 05:50:51 DEBUG : ignore_checksum_big_file_from_pipe: Uploading segments into "rclone-test-zowemud3tamiyul5veyomos7_segments" seems done (EOF) 2020/03/09 05:50:55 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by 0s, within tolerance 1ns) 2020/03/09 05:50:55 DEBUG : ignore_checksum_big_file_from_pipe: Returning empty Md5sum for swift large object 2020/03/09 05:50:57 DEBUG : ignore_checksum_big_file_from_pipe: Removing segment file "ignore_checksum_big_file_from_pipe/1583733049.531821396/-1/00000000" in container "rclone-test-zowemud3tamiyul5veyomos7_segments" 2020/03/09 05:50:59 DEBUG : ignore_checksum_big_file_from_pipe: Removed empty container "rclone-test-zowemud3tamiyul5veyomos7_segments" === RUN TestRcat/withChecksum=true,ignoreChecksum=true 2020/03/09 05:51:00 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: File to upload is small (34 bytes), uploading instead of streaming 2020/03/09 05:51:02 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2020/03/09 05:51:03 DEBUG : ignore_checksum_big_file_from_pipe: Uploading segment file "ignore_checksum_big_file_from_pipe/1583733063.56259545/-1/00000000" into "rclone-test-zowemud3tamiyul5veyomos7_segments" 2020/03/09 05:51:07 DEBUG : ignore_checksum_big_file_from_pipe: Uploading segments into "rclone-test-zowemud3tamiyul5veyomos7_segments" seems done (EOF) 2020/03/09 05:51:10 DEBUG : ignore_checksum_big_file_from_pipe: Returning empty Md5sum for swift large object 2020/03/09 05:51:10 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical 2020/03/09 05:51:10 DEBUG : ignore_checksum_big_file_from_pipe: Returning empty Md5sum for swift large object 2020/03/09 05:51:12 DEBUG : ignore_checksum_big_file_from_pipe: Removing segment file "ignore_checksum_big_file_from_pipe/1583733063.56259545/-1/00000000" in container "rclone-test-zowemud3tamiyul5veyomos7_segments" 2020/03/09 05:51:13 DEBUG : ignore_checksum_big_file_from_pipe: Removed empty container "rclone-test-zowemud3tamiyul5veyomos7_segments" --- PASS: TestRcat (60.00s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (15.52s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (15.20s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (14.72s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (14.56s) === RUN TestRcatSize 2020/03/09 05:51:18 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: File to upload is small (60 bytes), uploading instead of streaming 2020/03/09 05:51:20 INFO : potato2: Copied (new) --- PASS: TestRcatSize (7.82s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestCopyFileMaxTransfer 2020/03/09 05:51:23 DEBUG : file1: Need to transfer - File not found at Destination 2020/03/09 05:51:26 INFO : file1: Copied (new) 2020/03/09 05:51:26 DEBUG : file2: Need to transfer - File not found at Destination 2020/03/09 05:51:26 ERROR : file2: Failed to copy: Put https://lb9911.hubic.ovh.net/v1/AUTH_8ddbb879d8f66975e085651fc266c351/rclone-test-zowemud3tamiyul5veyomos7/sub/file2: Max transfer limit reached as set by --max-transfer 2020/03/09 05:51:27 DEBUG : file2: Need to transfer - File not found at Destination 2020/03/09 05:51:28 DEBUG : file2: Need to transfer - File not found at Destination 2020/03/09 05:51:30 INFO : file2: Copied (new) --- PASS: TestCopyFileMaxTransfer (10.48s) run.go:176: Remote "Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7", Local "Local file system at /tmp/rclone227902833", Modify Window "1ns" === RUN TestRcAbout --- SKIP: TestRcAbout (0.00s) rc_test.go:21: Skipping test on non local remote === RUN TestRcCleanup --- SKIP: TestRcCleanup (0.00s) rc_test.go:21: Skipping test on non local remote === RUN TestRcCopyfile --- SKIP: TestRcCopyfile (0.00s) rc_test.go:21: Skipping test on non local remote === RUN TestRcCopyurl --- SKIP: TestRcCopyurl (0.00s) rc_test.go:21: Skipping test on non local remote === RUN TestRcDelete --- SKIP: TestRcDelete (0.00s) rc_test.go:21: Skipping test on non local remote === RUN TestRcDeletefile --- SKIP: TestRcDeletefile (0.00s) rc_test.go:21: Skipping test on non local remote === RUN TestRcList --- SKIP: TestRcList (0.00s) rc_test.go:21: Skipping test on non local remote === RUN TestRcMkdir --- SKIP: TestRcMkdir (0.00s) rc_test.go:21: Skipping test on non local remote === RUN TestRcMovefile --- SKIP: TestRcMovefile (0.00s) rc_test.go:21: Skipping test on non local remote === RUN TestRcPurge --- SKIP: TestRcPurge (0.00s) rc_test.go:21: Skipping test on non local remote === RUN TestRcRmdir --- SKIP: TestRcRmdir (0.00s) rc_test.go:21: Skipping test on non local remote === RUN TestRcRmdirs --- SKIP: TestRcRmdirs (0.00s) rc_test.go:21: Skipping test on non local remote === RUN TestRcSize --- SKIP: TestRcSize (0.00s) rc_test.go:21: Skipping test on non local remote === RUN TestRcPublicLink --- SKIP: TestRcPublicLink (0.00s) rc_test.go:21: Skipping test on non local remote === RUN TestRcFsInfo --- SKIP: TestRcFsInfo (0.00s) rc_test.go:21: Skipping test on non local remote PASS 2020/03/09 05:51:33 DEBUG : Hubic Swift container rclone-test-zowemud3tamiyul5veyomos7: Purge remote 2020/03/09 05:51:33 DEBUG : Waiting for deletions to finish 2020/03/09 05:51:33 INFO : Swift container rclone-test-zowemud3tamiyul5veyomos7: Container "rclone-test-zowemud3tamiyul5veyomos7" removed "./operations.test -test.v -test.timeout 30m0s -remote TestHubic: -verbose" - Finished OK in 6m51.738734493s (try 1/5)