"./operations.test -test.v -test.timeout 1h0m0s -remote TestSFTPOpenssh: -verbose -test.run '^TestCopyFileBackupDir$'" - Starting (try 2/5)
2021/03/23 05:49:38 DEBUG : Creating backend with remote "TestSFTPOpenssh:rclone-test-yazetup4gemefaj1jofocos6"
2021/03/23 05:49:38 DEBUG : TestSFTPOpenssh: detected overridden config - adding "{jn7S0}" suffix to name
2021/03/23 05:49:38 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-yazetup4gemefaj1jofocos6: New connection 172.17.0.1:53402->172.17.0.3:22 to "SSH-2.0-OpenSSH_8.1"
2021/03/23 05:49:38 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-yazetup4gemefaj1jofocos6: Using absolute root directory "/home/rclone/rclone-test-yazetup4gemefaj1jofocos6"
2021/03/23 05:49:38 DEBUG : Creating backend with remote "/tmp/rclone582372507"
=== RUN   TestCopyFileBackupDir
    run.go:176: Remote "sftp://rclone@172.17.0.3:22/rclone-test-yazetup4gemefaj1jofocos6", Local "Local file system at /tmp/rclone582372507", Modify Window "1s"
2021/03/23 05:49:39 DEBUG : sftp cmd = /home/rclone/rclone-test-yazetup4gemefaj1jofocos6/dst/file1
2021/03/23 05:49:39 DEBUG : sftp output = "05164b153084ba910184c26e561a7c18  /home/rclone/rclone-test-yazetup4gemefaj1jofocos6/dst/file1\n"
2021/03/23 05:49:39 DEBUG : sftp hash = "05164b153084ba910184c26e561a7c18"
2021/03/23 05:49:39 DEBUG : sftp cmd = /home/rclone/rclone-test-yazetup4gemefaj1jofocos6/dst/file1
2021/03/23 05:49:39 DEBUG : sftp output = "9d125cddbdc7323ccf9db2d2b67af177f781b5c6  /home/rclone/rclone-test-yazetup4gemefaj1jofocos6/dst/file1\n"
2021/03/23 05:49:39 DEBUG : sftp hash = "9d125cddbdc7323ccf9db2d2b67af177f781b5c6"
2021/03/23 05:49:39 DEBUG : Creating backend with remote "TestSFTPOpenssh:rclone-test-yazetup4gemefaj1jofocos6/backup"
2021/03/23 05:49:39 DEBUG : TestSFTPOpenssh: detected overridden config - adding "{jn7S0}" suffix to name
2021/03/23 05:49:39 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-yazetup4gemefaj1jofocos6/backup: New connection 172.17.0.1:53404->172.17.0.3:22 to "SSH-2.0-OpenSSH_8.1"
2021/03/23 05:49:39 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-yazetup4gemefaj1jofocos6/backup: Using absolute root directory "/home/rclone/rclone-test-yazetup4gemefaj1jofocos6/backup"
2021/03/23 05:49:39 DEBUG : fs cache: renaming cache item "TestSFTPOpenssh:rclone-test-yazetup4gemefaj1jofocos6/backup" to be canonical "TestSFTPOpenssh{jn7S0}:rclone-test-yazetup4gemefaj1jofocos6/backup"
2021/03/23 05:49:39 DEBUG : dst/file1: Sizes differ (src 14 vs dst 18)
2021/03/23 05:49:39 INFO  : dst/file1: Moved (server-side)
2021/03/23 05:49:39 DEBUG : sftp cmd = /home/rclone/rclone-test-yazetup4gemefaj1jofocos6/dst/file1
2021/03/23 05:49:39 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595  /home/rclone/rclone-test-yazetup4gemefaj1jofocos6/dst/file1\n"
2021/03/23 05:49:39 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595"
2021/03/23 05:49:39 DEBUG : dst/file1: MD5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2021/03/23 05:49:39 INFO  : dst/file1: Copied (new)
2021/03/23 05:49:39 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-yazetup4gemefaj1jofocos6: New connection 172.17.0.1:53408->172.17.0.3:22 to "SSH-2.0-OpenSSH_8.1"
2021/03/23 05:49:39 DEBUG : sftp cmd = /home/rclone/rclone-test-yazetup4gemefaj1jofocos6/dst/file1
2021/03/23 05:49:39 DEBUG : sftp output = "0ef726ce9b1a7692357ff70dd321d595  /home/rclone/rclone-test-yazetup4gemefaj1jofocos6/dst/file1\n"
2021/03/23 05:49:39 DEBUG : sftp hash = "0ef726ce9b1a7692357ff70dd321d595"
2021/03/23 05:49:39 DEBUG : sftp cmd = /home/rclone/rclone-test-yazetup4gemefaj1jofocos6/dst/file1
2021/03/23 05:49:39 DEBUG : sftp output = "a379624177abc4679cafafa8eae1d73e1478aaa6  /home/rclone/rclone-test-yazetup4gemefaj1jofocos6/dst/file1\n"
2021/03/23 05:49:39 DEBUG : sftp hash = "a379624177abc4679cafafa8eae1d73e1478aaa6"
2021/03/23 05:49:39 DEBUG : sftp cmd = /home/rclone/rclone-test-yazetup4gemefaj1jofocos6/backup/dst/file1
2021/03/23 05:49:39 DEBUG : sftp output = "05164b153084ba910184c26e561a7c18  /home/rclone/rclone-test-yazetup4gemefaj1jofocos6/backup/dst/file1\n"
2021/03/23 05:49:39 DEBUG : sftp hash = "05164b153084ba910184c26e561a7c18"
2021/03/23 05:49:39 DEBUG : sftp cmd = /home/rclone/rclone-test-yazetup4gemefaj1jofocos6/backup/dst/file1
2021/03/23 05:49:39 DEBUG : sftp output = "9d125cddbdc7323ccf9db2d2b67af177f781b5c6  /home/rclone/rclone-test-yazetup4gemefaj1jofocos6/backup/dst/file1\n"
2021/03/23 05:49:39 DEBUG : sftp hash = "9d125cddbdc7323ccf9db2d2b67af177f781b5c6"
--- PASS: TestCopyFileBackupDir (0.28s)
PASS
2021/03/23 05:49:39 DEBUG : sftp://rclone@172.17.0.3:22/rclone-test-yazetup4gemefaj1jofocos6: Purge dir ""
"./operations.test -test.v -test.timeout 1h0m0s -remote TestSFTPOpenssh: -verbose -test.run '^TestCopyFileBackupDir$'" - Finished OK in 469.715887ms (try 2/5)