"./operations.test -test.v -test.timeout 30m0s -remote TestSftp: -verbose" - Starting (try 1/5) 2019/10/02 05:09:55 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: New connection 127.0.0.1:43600->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.6p1 Ubuntu-4ubuntu0.3" === 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} 2019/10/02 05:09:56 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:09:56 DEBUG : sftp output = "34c71a189bfac84497f54b67eeca999e rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:09:56 DEBUG : sftp hash = "34c71a189bfac84497f54b67eeca999e" 2019/10/02 05:09:56 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:09:56 DEBUG : sftp output = "ed3d134df1a2d8efbacf025ecdb498df0ecd302b rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:09:56 DEBUG : sftp hash = "ed3d134df1a2d8efbacf025ecdb498df0ecd302b" 2019/10/02 05:09:56 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64k 2019/10/02 05:09:56 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999k starting 2019/10/02 05:09:56 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k starting 2019/10/02 05:09:56 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999k finished 2019/10/02 05:09:56 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: New connection 127.0.0.1:43604->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.6p1 Ubuntu-4ubuntu0.3" 2019/10/02 05:09:57 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k finished 2019/10/02 05:09:57 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64k 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:09:57 DEBUG : sftp output = "34c71a189bfac84497f54b67eeca999e rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "34c71a189bfac84497f54b67eeca999e" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:09:57 DEBUG : sftp output = "ed3d134df1a2d8efbacf025ecdb498df0ecd302b rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "ed3d134df1a2d8efbacf025ecdb498df0ecd302b" === RUN TestMultithreadCopy/{size:131072_streams:2} 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:09:57 DEBUG : sftp output = "3971be4fb419962b9bde6184bad33ead rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "3971be4fb419962b9bde6184bad33ead" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:09:57 DEBUG : sftp output = "682d86a9a597eb1880bf4db6a8db757a5ff31a63 rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "682d86a9a597eb1880bf4db6a8db757a5ff31a63" 2019/10/02 05:09:57 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64k 2019/10/02 05:09:57 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64k starting 2019/10/02 05:09:57 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k starting 2019/10/02 05:09:57 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64k finished 2019/10/02 05:09:57 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131072) size 64k finished 2019/10/02 05:09:57 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64k 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:09:57 DEBUG : sftp output = "3971be4fb419962b9bde6184bad33ead rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "3971be4fb419962b9bde6184bad33ead" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:09:57 DEBUG : sftp output = "682d86a9a597eb1880bf4db6a8db757a5ff31a63 rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "682d86a9a597eb1880bf4db6a8db757a5ff31a63" === RUN TestMultithreadCopy/{size:131073_streams:2} 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:09:57 DEBUG : sftp output = "76ea0a376f00811c1113364e3c80a8fc rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "76ea0a376f00811c1113364e3c80a8fc" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:09:57 DEBUG : sftp output = "f14afdc970f2fcbf5b6c9c91c3f5ffb1c4781d24 rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "f14afdc970f2fcbf5b6c9c91c3f5ffb1c4781d24" 2019/10/02 05:09:57 DEBUG : file1: Starting multi-thread copy with 2 parts of size 128k 2019/10/02 05:09:57 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 starting 2019/10/02 05:09:57 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128k starting 2019/10/02 05:09:57 DEBUG : file1: multi-thread copy: stream 2/2 (131072-131073) size 1 finished 2019/10/02 05:09:57 DEBUG : file1: multi-thread copy: stream 1/2 (0-131072) size 128k finished 2019/10/02 05:09:57 DEBUG : file1: Finished multi-thread copy with 2 parts of size 128k 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:09:57 DEBUG : sftp output = "76ea0a376f00811c1113364e3c80a8fc rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "76ea0a376f00811c1113364e3c80a8fc" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:09:57 DEBUG : sftp output = "f14afdc970f2fcbf5b6c9c91c3f5ffb1c4781d24 rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "f14afdc970f2fcbf5b6c9c91c3f5ffb1c4781d24" --- PASS: TestMultithreadCopy (0.78s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" --- PASS: TestMultithreadCopy/{size:131071_streams:2} (0.71s) --- PASS: TestMultithreadCopy/{size:131072_streams:2} (0.04s) --- PASS: TestMultithreadCopy/{size:131073_streams:2} (0.04s) === RUN TestSizeDiffers --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Seek === RUN TestReOpen/Seek/Basics === RUN TestReOpen/Seek/ErrorAtStart === RUN TestReOpen/Seek/WithErrors 2019/10/02 05:09:57 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2019/10/02 05:09:57 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2019/10/02 05:09:57 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Seek/TooManyErrors 2019/10/02 05:09:57 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2019/10/02 05:09:57 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2019/10/02 05:09:57 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2019/10/02 05:09:57 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 2019/10/02 05:09:57 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/10: test error 2019/10/02 05:09:57 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/10: test error 2019/10/02 05:09:57 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/10: test error === RUN TestReOpen/Range/TooManyErrors 2019/10/02 05:09:57 DEBUG : potato: Reopening on read failure after 2 bytes: retry 1/3: test error 2019/10/02 05:09:57 DEBUG : potato: Reopening on read failure after 3 bytes: retry 2/3: test error 2019/10/02 05:09:57 DEBUG : potato: Reopening on read failure after 6 bytes: retry 3/3: test error 2019/10/02 05:09:57 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.00s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" dedupe_test.go:19: Can't test deduplicate - no duplicate files possible === RUN TestDeduplicateSkip --- SKIP: TestDeduplicateSkip (0.00s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" dedupe_test.go:19: Can't test deduplicate - no duplicate files possible === RUN TestDeduplicateFirst --- SKIP: TestDeduplicateFirst (0.00s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" dedupe_test.go:19: Can't test deduplicate - no duplicate files possible === RUN TestDeduplicateNewest --- SKIP: TestDeduplicateNewest (0.00s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" dedupe_test.go:19: Can't test deduplicate - no duplicate files possible === RUN TestDeduplicateOldest --- SKIP: TestDeduplicateOldest (0.00s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" dedupe_test.go:19: Can't test deduplicate - no duplicate files possible === RUN TestDeduplicateLargest --- SKIP: TestDeduplicateLargest (0.00s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" dedupe_test.go:19: Can't test deduplicate - no duplicate files possible === RUN TestDeduplicateRename --- SKIP: TestDeduplicateRename (0.00s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" dedupe_test.go:19: Can't test deduplicate - no duplicate files possible === RUN TestMergeDirs --- SKIP: TestMergeDirs (0.00s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" dedupe_test.go:197: Can't merge directories === RUN TestListDirSorted 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/a.txt 2019/10/02 05:09:57 DEBUG : sftp output = "5eb63bbbe01eeed093cb22bb8f5acdc3 rclone-test-roribom2zujeyed9kereyam3/a.txt\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "5eb63bbbe01eeed093cb22bb8f5acdc3" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/a.txt 2019/10/02 05:09:57 DEBUG : sftp output = "2aae6c35c94fcfb415dbe95f408b9ce91ee846ed rclone-test-roribom2zujeyed9kereyam3/a.txt\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "2aae6c35c94fcfb415dbe95f408b9ce91ee846ed" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/zend.txt 2019/10/02 05:09:57 DEBUG : sftp output = "5d41402abc4b2a76b9719d911017c592 rclone-test-roribom2zujeyed9kereyam3/zend.txt\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "5d41402abc4b2a76b9719d911017c592" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/zend.txt 2019/10/02 05:09:57 DEBUG : sftp output = "aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d rclone-test-roribom2zujeyed9kereyam3/zend.txt\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub\ dir/hello\ world 2019/10/02 05:09:57 DEBUG : sftp output = "5eb63bbbe01eeed093cb22bb8f5acdc3 rclone-test-roribom2zujeyed9kereyam3/sub dir/hello world\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "5eb63bbbe01eeed093cb22bb8f5acdc3" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub\ dir/hello\ world 2019/10/02 05:09:57 DEBUG : sftp output = "2aae6c35c94fcfb415dbe95f408b9ce91ee846ed rclone-test-roribom2zujeyed9kereyam3/sub dir/hello world\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "2aae6c35c94fcfb415dbe95f408b9ce91ee846ed" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub\ dir/hello\ world2 2019/10/02 05:09:57 DEBUG : sftp output = "5eb63bbbe01eeed093cb22bb8f5acdc3 rclone-test-roribom2zujeyed9kereyam3/sub dir/hello world2\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "5eb63bbbe01eeed093cb22bb8f5acdc3" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub\ dir/hello\ world2 2019/10/02 05:09:57 DEBUG : sftp output = "2aae6c35c94fcfb415dbe95f408b9ce91ee846ed rclone-test-roribom2zujeyed9kereyam3/sub dir/hello world2\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "2aae6c35c94fcfb415dbe95f408b9ce91ee846ed" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub\ dir/sub\ sub\ dir/hello\ world3 2019/10/02 05:09:57 DEBUG : sftp output = "5eb63bbbe01eeed093cb22bb8f5acdc3 rclone-test-roribom2zujeyed9kereyam3/sub dir/sub sub dir/hello world3\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "5eb63bbbe01eeed093cb22bb8f5acdc3" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub\ dir/sub\ sub\ dir/hello\ world3 2019/10/02 05:09:57 DEBUG : sftp output = "2aae6c35c94fcfb415dbe95f408b9ce91ee846ed rclone-test-roribom2zujeyed9kereyam3/sub dir/sub sub dir/hello world3\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "2aae6c35c94fcfb415dbe95f408b9ce91ee846ed" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub\ dir/ignore\ dir/.ignore 2019/10/02 05:09:57 DEBUG : sftp output = "336d5ebc5436534e61d16e63ddfca327 rclone-test-roribom2zujeyed9kereyam3/sub dir/ignore dir/.ignore\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "336d5ebc5436534e61d16e63ddfca327" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub\ dir/ignore\ dir/.ignore 2019/10/02 05:09:57 DEBUG : sftp output = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9 rclone-test-roribom2zujeyed9kereyam3/sub dir/ignore dir/.ignore\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub\ dir/ignore\ dir/should\ be\ ignored 2019/10/02 05:09:57 DEBUG : sftp output = "4b6adec83a97e5cb1ae5300b60fc8f80 rclone-test-roribom2zujeyed9kereyam3/sub dir/ignore dir/should be ignored\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "4b6adec83a97e5cb1ae5300b60fc8f80" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub\ dir/ignore\ dir/should\ be\ ignored 2019/10/02 05:09:57 DEBUG : sftp output = "18df938e1d79132fe9b501635288f829d9eea985 rclone-test-roribom2zujeyed9kereyam3/sub dir/ignore dir/should be ignored\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "18df938e1d79132fe9b501635288f829d9eea985" 2019/10/02 05:09:57 DEBUG : a.txt: Excluded 2019/10/02 05:09:57 DEBUG : sub dir/hello world2: Excluded 2019/10/02 05:09:57 DEBUG : sub dir/hello world: Excluded 2019/10/02 05:09:57 DEBUG : sub dir/hello world2: Excluded 2019/10/02 05:09:57 DEBUG : sub dir/ignore dir: Excluded 2019/10/02 05:09:57 DEBUG : sub dir/hello world: Excluded 2019/10/02 05:09:57 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (0.08s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestMkdir 2019/10/02 05:09:57 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: Making directory 2019/10/02 05:09:57 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: Making directory --- PASS: TestMkdir (0.00s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestLsd 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub\ dir/hello\ world 2019/10/02 05:09:57 DEBUG : sftp output = "5eb63bbbe01eeed093cb22bb8f5acdc3 rclone-test-roribom2zujeyed9kereyam3/sub dir/hello world\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "5eb63bbbe01eeed093cb22bb8f5acdc3" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub\ dir/hello\ world 2019/10/02 05:09:57 DEBUG : sftp output = "2aae6c35c94fcfb415dbe95f408b9ce91ee846ed rclone-test-roribom2zujeyed9kereyam3/sub dir/hello world\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "2aae6c35c94fcfb415dbe95f408b9ce91ee846ed" --- PASS: TestLsd (0.02s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestLs 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:57 DEBUG : sftp output = "336d5ebc5436534e61d16e63ddfca327 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "336d5ebc5436534e61d16e63ddfca327" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:57 DEBUG : sftp output = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:57 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:57 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" --- PASS: TestLs (0.02s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestLsWithFilesFrom 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:57 DEBUG : sftp output = "336d5ebc5436534e61d16e63ddfca327 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "336d5ebc5436534e61d16e63ddfca327" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:57 DEBUG : sftp output = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:57 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:57 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" 2019/10/02 05:09:57 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (0.02s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestLsLong 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:57 DEBUG : sftp output = "336d5ebc5436534e61d16e63ddfca327 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "336d5ebc5436534e61d16e63ddfca327" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:57 DEBUG : sftp output = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:57 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:57 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" --- PASS: TestLsLong (0.02s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestHashSums 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:57 DEBUG : sftp output = "336d5ebc5436534e61d16e63ddfca327 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "336d5ebc5436534e61d16e63ddfca327" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:57 DEBUG : sftp output = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:57 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:57 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:57 DEBUG : sftp output = "336d5ebc5436534e61d16e63ddfca327 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "336d5ebc5436534e61d16e63ddfca327" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:57 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:57 DEBUG : sftp output = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:57 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" --- PASS: TestHashSums (0.03s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:57 DEBUG : sftp output = "336d5ebc5436534e61d16e63ddfca327 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "336d5ebc5436534e61d16e63ddfca327" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:57 DEBUG : sftp output = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:57 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:57 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub\ dir/potato3 2019/10/02 05:09:57 DEBUG : sftp output = "5d41402abc4b2a76b9719d911017c592 rclone-test-roribom2zujeyed9kereyam3/sub dir/potato3\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "5d41402abc4b2a76b9719d911017c592" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub\ dir/potato3 2019/10/02 05:09:57 DEBUG : sftp output = "aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d rclone-test-roribom2zujeyed9kereyam3/sub dir/potato3\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d" --- PASS: TestCount (0.03s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestDelete 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/large 2019/10/02 05:09:57 DEBUG : sftp output = "8adc5937e635f6c9af646f0b23560fae rclone-test-roribom2zujeyed9kereyam3/large\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "8adc5937e635f6c9af646f0b23560fae" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/large 2019/10/02 05:09:57 DEBUG : sftp output = "7a4a9ae537ebbbb826b1060e704490ad0f365ead rclone-test-roribom2zujeyed9kereyam3/large\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "7a4a9ae537ebbbb826b1060e704490ad0f365ead" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/medium 2019/10/02 05:09:57 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/medium\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/medium 2019/10/02 05:09:57 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d rclone-test-roribom2zujeyed9kereyam3/medium\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/small 2019/10/02 05:09:57 DEBUG : sftp output = "e807f1fcf82d132f9bb018ca6738a19f rclone-test-roribom2zujeyed9kereyam3/small\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "e807f1fcf82d132f9bb018ca6738a19f" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/small 2019/10/02 05:09:57 DEBUG : sftp output = "01b307acba4f54f55aafc33bb06bbbf6ca803e9a rclone-test-roribom2zujeyed9kereyam3/small\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "01b307acba4f54f55aafc33bb06bbbf6ca803e9a" 2019/10/02 05:09:57 INFO : Waiting for deletions to finish 2019/10/02 05:09:57 DEBUG : large: Excluded 2019/10/02 05:09:57 INFO : small: Deleted 2019/10/02 05:09:57 INFO : medium: Deleted 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/large 2019/10/02 05:09:57 DEBUG : sftp output = "8adc5937e635f6c9af646f0b23560fae rclone-test-roribom2zujeyed9kereyam3/large\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "8adc5937e635f6c9af646f0b23560fae" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/large 2019/10/02 05:09:57 DEBUG : sftp output = "7a4a9ae537ebbbb826b1060e704490ad0f365ead rclone-test-roribom2zujeyed9kereyam3/large\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "7a4a9ae537ebbbb826b1060e704490ad0f365ead" --- PASS: TestDelete (0.03s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestCheck 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:57 DEBUG : sftp output = "a49405dcd1c6a545eace9d6825af944b rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "a49405dcd1c6a545eace9d6825af944b" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:57 DEBUG : sftp output = "01be66a605e122b67db7a40bfb9f171f24c5c8f4 rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "01be66a605e122b67db7a40bfb9f171f24c5c8f4" 2019/10/02 05:09:57 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 1: Starting check test 2019/10/02 05:09:57 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 2: Starting check test 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:57 DEBUG : sftp output = "336d5ebc5436534e61d16e63ddfca327 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "336d5ebc5436534e61d16e63ddfca327" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:57 DEBUG : sftp output = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:57 DEBUG : sftp output = "a49405dcd1c6a545eace9d6825af944b rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "a49405dcd1c6a545eace9d6825af944b" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:57 DEBUG : sftp output = "01be66a605e122b67db7a40bfb9f171f24c5c8f4 rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "01be66a605e122b67db7a40bfb9f171f24c5c8f4" 2019/10/02 05:09:57 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 3: Starting check test 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:57 DEBUG : sftp output = "336d5ebc5436534e61d16e63ddfca327 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "336d5ebc5436534e61d16e63ddfca327" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:57 DEBUG : sftp output = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:57 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:57 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:57 DEBUG : sftp output = "a49405dcd1c6a545eace9d6825af944b rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "a49405dcd1c6a545eace9d6825af944b" 2019/10/02 05:09:57 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:57 DEBUG : sftp output = "01be66a605e122b67db7a40bfb9f171f24c5c8f4 rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:57 DEBUG : sftp hash = "01be66a605e122b67db7a40bfb9f171f24c5c8f4" 2019/10/02 05:09:57 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 4: Starting check test 2019/10/02 05:09:57 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 5: Starting check test 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:58 DEBUG : sftp output = "336d5ebc5436534e61d16e63ddfca327 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "336d5ebc5436534e61d16e63ddfca327" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:58 DEBUG : sftp output = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:58 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:58 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/remotepotato 2019/10/02 05:09:58 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/remotepotato\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/remotepotato 2019/10/02 05:09:58 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d rclone-test-roribom2zujeyed9kereyam3/remotepotato\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "a49405dcd1c6a545eace9d6825af944b rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "a49405dcd1c6a545eace9d6825af944b" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "01be66a605e122b67db7a40bfb9f171f24c5c8f4 rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "01be66a605e122b67db7a40bfb9f171f24c5c8f4" 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 6: Starting check test 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 7: Starting check test --- PASS: TestCheck (0.15s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestCheckFsError 2019/10/02 05:09:58 INFO : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/non-existent: Waiting for checks to finish 2019/10/02 05:09:58 ERROR : : error reading source directory: directory not found 2019/10/02 05:09:58 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 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "a49405dcd1c6a545eace9d6825af944b rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "a49405dcd1c6a545eace9d6825af944b" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "01be66a605e122b67db7a40bfb9f171f24c5c8f4 rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "01be66a605e122b67db7a40bfb9f171f24c5c8f4" 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 1: Starting check test 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 2: Starting check test 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:58 DEBUG : sftp output = "336d5ebc5436534e61d16e63ddfca327 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "336d5ebc5436534e61d16e63ddfca327" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:58 DEBUG : sftp output = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "a49405dcd1c6a545eace9d6825af944b rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "a49405dcd1c6a545eace9d6825af944b" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "01be66a605e122b67db7a40bfb9f171f24c5c8f4 rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "01be66a605e122b67db7a40bfb9f171f24c5c8f4" 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 3: Starting check test 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:58 DEBUG : sftp output = "336d5ebc5436534e61d16e63ddfca327 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "336d5ebc5436534e61d16e63ddfca327" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:58 DEBUG : sftp output = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:58 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:58 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "a49405dcd1c6a545eace9d6825af944b rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "a49405dcd1c6a545eace9d6825af944b" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "01be66a605e122b67db7a40bfb9f171f24c5c8f4 rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "01be66a605e122b67db7a40bfb9f171f24c5c8f4" 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 4: Starting check test 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 5: Starting check test 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:58 DEBUG : sftp output = "336d5ebc5436534e61d16e63ddfca327 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "336d5ebc5436534e61d16e63ddfca327" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:58 DEBUG : sftp output = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:58 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:58 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/remotepotato 2019/10/02 05:09:58 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/remotepotato\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/remotepotato 2019/10/02 05:09:58 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d rclone-test-roribom2zujeyed9kereyam3/remotepotato\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "a49405dcd1c6a545eace9d6825af944b rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "a49405dcd1c6a545eace9d6825af944b" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "01be66a605e122b67db7a40bfb9f171f24c5c8f4 rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "01be66a605e122b67db7a40bfb9f171f24c5c8f4" 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 6: Starting check test 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 7: Starting check test --- PASS: TestCheckDownload (0.11s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestCheckSizeOnly 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "a49405dcd1c6a545eace9d6825af944b rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "a49405dcd1c6a545eace9d6825af944b" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "01be66a605e122b67db7a40bfb9f171f24c5c8f4 rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "01be66a605e122b67db7a40bfb9f171f24c5c8f4" 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 1: Starting check test 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 2: Starting check test 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:58 DEBUG : sftp output = "336d5ebc5436534e61d16e63ddfca327 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "336d5ebc5436534e61d16e63ddfca327" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:58 DEBUG : sftp output = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "a49405dcd1c6a545eace9d6825af944b rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "a49405dcd1c6a545eace9d6825af944b" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "01be66a605e122b67db7a40bfb9f171f24c5c8f4 rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "01be66a605e122b67db7a40bfb9f171f24c5c8f4" 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 3: Starting check test 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:58 DEBUG : sftp output = "336d5ebc5436534e61d16e63ddfca327 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "336d5ebc5436534e61d16e63ddfca327" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:58 DEBUG : sftp output = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:58 DEBUG : sftp output = "2ff4ef815de0b426b7da66694360a4c4 rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "2ff4ef815de0b426b7da66694360a4c4" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:58 DEBUG : sftp output = "e115e7e50dac0673c74cd7d01399dc706bd96500 rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "e115e7e50dac0673c74cd7d01399dc706bd96500" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "a49405dcd1c6a545eace9d6825af944b rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "a49405dcd1c6a545eace9d6825af944b" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "01be66a605e122b67db7a40bfb9f171f24c5c8f4 rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "01be66a605e122b67db7a40bfb9f171f24c5c8f4" 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 4: Starting check test 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 5: Starting check test 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:58 DEBUG : sftp output = "336d5ebc5436534e61d16e63ddfca327 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "336d5ebc5436534e61d16e63ddfca327" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/empty\ space 2019/10/02 05:09:58 DEBUG : sftp output = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9 rclone-test-roribom2zujeyed9kereyam3/empty space\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "3bc15c8aae3e4124dd409035f32ea2fd6835efc9" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:58 DEBUG : sftp output = "2ff4ef815de0b426b7da66694360a4c4 rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "2ff4ef815de0b426b7da66694360a4c4" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:09:58 DEBUG : sftp output = "e115e7e50dac0673c74cd7d01399dc706bd96500 rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "e115e7e50dac0673c74cd7d01399dc706bd96500" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/remotepotato 2019/10/02 05:09:58 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/remotepotato\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/remotepotato 2019/10/02 05:09:58 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d rclone-test-roribom2zujeyed9kereyam3/remotepotato\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "a49405dcd1c6a545eace9d6825af944b rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "a49405dcd1c6a545eace9d6825af944b" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/rutabaga 2019/10/02 05:09:58 DEBUG : sftp output = "01be66a605e122b67db7a40bfb9f171f24c5c8f4 rclone-test-roribom2zujeyed9kereyam3/rutabaga\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "01be66a605e122b67db7a40bfb9f171f24c5c8f4" 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 6: Starting check test 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: 7: Starting check test --- PASS: TestCheckSizeOnly (0.08s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestCat 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:09:58 DEBUG : sftp output = "e86410fa2d6e2634fd8ac5f4b3afe7f3 rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "e86410fa2d6e2634fd8ac5f4b3afe7f3" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:09:58 DEBUG : sftp output = "9f67efc6afa95f1aef9b3351d6b01d7e0591b748 rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "9f67efc6afa95f1aef9b3351d6b01d7e0591b748" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file2 2019/10/02 05:09:58 DEBUG : sftp output = "22975d8a5ed1b91445f6c55ac121505b rclone-test-roribom2zujeyed9kereyam3/file2\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "22975d8a5ed1b91445f6c55ac121505b" 2019/10/02 05:09:58 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file2 2019/10/02 05:09:58 DEBUG : sftp output = "9a7149a5a7786bb368e06d08c5d77774eb43a49e rclone-test-roribom2zujeyed9kereyam3/file2\n" 2019/10/02 05:09:58 DEBUG : sftp hash = "9a7149a5a7786bb368e06d08c5d77774eb43a49e" --- PASS: TestCat (0.03s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestPurge 2019/10/02 05:09:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2019/10/02 05:09:58 DEBUG : sftp://teststorage@localhost:22/rclone-test-yidawif3mivanak8jecayep3: New connection 127.0.0.1:43612->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.6p1 Ubuntu-4ubuntu0.3" 2019/10/02 05:09:58 DEBUG : A2: Making directory 2019/10/02 05:09:58 DEBUG : A1/B2: Making directory 2019/10/02 05:09:58 DEBUG : A1/B2/C2: Making directory 2019/10/02 05:09:58 DEBUG : A1/B1/C3: Making directory 2019/10/02 05:09:58 DEBUG : A3: Making directory 2019/10/02 05:09:58 DEBUG : A3/B3: Making directory 2019/10/02 05:09:58 DEBUG : A3/B3/C4: Making directory 2019/10/02 05:09:59 DEBUG : sftp://teststorage@localhost:22/rclone-test-yidawif3mivanak8jecayep3: New connection 127.0.0.1:43616->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.6p1 Ubuntu-4ubuntu0.3" 2019/10/02 05:09:59 DEBUG : sftp://teststorage@localhost:22/rclone-test-yidawif3mivanak8jecayep3: New connection 127.0.0.1:43626->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.6p1 Ubuntu-4ubuntu0.3" 2019/10/02 05:09:59 DEBUG : sftp://teststorage@localhost:22/rclone-test-yidawif3mivanak8jecayep3: New connection 127.0.0.1:43630->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.6p1 Ubuntu-4ubuntu0.3" 2019/10/02 05:10:00 DEBUG : sftp cmd = rclone-test-yidawif3mivanak8jecayep3/A1/two 2019/10/02 05:10:00 DEBUG : sftp output = "08f8e0260c64418510cefb2b06eee5cd rclone-test-yidawif3mivanak8jecayep3/A1/two\n" 2019/10/02 05:10:00 DEBUG : sftp hash = "08f8e0260c64418510cefb2b06eee5cd" 2019/10/02 05:10:00 DEBUG : sftp cmd = rclone-test-yidawif3mivanak8jecayep3/A1/two 2019/10/02 05:10:00 DEBUG : sftp output = "5cb138284d431abd6a053a56625ec088bfb88912 rclone-test-yidawif3mivanak8jecayep3/A1/two\n" 2019/10/02 05:10:00 DEBUG : sftp hash = "5cb138284d431abd6a053a56625ec088bfb88912" 2019/10/02 05:10:00 DEBUG : sftp cmd = rclone-test-yidawif3mivanak8jecayep3/A1/B1/C1/one 2019/10/02 05:10:00 DEBUG : sftp output = "47bce5c74f589f4867dbd57e9ca9f808 rclone-test-yidawif3mivanak8jecayep3/A1/B1/C1/one\n" 2019/10/02 05:10:00 DEBUG : sftp hash = "47bce5c74f589f4867dbd57e9ca9f808" 2019/10/02 05:10:00 DEBUG : sftp cmd = rclone-test-yidawif3mivanak8jecayep3/A1/B1/C1/one 2019/10/02 05:10:00 DEBUG : sftp output = "7e240de74fb1ed08fa08d38063f6a6a91462a815 rclone-test-yidawif3mivanak8jecayep3/A1/B1/C1/one\n" 2019/10/02 05:10:00 DEBUG : sftp hash = "7e240de74fb1ed08fa08d38063f6a6a91462a815" 2019/10/02 05:10:00 INFO : Waiting for deletions to finish 2019/10/02 05:10:00 INFO : A1/B1/C1/one: Deleted 2019/10/02 05:10:00 DEBUG : A1/B1/C3: Removing directory 2019/10/02 05:10:00 DEBUG : A1/B1/C1: Removing directory 2019/10/02 05:10:00 DEBUG : A1/B1: Removing directory 2019/10/02 05:10:00 DEBUG : sftp cmd = rclone-test-yidawif3mivanak8jecayep3/A1/two 2019/10/02 05:10:00 DEBUG : sftp output = "08f8e0260c64418510cefb2b06eee5cd rclone-test-yidawif3mivanak8jecayep3/A1/two\n" 2019/10/02 05:10:00 DEBUG : sftp hash = "08f8e0260c64418510cefb2b06eee5cd" 2019/10/02 05:10:00 DEBUG : sftp cmd = rclone-test-yidawif3mivanak8jecayep3/A1/two 2019/10/02 05:10:00 DEBUG : sftp output = "5cb138284d431abd6a053a56625ec088bfb88912 rclone-test-yidawif3mivanak8jecayep3/A1/two\n" 2019/10/02 05:10:00 DEBUG : sftp hash = "5cb138284d431abd6a053a56625ec088bfb88912" 2019/10/02 05:10:00 INFO : Waiting for deletions to finish 2019/10/02 05:10:00 INFO : A1/two: Deleted 2019/10/02 05:10:00 DEBUG : A3/B3/C4: Removing directory 2019/10/02 05:10:00 DEBUG : A3/B3: Removing directory 2019/10/02 05:10:00 DEBUG : A3: Removing directory 2019/10/02 05:10:00 DEBUG : A2: Removing directory 2019/10/02 05:10:00 DEBUG : A1/B2/C2: Removing directory 2019/10/02 05:10:00 DEBUG : A1/B2: Removing directory 2019/10/02 05:10:00 DEBUG : A1: Removing directory 2019/10/02 05:10:00 DEBUG : sftp://teststorage@localhost:22/rclone-test-yidawif3mivanak8jecayep3: Removing directory 2019/10/02 05:10:00 ERROR : : error listing: directory not found 2019/10/02 05:10:00 ERROR : : error listing: directory not found 2019/10/02 05:10:00 DEBUG : sftp://teststorage@localhost:22/rclone-test-yidawif3mivanak8jecayep3: Purge dir "" 2019/10/02 05:10:00 purge failed to rmdir "": Rmdir: directory not found 2019/10/02 05:10:00 purge failed: directory not found --- PASS: TestPurge (2.18s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-yidawif3mivanak8jecayep3", Local "Local file system at /tmp/rclone026289401", Modify Window "1s" === RUN TestRmdirsNoLeaveRoot 2019/10/02 05:10:00 DEBUG : A2: Making directory 2019/10/02 05:10:00 DEBUG : A1/B2: Making directory 2019/10/02 05:10:00 DEBUG : A1/B2/C2: Making directory 2019/10/02 05:10:00 DEBUG : A1/B1/C3: Making directory 2019/10/02 05:10:00 DEBUG : A3: Making directory 2019/10/02 05:10:00 DEBUG : A3/B3: Making directory 2019/10/02 05:10:00 DEBUG : A3/B3/C4: Making directory 2019/10/02 05:10:00 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: New connection 127.0.0.1:43634->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.6p1 Ubuntu-4ubuntu0.3" 2019/10/02 05:10:00 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: New connection 127.0.0.1:43638->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.6p1 Ubuntu-4ubuntu0.3" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/two 2019/10/02 05:10:01 DEBUG : sftp output = "08f8e0260c64418510cefb2b06eee5cd rclone-test-roribom2zujeyed9kereyam3/A1/two\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "08f8e0260c64418510cefb2b06eee5cd" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/two 2019/10/02 05:10:01 DEBUG : sftp output = "5cb138284d431abd6a053a56625ec088bfb88912 rclone-test-roribom2zujeyed9kereyam3/A1/two\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "5cb138284d431abd6a053a56625ec088bfb88912" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/B1/C1/one 2019/10/02 05:10:01 DEBUG : sftp output = "47bce5c74f589f4867dbd57e9ca9f808 rclone-test-roribom2zujeyed9kereyam3/A1/B1/C1/one\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "47bce5c74f589f4867dbd57e9ca9f808" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/B1/C1/one 2019/10/02 05:10:01 DEBUG : sftp output = "7e240de74fb1ed08fa08d38063f6a6a91462a815 rclone-test-roribom2zujeyed9kereyam3/A1/B1/C1/one\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "7e240de74fb1ed08fa08d38063f6a6a91462a815" 2019/10/02 05:10:01 DEBUG : A3/B3/C4: Removing directory 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/two 2019/10/02 05:10:01 DEBUG : sftp output = "08f8e0260c64418510cefb2b06eee5cd rclone-test-roribom2zujeyed9kereyam3/A1/two\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "08f8e0260c64418510cefb2b06eee5cd" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/two 2019/10/02 05:10:01 DEBUG : sftp output = "5cb138284d431abd6a053a56625ec088bfb88912 rclone-test-roribom2zujeyed9kereyam3/A1/two\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "5cb138284d431abd6a053a56625ec088bfb88912" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/B1/C1/one 2019/10/02 05:10:01 DEBUG : sftp output = "47bce5c74f589f4867dbd57e9ca9f808 rclone-test-roribom2zujeyed9kereyam3/A1/B1/C1/one\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "47bce5c74f589f4867dbd57e9ca9f808" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/B1/C1/one 2019/10/02 05:10:01 DEBUG : sftp output = "7e240de74fb1ed08fa08d38063f6a6a91462a815 rclone-test-roribom2zujeyed9kereyam3/A1/B1/C1/one\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "7e240de74fb1ed08fa08d38063f6a6a91462a815" 2019/10/02 05:10:01 DEBUG : A3/B3: Removing directory 2019/10/02 05:10:01 DEBUG : A3: Removing directory 2019/10/02 05:10:01 DEBUG : A2: Removing directory 2019/10/02 05:10:01 DEBUG : A1/B2/C2: Removing directory 2019/10/02 05:10:01 DEBUG : A1/B2: Removing directory 2019/10/02 05:10:01 DEBUG : A1/B1/C3: Removing directory 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/two 2019/10/02 05:10:01 DEBUG : sftp output = "08f8e0260c64418510cefb2b06eee5cd rclone-test-roribom2zujeyed9kereyam3/A1/two\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "08f8e0260c64418510cefb2b06eee5cd" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/two 2019/10/02 05:10:01 DEBUG : sftp output = "5cb138284d431abd6a053a56625ec088bfb88912 rclone-test-roribom2zujeyed9kereyam3/A1/two\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "5cb138284d431abd6a053a56625ec088bfb88912" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/B1/C1/one 2019/10/02 05:10:01 DEBUG : sftp output = "47bce5c74f589f4867dbd57e9ca9f808 rclone-test-roribom2zujeyed9kereyam3/A1/B1/C1/one\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "47bce5c74f589f4867dbd57e9ca9f808" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/B1/C1/one 2019/10/02 05:10:01 DEBUG : sftp output = "7e240de74fb1ed08fa08d38063f6a6a91462a815 rclone-test-roribom2zujeyed9kereyam3/A1/B1/C1/one\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "7e240de74fb1ed08fa08d38063f6a6a91462a815" --- PASS: TestRmdirsNoLeaveRoot (0.89s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestRmdirsLeaveRoot 2019/10/02 05:10:01 DEBUG : A1: Making directory 2019/10/02 05:10:01 DEBUG : A1/B1: Making directory 2019/10/02 05:10:01 DEBUG : A1/B1/C1: Making directory 2019/10/02 05:10:01 DEBUG : A1/B1/C1: Removing directory 2019/10/02 05:10:01 DEBUG : A1/B1: Removing directory --- PASS: TestRmdirsLeaveRoot (0.02s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestCopyURL 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:10:01 DEBUG : sftp output = "081404b3d2ae5bf599add15b7445ac07 rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "081404b3d2ae5bf599add15b7445ac07" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:10:01 DEBUG : sftp output = "3c3771c1f3304bdb052b101cc97a23df1ab9531f rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "3c3771c1f3304bdb052b101cc97a23df1ab9531f" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:10:01 DEBUG : sftp output = "081404b3d2ae5bf599add15b7445ac07 rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "081404b3d2ae5bf599add15b7445ac07" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file1 2019/10/02 05:10:01 DEBUG : sftp output = "3c3771c1f3304bdb052b101cc97a23df1ab9531f rclone-test-roribom2zujeyed9kereyam3/file1\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "3c3771c1f3304bdb052b101cc97a23df1ab9531f" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file2 2019/10/02 05:10:01 DEBUG : sftp output = "081404b3d2ae5bf599add15b7445ac07 rclone-test-roribom2zujeyed9kereyam3/file2\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "081404b3d2ae5bf599add15b7445ac07" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/file2 2019/10/02 05:10:01 DEBUG : sftp output = "3c3771c1f3304bdb052b101cc97a23df1ab9531f rclone-test-roribom2zujeyed9kereyam3/file2\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "3c3771c1f3304bdb052b101cc97a23df1ab9531f" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/filename.txt 2019/10/02 05:10:01 DEBUG : sftp output = "081404b3d2ae5bf599add15b7445ac07 rclone-test-roribom2zujeyed9kereyam3/filename.txt\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "081404b3d2ae5bf599add15b7445ac07" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/filename.txt 2019/10/02 05:10:01 DEBUG : sftp output = "3c3771c1f3304bdb052b101cc97a23df1ab9531f rclone-test-roribom2zujeyed9kereyam3/filename.txt\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "3c3771c1f3304bdb052b101cc97a23df1ab9531f" --- PASS: TestCopyURL (0.04s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestMoveFile 2019/10/02 05:10:01 DEBUG : file1: Couldn't find file - need to transfer 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub/file2 2019/10/02 05:10:01 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 rclone-test-roribom2zujeyed9kereyam3/sub/file2\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2019/10/02 05:10:01 DEBUG : file1: MD5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2019/10/02 05:10:01 INFO : file1: Copied (new) 2019/10/02 05:10:01 INFO : file1: Deleted 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub/file2 2019/10/02 05:10:01 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 rclone-test-roribom2zujeyed9kereyam3/sub/file2\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub/file2 2019/10/02 05:10:01 DEBUG : sftp output = "a379624177abc4679cafafa8eae1d73e1478aaa6 rclone-test-roribom2zujeyed9kereyam3/sub/file2\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "a379624177abc4679cafafa8eae1d73e1478aaa6" 2019/10/02 05:10:01 DEBUG : file1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2019/10/02 05:10:01 DEBUG : file1: Unchanged skipping 2019/10/02 05:10:01 INFO : file1: Deleted 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub/file2 2019/10/02 05:10:01 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 rclone-test-roribom2zujeyed9kereyam3/sub/file2\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub/file2 2019/10/02 05:10:01 DEBUG : sftp output = "a379624177abc4679cafafa8eae1d73e1478aaa6 rclone-test-roribom2zujeyed9kereyam3/sub/file2\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "a379624177abc4679cafafa8eae1d73e1478aaa6" 2019/10/02 05:10:01 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: don't need to copy/move sub/file2, it is already at target location 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub/file2 2019/10/02 05:10:01 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 rclone-test-roribom2zujeyed9kereyam3/sub/file2\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub/file2 2019/10/02 05:10:01 DEBUG : sftp output = "a379624177abc4679cafafa8eae1d73e1478aaa6 rclone-test-roribom2zujeyed9kereyam3/sub/file2\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "a379624177abc4679cafafa8eae1d73e1478aaa6" --- PASS: TestMoveFile (0.04s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestCaseInsensitiveMoveFile --- PASS: TestCaseInsensitiveMoveFile (0.00s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestMoveFileBackupDir 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/file1 2019/10/02 05:10:01 DEBUG : sftp output = "05164b153084ba910184c26e561a7c18 rclone-test-roribom2zujeyed9kereyam3/dst/file1\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "05164b153084ba910184c26e561a7c18" 2019/10/02 05:10:01 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/file1 2019/10/02 05:10:01 DEBUG : sftp output = "9d125cddbdc7323ccf9db2d2b67af177f781b5c6 rclone-test-roribom2zujeyed9kereyam3/dst/file1\n" 2019/10/02 05:10:01 DEBUG : sftp hash = "9d125cddbdc7323ccf9db2d2b67af177f781b5c6" 2019/10/02 05:10:01 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3/backup: New connection 127.0.0.1:43652->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.6p1 Ubuntu-4ubuntu0.3" 2019/10/02 05:10:02 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2019/10/02 05:10:02 INFO : dst/file1: Moved (server side) 2019/10/02 05:10:02 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/file1 2019/10/02 05:10:02 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 rclone-test-roribom2zujeyed9kereyam3/dst/file1\n" 2019/10/02 05:10:02 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2019/10/02 05:10:02 DEBUG : dst/file1: MD5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2019/10/02 05:10:02 INFO : dst/file1: Copied (new) 2019/10/02 05:10:02 INFO : dst/file1: Deleted 2019/10/02 05:10:02 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/file1 2019/10/02 05:10:02 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 rclone-test-roribom2zujeyed9kereyam3/dst/file1\n" 2019/10/02 05:10:02 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2019/10/02 05:10:02 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/file1 2019/10/02 05:10:02 DEBUG : sftp output = "a379624177abc4679cafafa8eae1d73e1478aaa6 rclone-test-roribom2zujeyed9kereyam3/dst/file1\n" 2019/10/02 05:10:02 DEBUG : sftp hash = "a379624177abc4679cafafa8eae1d73e1478aaa6" 2019/10/02 05:10:02 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/backup/dst/file1 2019/10/02 05:10:02 DEBUG : sftp output = "05164b153084ba910184c26e561a7c18 rclone-test-roribom2zujeyed9kereyam3/backup/dst/file1\n" 2019/10/02 05:10:02 DEBUG : sftp hash = "05164b153084ba910184c26e561a7c18" 2019/10/02 05:10:02 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/backup/dst/file1 2019/10/02 05:10:02 DEBUG : sftp output = "9d125cddbdc7323ccf9db2d2b67af177f781b5c6 rclone-test-roribom2zujeyed9kereyam3/backup/dst/file1\n" 2019/10/02 05:10:02 DEBUG : sftp hash = "9d125cddbdc7323ccf9db2d2b67af177f781b5c6" --- PASS: TestMoveFileBackupDir (0.77s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestCopyFile 2019/10/02 05:10:02 DEBUG : file1: Couldn't find file - need to transfer 2019/10/02 05:10:02 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub/file2 2019/10/02 05:10:02 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 rclone-test-roribom2zujeyed9kereyam3/sub/file2\n" 2019/10/02 05:10:02 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2019/10/02 05:10:02 DEBUG : file1: MD5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2019/10/02 05:10:02 INFO : file1: Copied (new) 2019/10/02 05:10:02 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub/file2 2019/10/02 05:10:02 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 rclone-test-roribom2zujeyed9kereyam3/sub/file2\n" 2019/10/02 05:10:02 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2019/10/02 05:10:02 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub/file2 2019/10/02 05:10:02 DEBUG : sftp output = "a379624177abc4679cafafa8eae1d73e1478aaa6 rclone-test-roribom2zujeyed9kereyam3/sub/file2\n" 2019/10/02 05:10:02 DEBUG : sftp hash = "a379624177abc4679cafafa8eae1d73e1478aaa6" 2019/10/02 05:10:02 DEBUG : file1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2019/10/02 05:10:02 DEBUG : file1: Unchanged skipping 2019/10/02 05:10:02 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub/file2 2019/10/02 05:10:02 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 rclone-test-roribom2zujeyed9kereyam3/sub/file2\n" 2019/10/02 05:10:02 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2019/10/02 05:10:02 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub/file2 2019/10/02 05:10:02 DEBUG : sftp output = "a379624177abc4679cafafa8eae1d73e1478aaa6 rclone-test-roribom2zujeyed9kereyam3/sub/file2\n" 2019/10/02 05:10:02 DEBUG : sftp hash = "a379624177abc4679cafafa8eae1d73e1478aaa6" 2019/10/02 05:10:02 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: don't need to copy/move sub/file2, it is already at target location 2019/10/02 05:10:02 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub/file2 2019/10/02 05:10:02 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 rclone-test-roribom2zujeyed9kereyam3/sub/file2\n" 2019/10/02 05:10:02 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2019/10/02 05:10:02 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/sub/file2 2019/10/02 05:10:02 DEBUG : sftp output = "a379624177abc4679cafafa8eae1d73e1478aaa6 rclone-test-roribom2zujeyed9kereyam3/sub/file2\n" 2019/10/02 05:10:02 DEBUG : sftp hash = "a379624177abc4679cafafa8eae1d73e1478aaa6" --- PASS: TestCopyFile (0.04s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestCopyFileBackupDir 2019/10/02 05:10:02 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/file1 2019/10/02 05:10:02 DEBUG : sftp output = "05164b153084ba910184c26e561a7c18 rclone-test-roribom2zujeyed9kereyam3/dst/file1\n" 2019/10/02 05:10:02 DEBUG : sftp hash = "05164b153084ba910184c26e561a7c18" 2019/10/02 05:10:02 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/file1 2019/10/02 05:10:02 DEBUG : sftp output = "9d125cddbdc7323ccf9db2d2b67af177f781b5c6 rclone-test-roribom2zujeyed9kereyam3/dst/file1\n" 2019/10/02 05:10:02 DEBUG : sftp hash = "9d125cddbdc7323ccf9db2d2b67af177f781b5c6" 2019/10/02 05:10:02 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3/backup: New connection 127.0.0.1:43662->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.6p1 Ubuntu-4ubuntu0.3" 2019/10/02 05:10:03 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18) 2019/10/02 05:10:03 INFO : dst/file1: Moved (server side) 2019/10/02 05:10:03 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/file1 2019/10/02 05:10:03 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 rclone-test-roribom2zujeyed9kereyam3/dst/file1\n" 2019/10/02 05:10:03 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2019/10/02 05:10:03 DEBUG : dst/file1: MD5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2019/10/02 05:10:03 INFO : dst/file1: Copied (new) 2019/10/02 05:10:03 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/file1 2019/10/02 05:10:03 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 rclone-test-roribom2zujeyed9kereyam3/dst/file1\n" 2019/10/02 05:10:03 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2019/10/02 05:10:03 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/file1 2019/10/02 05:10:03 DEBUG : sftp output = "a379624177abc4679cafafa8eae1d73e1478aaa6 rclone-test-roribom2zujeyed9kereyam3/dst/file1\n" 2019/10/02 05:10:03 DEBUG : sftp hash = "a379624177abc4679cafafa8eae1d73e1478aaa6" 2019/10/02 05:10:03 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/backup/dst/file1 2019/10/02 05:10:03 DEBUG : sftp output = "05164b153084ba910184c26e561a7c18 rclone-test-roribom2zujeyed9kereyam3/backup/dst/file1\n" 2019/10/02 05:10:03 DEBUG : sftp hash = "05164b153084ba910184c26e561a7c18" 2019/10/02 05:10:03 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/backup/dst/file1 2019/10/02 05:10:03 DEBUG : sftp output = "9d125cddbdc7323ccf9db2d2b67af177f781b5c6 rclone-test-roribom2zujeyed9kereyam3/backup/dst/file1\n" 2019/10/02 05:10:03 DEBUG : sftp hash = "9d125cddbdc7323ccf9db2d2b67af177f781b5c6" --- PASS: TestCopyFileBackupDir (0.82s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestCopyFileCompareDest 2019/10/02 05:10:03 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3/dst: New connection 127.0.0.1:43670->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.6p1 Ubuntu-4ubuntu0.3" 2019/10/02 05:10:03 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3/CompareDest: New connection 127.0.0.1:43678->127.0.0.1:22 to "SSH-2.0-OpenSSH_7.6p1 Ubuntu-4ubuntu0.3" 2019/10/02 05:10:04 DEBUG : one: Couldn't find file - need to transfer 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "f97c5d29941bfb1b2fdab0874906ab82 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "f97c5d29941bfb1b2fdab0874906ab82" 2019/10/02 05:10:04 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2019/10/02 05:10:04 INFO : one: Copied (new) 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "f97c5d29941bfb1b2fdab0874906ab82 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "f97c5d29941bfb1b2fdab0874906ab82" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "f97c5d29941bfb1b2fdab0874906ab82 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "f97c5d29941bfb1b2fdab0874906ab82" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106" 2019/10/02 05:10:04 DEBUG : one: Sizes differ (src 5 vs dst 3) 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "07912d142f5d63ee918b34796b5a2432 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "07912d142f5d63ee918b34796b5a2432" 2019/10/02 05:10:04 DEBUG : one: MD5 = 07912d142f5d63ee918b34796b5a2432 OK 2019/10/02 05:10:04 INFO : one: Copied (replaced existing) 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "07912d142f5d63ee918b34796b5a2432 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "07912d142f5d63ee918b34796b5a2432" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "7f376fd65843a1267de8f986d40e164e5a321c13 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "7f376fd65843a1267de8f986d40e164e5a321c13" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/one 2019/10/02 05:10:04 DEBUG : sftp output = "07912d142f5d63ee918b34796b5a2432 rclone-test-roribom2zujeyed9kereyam3/CompareDest/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "07912d142f5d63ee918b34796b5a2432" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/one 2019/10/02 05:10:04 DEBUG : sftp output = "7f376fd65843a1267de8f986d40e164e5a321c13 rclone-test-roribom2zujeyed9kereyam3/CompareDest/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "7f376fd65843a1267de8f986d40e164e5a321c13" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "f97c5d29941bfb1b2fdab0874906ab82 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "f97c5d29941bfb1b2fdab0874906ab82" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106" 2019/10/02 05:10:04 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2019/10/02 05:10:04 DEBUG : one: Destination found in --compare-dest, skipping 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/one 2019/10/02 05:10:04 DEBUG : sftp output = "07912d142f5d63ee918b34796b5a2432 rclone-test-roribom2zujeyed9kereyam3/CompareDest/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "07912d142f5d63ee918b34796b5a2432" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/one 2019/10/02 05:10:04 DEBUG : sftp output = "7f376fd65843a1267de8f986d40e164e5a321c13 rclone-test-roribom2zujeyed9kereyam3/CompareDest/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "7f376fd65843a1267de8f986d40e164e5a321c13" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "f97c5d29941bfb1b2fdab0874906ab82 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "f97c5d29941bfb1b2fdab0874906ab82" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "f97c5d29941bfb1b2fdab0874906ab82 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "f97c5d29941bfb1b2fdab0874906ab82" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/one 2019/10/02 05:10:04 DEBUG : sftp output = "07912d142f5d63ee918b34796b5a2432 rclone-test-roribom2zujeyed9kereyam3/CompareDest/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "07912d142f5d63ee918b34796b5a2432" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/one 2019/10/02 05:10:04 DEBUG : sftp output = "7f376fd65843a1267de8f986d40e164e5a321c13 rclone-test-roribom2zujeyed9kereyam3/CompareDest/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "7f376fd65843a1267de8f986d40e164e5a321c13" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/two 2019/10/02 05:10:04 DEBUG : sftp output = "b8a9f715dbb64fd5c56e7783c6820a61 rclone-test-roribom2zujeyed9kereyam3/CompareDest/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "b8a9f715dbb64fd5c56e7783c6820a61" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/two 2019/10/02 05:10:04 DEBUG : sftp output = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b rclone-test-roribom2zujeyed9kereyam3/CompareDest/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b" 2019/10/02 05:10:04 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2019/10/02 05:10:04 DEBUG : two: Destination found in --compare-dest, skipping 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "f97c5d29941bfb1b2fdab0874906ab82 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "f97c5d29941bfb1b2fdab0874906ab82" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/one 2019/10/02 05:10:04 DEBUG : sftp output = "07912d142f5d63ee918b34796b5a2432 rclone-test-roribom2zujeyed9kereyam3/CompareDest/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "07912d142f5d63ee918b34796b5a2432" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/one 2019/10/02 05:10:04 DEBUG : sftp output = "7f376fd65843a1267de8f986d40e164e5a321c13 rclone-test-roribom2zujeyed9kereyam3/CompareDest/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "7f376fd65843a1267de8f986d40e164e5a321c13" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/two 2019/10/02 05:10:04 DEBUG : sftp output = "b8a9f715dbb64fd5c56e7783c6820a61 rclone-test-roribom2zujeyed9kereyam3/CompareDest/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "b8a9f715dbb64fd5c56e7783c6820a61" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/two 2019/10/02 05:10:04 DEBUG : sftp output = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b rclone-test-roribom2zujeyed9kereyam3/CompareDest/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b" 2019/10/02 05:10:04 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2019/10/02 05:10:04 DEBUG : two: Destination found in --compare-dest, skipping 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "f97c5d29941bfb1b2fdab0874906ab82 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "f97c5d29941bfb1b2fdab0874906ab82" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/one 2019/10/02 05:10:04 DEBUG : sftp output = "07912d142f5d63ee918b34796b5a2432 rclone-test-roribom2zujeyed9kereyam3/CompareDest/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "07912d142f5d63ee918b34796b5a2432" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/one 2019/10/02 05:10:04 DEBUG : sftp output = "7f376fd65843a1267de8f986d40e164e5a321c13 rclone-test-roribom2zujeyed9kereyam3/CompareDest/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "7f376fd65843a1267de8f986d40e164e5a321c13" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/two 2019/10/02 05:10:04 DEBUG : sftp output = "b8a9f715dbb64fd5c56e7783c6820a61 rclone-test-roribom2zujeyed9kereyam3/CompareDest/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "b8a9f715dbb64fd5c56e7783c6820a61" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/two 2019/10/02 05:10:04 DEBUG : sftp output = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b rclone-test-roribom2zujeyed9kereyam3/CompareDest/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/one 2019/10/02 05:10:04 DEBUG : sftp output = "07912d142f5d63ee918b34796b5a2432 rclone-test-roribom2zujeyed9kereyam3/CompareDest/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "07912d142f5d63ee918b34796b5a2432" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/one 2019/10/02 05:10:04 DEBUG : sftp output = "7f376fd65843a1267de8f986d40e164e5a321c13 rclone-test-roribom2zujeyed9kereyam3/CompareDest/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "7f376fd65843a1267de8f986d40e164e5a321c13" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/two 2019/10/02 05:10:04 DEBUG : sftp output = "b8a9f715dbb64fd5c56e7783c6820a61 rclone-test-roribom2zujeyed9kereyam3/CompareDest/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "b8a9f715dbb64fd5c56e7783c6820a61" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/two 2019/10/02 05:10:04 DEBUG : sftp output = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b rclone-test-roribom2zujeyed9kereyam3/CompareDest/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "f97c5d29941bfb1b2fdab0874906ab82 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "f97c5d29941bfb1b2fdab0874906ab82" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106" 2019/10/02 05:10:04 DEBUG : two: Sizes differ (src 5 vs dst 3) 2019/10/02 05:10:04 DEBUG : two: Couldn't find file - need to transfer 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/two 2019/10/02 05:10:04 DEBUG : sftp output = "2379e4ce8c3380e996ab0509f17069ad rclone-test-roribom2zujeyed9kereyam3/dst/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "2379e4ce8c3380e996ab0509f17069ad" 2019/10/02 05:10:04 DEBUG : two: MD5 = 2379e4ce8c3380e996ab0509f17069ad OK 2019/10/02 05:10:04 INFO : two: Copied (new) 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/one 2019/10/02 05:10:04 DEBUG : sftp output = "07912d142f5d63ee918b34796b5a2432 rclone-test-roribom2zujeyed9kereyam3/CompareDest/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "07912d142f5d63ee918b34796b5a2432" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/one 2019/10/02 05:10:04 DEBUG : sftp output = "7f376fd65843a1267de8f986d40e164e5a321c13 rclone-test-roribom2zujeyed9kereyam3/CompareDest/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "7f376fd65843a1267de8f986d40e164e5a321c13" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/two 2019/10/02 05:10:04 DEBUG : sftp output = "b8a9f715dbb64fd5c56e7783c6820a61 rclone-test-roribom2zujeyed9kereyam3/CompareDest/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "b8a9f715dbb64fd5c56e7783c6820a61" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/CompareDest/two 2019/10/02 05:10:04 DEBUG : sftp output = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b rclone-test-roribom2zujeyed9kereyam3/CompareDest/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "f97c5d29941bfb1b2fdab0874906ab82 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "f97c5d29941bfb1b2fdab0874906ab82" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/one 2019/10/02 05:10:04 DEBUG : sftp output = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106 rclone-test-roribom2zujeyed9kereyam3/dst/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/two 2019/10/02 05:10:04 DEBUG : sftp output = "2379e4ce8c3380e996ab0509f17069ad rclone-test-roribom2zujeyed9kereyam3/dst/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "2379e4ce8c3380e996ab0509f17069ad" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/dst/two 2019/10/02 05:10:04 DEBUG : sftp output = "a4be8877ad88f3109a6411985a1f359e10e2d930 rclone-test-roribom2zujeyed9kereyam3/dst/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "a4be8877ad88f3109a6411985a1f359e10e2d930" --- PASS: TestCopyFileCompareDest (1.70s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestCopyFileCopyDest --- SKIP: TestCopyFileCopyDest (0.00s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" operations_test.go:896: Skipping test as remote does not support server side copy === 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 2019/10/02 05:10:04 DEBUG : A1/B2: Making directory 2019/10/02 05:10:04 DEBUG : A1/B1/C3: Making directory 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/one 2019/10/02 05:10:04 DEBUG : sftp output = "f97c5d29941bfb1b2fdab0874906ab82 rclone-test-roribom2zujeyed9kereyam3/A1/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "f97c5d29941bfb1b2fdab0874906ab82" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/one 2019/10/02 05:10:04 DEBUG : sftp output = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106 rclone-test-roribom2zujeyed9kereyam3/A1/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/two 2019/10/02 05:10:04 DEBUG : sftp output = "b8a9f715dbb64fd5c56e7783c6820a61 rclone-test-roribom2zujeyed9kereyam3/A1/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "b8a9f715dbb64fd5c56e7783c6820a61" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/two 2019/10/02 05:10:04 DEBUG : sftp output = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b rclone-test-roribom2zujeyed9kereyam3/A1/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/B1/three 2019/10/02 05:10:04 DEBUG : sftp output = "35d6d33467aae9a2e3dccb4b6b027878 rclone-test-roribom2zujeyed9kereyam3/A1/B1/three\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "35d6d33467aae9a2e3dccb4b6b027878" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/B1/three 2019/10/02 05:10:04 DEBUG : sftp output = "b802f384302cb24fbab0a44997e820bf2e8507bb rclone-test-roribom2zujeyed9kereyam3/A1/B1/three\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "b802f384302cb24fbab0a44997e820bf2e8507bb" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/B1/C1/four 2019/10/02 05:10:04 DEBUG : sftp output = "8cbad96aced40b3838dd9f07f6ef5772 rclone-test-roribom2zujeyed9kereyam3/A1/B1/C1/four\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "8cbad96aced40b3838dd9f07f6ef5772" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/B1/C1/four 2019/10/02 05:10:04 DEBUG : sftp output = "9f8f7eec5dea5ac43738721939c120318cbff1df rclone-test-roribom2zujeyed9kereyam3/A1/B1/C1/four\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "9f8f7eec5dea5ac43738721939c120318cbff1df" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/B1/C2/five 2019/10/02 05:10:04 DEBUG : sftp output = "30056e1cab7a61d256fc8edd970d14f5 rclone-test-roribom2zujeyed9kereyam3/A1/B1/C2/five\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "30056e1cab7a61d256fc8edd970d14f5" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A1/B1/C2/five 2019/10/02 05:10:04 DEBUG : sftp output = "4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 rclone-test-roribom2zujeyed9kereyam3/A1/B1/C2/five\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A2/one 2019/10/02 05:10:04 DEBUG : sftp output = "f97c5d29941bfb1b2fdab0874906ab82 rclone-test-roribom2zujeyed9kereyam3/A2/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "f97c5d29941bfb1b2fdab0874906ab82" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A2/one 2019/10/02 05:10:04 DEBUG : sftp output = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106 rclone-test-roribom2zujeyed9kereyam3/A2/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A2/two 2019/10/02 05:10:04 DEBUG : sftp output = "b8a9f715dbb64fd5c56e7783c6820a61 rclone-test-roribom2zujeyed9kereyam3/A2/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "b8a9f715dbb64fd5c56e7783c6820a61" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A2/two 2019/10/02 05:10:04 DEBUG : sftp output = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b rclone-test-roribom2zujeyed9kereyam3/A2/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A2/B1/three 2019/10/02 05:10:04 DEBUG : sftp output = "35d6d33467aae9a2e3dccb4b6b027878 rclone-test-roribom2zujeyed9kereyam3/A2/B1/three\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "35d6d33467aae9a2e3dccb4b6b027878" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A2/B1/three 2019/10/02 05:10:04 DEBUG : sftp output = "b802f384302cb24fbab0a44997e820bf2e8507bb rclone-test-roribom2zujeyed9kereyam3/A2/B1/three\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "b802f384302cb24fbab0a44997e820bf2e8507bb" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A2/B1/C1/four 2019/10/02 05:10:04 DEBUG : sftp output = "8cbad96aced40b3838dd9f07f6ef5772 rclone-test-roribom2zujeyed9kereyam3/A2/B1/C1/four\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "8cbad96aced40b3838dd9f07f6ef5772" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A2/B1/C1/four 2019/10/02 05:10:04 DEBUG : sftp output = "9f8f7eec5dea5ac43738721939c120318cbff1df rclone-test-roribom2zujeyed9kereyam3/A2/B1/C1/four\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "9f8f7eec5dea5ac43738721939c120318cbff1df" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A2/B1/C2/five 2019/10/02 05:10:04 DEBUG : sftp output = "30056e1cab7a61d256fc8edd970d14f5 rclone-test-roribom2zujeyed9kereyam3/A2/B1/C2/five\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "30056e1cab7a61d256fc8edd970d14f5" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A2/B1/C2/five 2019/10/02 05:10:04 DEBUG : sftp output = "4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 rclone-test-roribom2zujeyed9kereyam3/A2/B1/C2/five\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00" 2019/10/02 05:10:04 INFO : A2/B1/C1/four: Moved (server side) 2019/10/02 05:10:04 INFO : A2/B1/three: Moved (server side) 2019/10/02 05:10:04 INFO : A2/two: Moved (server side) 2019/10/02 05:10:04 INFO : A2/one: Moved (server side) 2019/10/02 05:10:04 INFO : A2/B1/C2/five: Moved (server side) 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A3/one 2019/10/02 05:10:04 DEBUG : sftp output = "f97c5d29941bfb1b2fdab0874906ab82 rclone-test-roribom2zujeyed9kereyam3/A3/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "f97c5d29941bfb1b2fdab0874906ab82" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A3/one 2019/10/02 05:10:04 DEBUG : sftp output = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106 rclone-test-roribom2zujeyed9kereyam3/A3/one\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A3/two 2019/10/02 05:10:04 DEBUG : sftp output = "b8a9f715dbb64fd5c56e7783c6820a61 rclone-test-roribom2zujeyed9kereyam3/A3/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "b8a9f715dbb64fd5c56e7783c6820a61" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A3/two 2019/10/02 05:10:04 DEBUG : sftp output = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b rclone-test-roribom2zujeyed9kereyam3/A3/two\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A3/B1/three 2019/10/02 05:10:04 DEBUG : sftp output = "35d6d33467aae9a2e3dccb4b6b027878 rclone-test-roribom2zujeyed9kereyam3/A3/B1/three\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "35d6d33467aae9a2e3dccb4b6b027878" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A3/B1/three 2019/10/02 05:10:04 DEBUG : sftp output = "b802f384302cb24fbab0a44997e820bf2e8507bb rclone-test-roribom2zujeyed9kereyam3/A3/B1/three\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "b802f384302cb24fbab0a44997e820bf2e8507bb" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A3/B1/C2/five 2019/10/02 05:10:04 DEBUG : sftp output = "30056e1cab7a61d256fc8edd970d14f5 rclone-test-roribom2zujeyed9kereyam3/A3/B1/C2/five\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "30056e1cab7a61d256fc8edd970d14f5" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A3/B1/C2/five 2019/10/02 05:10:04 DEBUG : sftp output = "4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 rclone-test-roribom2zujeyed9kereyam3/A3/B1/C2/five\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A3/B1/C1/four 2019/10/02 05:10:04 DEBUG : sftp output = "8cbad96aced40b3838dd9f07f6ef5772 rclone-test-roribom2zujeyed9kereyam3/A3/B1/C1/four\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "8cbad96aced40b3838dd9f07f6ef5772" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/A3/B1/C1/four 2019/10/02 05:10:04 DEBUG : sftp output = "9f8f7eec5dea5ac43738721939c120318cbff1df rclone-test-roribom2zujeyed9kereyam3/A3/B1/C1/four\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "9f8f7eec5dea5ac43738721939c120318cbff1df" --- PASS: TestDirMove (0.15s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestGetFsInfo --- PASS: TestGetFsInfo (0.00s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === 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 === RUN TestRcat 2019/10/02 05:10:04 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: File to upload is small (34 bytes), uploading instead of streaming 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/with_checksum_small_file_from_pipe 2019/10/02 05:10:04 DEBUG : sftp output = "4c762bc6ec18f21b23fcae426c7863b5 rclone-test-roribom2zujeyed9kereyam3/with_checksum_small_file_from_pipe\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "4c762bc6ec18f21b23fcae426c7863b5" 2019/10/02 05:10:04 DEBUG : with_checksum_small_file_from_pipe: MD5 = 4c762bc6ec18f21b23fcae426c7863b5 OK 2019/10/02 05:10:04 INFO : with_checksum_small_file_from_pipe: Copied (new) 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/with_checksum_big_file_from_pipe 2019/10/02 05:10:04 DEBUG : sftp output = "fffc7956ba9a7b58a63c01b6ce1ddc45 rclone-test-roribom2zujeyed9kereyam3/with_checksum_big_file_from_pipe\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "fffc7956ba9a7b58a63c01b6ce1ddc45" 2019/10/02 05:10:04 DEBUG : with_checksum_big_file_from_pipe: MD5 = fffc7956ba9a7b58a63c01b6ce1ddc45 OK 2019/10/02 05:10:04 DEBUG : with_checksum_big_file_from_pipe: Size and MD5 of src and dst objects identical 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/with_checksum_big_file_from_pipe 2019/10/02 05:10:04 DEBUG : sftp output = "fffc7956ba9a7b58a63c01b6ce1ddc45 rclone-test-roribom2zujeyed9kereyam3/with_checksum_big_file_from_pipe\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "fffc7956ba9a7b58a63c01b6ce1ddc45" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/with_checksum_big_file_from_pipe 2019/10/02 05:10:04 DEBUG : sftp output = "d0080f73aa8b494570ad63df1beb25c01ea9eea2 rclone-test-roribom2zujeyed9kereyam3/with_checksum_big_file_from_pipe\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "d0080f73aa8b494570ad63df1beb25c01ea9eea2" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/with_checksum_small_file_from_pipe 2019/10/02 05:10:04 DEBUG : sftp output = "4c762bc6ec18f21b23fcae426c7863b5 rclone-test-roribom2zujeyed9kereyam3/with_checksum_small_file_from_pipe\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "4c762bc6ec18f21b23fcae426c7863b5" 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/with_checksum_small_file_from_pipe 2019/10/02 05:10:04 DEBUG : sftp output = "4c3452689ee5e2b950454c46181b8b5e7bcfeca9 rclone-test-roribom2zujeyed9kereyam3/with_checksum_small_file_from_pipe\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "4c3452689ee5e2b950454c46181b8b5e7bcfeca9" 2019/10/02 05:10:04 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: File to upload is small (34 bytes), uploading instead of streaming 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/no_checksum_small_file_from_pipe 2019/10/02 05:10:04 DEBUG : sftp output = "4c762bc6ec18f21b23fcae426c7863b5 rclone-test-roribom2zujeyed9kereyam3/no_checksum_small_file_from_pipe\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "4c762bc6ec18f21b23fcae426c7863b5" 2019/10/02 05:10:04 DEBUG : no_checksum_small_file_from_pipe: MD5 = 4c762bc6ec18f21b23fcae426c7863b5 OK 2019/10/02 05:10:04 INFO : no_checksum_small_file_from_pipe: Copied (new) 2019/10/02 05:10:04 DEBUG : no_checksum_big_file_from_pipe: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2019/10/02 05:10:04 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/no_checksum_big_file_from_pipe 2019/10/02 05:10:04 DEBUG : sftp output = "fffc7956ba9a7b58a63c01b6ce1ddc45 rclone-test-roribom2zujeyed9kereyam3/no_checksum_big_file_from_pipe\n" 2019/10/02 05:10:04 DEBUG : sftp hash = "fffc7956ba9a7b58a63c01b6ce1ddc45" 2019/10/02 05:10:05 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/no_checksum_big_file_from_pipe 2019/10/02 05:10:05 DEBUG : sftp output = "d0080f73aa8b494570ad63df1beb25c01ea9eea2 rclone-test-roribom2zujeyed9kereyam3/no_checksum_big_file_from_pipe\n" 2019/10/02 05:10:05 DEBUG : sftp hash = "d0080f73aa8b494570ad63df1beb25c01ea9eea2" 2019/10/02 05:10:05 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/no_checksum_small_file_from_pipe 2019/10/02 05:10:05 DEBUG : sftp output = "4c762bc6ec18f21b23fcae426c7863b5 rclone-test-roribom2zujeyed9kereyam3/no_checksum_small_file_from_pipe\n" 2019/10/02 05:10:05 DEBUG : sftp hash = "4c762bc6ec18f21b23fcae426c7863b5" 2019/10/02 05:10:05 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/no_checksum_small_file_from_pipe 2019/10/02 05:10:05 DEBUG : sftp output = "4c3452689ee5e2b950454c46181b8b5e7bcfeca9 rclone-test-roribom2zujeyed9kereyam3/no_checksum_small_file_from_pipe\n" 2019/10/02 05:10:05 DEBUG : sftp hash = "4c3452689ee5e2b950454c46181b8b5e7bcfeca9" --- PASS: TestRcat (0.08s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" === RUN TestRcatSize 2019/10/02 05:10:05 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: File to upload is small (60 bytes), uploading instead of streaming 2019/10/02 05:10:05 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:10:05 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:10:05 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:10:05 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2019/10/02 05:10:05 INFO : potato2: Copied (new) 2019/10/02 05:10:05 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato1 2019/10/02 05:10:05 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/potato1\n" 2019/10/02 05:10:05 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:10:05 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato1 2019/10/02 05:10:05 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d rclone-test-roribom2zujeyed9kereyam3/potato1\n" 2019/10/02 05:10:05 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" 2019/10/02 05:10:05 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:10:05 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:10:05 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2019/10/02 05:10:05 DEBUG : sftp cmd = rclone-test-roribom2zujeyed9kereyam3/potato2 2019/10/02 05:10:05 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d rclone-test-roribom2zujeyed9kereyam3/potato2\n" 2019/10/02 05:10:05 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" --- PASS: TestRcatSize (0.02s) run.go:176: Remote "sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3", Local "Local file system at /tmp/rclone056508143", Modify Window "1s" PASS 2019/10/02 05:10:05 DEBUG : sftp://teststorage@localhost:22/rclone-test-roribom2zujeyed9kereyam3: Purge dir "" "./operations.test -test.v -test.timeout 30m0s -remote TestSftp: -verbose" - Finished OK in 9.214431442s (try 1/5)