"./sync.test -test.v -test.timeout 1h0m0s -remote TestFTPProftpd: -verbose -test.run '^TestSyncBackupDirWithSuffixKeepExtension$'" - Starting (try 3/5)
2021/03/23 05:16:46 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-fuvesil2podudoh4cirizag1"
2021/03/23 05:16:46 DEBUG : TestFTPProftpd: detected overridden config - adding "{fNazc}" suffix to name
2021/03/23 05:16:46 DEBUG : ftp://172.17.0.3:21/rclone-test-fuvesil2podudoh4cirizag1: Connecting to FTP server
2021/03/23 05:16:46 DEBUG : Creating backend with remote "/tmp/rclone100565119"
=== RUN   TestSyncBackupDirWithSuffixKeepExtension
    run.go:176: Remote "ftp://172.17.0.3:21/rclone-test-fuvesil2podudoh4cirizag1", Local "Local file system at /tmp/rclone100565119", Modify Window "876000h0m0s"
2021/03/23 05:16:46 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-fuvesil2podudoh4cirizag1/dst"
2021/03/23 05:16:46 DEBUG : TestFTPProftpd: detected overridden config - adding "{fNazc}" suffix to name
2021/03/23 05:16:46 DEBUG : ftp://172.17.0.3:21/rclone-test-fuvesil2podudoh4cirizag1/dst: Connecting to FTP server
2021/03/23 05:16:46 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-fuvesil2podudoh4cirizag1/backup"
2021/03/23 05:16:46 DEBUG : TestFTPProftpd: detected overridden config - adding "{fNazc}" suffix to name
2021/03/23 05:16:46 DEBUG : ftp://172.17.0.3:21/rclone-test-fuvesil2podudoh4cirizag1/backup: Connecting to FTP server
2021/03/23 05:16:46 DEBUG : fs cache: renaming cache item "TestFTPProftpd:rclone-test-fuvesil2podudoh4cirizag1/backup" to be canonical "TestFTPProftpd{fNazc}:rclone-test-fuvesil2podudoh4cirizag1/backup"
2021/03/23 05:16:46 DEBUG : one: Sizes differ (src 4 vs dst 3)
2021/03/23 05:16:46 DEBUG : two: Sizes identical
2021/03/23 05:16:46 DEBUG : two: Unchanged skipping
2021/03/23 05:16:46 DEBUG : ftp://172.17.0.3:21/rclone-test-fuvesil2podudoh4cirizag1/dst: Waiting for checks to finish
2021/03/23 05:16:46 INFO  : one: Moved (server-side) to: one-2019-01-01
2021/03/23 05:16:46 DEBUG : ftp://172.17.0.3:21/rclone-test-fuvesil2podudoh4cirizag1/dst: Waiting for transfers to finish
2021/03/23 05:16:46 INFO  : one: Copied (new)
2021/03/23 05:16:46 DEBUG : Waiting for deletions to finish
2021/03/23 05:16:46 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2021/03/23 05:16:46 INFO  : three.txt: Moved into backup dir
2021/03/23 05:16:46 DEBUG : ftp://172.17.0.3:21/rclone-test-fuvesil2podudoh4cirizag1: Connecting to FTP server
2021/03/23 05:16:46 DEBUG : fs cache: switching user supplied name "TestFTPProftpd:rclone-test-fuvesil2podudoh4cirizag1/backup" for canonical name "TestFTPProftpd{fNazc}:rclone-test-fuvesil2podudoh4cirizag1/backup"
2021/03/23 05:16:46 DEBUG : one: Sizes differ (src 5 vs dst 4)
2021/03/23 05:16:46 DEBUG : two: Sizes identical
2021/03/23 05:16:46 DEBUG : two: Unchanged skipping
2021/03/23 05:16:46 DEBUG : ftp://172.17.0.3:21/rclone-test-fuvesil2podudoh4cirizag1/dst: Waiting for checks to finish
2021/03/23 05:16:46 INFO  : one-2019-01-01: Deleted
2021/03/23 05:16:46 INFO  : one: Moved (server-side) to: one-2019-01-01
2021/03/23 05:16:46 DEBUG : ftp://172.17.0.3:21/rclone-test-fuvesil2podudoh4cirizag1/dst: Waiting for transfers to finish
2021/03/23 05:16:46 INFO  : one: Copied (new)
2021/03/23 05:16:46 DEBUG : Waiting for deletions to finish
2021/03/23 05:16:46 INFO  : three-2019-01-01.txt: Deleted
2021/03/23 05:16:46 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2021/03/23 05:16:46 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffixKeepExtension (0.24s)
PASS
2021/03/23 05:16:46 DEBUG : ftp://172.17.0.3:21/rclone-test-fuvesil2podudoh4cirizag1: Purge dir ""
"./sync.test -test.v -test.timeout 1h0m0s -remote TestFTPProftpd: -verbose -test.run '^TestSyncBackupDirWithSuffixKeepExtension$'" - Finished OK in 462.388158ms (try 3/5)