"./sync.test -test.v -test.timeout 1h0m0s -remote TestFTPProftpd: -verbose -test.run '^(TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncSuffix|TestSyncSuffixKeepExtension)$'" - Starting (try 2/5) 2026/04/30 05:41:18 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-gedibon7buto" 2026/04/30 05:41:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/04/30 05:41:18 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/04/30 05:41:18 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/04/30 05:41:18 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/04/30 05:41:18 DEBUG : Setting pass="IUkwnytnB_eUiYBvUdjCvJBRmdWclT-iwId0BhY4NK7y7g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/04/30 05:41:18 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/04/30 05:41:18 DEBUG : TestFTPProftpd: detected overridden config - adding "{zHO13}" suffix to name 2026/04/30 05:41:18 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/04/30 05:41:18 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/04/30 05:41:18 DEBUG : Setting pass="IUkwnytnB_eUiYBvUdjCvJBRmdWclT-iwId0BhY4NK7y7g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/04/30 05:41:18 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: Connecting to FTP server 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:21") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:39356->172.17.0.4:21, err= 2026/04/30 05:41:18 DEBUG : Creating backend with remote "/tmp/rclone381554492" === RUN TestSyncBackupDirWithSuffix run.go:185: Remote "ftp://172.17.0.4:21/rclone-test-gedibon7buto", Local "Local file system at /tmp/rclone381554492", Modify Window "1s" 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:9393") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:43292->172.17.0.4:9393, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:2793") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:34176->172.17.0.4:2793, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:49058") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:46052->172.17.0.4:49058, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:2731") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:50850->172.17.0.4:2731, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:18737") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:40496->172.17.0.4:18737, err= 2026/04/30 05:41:18 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-gedibon7buto/dst" 2026/04/30 05:41:18 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/04/30 05:41:18 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/04/30 05:41:18 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/04/30 05:41:18 DEBUG : Setting pass="IUkwnytnB_eUiYBvUdjCvJBRmdWclT-iwId0BhY4NK7y7g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/04/30 05:41:18 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/04/30 05:41:18 DEBUG : TestFTPProftpd: detected overridden config - adding "{zHO13}" suffix to name 2026/04/30 05:41:18 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/04/30 05:41:18 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/04/30 05:41:18 DEBUG : Setting pass="IUkwnytnB_eUiYBvUdjCvJBRmdWclT-iwId0BhY4NK7y7g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/04/30 05:41:18 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Connecting to FTP server 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:21") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:39360->172.17.0.4:21, err= 2026/04/30 05:41:18 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-gedibon7buto/backup" 2026/04/30 05:41:18 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/04/30 05:41:18 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/04/30 05:41:18 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/04/30 05:41:18 DEBUG : Setting pass="IUkwnytnB_eUiYBvUdjCvJBRmdWclT-iwId0BhY4NK7y7g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/04/30 05:41:18 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/04/30 05:41:18 DEBUG : TestFTPProftpd: detected overridden config - adding "{zHO13}" suffix to name 2026/04/30 05:41:18 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/04/30 05:41:18 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/04/30 05:41:18 DEBUG : Setting pass="IUkwnytnB_eUiYBvUdjCvJBRmdWclT-iwId0BhY4NK7y7g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/04/30 05:41:18 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/backup: Connecting to FTP server 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/backup: dial("tcp","172.17.0.4:21") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/backup: > dial: conn=172.17.0.1:39374->172.17.0.4:21, err= 2026/04/30 05:41:18 DEBUG : fs cache: renaming cache item "TestFTPProftpd:rclone-test-gedibon7buto/backup" to be canonical "TestFTPProftpd{zHO13}:rclone-test-gedibon7buto/backup" 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:11561") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:53092->172.17.0.4:11561, err= 2026/04/30 05:41:18 DEBUG : one: size = 4 (Local file system at /tmp/rclone381554492) 2026/04/30 05:41:18 DEBUG : one: size = 3 (ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst) 2026/04/30 05:41:18 DEBUG : one: Sizes differ 2026/04/30 05:41:18 DEBUG : two: size = 3 OK 2026/04/30 05:41:18 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/30 05:41:18 DEBUG : two: Unchanged skipping 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Waiting for checks to finish 2026/04/30 05:41:18 INFO : one: Moved (server-side) to: one.bak 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Waiting for transfers to finish 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:28682") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:53098->172.17.0.4:28682, err= 2026/04/30 05:41:18 DEBUG : one.a292864a.partial: size = 4 OK 2026/04/30 05:41:18 DEBUG : one.a292864a.partial: renamed to: one 2026/04/30 05:41:18 INFO : one: Copied (new) 2026/04/30 05:41:18 DEBUG : Waiting for deletions to finish 2026/04/30 05:41:18 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/04/30 05:41:18 INFO : three.txt: Moved into backup dir 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:61303") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:44304->172.17.0.4:61303, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: Connecting to FTP server 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:21") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:39382->172.17.0.4:21, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:62383") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:56168->172.17.0.4:62383, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:27788") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:51436->172.17.0.4:27788, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:38369") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:52868->172.17.0.4:38369, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:30203") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:37124->172.17.0.4:30203, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:10344") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:60171") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:46364->172.17.0.4:60171, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:57228->172.17.0.4:10344, err= 2026/04/30 05:41:18 DEBUG : fs cache: switching user supplied name "TestFTPProftpd:rclone-test-gedibon7buto/backup" for canonical name "TestFTPProftpd{zHO13}:rclone-test-gedibon7buto/backup" 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:58207") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:48564->172.17.0.4:58207, err= 2026/04/30 05:41:18 DEBUG : one: size = 5 (Local file system at /tmp/rclone381554492) 2026/04/30 05:41:18 DEBUG : one: size = 4 (ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst) 2026/04/30 05:41:18 DEBUG : one: Sizes differ 2026/04/30 05:41:18 DEBUG : two: size = 3 OK 2026/04/30 05:41:18 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/30 05:41:18 DEBUG : two: Unchanged skipping 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Waiting for checks to finish 2026/04/30 05:41:18 INFO : one.bak: Deleted 2026/04/30 05:41:18 INFO : one: Moved (server-side) to: one.bak 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Waiting for transfers to finish 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:29477") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:54226->172.17.0.4:29477, err= 2026/04/30 05:41:18 DEBUG : one.4c47835a.partial: size = 5 OK 2026/04/30 05:41:18 DEBUG : one.4c47835a.partial: renamed to: one 2026/04/30 05:41:18 INFO : one: Copied (new) 2026/04/30 05:41:18 DEBUG : Waiting for deletions to finish 2026/04/30 05:41:18 INFO : three.txt.bak: Deleted 2026/04/30 05:41:18 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/04/30 05:41:18 INFO : three.txt: Moved into backup dir 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:29235") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:34290->172.17.0.4:29235, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:39025") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:51800->172.17.0.4:39025, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:23736") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:53660->172.17.0.4:23736, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:3220") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:39180->172.17.0.4:3220, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:44239") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:51408->172.17.0.4:44239, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:23818") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:39400->172.17.0.4:23818, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:16943") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:41946->172.17.0.4:16943, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/backup: closing 1 unused connections --- PASS: TestSyncBackupDirWithSuffix (0.11s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:185: Remote "ftp://172.17.0.4:21/rclone-test-gedibon7buto", Local "Local file system at /tmp/rclone381554492", Modify Window "1s" 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:30165") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:41544->172.17.0.4:30165, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:27359") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:37072->172.17.0.4:27359, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:33708") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:57790->172.17.0.4:33708, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:6861") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:51058->172.17.0.4:6861, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:8372") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:33688->172.17.0.4:8372, err= 2026/04/30 05:41:18 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-gedibon7buto/dst" 2026/04/30 05:41:18 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/04/30 05:41:18 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/04/30 05:41:18 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/04/30 05:41:18 DEBUG : Setting pass="IUkwnytnB_eUiYBvUdjCvJBRmdWclT-iwId0BhY4NK7y7g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/04/30 05:41:18 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/04/30 05:41:18 DEBUG : TestFTPProftpd: detected overridden config - adding "{zHO13}" suffix to name 2026/04/30 05:41:18 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/04/30 05:41:18 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/04/30 05:41:18 DEBUG : Setting pass="IUkwnytnB_eUiYBvUdjCvJBRmdWclT-iwId0BhY4NK7y7g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/04/30 05:41:18 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Connecting to FTP server 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:21") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:39386->172.17.0.4:21, err= 2026/04/30 05:41:18 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-gedibon7buto/backup" 2026/04/30 05:41:18 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/04/30 05:41:18 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/04/30 05:41:18 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/04/30 05:41:18 DEBUG : Setting pass="IUkwnytnB_eUiYBvUdjCvJBRmdWclT-iwId0BhY4NK7y7g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/04/30 05:41:18 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/04/30 05:41:18 DEBUG : TestFTPProftpd: detected overridden config - adding "{zHO13}" suffix to name 2026/04/30 05:41:18 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/04/30 05:41:18 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/04/30 05:41:18 DEBUG : Setting pass="IUkwnytnB_eUiYBvUdjCvJBRmdWclT-iwId0BhY4NK7y7g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/04/30 05:41:18 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/backup: Connecting to FTP server 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/backup: dial("tcp","172.17.0.4:21") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/backup: > dial: conn=172.17.0.1:39418->172.17.0.4:21, err= 2026/04/30 05:41:18 DEBUG : fs cache: renaming cache item "TestFTPProftpd:rclone-test-gedibon7buto/backup" to be canonical "TestFTPProftpd{zHO13}:rclone-test-gedibon7buto/backup" 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:23744") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:51024->172.17.0.4:23744, err= 2026/04/30 05:41:18 DEBUG : one: size = 4 (Local file system at /tmp/rclone381554492) 2026/04/30 05:41:18 DEBUG : one: size = 3 (ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst) 2026/04/30 05:41:18 DEBUG : one: Sizes differ 2026/04/30 05:41:18 DEBUG : two: size = 3 OK 2026/04/30 05:41:18 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/30 05:41:18 DEBUG : two: Unchanged skipping 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Waiting for checks to finish 2026/04/30 05:41:18 INFO : one: Moved (server-side) to: one-2019-01-01 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Waiting for transfers to finish 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:65450") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:38262->172.17.0.4:65450, err= 2026/04/30 05:41:18 DEBUG : one.a292864a.partial: size = 4 OK 2026/04/30 05:41:18 DEBUG : one.a292864a.partial: renamed to: one 2026/04/30 05:41:18 INFO : one: Copied (new) 2026/04/30 05:41:18 DEBUG : Waiting for deletions to finish 2026/04/30 05:41:18 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/04/30 05:41:18 INFO : three.txt: Moved into backup dir 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:40620") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:41928->172.17.0.4:40620, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:17429") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:64254") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:43940->172.17.0.4:64254, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:45008->172.17.0.4:17429, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:39407") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:60382->172.17.0.4:39407, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:55223") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:56456->172.17.0.4:55223, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:51312") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:31969") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:53178->172.17.0.4:51312, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:38806->172.17.0.4:31969, err= 2026/04/30 05:41:18 DEBUG : fs cache: switching user supplied name "TestFTPProftpd:rclone-test-gedibon7buto/backup" for canonical name "TestFTPProftpd{zHO13}:rclone-test-gedibon7buto/backup" 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:41501") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:46332->172.17.0.4:41501, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Waiting for checks to finish 2026/04/30 05:41:18 DEBUG : one: size = 5 (Local file system at /tmp/rclone381554492) 2026/04/30 05:41:18 DEBUG : one: size = 4 (ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst) 2026/04/30 05:41:18 DEBUG : one: Sizes differ 2026/04/30 05:41:18 DEBUG : two: size = 3 OK 2026/04/30 05:41:18 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/30 05:41:18 DEBUG : two: Unchanged skipping 2026/04/30 05:41:18 INFO : one-2019-01-01: Deleted 2026/04/30 05:41:18 INFO : one: Moved (server-side) to: one-2019-01-01 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Waiting for transfers to finish 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:28805") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:36430->172.17.0.4:28805, err= 2026/04/30 05:41:18 DEBUG : one.4c47835a.partial: size = 5 OK 2026/04/30 05:41:18 DEBUG : one.4c47835a.partial: renamed to: one 2026/04/30 05:41:18 INFO : one: Copied (new) 2026/04/30 05:41:18 DEBUG : Waiting for deletions to finish 2026/04/30 05:41:18 INFO : three-2019-01-01.txt: Deleted 2026/04/30 05:41:18 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/04/30 05:41:18 INFO : three.txt: Moved into backup dir 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:31780") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:44748->172.17.0.4:31780, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:30510") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:58768->172.17.0.4:30510, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:17070") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:46710->172.17.0.4:17070, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:16527") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:55384->172.17.0.4:16527, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:20502") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:41576->172.17.0.4:20502, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:31447") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:40672->172.17.0.4:31447, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:30019") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:52880->172.17.0.4:30019, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/backup: closing 1 unused connections --- PASS: TestSyncBackupDirWithSuffixKeepExtension (0.26s) === RUN TestSyncBackupDirSuffixOnly run.go:185: Remote "ftp://172.17.0.4:21/rclone-test-gedibon7buto", Local "Local file system at /tmp/rclone381554492", Modify Window "1s" 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:47587") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:45844->172.17.0.4:47587, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:2413") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:54888->172.17.0.4:2413, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:37022") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:57052->172.17.0.4:37022, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:18985") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:35834->172.17.0.4:18985, err= 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:16928") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:42742->172.17.0.4:16928, err= 2026/04/30 05:41:18 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-gedibon7buto/dst" 2026/04/30 05:41:18 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/04/30 05:41:18 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/04/30 05:41:18 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/04/30 05:41:18 DEBUG : Setting pass="IUkwnytnB_eUiYBvUdjCvJBRmdWclT-iwId0BhY4NK7y7g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/04/30 05:41:18 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/04/30 05:41:18 DEBUG : TestFTPProftpd: detected overridden config - adding "{zHO13}" suffix to name 2026/04/30 05:41:18 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/04/30 05:41:18 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/04/30 05:41:18 DEBUG : Setting pass="IUkwnytnB_eUiYBvUdjCvJBRmdWclT-iwId0BhY4NK7y7g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/04/30 05:41:18 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Connecting to FTP server 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:21") 2026/04/30 05:41:18 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:39434->172.17.0.4:21, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:36248") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:40944->172.17.0.4:36248, err= 2026/04/30 05:41:19 DEBUG : two: size = 3 OK 2026/04/30 05:41:19 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/30 05:41:19 DEBUG : two: Unchanged skipping 2026/04/30 05:41:19 DEBUG : one: size = 4 (Local file system at /tmp/rclone381554492) 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Waiting for checks to finish 2026/04/30 05:41:19 DEBUG : one: size = 3 (ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst) 2026/04/30 05:41:19 DEBUG : one: Sizes differ 2026/04/30 05:41:19 INFO : one: Moved (server-side) to: one.bak 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Waiting for transfers to finish 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:8878") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:40250->172.17.0.4:8878, err= 2026/04/30 05:41:19 DEBUG : one.a292864a.partial: size = 4 OK 2026/04/30 05:41:19 DEBUG : one.a292864a.partial: renamed to: one 2026/04/30 05:41:19 INFO : one: Copied (new) 2026/04/30 05:41:19 DEBUG : Waiting for deletions to finish 2026/04/30 05:41:19 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/04/30 05:41:19 INFO : three.txt: Moved into backup dir 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:53063") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:58210->172.17.0.4:53063, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:27559") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:39564->172.17.0.4:27559, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:16550") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:54964->172.17.0.4:16550, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:16310") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:35878->172.17.0.4:16310, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:1370") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:40106->172.17.0.4:1370, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:16915") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:40398->172.17.0.4:16915, err= 2026/04/30 05:41:19 DEBUG : one.bak: Excluded (Path Filter) 2026/04/30 05:41:19 DEBUG : one.bak: Excluded 2026/04/30 05:41:19 DEBUG : three.txt.bak: Excluded (Path Filter) 2026/04/30 05:41:19 DEBUG : three.txt.bak: Excluded 2026/04/30 05:41:19 DEBUG : one: size = 5 (Local file system at /tmp/rclone381554492) 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Waiting for checks to finish 2026/04/30 05:41:19 DEBUG : one: size = 4 (ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst) 2026/04/30 05:41:19 DEBUG : two: size = 3 OK 2026/04/30 05:41:19 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/30 05:41:19 DEBUG : two: Unchanged skipping 2026/04/30 05:41:19 DEBUG : one: Sizes differ 2026/04/30 05:41:19 INFO : one.bak: Deleted 2026/04/30 05:41:19 INFO : one: Moved (server-side) to: one.bak 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Waiting for transfers to finish 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:6828") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:33028->172.17.0.4:6828, err= 2026/04/30 05:41:19 DEBUG : one.4c47835a.partial: size = 5 OK 2026/04/30 05:41:19 DEBUG : one.4c47835a.partial: renamed to: one 2026/04/30 05:41:19 INFO : one: Copied (new) 2026/04/30 05:41:19 DEBUG : Waiting for deletions to finish 2026/04/30 05:41:19 INFO : three.txt.bak: Deleted 2026/04/30 05:41:19 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/04/30 05:41:19 INFO : three.txt: Moved into backup dir 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:42506") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:34576->172.17.0.4:42506, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:24442") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:43916->172.17.0.4:24442, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:47250") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:56574->172.17.0.4:47250, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:1219") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:53416->172.17.0.4:1219, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:11532") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:42220->172.17.0.4:11532, err= --- PASS: TestSyncBackupDirSuffixOnly (0.27s) === RUN TestSyncSuffix run.go:185: Remote "ftp://172.17.0.4:21/rclone-test-gedibon7buto", Local "Local file system at /tmp/rclone381554492", Modify Window "1s" 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:32811") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:41538->172.17.0.4:32811, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:2989") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:33930->172.17.0.4:2989, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:59566") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:58462->172.17.0.4:59566, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:60839") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:56658->172.17.0.4:60839, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:34519") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:36580->172.17.0.4:34519, err= 2026/04/30 05:41:19 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-gedibon7buto/dst" 2026/04/30 05:41:19 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/04/30 05:41:19 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/04/30 05:41:19 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/04/30 05:41:19 DEBUG : Setting pass="IUkwnytnB_eUiYBvUdjCvJBRmdWclT-iwId0BhY4NK7y7g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/04/30 05:41:19 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/04/30 05:41:19 DEBUG : TestFTPProftpd: detected overridden config - adding "{zHO13}" suffix to name 2026/04/30 05:41:19 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/04/30 05:41:19 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/04/30 05:41:19 DEBUG : Setting pass="IUkwnytnB_eUiYBvUdjCvJBRmdWclT-iwId0BhY4NK7y7g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/04/30 05:41:19 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Connecting to FTP server 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:21") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:39448->172.17.0.4:21, err= 2026/04/30 05:41:19 DEBUG : one: size = 4 (Local file system at /tmp/rclone381554492) 2026/04/30 05:41:19 DEBUG : one: size = 3 (ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst) 2026/04/30 05:41:19 DEBUG : one: Sizes differ 2026/04/30 05:41:19 INFO : one: Moved (server-side) to: one.bak 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:29560") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:51536->172.17.0.4:29560, err= 2026/04/30 05:41:19 DEBUG : one.a292864a.partial: size = 4 OK 2026/04/30 05:41:19 DEBUG : one.a292864a.partial: renamed to: one 2026/04/30 05:41:19 INFO : one: Copied (new) 2026/04/30 05:41:19 DEBUG : two: size = 3 OK 2026/04/30 05:41:19 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/30 05:41:19 DEBUG : two: Unchanged skipping 2026/04/30 05:41:19 DEBUG : three.txt: size = 6 (Local file system at /tmp/rclone381554492) 2026/04/30 05:41:19 DEBUG : three.txt: size = 5 (ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst) 2026/04/30 05:41:19 DEBUG : three.txt: Sizes differ 2026/04/30 05:41:19 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:29419") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:49796->172.17.0.4:29419, err= 2026/04/30 05:41:19 DEBUG : three.txt.a30b1e7a.partial: size = 6 OK 2026/04/30 05:41:19 DEBUG : three.txt.a30b1e7a.partial: renamed to: three.txt 2026/04/30 05:41:19 INFO : three.txt: Copied (new) 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:41199") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:57642->172.17.0.4:41199, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:20703") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:42322->172.17.0.4:20703, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:59929") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:41890->172.17.0.4:59929, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:55335") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:34892->172.17.0.4:55335, err= 2026/04/30 05:41:19 DEBUG : one: size = 5 (Local file system at /tmp/rclone381554492) 2026/04/30 05:41:19 DEBUG : one: size = 4 (ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst) 2026/04/30 05:41:19 DEBUG : one: Sizes differ 2026/04/30 05:41:19 INFO : one.bak: Deleted 2026/04/30 05:41:19 INFO : one: Moved (server-side) to: one.bak 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:46102") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:50500->172.17.0.4:46102, err= 2026/04/30 05:41:19 DEBUG : one.4c47835a.partial: size = 5 OK 2026/04/30 05:41:19 DEBUG : one.4c47835a.partial: renamed to: one 2026/04/30 05:41:19 INFO : one: Copied (new) 2026/04/30 05:41:19 DEBUG : two: size = 3 OK 2026/04/30 05:41:19 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/30 05:41:19 DEBUG : two: Unchanged skipping 2026/04/30 05:41:19 DEBUG : three.txt: size = 19 (Local file system at /tmp/rclone381554492) 2026/04/30 05:41:19 DEBUG : three.txt: size = 6 (ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst) 2026/04/30 05:41:19 DEBUG : three.txt: Sizes differ 2026/04/30 05:41:19 INFO : three.txt.bak: Deleted 2026/04/30 05:41:19 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:54580") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:50288->172.17.0.4:54580, err= 2026/04/30 05:41:19 DEBUG : three.txt.ef54d6c9.partial: size = 19 OK 2026/04/30 05:41:19 DEBUG : three.txt.ef54d6c9.partial: renamed to: three.txt 2026/04/30 05:41:19 INFO : three.txt: Copied (new) 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:7911") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:47022->172.17.0.4:7911, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:31367") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:41648->172.17.0.4:31367, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:61919") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:52228->172.17.0.4:61919, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:49883") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:37206->172.17.0.4:49883, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:49971") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:49408->172.17.0.4:49971, err= --- PASS: TestSyncSuffix (0.09s) === RUN TestSyncSuffixKeepExtension run.go:185: Remote "ftp://172.17.0.4:21/rclone-test-gedibon7buto", Local "Local file system at /tmp/rclone381554492", Modify Window "1s" 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:4858") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:57104->172.17.0.4:4858, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:52079") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:57156->172.17.0.4:52079, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:8254") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:38030->172.17.0.4:8254, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:37339") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:56846->172.17.0.4:37339, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:10696") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:54688->172.17.0.4:10696, err= 2026/04/30 05:41:19 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-gedibon7buto/dst" 2026/04/30 05:41:19 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/04/30 05:41:19 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/04/30 05:41:19 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/04/30 05:41:19 DEBUG : Setting pass="IUkwnytnB_eUiYBvUdjCvJBRmdWclT-iwId0BhY4NK7y7g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/04/30 05:41:19 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/04/30 05:41:19 DEBUG : TestFTPProftpd: detected overridden config - adding "{zHO13}" suffix to name 2026/04/30 05:41:19 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/04/30 05:41:19 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/04/30 05:41:19 DEBUG : Setting pass="IUkwnytnB_eUiYBvUdjCvJBRmdWclT-iwId0BhY4NK7y7g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/04/30 05:41:19 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: Connecting to FTP server 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:21") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:39462->172.17.0.4:21, err= 2026/04/30 05:41:19 DEBUG : one: size = 4 (Local file system at /tmp/rclone381554492) 2026/04/30 05:41:19 DEBUG : one: size = 3 (ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst) 2026/04/30 05:41:19 DEBUG : one: Sizes differ 2026/04/30 05:41:19 INFO : one: Moved (server-side) to: one-2019-01-01 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:61574") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:42898->172.17.0.4:61574, err= 2026/04/30 05:41:19 DEBUG : one.a292864a.partial: size = 4 OK 2026/04/30 05:41:19 DEBUG : one.a292864a.partial: renamed to: one 2026/04/30 05:41:19 INFO : one: Copied (new) 2026/04/30 05:41:19 DEBUG : two: size = 3 OK 2026/04/30 05:41:19 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/30 05:41:19 DEBUG : two: Unchanged skipping 2026/04/30 05:41:19 DEBUG : three.txt: size = 6 (Local file system at /tmp/rclone381554492) 2026/04/30 05:41:19 DEBUG : three.txt: size = 5 (ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst) 2026/04/30 05:41:19 DEBUG : three.txt: Sizes differ 2026/04/30 05:41:19 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:36515") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:37094->172.17.0.4:36515, err= 2026/04/30 05:41:19 DEBUG : three.txt.a30b1e7a.partial: size = 6 OK 2026/04/30 05:41:19 DEBUG : three.txt.a30b1e7a.partial: renamed to: three.txt 2026/04/30 05:41:19 INFO : three.txt: Copied (new) 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:62566") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:45380->172.17.0.4:62566, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:27165") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:36264->172.17.0.4:27165, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:60936") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:37170->172.17.0.4:60936, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:24659") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:34654->172.17.0.4:24659, err= 2026/04/30 05:41:19 DEBUG : one: size = 5 (Local file system at /tmp/rclone381554492) 2026/04/30 05:41:19 DEBUG : one: size = 4 (ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst) 2026/04/30 05:41:19 DEBUG : one: Sizes differ 2026/04/30 05:41:19 INFO : one-2019-01-01: Deleted 2026/04/30 05:41:19 INFO : one: Moved (server-side) to: one-2019-01-01 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:29198") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:38320->172.17.0.4:29198, err= 2026/04/30 05:41:19 DEBUG : one.4c47835a.partial: size = 5 OK 2026/04/30 05:41:19 DEBUG : one.4c47835a.partial: renamed to: one 2026/04/30 05:41:19 INFO : one: Copied (new) 2026/04/30 05:41:19 DEBUG : two: size = 3 OK 2026/04/30 05:41:19 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/30 05:41:19 DEBUG : two: Unchanged skipping 2026/04/30 05:41:19 DEBUG : three.txt: size = 19 (Local file system at /tmp/rclone381554492) 2026/04/30 05:41:19 DEBUG : three.txt: size = 6 (ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst) 2026/04/30 05:41:19 DEBUG : three.txt: Sizes differ 2026/04/30 05:41:19 INFO : three-2019-01-01.txt: Deleted 2026/04/30 05:41:19 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: dial("tcp","172.17.0.4:2398") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto/dst: > dial: conn=172.17.0.1:59568->172.17.0.4:2398, err= 2026/04/30 05:41:19 DEBUG : three.txt.ef54d6c9.partial: size = 19 OK 2026/04/30 05:41:19 DEBUG : three.txt.ef54d6c9.partial: renamed to: three.txt 2026/04/30 05:41:19 INFO : three.txt: Copied (new) 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:6738") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:41778->172.17.0.4:6738, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:64896") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:32894->172.17.0.4:64896, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:24820") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:52894->172.17.0.4:24820, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:58110") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:39416->172.17.0.4:58110, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:54146") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:47904->172.17.0.4:54146, err= --- PASS: TestSyncSuffixKeepExtension (0.06s) PASS 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: dial("tcp","172.17.0.4:33770") 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: > dial: conn=172.17.0.1:36008->172.17.0.4:33770, err= 2026/04/30 05:41:19 DEBUG : ftp://172.17.0.4:21/rclone-test-gedibon7buto: Purge dir "" "./sync.test -test.v -test.timeout 1h0m0s -remote TestFTPProftpd: -verbose -test.run '^(TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncSuffix|TestSyncSuffixKeepExtension)$'" - Finished OK in 1.011923229s (try 2/5)