"./operations.test -test.v -test.timeout 1h0m0s -remote TestSFTPRclone: -verbose -test.run '^(TestCopyFileMaxTransfer|TestDirMove|TestGetFsInfo|TestRcat|TestRcatSize)$'" - Starting (try 3/5) 2020/11/03 06:23:42 DEBUG : Creating backend with remote "TestSFTPRclone:rclone-test-wiqogab7gutoyav8fojibiq7" 2020/11/03 06:23:42 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: New connection 172.17.0.1:42660->172.17.0.3:22 to "SSH-2.0-rclone/v1.53.2" 2020/11/03 06:23:42 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: Using absolute root directory "/rclone-test-wiqogab7gutoyav8fojibiq7" 2020/11/03 06:23:42 DEBUG : Creating backend with remote "/tmp/rclone738578292" === RUN TestDirMove run.go:176: Remote "sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7", Local "Local file system at /tmp/rclone738578292", Modify Window "1s" 2020/11/03 06:23:42 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: checking "md5sum" command: "d41d8cd98f00b204e9800998ecf8427e -" 2020/11/03 06:23:42 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: checking "sha1sum" command: "da39a3ee5e6b4b0d3255bfef95601890afd80709 -" 2020/11/03 06:23:42 DEBUG : Saving config "md5sum_command" = "md5sum" in section "TestSFTPRclone" of the config file 2020/11/03 06:23:42 DEBUG : Saving config "sha1sum_command" = "sha1sum" in section "TestSFTPRclone" of the config file 2020/11/03 06:23:42 DEBUG : A1/B2: Making directory 2020/11/03 06:23:42 DEBUG : A1/B1/C3: Making directory 2020/11/03 06:23:42 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: New connection 172.17.0.1:42662->172.17.0.3:22 to "SSH-2.0-rclone/v1.53.2" 2020/11/03 06:23:42 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: New connection 172.17.0.1:42664->172.17.0.3:22 to "SSH-2.0-rclone/v1.53.2" 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A1/one 2020/11/03 06:23:42 DEBUG : sftp output = "f97c5d29941bfb1b2fdab0874906ab82 /rclone-test-wiqogab7gutoyav8fojibiq7/A1/one\n" 2020/11/03 06:23:42 DEBUG : sftp hash = "f97c5d29941bfb1b2fdab0874906ab82" 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A1/one 2020/11/03 06:23:42 DEBUG : sftp output = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106 /rclone-test-wiqogab7gutoyav8fojibiq7/A1/one\n" 2020/11/03 06:23:42 DEBUG : sftp hash = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106" 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A1/two 2020/11/03 06:23:42 DEBUG : sftp output = "b8a9f715dbb64fd5c56e7783c6820a61 /rclone-test-wiqogab7gutoyav8fojibiq7/A1/two\n" 2020/11/03 06:23:42 DEBUG : sftp hash = "b8a9f715dbb64fd5c56e7783c6820a61" 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A1/two 2020/11/03 06:23:42 DEBUG : sftp output = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b /rclone-test-wiqogab7gutoyav8fojibiq7/A1/two\n" 2020/11/03 06:23:42 DEBUG : sftp hash = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b" 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/three 2020/11/03 06:23:42 DEBUG : sftp output = "35d6d33467aae9a2e3dccb4b6b027878 /rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/three\n" 2020/11/03 06:23:42 DEBUG : sftp hash = "35d6d33467aae9a2e3dccb4b6b027878" 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/three 2020/11/03 06:23:42 DEBUG : sftp output = "b802f384302cb24fbab0a44997e820bf2e8507bb /rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/three\n" 2020/11/03 06:23:42 DEBUG : sftp hash = "b802f384302cb24fbab0a44997e820bf2e8507bb" 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/C1/four 2020/11/03 06:23:42 DEBUG : sftp output = "8cbad96aced40b3838dd9f07f6ef5772 /rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/C1/four\n" 2020/11/03 06:23:42 DEBUG : sftp hash = "8cbad96aced40b3838dd9f07f6ef5772" 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/C1/four 2020/11/03 06:23:42 DEBUG : sftp output = "9f8f7eec5dea5ac43738721939c120318cbff1df /rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/C1/four\n" 2020/11/03 06:23:42 DEBUG : sftp hash = "9f8f7eec5dea5ac43738721939c120318cbff1df" 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/C2/five 2020/11/03 06:23:42 DEBUG : sftp output = "30056e1cab7a61d256fc8edd970d14f5 /rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/C2/five\n" 2020/11/03 06:23:42 DEBUG : sftp hash = "30056e1cab7a61d256fc8edd970d14f5" 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/C2/five 2020/11/03 06:23:42 DEBUG : sftp output = "4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00 /rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/C2/five\n" 2020/11/03 06:23:42 DEBUG : sftp hash = "4db2c1df4610cd6c0da6b9197b066bfb4e9b9d00" 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A2/one 2020/11/03 06:23:42 DEBUG : sftp output = "f97c5d29941bfb1b2fdab0874906ab82 /rclone-test-wiqogab7gutoyav8fojibiq7/A2/one\n" 2020/11/03 06:23:42 DEBUG : sftp hash = "f97c5d29941bfb1b2fdab0874906ab82" 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A2/one 2020/11/03 06:23:42 DEBUG : sftp output = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106 /rclone-test-wiqogab7gutoyav8fojibiq7/A2/one\n" 2020/11/03 06:23:42 DEBUG : sftp hash = "fe05bcdcdc4928012781a5f1a2a77cbb5398e106" 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A2/two 2020/11/03 06:23:42 DEBUG : sftp output = "b8a9f715dbb64fd5c56e7783c6820a61 /rclone-test-wiqogab7gutoyav8fojibiq7/A2/two\n" 2020/11/03 06:23:42 DEBUG : sftp hash = "b8a9f715dbb64fd5c56e7783c6820a61" 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A2/two 2020/11/03 06:23:42 DEBUG : sftp output = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b /rclone-test-wiqogab7gutoyav8fojibiq7/A2/two\n" 2020/11/03 06:23:42 DEBUG : sftp hash = "ad782ecdac770fc6eb9a62e44f90873fb97fb26b" 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A2/B1/three 2020/11/03 06:23:42 DEBUG : A2/B1/three: Failed to calculate MD5 hash: Process exited with status 1 (hash failed: hash: failed to open: open /data/rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/three: no such file or directory) 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A2/B1/three 2020/11/03 06:23:42 DEBUG : A2/B1/three: Failed to calculate SHA-1 hash: Process exited with status 1 (hash failed: hash: failed to open: open /data/rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/three: no such file or directory) 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A2/B1/C1/four 2020/11/03 06:23:42 DEBUG : A2/B1/C1/four: Failed to calculate MD5 hash: Process exited with status 1 (hash failed: hash: failed to open: open /data/rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/C1/four: no such file or directory) 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A2/B1/C1/four 2020/11/03 06:23:42 DEBUG : A2/B1/C1/four: Failed to calculate SHA-1 hash: Process exited with status 1 (hash failed: hash: failed to open: open /data/rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/C1/four: no such file or directory) 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A2/B1/C2/five 2020/11/03 06:23:42 DEBUG : A2/B1/C2/five: Failed to calculate MD5 hash: Process exited with status 1 (hash failed: hash: failed to open: open /data/rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/C2/five: no such file or directory) 2020/11/03 06:23:42 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/A2/B1/C2/five 2020/11/03 06:23:42 DEBUG : A2/B1/C2/five: Failed to calculate SHA-1 hash: Process exited with status 1 (hash failed: hash: failed to open: open /data/rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/C2/five: no such file or directory) 2020/11/03 06:23:42 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: New connection 172.17.0.1:42666->172.17.0.3:22 to "SSH-2.0-rclone/v1.53.2" 2020/11/03 06:23:42 ERROR : A2/B1/C2/five: Couldn't move: Move Rename failed: sftp: "rename /data/rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/C2/five /data/rclone-test-wiqogab7gutoyav8fojibiq7/A3/B1/C2/five: no such file or directory" (SSH_FX_FAILURE) 2020/11/03 06:23:42 ERROR : A2/B1/C1/four: Couldn't move: Move Rename failed: sftp: "rename /data/rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/C1/four /data/rclone-test-wiqogab7gutoyav8fojibiq7/A3/B1/C1/four: no such file or directory" (SSH_FX_FAILURE) 2020/11/03 06:23:42 INFO : A2/one: Moved (server-side) to: A3/one 2020/11/03 06:23:42 INFO : A2/two: Moved (server-side) to: A3/two operations_test.go:1266: Error Trace: operations_test.go:1266 Error: Received unexpected error: Move Rename failed: sftp: "rename /data/rclone-test-wiqogab7gutoyav8fojibiq7/A1/B1/C2/five /data/rclone-test-wiqogab7gutoyav8fojibiq7/A3/B1/C2/five: no such file or directory" (SSH_FX_FAILURE) RenameDir renames github.com/rclone/rclone/fs/operations.DirMove /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:1907 github.com/rclone/rclone/fs/operations_test.TestDirMove /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1266 testing.tRunner /usr/local/go/src/testing/testing.go:1123 runtime.goexit /usr/local/go/src/runtime/asm_amd64.s:1374 Test: TestDirMove run.go:121: removing file "A2/B1/three" failed - try 1/3: file does not exist run.go:121: removing file "A2/B1/three" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/three" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/three" failed: file does not exist 2020/11/03 06:23:45 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: New connection 172.17.0.1:42668->172.17.0.3:22 to "SSH-2.0-rclone/v1.53.2" run.go:121: removing file "A2/B1/C1/four" failed - try 1/3: file does not exist 2020/11/03 06:23:45 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: New connection 172.17.0.1:42670->172.17.0.3:22 to "SSH-2.0-rclone/v1.53.2" run.go:121: removing file "A2/B1/C1/four" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/C1/four" failed: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 1/3: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/C2/five" failed: file does not exist run.go:121: removing dir "A2/B2" failed - try 1/3: file does not exist run.go:121: removing dir "A2/B2" failed - try 2/3: file does not exist run.go:121: removing dir "A2/B2" failed - try 3/3: file does not exist run.go:124: removing dir "A2/B2" failed: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 1/3: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 2/3: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 3/3: file does not exist run.go:124: removing dir "A2/B1/C3" failed: file does not exist run.go:121: removing dir "A2/B1/C2" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1/C2" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1/C2" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1/C2" failed: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1/C1" failed: directory not empty run.go:121: removing dir "A2/B1" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1" failed: directory not empty run.go:121: removing dir "A2" failed - try 1/3: directory not empty run.go:121: removing dir "A2" failed - try 2/3: directory not empty run.go:121: removing dir "A2" failed - try 3/3: directory not empty run.go:124: removing dir "A2" failed: directory not empty fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 run.go:169 run.go:339 panic.go:617 testing.go:732 operations_test.go:1266 Error: Should be true Test: TestDirMove Messages: listing wrong, want got A2/B1/C1/four (4), A2/B1/C2/five (4) fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 panic.go:617 testing.go:732 operations_test.go:1266 Error: Should be true Test: TestDirMove Messages: Unexpected file "A2/B1/C1/four" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 panic.go:617 testing.go:732 operations_test.go:1266 Error: Should be true Test: TestDirMove Messages: Unexpected file "A2/B1/C2/five" fstest.go:324: Error Trace: fstest.go:324 fstest.go:335 run.go:169 run.go:339 panic.go:617 testing.go:732 operations_test.go:1266 Error: Not equal: expected: []string{} actual : []string{"A2", "A2/B1", "A2/B1/C1", "A2/B1/C2", "A2/B1/C3", "A2/B2"} Diff: --- Expected +++ Actual @@ -1,2 +1,8 @@ -([]string) { +([]string) (len=6) { + (string) (len=2) "A2", + (string) (len=5) "A2/B1", + (string) (len=8) "A2/B1/C1", + (string) (len=8) "A2/B1/C2", + (string) (len=8) "A2/B1/C3", + (string) (len=5) "A2/B2" } Test: TestDirMove Messages: directories --- FAIL: TestDirMove (34.45s) === RUN TestGetFsInfo run.go:176: Remote "sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7", Local "Local file system at /tmp/rclone738578292", Modify Window "1s" run.go:121: removing file "A2/B1/C1/four" failed - try 1/3: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/C1/four" failed: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 1/3: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/C2/five" failed: file does not exist run.go:121: removing dir "A2/B2" failed - try 1/3: file does not exist run.go:121: removing dir "A2/B2" failed - try 2/3: file does not exist run.go:121: removing dir "A2/B2" failed - try 3/3: file does not exist run.go:124: removing dir "A2/B2" failed: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 1/3: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 2/3: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 3/3: file does not exist run.go:124: removing dir "A2/B1/C3" failed: file does not exist run.go:121: removing dir "A2/B1/C2" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1/C2" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1/C2" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1/C2" failed: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1/C1" failed: directory not empty run.go:121: removing dir "A2/B1" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1" failed: directory not empty run.go:121: removing dir "A2" failed - try 1/3: directory not empty run.go:121: removing dir "A2" failed - try 2/3: directory not empty run.go:121: removing dir "A2" failed - try 3/3: directory not empty run.go:124: removing dir "A2" failed: directory not empty fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 run.go:169 run.go:339 operations_test.go:1307 Error: Should be true Test: TestGetFsInfo Messages: listing wrong, want got A2/B1/C1/four (4), A2/B1/C2/five (4) fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 operations_test.go:1307 Error: Should be true Test: TestGetFsInfo Messages: Unexpected file "A2/B1/C1/four" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 operations_test.go:1307 Error: Should be true Test: TestGetFsInfo Messages: Unexpected file "A2/B1/C2/five" fstest.go:324: Error Trace: fstest.go:324 fstest.go:335 run.go:169 run.go:339 operations_test.go:1307 Error: Not equal: expected: []string{} actual : []string{"A2", "A2/B1", "A2/B1/C1", "A2/B1/C2", "A2/B1/C3", "A2/B2"} Diff: --- Expected +++ Actual @@ -1,2 +1,8 @@ -([]string) { +([]string) (len=6) { + (string) (len=2) "A2", + (string) (len=5) "A2/B1", + (string) (len=8) "A2/B1/C1", + (string) (len=8) "A2/B1/C2", + (string) (len=8) "A2/B1/C3", + (string) (len=5) "A2/B2" } Test: TestGetFsInfo Messages: directories --- FAIL: TestGetFsInfo (31.11s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false === CONT TestRcat run.go:176: Remote "sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7", Local "Local file system at /tmp/rclone738578292", Modify Window "1s" fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 fstest.go:341 operations_test.go:1339 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: listing wrong, want got A2/B1/C1/four (4), A2/B1/C2/five (4) fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:341 operations_test.go:1339 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C1/four" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:341 operations_test.go:1339 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C2/five" 2020/11/03 06:24:54 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: File to upload is small (34 bytes), uploading instead of streaming 2020/11/03 06:24:54 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/no_checksum_small_file_from_pipe 2020/11/03 06:24:54 DEBUG : sftp output = "4c762bc6ec18f21b23fcae426c7863b5 /rclone-test-wiqogab7gutoyav8fojibiq7/no_checksum_small_file_from_pipe\n" 2020/11/03 06:24:54 DEBUG : sftp hash = "4c762bc6ec18f21b23fcae426c7863b5" 2020/11/03 06:24:54 DEBUG : no_checksum_small_file_from_pipe: MD5 = 4c762bc6ec18f21b23fcae426c7863b5 OK 2020/11/03 06:24:54 INFO : no_checksum_small_file_from_pipe: Copied (new) 2020/11/03 06:24:54 DEBUG : no_checksum_big_file_from_pipe: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 fstest.go:347 operations_test.go:1357 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: listing wrong, want no_checksum_big_file_from_pipe (102401), no_checksum_small_file_from_pipe (34) got A2/B1/C1/four (4), A2/B1/C2/five (4), no_checksum_big_file_from_pipe (102401), no_checksum_small_file_from_pipe (34) 2020/11/03 06:25:02 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/no_checksum_big_file_from_pipe 2020/11/03 06:25:02 DEBUG : sftp output = "fffc7956ba9a7b58a63c01b6ce1ddc45 /rclone-test-wiqogab7gutoyav8fojibiq7/no_checksum_big_file_from_pipe\n" 2020/11/03 06:25:02 DEBUG : sftp hash = "fffc7956ba9a7b58a63c01b6ce1ddc45" 2020/11/03 06:25:02 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/no_checksum_big_file_from_pipe 2020/11/03 06:25:02 DEBUG : sftp output = "d0080f73aa8b494570ad63df1beb25c01ea9eea2 /rclone-test-wiqogab7gutoyav8fojibiq7/no_checksum_big_file_from_pipe\n" 2020/11/03 06:25:02 DEBUG : sftp hash = "d0080f73aa8b494570ad63df1beb25c01ea9eea2" 2020/11/03 06:25:02 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/no_checksum_small_file_from_pipe 2020/11/03 06:25:02 DEBUG : sftp output = "4c762bc6ec18f21b23fcae426c7863b5 /rclone-test-wiqogab7gutoyav8fojibiq7/no_checksum_small_file_from_pipe\n" 2020/11/03 06:25:02 DEBUG : sftp hash = "4c762bc6ec18f21b23fcae426c7863b5" 2020/11/03 06:25:02 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/no_checksum_small_file_from_pipe 2020/11/03 06:25:02 DEBUG : sftp output = "4c3452689ee5e2b950454c46181b8b5e7bcfeca9 /rclone-test-wiqogab7gutoyav8fojibiq7/no_checksum_small_file_from_pipe\n" 2020/11/03 06:25:02 DEBUG : sftp hash = "4c3452689ee5e2b950454c46181b8b5e7bcfeca9" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1357 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C1/four" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1357 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C2/five" run.go:121: removing file "A2/B1/C1/four" failed - try 1/3: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/C1/four" failed: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 1/3: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/C2/five" failed: file does not exist run.go:121: removing dir "A2/B2" failed - try 1/3: file does not exist run.go:121: removing dir "A2/B2" failed - try 2/3: file does not exist run.go:121: removing dir "A2/B2" failed - try 3/3: file does not exist run.go:124: removing dir "A2/B2" failed: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 1/3: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 2/3: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 3/3: file does not exist run.go:124: removing dir "A2/B1/C3" failed: file does not exist run.go:121: removing dir "A2/B1/C2" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1/C2" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1/C2" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1/C2" failed: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1/C1" failed: directory not empty run.go:121: removing dir "A2/B1" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1" failed: directory not empty run.go:121: removing dir "A2" failed - try 1/3: directory not empty run.go:121: removing dir "A2" failed - try 2/3: directory not empty run.go:121: removing dir "A2" failed - try 3/3: directory not empty run.go:124: removing dir "A2" failed: directory not empty fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 run.go:169 run.go:339 operations_test.go:1358 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: listing wrong, want got A2/B1/C1/four (4), A2/B1/C2/five (4) fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 operations_test.go:1358 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C2/five" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 operations_test.go:1358 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C1/four" fstest.go:324: Error Trace: fstest.go:324 fstest.go:335 run.go:169 run.go:339 operations_test.go:1358 operations_test.go:1364 Error: Not equal: expected: []string{} actual : []string{"A2", "A2/B1", "A2/B1/C1", "A2/B1/C2", "A2/B1/C3", "A2/B2"} Diff: --- Expected +++ Actual @@ -1,2 +1,8 @@ -([]string) { +([]string) (len=6) { + (string) (len=2) "A2", + (string) (len=5) "A2/B1", + (string) (len=8) "A2/B1/C1", + (string) (len=8) "A2/B1/C2", + (string) (len=8) "A2/B1/C3", + (string) (len=5) "A2/B2" } Test: TestRcat Messages: directories === RUN TestRcat/withChecksum=true,ignoreChecksum=false === CONT TestRcat run.go:176: Remote "sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7", Local "Local file system at /tmp/rclone738578292", Modify Window "1s" fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 fstest.go:341 operations_test.go:1339 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: listing wrong, want got A2/B1/C1/four (4), A2/B1/C2/five (4) fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:341 operations_test.go:1339 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C2/five" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:341 operations_test.go:1339 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C1/four" 2020/11/03 06:25:40 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: File to upload is small (34 bytes), uploading instead of streaming 2020/11/03 06:25:40 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/with_checksum_small_file_from_pipe 2020/11/03 06:25:40 DEBUG : sftp output = "4c762bc6ec18f21b23fcae426c7863b5 /rclone-test-wiqogab7gutoyav8fojibiq7/with_checksum_small_file_from_pipe\n" 2020/11/03 06:25:40 DEBUG : sftp hash = "4c762bc6ec18f21b23fcae426c7863b5" 2020/11/03 06:25:40 DEBUG : with_checksum_small_file_from_pipe: MD5 = 4c762bc6ec18f21b23fcae426c7863b5 OK 2020/11/03 06:25:40 INFO : with_checksum_small_file_from_pipe: Copied (new) 2020/11/03 06:25:40 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/with_checksum_big_file_from_pipe 2020/11/03 06:25:40 DEBUG : sftp output = "fffc7956ba9a7b58a63c01b6ce1ddc45 /rclone-test-wiqogab7gutoyav8fojibiq7/with_checksum_big_file_from_pipe\n" 2020/11/03 06:25:40 DEBUG : sftp hash = "fffc7956ba9a7b58a63c01b6ce1ddc45" 2020/11/03 06:25:40 DEBUG : with_checksum_big_file_from_pipe: MD5 = fffc7956ba9a7b58a63c01b6ce1ddc45 OK 2020/11/03 06:25:40 DEBUG : with_checksum_big_file_from_pipe: Size and MD5 of src and dst objects identical fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 fstest.go:347 operations_test.go:1357 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: listing wrong, want with_checksum_big_file_from_pipe (102401), with_checksum_small_file_from_pipe (34) got A2/B1/C1/four (4), A2/B1/C2/five (4), with_checksum_big_file_from_pipe (102401), with_checksum_small_file_from_pipe (34) 2020/11/03 06:25:47 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/with_checksum_big_file_from_pipe 2020/11/03 06:25:47 DEBUG : sftp output = "fffc7956ba9a7b58a63c01b6ce1ddc45 /rclone-test-wiqogab7gutoyav8fojibiq7/with_checksum_big_file_from_pipe\n" 2020/11/03 06:25:47 DEBUG : sftp hash = "fffc7956ba9a7b58a63c01b6ce1ddc45" 2020/11/03 06:25:47 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/with_checksum_big_file_from_pipe 2020/11/03 06:25:47 DEBUG : sftp output = "d0080f73aa8b494570ad63df1beb25c01ea9eea2 /rclone-test-wiqogab7gutoyav8fojibiq7/with_checksum_big_file_from_pipe\n" 2020/11/03 06:25:47 DEBUG : sftp hash = "d0080f73aa8b494570ad63df1beb25c01ea9eea2" 2020/11/03 06:25:47 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/with_checksum_small_file_from_pipe 2020/11/03 06:25:47 DEBUG : sftp output = "4c762bc6ec18f21b23fcae426c7863b5 /rclone-test-wiqogab7gutoyav8fojibiq7/with_checksum_small_file_from_pipe\n" 2020/11/03 06:25:47 DEBUG : sftp hash = "4c762bc6ec18f21b23fcae426c7863b5" 2020/11/03 06:25:47 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/with_checksum_small_file_from_pipe 2020/11/03 06:25:47 DEBUG : sftp output = "4c3452689ee5e2b950454c46181b8b5e7bcfeca9 /rclone-test-wiqogab7gutoyav8fojibiq7/with_checksum_small_file_from_pipe\n" 2020/11/03 06:25:47 DEBUG : sftp hash = "4c3452689ee5e2b950454c46181b8b5e7bcfeca9" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1357 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C1/four" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1357 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C2/five" run.go:121: removing file "A2/B1/C1/four" failed - try 1/3: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/C1/four" failed: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 1/3: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/C2/five" failed: file does not exist run.go:121: removing dir "A2/B2" failed - try 1/3: file does not exist run.go:121: removing dir "A2/B2" failed - try 2/3: file does not exist run.go:121: removing dir "A2/B2" failed - try 3/3: file does not exist run.go:124: removing dir "A2/B2" failed: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 1/3: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 2/3: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 3/3: file does not exist run.go:124: removing dir "A2/B1/C3" failed: file does not exist run.go:121: removing dir "A2/B1/C2" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1/C2" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1/C2" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1/C2" failed: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1/C1" failed: directory not empty run.go:121: removing dir "A2/B1" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1" failed: directory not empty run.go:121: removing dir "A2" failed - try 1/3: directory not empty run.go:121: removing dir "A2" failed - try 2/3: directory not empty run.go:121: removing dir "A2" failed - try 3/3: directory not empty run.go:124: removing dir "A2" failed: directory not empty fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 run.go:169 run.go:339 operations_test.go:1358 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: listing wrong, want got A2/B1/C1/four (4), A2/B1/C2/five (4) fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 operations_test.go:1358 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C2/five" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 operations_test.go:1358 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C1/four" fstest.go:324: Error Trace: fstest.go:324 fstest.go:335 run.go:169 run.go:339 operations_test.go:1358 operations_test.go:1364 Error: Not equal: expected: []string{} actual : []string{"A2", "A2/B1", "A2/B1/C1", "A2/B1/C2", "A2/B1/C3", "A2/B2"} Diff: --- Expected +++ Actual @@ -1,2 +1,8 @@ -([]string) { +([]string) (len=6) { + (string) (len=2) "A2", + (string) (len=5) "A2/B1", + (string) (len=8) "A2/B1/C1", + (string) (len=8) "A2/B1/C2", + (string) (len=8) "A2/B1/C3", + (string) (len=5) "A2/B2" } Test: TestRcat Messages: directories === RUN TestRcat/withChecksum=false,ignoreChecksum=true === CONT TestRcat run.go:176: Remote "sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7", Local "Local file system at /tmp/rclone738578292", Modify Window "1s" fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 fstest.go:341 operations_test.go:1339 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: listing wrong, want got A2/B1/C1/four (4), A2/B1/C2/five (4) fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:341 operations_test.go:1339 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C1/four" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:341 operations_test.go:1339 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C2/five" 2020/11/03 06:26:25 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: File to upload is small (34 bytes), uploading instead of streaming 2020/11/03 06:26:25 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2020/11/03 06:26:25 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 fstest.go:347 operations_test.go:1357 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: listing wrong, want ignore_checksum_big_file_from_pipe (102401), ignore_checksum_small_file_from_pipe (34) got A2/B1/C1/four (4), A2/B1/C2/five (4), ignore_checksum_big_file_from_pipe (102401), ignore_checksum_small_file_from_pipe (34) 2020/11/03 06:26:32 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_big_file_from_pipe 2020/11/03 06:26:32 DEBUG : sftp output = "fffc7956ba9a7b58a63c01b6ce1ddc45 /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_big_file_from_pipe\n" 2020/11/03 06:26:32 DEBUG : sftp hash = "fffc7956ba9a7b58a63c01b6ce1ddc45" 2020/11/03 06:26:32 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_big_file_from_pipe 2020/11/03 06:26:32 DEBUG : sftp output = "d0080f73aa8b494570ad63df1beb25c01ea9eea2 /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_big_file_from_pipe\n" 2020/11/03 06:26:32 DEBUG : sftp hash = "d0080f73aa8b494570ad63df1beb25c01ea9eea2" 2020/11/03 06:26:32 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_small_file_from_pipe 2020/11/03 06:26:32 DEBUG : sftp output = "4c762bc6ec18f21b23fcae426c7863b5 /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_small_file_from_pipe\n" 2020/11/03 06:26:32 DEBUG : sftp hash = "4c762bc6ec18f21b23fcae426c7863b5" 2020/11/03 06:26:32 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_small_file_from_pipe 2020/11/03 06:26:32 DEBUG : sftp output = "4c3452689ee5e2b950454c46181b8b5e7bcfeca9 /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_small_file_from_pipe\n" 2020/11/03 06:26:32 DEBUG : sftp hash = "4c3452689ee5e2b950454c46181b8b5e7bcfeca9" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1357 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C2/five" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1357 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C1/four" run.go:121: removing file "A2/B1/C2/five" failed - try 1/3: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/C2/five" failed: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 1/3: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/C1/four" failed: file does not exist run.go:121: removing dir "A2/B2" failed - try 1/3: file does not exist run.go:121: removing dir "A2/B2" failed - try 2/3: file does not exist run.go:121: removing dir "A2/B2" failed - try 3/3: file does not exist run.go:124: removing dir "A2/B2" failed: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 1/3: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 2/3: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 3/3: file does not exist run.go:124: removing dir "A2/B1/C3" failed: file does not exist run.go:121: removing dir "A2/B1/C2" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1/C2" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1/C2" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1/C2" failed: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1/C1" failed: directory not empty run.go:121: removing dir "A2/B1" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1" failed: directory not empty run.go:121: removing dir "A2" failed - try 1/3: directory not empty run.go:121: removing dir "A2" failed - try 2/3: directory not empty run.go:121: removing dir "A2" failed - try 3/3: directory not empty run.go:124: removing dir "A2" failed: directory not empty fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 run.go:169 run.go:339 operations_test.go:1358 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: listing wrong, want got A2/B1/C1/four (4), A2/B1/C2/five (4) fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 operations_test.go:1358 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C2/five" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 operations_test.go:1358 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C1/four" fstest.go:324: Error Trace: fstest.go:324 fstest.go:335 run.go:169 run.go:339 operations_test.go:1358 operations_test.go:1364 Error: Not equal: expected: []string{} actual : []string{"A2", "A2/B1", "A2/B1/C1", "A2/B1/C2", "A2/B1/C3", "A2/B2"} Diff: --- Expected +++ Actual @@ -1,2 +1,8 @@ -([]string) { +([]string) (len=6) { + (string) (len=2) "A2", + (string) (len=5) "A2/B1", + (string) (len=8) "A2/B1/C1", + (string) (len=8) "A2/B1/C2", + (string) (len=8) "A2/B1/C3", + (string) (len=5) "A2/B2" } Test: TestRcat Messages: directories === RUN TestRcat/withChecksum=true,ignoreChecksum=true === CONT TestRcat run.go:176: Remote "sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7", Local "Local file system at /tmp/rclone738578292", Modify Window "1s" fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 fstest.go:341 operations_test.go:1339 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: listing wrong, want got A2/B1/C1/four (4), A2/B1/C2/five (4) fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:341 operations_test.go:1339 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C2/five" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:341 operations_test.go:1339 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C1/four" 2020/11/03 06:27:10 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: File to upload is small (34 bytes), uploading instead of streaming 2020/11/03 06:27:10 INFO : ignore_checksum_small_file_from_pipe: Copied (new) 2020/11/03 06:27:10 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_big_file_from_pipe 2020/11/03 06:27:10 DEBUG : sftp output = "fffc7956ba9a7b58a63c01b6ce1ddc45 /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_big_file_from_pipe\n" 2020/11/03 06:27:10 DEBUG : sftp hash = "fffc7956ba9a7b58a63c01b6ce1ddc45" 2020/11/03 06:27:10 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 fstest.go:347 operations_test.go:1357 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: listing wrong, want ignore_checksum_big_file_from_pipe (102401), ignore_checksum_small_file_from_pipe (34) got A2/B1/C1/four (4), A2/B1/C2/five (4), ignore_checksum_big_file_from_pipe (102401), ignore_checksum_small_file_from_pipe (34) 2020/11/03 06:27:17 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_big_file_from_pipe 2020/11/03 06:27:17 DEBUG : sftp output = "fffc7956ba9a7b58a63c01b6ce1ddc45 /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_big_file_from_pipe\n" 2020/11/03 06:27:17 DEBUG : sftp hash = "fffc7956ba9a7b58a63c01b6ce1ddc45" 2020/11/03 06:27:17 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_big_file_from_pipe 2020/11/03 06:27:17 DEBUG : sftp output = "d0080f73aa8b494570ad63df1beb25c01ea9eea2 /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_big_file_from_pipe\n" 2020/11/03 06:27:17 DEBUG : sftp hash = "d0080f73aa8b494570ad63df1beb25c01ea9eea2" 2020/11/03 06:27:17 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_small_file_from_pipe 2020/11/03 06:27:17 DEBUG : sftp output = "4c762bc6ec18f21b23fcae426c7863b5 /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_small_file_from_pipe\n" 2020/11/03 06:27:17 DEBUG : sftp hash = "4c762bc6ec18f21b23fcae426c7863b5" 2020/11/03 06:27:17 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_small_file_from_pipe 2020/11/03 06:27:17 DEBUG : sftp output = "4c3452689ee5e2b950454c46181b8b5e7bcfeca9 /rclone-test-wiqogab7gutoyav8fojibiq7/ignore_checksum_small_file_from_pipe\n" 2020/11/03 06:27:17 DEBUG : sftp hash = "4c3452689ee5e2b950454c46181b8b5e7bcfeca9" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1357 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C1/four" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1357 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C2/five" run.go:121: removing file "A2/B1/C2/five" failed - try 1/3: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/C2/five" failed: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 1/3: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/C1/four" failed: file does not exist run.go:121: removing dir "A2/B2" failed - try 1/3: file does not exist run.go:121: removing dir "A2/B2" failed - try 2/3: file does not exist run.go:121: removing dir "A2/B2" failed - try 3/3: file does not exist run.go:124: removing dir "A2/B2" failed: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 1/3: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 2/3: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 3/3: file does not exist run.go:124: removing dir "A2/B1/C3" failed: file does not exist run.go:121: removing dir "A2/B1/C2" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1/C2" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1/C2" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1/C2" failed: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1/C1" failed: directory not empty run.go:121: removing dir "A2/B1" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1" failed: directory not empty run.go:121: removing dir "A2" failed - try 1/3: directory not empty run.go:121: removing dir "A2" failed - try 2/3: directory not empty run.go:121: removing dir "A2" failed - try 3/3: directory not empty run.go:124: removing dir "A2" failed: directory not empty fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 run.go:169 run.go:339 operations_test.go:1358 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: listing wrong, want got A2/B1/C1/four (4), A2/B1/C2/five (4) fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 operations_test.go:1358 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C2/five" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 operations_test.go:1358 operations_test.go:1364 Error: Should be true Test: TestRcat Messages: Unexpected file "A2/B1/C1/four" fstest.go:324: Error Trace: fstest.go:324 fstest.go:335 run.go:169 run.go:339 operations_test.go:1358 operations_test.go:1364 Error: Not equal: expected: []string{} actual : []string{"A2", "A2/B1", "A2/B1/C1", "A2/B1/C2", "A2/B1/C3", "A2/B2"} Diff: --- Expected +++ Actual @@ -1,2 +1,8 @@ -([]string) { +([]string) (len=6) { + (string) (len=2) "A2", + (string) (len=5) "A2/B1", + (string) (len=8) "A2/B1/C1", + (string) (len=8) "A2/B1/C2", + (string) (len=8) "A2/B1/C3", + (string) (len=5) "A2/B2" } Test: TestRcat Messages: directories --- FAIL: TestRcat (181.03s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (45.41s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (45.21s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (45.20s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (45.20s) === RUN TestRcatSize run.go:176: Remote "sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7", Local "Local file system at /tmp/rclone738578292", Modify Window "1s" 2020/11/03 06:27:48 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: File to upload is small (60 bytes), uploading instead of streaming 2020/11/03 06:27:48 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/potato2 2020/11/03 06:27:48 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 /rclone-test-wiqogab7gutoyav8fojibiq7/potato2\n" 2020/11/03 06:27:48 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2020/11/03 06:27:48 DEBUG : potato2: MD5 = d6548b156ea68a4e003e786df99eee76 OK 2020/11/03 06:27:48 INFO : potato2: Copied (new) fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 fstest.go:347 operations_test.go:1392 Error: Should be true Test: TestRcatSize Messages: listing wrong, want potato1 (60), potato2 (60) got A2/B1/C1/four (4), A2/B1/C2/five (4), potato1 (60), potato2 (60) 2020/11/03 06:27:55 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/potato1 2020/11/03 06:27:55 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 /rclone-test-wiqogab7gutoyav8fojibiq7/potato1\n" 2020/11/03 06:27:55 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2020/11/03 06:27:55 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/potato1 2020/11/03 06:27:55 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d /rclone-test-wiqogab7gutoyav8fojibiq7/potato1\n" 2020/11/03 06:27:55 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" 2020/11/03 06:27:55 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/potato2 2020/11/03 06:27:55 DEBUG : sftp output = "d6548b156ea68a4e003e786df99eee76 /rclone-test-wiqogab7gutoyav8fojibiq7/potato2\n" 2020/11/03 06:27:55 DEBUG : sftp hash = "d6548b156ea68a4e003e786df99eee76" 2020/11/03 06:27:55 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/potato2 2020/11/03 06:27:55 DEBUG : sftp output = "9dc7f7d3279715991a22853f5981df582b7f9f6d /rclone-test-wiqogab7gutoyav8fojibiq7/potato2\n" 2020/11/03 06:27:55 DEBUG : sftp hash = "9dc7f7d3279715991a22853f5981df582b7f9f6d" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1392 Error: Should be true Test: TestRcatSize Messages: Unexpected file "A2/B1/C1/four" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1392 Error: Should be true Test: TestRcatSize Messages: Unexpected file "A2/B1/C2/five" run.go:121: removing file "A2/B1/C2/five" failed - try 1/3: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/C2/five" failed: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 1/3: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/C1/four" failed: file does not exist run.go:121: removing dir "A2/B2" failed - try 1/3: file does not exist run.go:121: removing dir "A2/B2" failed - try 2/3: file does not exist run.go:121: removing dir "A2/B2" failed - try 3/3: file does not exist run.go:124: removing dir "A2/B2" failed: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 1/3: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 2/3: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 3/3: file does not exist run.go:124: removing dir "A2/B1/C3" failed: file does not exist run.go:121: removing dir "A2/B1/C2" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1/C2" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1/C2" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1/C2" failed: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1/C1" failed: directory not empty run.go:121: removing dir "A2/B1" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1" failed: directory not empty run.go:121: removing dir "A2" failed - try 1/3: directory not empty run.go:121: removing dir "A2" failed - try 2/3: directory not empty run.go:121: removing dir "A2" failed - try 3/3: directory not empty run.go:124: removing dir "A2" failed: directory not empty fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 run.go:169 run.go:339 operations_test.go:1393 Error: Should be true Test: TestRcatSize Messages: listing wrong, want got A2/B1/C1/four (4), A2/B1/C2/five (4) fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 operations_test.go:1393 Error: Should be true Test: TestRcatSize Messages: Unexpected file "A2/B1/C2/five" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 operations_test.go:1393 Error: Should be true Test: TestRcatSize Messages: Unexpected file "A2/B1/C1/four" fstest.go:324: Error Trace: fstest.go:324 fstest.go:335 run.go:169 run.go:339 operations_test.go:1393 Error: Not equal: expected: []string{} actual : []string{"A2", "A2/B1", "A2/B1/C1", "A2/B1/C2", "A2/B1/C3", "A2/B2"} Diff: --- Expected +++ Actual @@ -1,2 +1,8 @@ -([]string) { +([]string) (len=6) { + (string) (len=2) "A2", + (string) (len=5) "A2/B1", + (string) (len=8) "A2/B1/C1", + (string) (len=8) "A2/B1/C2", + (string) (len=8) "A2/B1/C3", + (string) (len=5) "A2/B2" } Test: TestRcatSize Messages: directories --- FAIL: TestRcatSize (38.16s) === RUN TestCopyFileMaxTransfer run.go:176: Remote "sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7", Local "Local file system at /tmp/rclone738578292", Modify Window "1s" 2020/11/03 06:28:27 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2020/11/03 06:28:27 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1 2020/11/03 06:28:27 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1\n" 2020/11/03 06:28:27 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2020/11/03 06:28:27 DEBUG : TestCopyFileMaxTransfer/file1: MD5 = 0ef726ce9b1a7692357ff70dd321d595 OK 2020/11/03 06:28:27 INFO : TestCopyFileMaxTransfer/file1: Copied (new) fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 fstest.go:347 operations_test.go:1424 Error: Should be true Test: TestCopyFileMaxTransfer Messages: listing wrong, want TestCopyFileMaxTransfer/file1 (14) got A2/B1/C1/four (4), A2/B1/C2/five (4), TestCopyFileMaxTransfer/file1 (14) fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1424 Error: Should be true Test: TestCopyFileMaxTransfer Messages: Unexpected file "A2/B1/C1/four" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1424 Error: Should be true Test: TestCopyFileMaxTransfer Messages: Unexpected file "A2/B1/C2/five" 2020/11/03 06:28:34 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1 2020/11/03 06:28:34 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1\n" 2020/11/03 06:28:34 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2020/11/03 06:28:34 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1 2020/11/03 06:28:34 DEBUG : sftp output = "a379624177abc4679cafafa8eae1d73e1478aaa6 /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1\n" 2020/11/03 06:28:34 DEBUG : sftp hash = "a379624177abc4679cafafa8eae1d73e1478aaa6" 2020/11/03 06:28:34 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2020/11/03 06:28:34 DEBUG : TestCopyFileMaxTransfer/file2: Removed after failed upload: Max transfer limit reached as set by --max-transfer 2020/11/03 06:28:34 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Update ReadFrom failed: Max transfer limit reached as set by --max-transfer fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 fstest.go:347 operations_test.go:1433 Error: Should be true Test: TestCopyFileMaxTransfer Messages: listing wrong, want TestCopyFileMaxTransfer/file1 (14) got A2/B1/C1/four (4), A2/B1/C2/five (4), TestCopyFileMaxTransfer/file1 (14) 2020/11/03 06:28:41 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1 2020/11/03 06:28:41 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1\n" 2020/11/03 06:28:41 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2020/11/03 06:28:41 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1 2020/11/03 06:28:41 DEBUG : sftp output = "a379624177abc4679cafafa8eae1d73e1478aaa6 /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1\n" 2020/11/03 06:28:41 DEBUG : sftp hash = "a379624177abc4679cafafa8eae1d73e1478aaa6" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1433 Error: Should be true Test: TestCopyFileMaxTransfer Messages: Unexpected file "A2/B1/C1/four" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1433 Error: Should be true Test: TestCopyFileMaxTransfer Messages: Unexpected file "A2/B1/C2/five" 2020/11/03 06:28:41 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 fstest.go:347 operations_test.go:1445 Error: Should be true Test: TestCopyFileMaxTransfer Messages: listing wrong, want TestCopyFileMaxTransfer/file1 (14) got A2/B1/C1/four (4), A2/B1/C2/five (4), TestCopyFileMaxTransfer/file1 (14) 2020/11/03 06:28:48 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1 2020/11/03 06:28:48 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1\n" 2020/11/03 06:28:48 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2020/11/03 06:28:48 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1 2020/11/03 06:28:48 DEBUG : sftp output = "a379624177abc4679cafafa8eae1d73e1478aaa6 /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1\n" 2020/11/03 06:28:48 DEBUG : sftp hash = "a379624177abc4679cafafa8eae1d73e1478aaa6" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1445 Error: Should be true Test: TestCopyFileMaxTransfer Messages: Unexpected file "A2/B1/C2/five" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1445 Error: Should be true Test: TestCopyFileMaxTransfer Messages: Unexpected file "A2/B1/C1/four" 2020/11/03 06:28:48 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2020/11/03 06:28:48 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file4 2020/11/03 06:28:48 DEBUG : sftp output = "1e2470cb18ade4a6f60137e53f4c5f02 /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file4\n" 2020/11/03 06:28:48 DEBUG : sftp hash = "1e2470cb18ade4a6f60137e53f4c5f02" 2020/11/03 06:28:48 DEBUG : TestCopyFileMaxTransfer/file4: MD5 = 1e2470cb18ade4a6f60137e53f4c5f02 OK 2020/11/03 06:28:48 INFO : TestCopyFileMaxTransfer/file4: Copied (new) fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 fstest.go:347 operations_test.go:1460 Error: Should be true Test: TestCopyFileMaxTransfer Messages: listing wrong, want TestCopyFileMaxTransfer/file1 (14), TestCopyFileMaxTransfer/file4 (2062) got A2/B1/C1/four (4), A2/B1/C2/five (4), TestCopyFileMaxTransfer/file1 (14), TestCopyFileMaxTransfer/file4 (2062) 2020/11/03 06:28:55 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1 2020/11/03 06:28:55 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595 /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1\n" 2020/11/03 06:28:55 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595" 2020/11/03 06:28:55 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1 2020/11/03 06:28:55 DEBUG : sftp output = "a379624177abc4679cafafa8eae1d73e1478aaa6 /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file1\n" 2020/11/03 06:28:55 DEBUG : sftp hash = "a379624177abc4679cafafa8eae1d73e1478aaa6" 2020/11/03 06:28:55 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file4 2020/11/03 06:28:55 DEBUG : sftp output = "1e2470cb18ade4a6f60137e53f4c5f02 /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file4\n" 2020/11/03 06:28:55 DEBUG : sftp hash = "1e2470cb18ade4a6f60137e53f4c5f02" 2020/11/03 06:28:55 DEBUG : sftp cmd = /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file4 2020/11/03 06:28:55 DEBUG : sftp output = "2f00454919cd8e6512e3c0f90ddcc37df245d9bf /rclone-test-wiqogab7gutoyav8fojibiq7/TestCopyFileMaxTransfer/file4\n" 2020/11/03 06:28:55 DEBUG : sftp hash = "2f00454919cd8e6512e3c0f90ddcc37df245d9bf" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1460 Error: Should be true Test: TestCopyFileMaxTransfer Messages: Unexpected file "A2/B1/C2/five" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 fstest.go:347 operations_test.go:1460 Error: Should be true Test: TestCopyFileMaxTransfer Messages: Unexpected file "A2/B1/C1/four" run.go:121: removing file "A2/B1/C1/four" failed - try 1/3: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/C1/four" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/C1/four" failed: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 1/3: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 2/3: file does not exist run.go:121: removing file "A2/B1/C2/five" failed - try 3/3: file does not exist run.go:124: removing file "A2/B1/C2/five" failed: file does not exist run.go:121: removing dir "A2/B2" failed - try 1/3: file does not exist run.go:121: removing dir "A2/B2" failed - try 2/3: file does not exist run.go:121: removing dir "A2/B2" failed - try 3/3: file does not exist run.go:124: removing dir "A2/B2" failed: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 1/3: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 2/3: file does not exist run.go:121: removing dir "A2/B1/C3" failed - try 3/3: file does not exist run.go:124: removing dir "A2/B1/C3" failed: file does not exist run.go:121: removing dir "A2/B1/C2" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1/C2" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1/C2" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1/C2" failed: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1/C1" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1/C1" failed: directory not empty run.go:121: removing dir "A2/B1" failed - try 1/3: directory not empty run.go:121: removing dir "A2/B1" failed - try 2/3: directory not empty run.go:121: removing dir "A2/B1" failed - try 3/3: directory not empty run.go:124: removing dir "A2/B1" failed: directory not empty run.go:121: removing dir "A2" failed - try 1/3: directory not empty run.go:121: removing dir "A2" failed - try 2/3: directory not empty run.go:121: removing dir "A2" failed - try 3/3: directory not empty run.go:124: removing dir "A2" failed: directory not empty fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: fstest.go:302 fstest.go:335 run.go:169 run.go:339 operations_test.go:1461 Error: Should be true Test: TestCopyFileMaxTransfer Messages: listing wrong, want got A2/B1/C1/four (4), A2/B1/C2/five (4) fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 operations_test.go:1461 Error: Should be true Test: TestCopyFileMaxTransfer Messages: Unexpected file "A2/B1/C1/four" fstest.go:188: Error Trace: fstest.go:188 fstest.go:305 fstest.go:335 run.go:169 run.go:339 operations_test.go:1461 Error: Should be true Test: TestCopyFileMaxTransfer Messages: Unexpected file "A2/B1/C2/five" fstest.go:324: Error Trace: fstest.go:324 fstest.go:335 run.go:169 run.go:339 operations_test.go:1461 Error: Not equal: expected: []string{} actual : []string{"A2", "A2/B1", "A2/B1/C1", "A2/B1/C2", "A2/B1/C3", "A2/B2"} Diff: --- Expected +++ Actual @@ -1,2 +1,8 @@ -([]string) { +([]string) (len=6) { + (string) (len=2) "A2", + (string) (len=5) "A2/B1", + (string) (len=8) "A2/B1/C1", + (string) (len=8) "A2/B1/C2", + (string) (len=8) "A2/B1/C3", + (string) (len=5) "A2/B2" } Test: TestCopyFileMaxTransfer Messages: directories --- FAIL: TestCopyFileMaxTransfer (59.35s) FAIL 2020/11/03 06:29:26 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: Purge object "A2/B1/C1/four" 2020/11/03 06:29:26 purge failed to remove "A2/B1/C1/four": file does not exist 2020/11/03 06:29:26 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: Purge object "A2/B1/C2/five" 2020/11/03 06:29:26 purge failed to remove "A2/B1/C2/five": file does not exist 2020/11/03 06:29:26 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: Purge dir "A2/B2" 2020/11/03 06:29:26 purge failed to rmdir "A2/B2": file does not exist 2020/11/03 06:29:26 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: Purge dir "A2/B1/C3" 2020/11/03 06:29:26 purge failed to rmdir "A2/B1/C3": file does not exist 2020/11/03 06:29:26 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: Purge dir "A2/B1/C2" 2020/11/03 06:29:26 purge failed to rmdir "A2/B1/C2": directory not empty 2020/11/03 06:29:26 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: Purge dir "A2/B1/C1" 2020/11/03 06:29:26 purge failed to rmdir "A2/B1/C1": directory not empty 2020/11/03 06:29:26 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: Purge dir "A2/B1" 2020/11/03 06:29:26 purge failed to rmdir "A2/B1": directory not empty 2020/11/03 06:29:26 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: Purge dir "A2" 2020/11/03 06:29:26 purge failed to rmdir "A2": directory not empty 2020/11/03 06:29:26 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-wiqogab7gutoyav8fojibiq7: Purge dir "" 2020/11/03 06:29:26 purge failed to rmdir "": directory not empty "./operations.test -test.v -test.timeout 1h0m0s -remote TestSFTPRclone: -verbose -test.run '^(TestCopyFileMaxTransfer|TestDirMove|TestGetFsInfo|TestRcat|TestRcatSize)$'" - Finished ERROR in 5m44.15973042s (try 3/5): exit status 1: Failed [TestDirMove TestGetFsInfo TestRcat TestRcatSize TestCopyFileMaxTransfer]