"./sync.test -test.v -test.timeout 1h0m0s -remote TestFTPPureftpd: -verbose -test.run '^(TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveWithFilter|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffixKeepExtension|TestSyncSuffix)$'" - Starting (try 2/5) 2026/02/23 01:16:02 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-cidiras8bedi" 2026/02/23 01:16:02 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/23 01:16:02 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : TestFTPPureftpd: detected overridden config - adding "{osbDa}" suffix to name 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: Connecting to FTP server 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:21") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:42954->172.17.0.9:21, err= 2026/02/23 01:16:02 DEBUG : Creating backend with remote "/tmp/rclone731763524" === RUN TestServerSideMoveWithFilter run.go:185: Remote "ftp://172.17.0.9:21/rclone-test-cidiras8bedi", Local "Local file system at /tmp/rclone731763524", Modify Window "1s" 2026/02/23 01:16:02 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-boyotib1goke" 2026/02/23 01:16:02 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : TestFTPPureftpd: detected overridden config - adding "{osbDa}" suffix to name 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: Connecting to FTP server 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: dial("tcp","172.17.0.9:21") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: > dial: conn=172.17.0.1:42968->172.17.0.9:21, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:34449") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:42778->172.17.0.9:34449, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:31032") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:49958->172.17.0.9:31032, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:38013") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:34734->172.17.0.9:38013, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:31738") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:40050->172.17.0.9:31738, err= sync_test.go:1736: Server side move (if possible) ftp://172.17.0.9:21/rclone-test-cidiras8bedi -> ftp://172.17.0.9:21/rclone-test-boyotib1goke 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: dial("tcp","172.17.0.9:37031") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: > dial: conn=172.17.0.1:55376->172.17.0.9:37031, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: dial("tcp","172.17.0.9:31049") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: > dial: conn=172.17.0.1:35210->172.17.0.9:31049, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: dial("tcp","172.17.0.9:39710") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: > dial: conn=172.17.0.1:41712->172.17.0.9:39710, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: dial("tcp","172.17.0.9:31549") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:30400") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: > dial: conn=172.17.0.1:59926->172.17.0.9:31549, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:39866->172.17.0.9:30400, err= 2026/02/23 01:16:02 DEBUG : empty space: Excluded (Size Filter) 2026/02/23 01:16:02 DEBUG : empty space: Excluded 2026/02/23 01:16:02 DEBUG : empty space: Excluded (Size Filter) 2026/02/23 01:16:02 DEBUG : empty space: Excluded 2026/02/23 01:16:02 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/23 01:16:02 DEBUG : potato3: size = 68 (ftp://172.17.0.9:21/rclone-test-cidiras8bedi) 2026/02/23 01:16:02 DEBUG : potato3: size = 60 (ftp://172.17.0.9:21/rclone-test-boyotib1goke) 2026/02/23 01:16:02 DEBUG : potato3: Sizes differ 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: Waiting for checks to finish 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: Waiting for transfers to finish 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: Connecting to FTP server 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: dial("tcp","172.17.0.9:21") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: > dial: conn=172.17.0.1:42982->172.17.0.9:21, err= 2026/02/23 01:16:02 INFO : potato2: Moved (server-side) 2026/02/23 01:16:02 INFO : potato3: Deleted 2026/02/23 01:16:02 INFO : potato3: Moved (server-side) 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:31112") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:54460->172.17.0.9:31112, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: dial("tcp","172.17.0.9:31078") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: > dial: conn=172.17.0.1:53970->172.17.0.9:31078, err= 2026/02/23 01:16:02 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-balafok9juri" 2026/02/23 01:16:02 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : TestFTPPureftpd: detected overridden config - adding "{osbDa}" suffix to name 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: Connecting to FTP server 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: dial("tcp","172.17.0.9:21") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: > dial: conn=172.17.0.1:42992->172.17.0.9:21, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: dial("tcp","172.17.0.9:33406") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: dial("tcp","172.17.0.9:30266") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: > dial: conn=172.17.0.1:56872->172.17.0.9:33406, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: > dial: conn=172.17.0.1:42782->172.17.0.9:30266, err= 2026/02/23 01:16:02 DEBUG : empty space: Excluded (Size Filter) 2026/02/23 01:16:02 DEBUG : empty space: Excluded 2026/02/23 01:16:02 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/23 01:16:02 DEBUG : potato3: Need to transfer - File not found at Destination 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: Connecting to FTP server 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: dial("tcp","172.17.0.9:21") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: Waiting for checks to finish 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: Waiting for transfers to finish 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: > dial: conn=172.17.0.1:43008->172.17.0.9:21, err= 2026/02/23 01:16:02 INFO : potato2: Moved (server-side) 2026/02/23 01:16:02 INFO : potato3: Moved (server-side) 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: dial("tcp","172.17.0.9:36725") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: > dial: conn=172.17.0.1:58192->172.17.0.9:36725, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: dial("tcp","172.17.0.9:37536") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: > dial: conn=172.17.0.1:46658->172.17.0.9:37536, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: dial("tcp","172.17.0.9:39269") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: > dial: conn=172.17.0.1:43028->172.17.0.9:39269, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: Purge object "potato2" 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: Purge object "potato3" 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-balafok9juri: Purge dir "" 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: dial("tcp","172.17.0.9:38018") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: > dial: conn=172.17.0.1:38718->172.17.0.9:38018, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: Purge object "empty space" 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-boyotib1goke: Purge dir "" 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:34592") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:57832->172.17.0.9:34592, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:37337") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:42690->172.17.0.9:37337, err= --- PASS: TestServerSideMoveWithFilter (0.09s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "ftp://172.17.0.9:21/rclone-test-cidiras8bedi", Local "Local file system at /tmp/rclone731763524", Modify Window "1s" 2026/02/23 01:16:02 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-zuwawas3hohe" 2026/02/23 01:16:02 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : TestFTPPureftpd: detected overridden config - adding "{osbDa}" suffix to name 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: Connecting to FTP server 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: dial("tcp","172.17.0.9:21") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: > dial: conn=172.17.0.1:43014->172.17.0.9:21, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:30989") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:44340->172.17.0.9:30989, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:31362") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:59836->172.17.0.9:31362, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:35149") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:39956->172.17.0.9:35149, err= 2026/02/23 01:16:02 INFO : tomatoDir: Making directory 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:34273") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:37308->172.17.0.9:34273, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:33980") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:35980->172.17.0.9:33980, err= sync_test.go:1736: Server side move (if possible) ftp://172.17.0.9:21/rclone-test-cidiras8bedi -> ftp://172.17.0.9:21/rclone-test-zuwawas3hohe 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: dial("tcp","172.17.0.9:37552") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: > dial: conn=172.17.0.1:47216->172.17.0.9:37552, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: dial("tcp","172.17.0.9:32514") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: > dial: conn=172.17.0.1:37466->172.17.0.9:32514, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: dial("tcp","172.17.0.9:32303") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: > dial: conn=172.17.0.1:55418->172.17.0.9:32303, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: Using server-side directory move 2026/02/23 01:16:02 INFO : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:37354") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: dial("tcp","172.17.0.9:39057") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:34506->172.17.0.9:37354, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: > dial: conn=172.17.0.1:56446->172.17.0.9:39057, err= 2026/02/23 01:16:02 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/23 01:16:02 DEBUG : potato3: size = 68 (ftp://172.17.0.9:21/rclone-test-cidiras8bedi) 2026/02/23 01:16:02 DEBUG : potato3: size = 60 (ftp://172.17.0.9:21/rclone-test-zuwawas3hohe) 2026/02/23 01:16:02 DEBUG : potato3: Sizes differ 2026/02/23 01:16:02 DEBUG : empty space: size = 1 OK 2026/02/23 01:16:02 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:30697") 2026/02/23 01:16:02 DEBUG : empty space: Unchanged skipping 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: Connecting to FTP server 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:21") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:54910->172.17.0.9:30697, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: Connecting to FTP server 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: dial("tcp","172.17.0.9:21") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: > dial: conn=172.17.0.1:43040->172.17.0.9:21, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: Waiting for checks to finish 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:43030->172.17.0.9:21, err= 2026/02/23 01:16:02 INFO : potato2: Moved (server-side) 2026/02/23 01:16:02 INFO : potato3: Deleted 2026/02/23 01:16:02 INFO : empty space: Deleted 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: Waiting for transfers to finish 2026/02/23 01:16:02 INFO : potato3: Moved (server-side) 2026/02/23 01:16:02 INFO : tomatoDir: Removing directory 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: deleted 1 directories 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:35732") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:52914->172.17.0.9:35732, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:35808") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:40464->172.17.0.9:35808, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: dial("tcp","172.17.0.9:35979") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: > dial: conn=172.17.0.1:58020->172.17.0.9:35979, err= 2026/02/23 01:16:02 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-yazimej0mace" 2026/02/23 01:16:02 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : TestFTPPureftpd: detected overridden config - adding "{osbDa}" suffix to name 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: Connecting to FTP server 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: dial("tcp","172.17.0.9:21") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: > dial: conn=172.17.0.1:43052->172.17.0.9:21, err= 2026/02/23 01:16:02 INFO : tomatoDir: Making directory 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: Using server-side directory move 2026/02/23 01:16:02 INFO : ftp://172.17.0.9:21/rclone-test-yazimej0mace: Server side directory move succeeded 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: dial("tcp","172.17.0.9:35359") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: > dial: conn=172.17.0.1:38810->172.17.0.9:35359, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: dial("tcp","172.17.0.9:39805") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: > dial: conn=172.17.0.1:35204->172.17.0.9:39805, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: dial("tcp","172.17.0.9:30572") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: > dial: conn=172.17.0.1:39790->172.17.0.9:30572, err= 2026/02/23 01:16:02 ERROR : error listing: directory not found 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: dial("tcp","172.17.0.9:30019") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: > dial: conn=172.17.0.1:39062->172.17.0.9:30019, err= 2026/02/23 01:16:02 ERROR : error listing: directory not found 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: dial("tcp","172.17.0.9:34812") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: > dial: conn=172.17.0.1:56372->172.17.0.9:34812, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: Purge object "empty space" 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: Purge object "potato2" 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: Purge object "potato3" 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: dial("tcp","172.17.0.9:32589") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: > dial: conn=172.17.0.1:48936->172.17.0.9:32589, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: Purge dir "tomatoDir" 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-yazimej0mace: Purge dir "" 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: dial("tcp","172.17.0.9:33557") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: > dial: conn=172.17.0.1:37028->172.17.0.9:33557, err= 2026/02/23 01:16:02 ERROR : error listing: directory not found 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-zuwawas3hohe: Purge dir "" 2026/02/23 01:16:02 NOTICE: purge failed to rmdir "": directory not found 2026/02/23 01:16:02 NOTICE: purge failed: directory not found 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:37644") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:40238->172.17.0.9:37644, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:32348") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:53916->172.17.0.9:32348, err= --- PASS: TestServerSideMoveDeleteEmptySourceDirs (0.07s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:185: Remote "ftp://172.17.0.9:21/rclone-test-cidiras8bedi", Local "Local file system at /tmp/rclone731763524", Modify Window "1s" 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:38699") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:38058->172.17.0.9:38699, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:37827") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:37748->172.17.0.9:37827, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:37795") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:35638->172.17.0.9:37795, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:32096") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:57324->172.17.0.9:32096, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:37521") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:56270->172.17.0.9:37521, err= 2026/02/23 01:16:02 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-cidiras8bedi/dst" 2026/02/23 01:16:02 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : TestFTPPureftpd: detected overridden config - adding "{osbDa}" suffix to name 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: Connecting to FTP server 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: dial("tcp","172.17.0.9:21") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: > dial: conn=172.17.0.1:43056->172.17.0.9:21, err= 2026/02/23 01:16:02 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-cidiras8bedi/backup" 2026/02/23 01:16:02 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : TestFTPPureftpd: detected overridden config - adding "{osbDa}" suffix to name 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/backup: Connecting to FTP server 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/backup: dial("tcp","172.17.0.9:21") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/backup: > dial: conn=172.17.0.1:43068->172.17.0.9:21, err= 2026/02/23 01:16:02 DEBUG : fs cache: renaming cache item "TestFTPPureftpd:rclone-test-cidiras8bedi/backup" to be canonical "TestFTPPureftpd{osbDa}:rclone-test-cidiras8bedi/backup" 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: dial("tcp","172.17.0.9:37133") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: > dial: conn=172.17.0.1:55956->172.17.0.9:37133, err= 2026/02/23 01:16:02 DEBUG : two: size = 3 OK 2026/02/23 01:16:02 DEBUG : one: size = 4 (Local file system at /tmp/rclone731763524) 2026/02/23 01:16:02 DEBUG : one: size = 3 (ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst) 2026/02/23 01:16:02 DEBUG : one: Sizes differ 2026/02/23 01:16:02 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/23 01:16:02 DEBUG : two: Unchanged skipping 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: Waiting for checks to finish 2026/02/23 01:16:02 INFO : one: Moved (server-side) to: one-2019-01-01 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: Waiting for transfers to finish 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: dial("tcp","172.17.0.9:36364") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: > dial: conn=172.17.0.1:34298->172.17.0.9:36364, err= 2026/02/23 01:16:02 DEBUG : one.a292864a.partial: size = 4 OK 2026/02/23 01:16:02 DEBUG : one.a292864a.partial: renamed to: one 2026/02/23 01:16:02 INFO : one: Copied (new) 2026/02/23 01:16:02 DEBUG : Waiting for deletions to finish 2026/02/23 01:16:02 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/02/23 01:16:02 INFO : three.txt: Moved into backup dir 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:34710") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:33620->172.17.0.9:34710, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:33705") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:35655") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:49802->172.17.0.9:35655, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:51890->172.17.0.9:33705, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:33661") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:46668->172.17.0.9:33661, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:38651") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:60186->172.17.0.9:38651, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:31885") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:32267") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:59176->172.17.0.9:31885, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:45554->172.17.0.9:32267, err= 2026/02/23 01:16:02 DEBUG : fs cache: switching user supplied name "TestFTPPureftpd:rclone-test-cidiras8bedi/backup" for canonical name "TestFTPPureftpd{osbDa}:rclone-test-cidiras8bedi/backup" 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: dial("tcp","172.17.0.9:33899") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: > dial: conn=172.17.0.1:37320->172.17.0.9:33899, err= 2026/02/23 01:16:02 DEBUG : one: size = 5 (Local file system at /tmp/rclone731763524) 2026/02/23 01:16:02 DEBUG : one: size = 4 (ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst) 2026/02/23 01:16:02 DEBUG : one: Sizes differ 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: Waiting for checks to finish 2026/02/23 01:16:02 DEBUG : two: size = 3 OK 2026/02/23 01:16:02 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/23 01:16:02 DEBUG : two: Unchanged skipping 2026/02/23 01:16:02 INFO : one-2019-01-01: Deleted 2026/02/23 01:16:02 INFO : one: Moved (server-side) to: one-2019-01-01 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: Waiting for transfers to finish 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: dial("tcp","172.17.0.9:37990") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: > dial: conn=172.17.0.1:46486->172.17.0.9:37990, err= 2026/02/23 01:16:02 DEBUG : one.4c47835a.partial: size = 5 OK 2026/02/23 01:16:02 DEBUG : one.4c47835a.partial: renamed to: one 2026/02/23 01:16:02 INFO : one: Copied (new) 2026/02/23 01:16:02 DEBUG : Waiting for deletions to finish 2026/02/23 01:16:02 INFO : three-2019-01-01.txt: Deleted 2026/02/23 01:16:02 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/02/23 01:16:02 INFO : three.txt: Moved into backup dir 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:37535") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:36212->172.17.0.9:37535, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:37302") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:38113") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:57462->172.17.0.9:37302, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:34042->172.17.0.9:38113, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:34161") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:60986->172.17.0.9:34161, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:34243") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:30863") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:37182->172.17.0.9:30863, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:54520->172.17.0.9:34243, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:37367") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:60124->172.17.0.9:37367, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/backup: closing 1 unused connections --- PASS: TestSyncBackupDirWithSuffixKeepExtension (0.07s) === RUN TestSyncBackupDirSuffixOnly run.go:185: Remote "ftp://172.17.0.9:21/rclone-test-cidiras8bedi", Local "Local file system at /tmp/rclone731763524", Modify Window "1s" 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:36616") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:44388->172.17.0.9:36616, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:37853") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:39358->172.17.0.9:37853, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:39158") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:35412->172.17.0.9:39158, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:37613") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:56126->172.17.0.9:37613, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:37198") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:47932->172.17.0.9:37198, err= 2026/02/23 01:16:02 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-cidiras8bedi/dst" 2026/02/23 01:16:02 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : TestFTPPureftpd: detected overridden config - adding "{osbDa}" suffix to name 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: Connecting to FTP server 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: dial("tcp","172.17.0.9:21") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: > dial: conn=172.17.0.1:43084->172.17.0.9:21, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: dial("tcp","172.17.0.9:33495") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: > dial: conn=172.17.0.1:35178->172.17.0.9:33495, err= 2026/02/23 01:16:02 DEBUG : one: size = 4 (Local file system at /tmp/rclone731763524) 2026/02/23 01:16:02 DEBUG : one: size = 3 (ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst) 2026/02/23 01:16:02 DEBUG : one: Sizes differ 2026/02/23 01:16:02 DEBUG : two: size = 3 OK 2026/02/23 01:16:02 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/23 01:16:02 DEBUG : two: Unchanged skipping 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: Waiting for checks to finish 2026/02/23 01:16:02 INFO : one: Moved (server-side) to: one.bak 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: Waiting for transfers to finish 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: dial("tcp","172.17.0.9:36380") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: > dial: conn=172.17.0.1:46810->172.17.0.9:36380, err= 2026/02/23 01:16:02 DEBUG : one.a292864a.partial: size = 4 OK 2026/02/23 01:16:02 DEBUG : one.a292864a.partial: renamed to: one 2026/02/23 01:16:02 INFO : one: Copied (new) 2026/02/23 01:16:02 DEBUG : Waiting for deletions to finish 2026/02/23 01:16:02 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/02/23 01:16:02 INFO : three.txt: Moved into backup dir 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:32865") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:34350->172.17.0.9:32865, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:34538") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:41364->172.17.0.9:34538, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:35137") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:58336->172.17.0.9:35137, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:32218") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:44700->172.17.0.9:32218, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:34906") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:36440->172.17.0.9:34906, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: dial("tcp","172.17.0.9:35933") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: > dial: conn=172.17.0.1:39772->172.17.0.9:35933, err= 2026/02/23 01:16:02 DEBUG : one.bak: Excluded (Path Filter) 2026/02/23 01:16:02 DEBUG : one.bak: Excluded 2026/02/23 01:16:02 DEBUG : three.txt.bak: Excluded (Path Filter) 2026/02/23 01:16:02 DEBUG : three.txt.bak: Excluded 2026/02/23 01:16:02 DEBUG : one: size = 5 (Local file system at /tmp/rclone731763524) 2026/02/23 01:16:02 DEBUG : one: size = 4 (ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst) 2026/02/23 01:16:02 DEBUG : one: Sizes differ 2026/02/23 01:16:02 DEBUG : two: size = 3 OK 2026/02/23 01:16:02 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/23 01:16:02 DEBUG : two: Unchanged skipping 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: Waiting for checks to finish 2026/02/23 01:16:02 INFO : one.bak: Deleted 2026/02/23 01:16:02 INFO : one: Moved (server-side) to: one.bak 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: Waiting for transfers to finish 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: dial("tcp","172.17.0.9:34313") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: > dial: conn=172.17.0.1:38078->172.17.0.9:34313, err= 2026/02/23 01:16:02 DEBUG : one.4c47835a.partial: size = 5 OK 2026/02/23 01:16:02 DEBUG : one.4c47835a.partial: renamed to: one 2026/02/23 01:16:02 INFO : one: Copied (new) 2026/02/23 01:16:02 DEBUG : Waiting for deletions to finish 2026/02/23 01:16:02 INFO : three.txt.bak: Deleted 2026/02/23 01:16:02 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/02/23 01:16:02 INFO : three.txt: Moved into backup dir 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:32129") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:44216->172.17.0.9:32129, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:30926") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:53288->172.17.0.9:30926, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:38902") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:40482->172.17.0.9:38902, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:33588") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:57876->172.17.0.9:33588, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:31923") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:42858->172.17.0.9:31923, err= --- PASS: TestSyncBackupDirSuffixOnly (0.04s) === RUN TestSyncSuffix run.go:185: Remote "ftp://172.17.0.9:21/rclone-test-cidiras8bedi", Local "Local file system at /tmp/rclone731763524", Modify Window "1s" 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:32685") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:48972->172.17.0.9:32685, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:31153") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:38078->172.17.0.9:31153, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:37488") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:38132->172.17.0.9:37488, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:39199") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:33930->172.17.0.9:39199, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:36729") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:52006->172.17.0.9:36729, err= 2026/02/23 01:16:02 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-cidiras8bedi/dst" 2026/02/23 01:16:02 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : TestFTPPureftpd: detected overridden config - adding "{osbDa}" suffix to name 2026/02/23 01:16:02 DEBUG : Setting host="172.17.0.9" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/02/23 01:16:02 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/02/23 01:16:02 DEBUG : Setting pass="6RE5Z-eU18qYzwiPVZjDF0IjuQ2TNgP2kgduCpo2qGA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/02/23 01:16:02 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: Connecting to FTP server 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: dial("tcp","172.17.0.9:21") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: > dial: conn=172.17.0.1:43100->172.17.0.9:21, err= 2026/02/23 01:16:02 DEBUG : one: size = 4 (Local file system at /tmp/rclone731763524) 2026/02/23 01:16:02 DEBUG : one: size = 3 (ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst) 2026/02/23 01:16:02 DEBUG : one: Sizes differ 2026/02/23 01:16:02 INFO : one: Moved (server-side) to: one.bak 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: dial("tcp","172.17.0.9:35951") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: > dial: conn=172.17.0.1:43872->172.17.0.9:35951, err= 2026/02/23 01:16:02 DEBUG : one.a292864a.partial: size = 4 OK 2026/02/23 01:16:02 DEBUG : one.a292864a.partial: renamed to: one 2026/02/23 01:16:02 INFO : one: Copied (new) 2026/02/23 01:16:02 DEBUG : two: size = 3 OK 2026/02/23 01:16:02 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/23 01:16:02 DEBUG : two: Unchanged skipping 2026/02/23 01:16:02 DEBUG : three.txt: size = 6 (Local file system at /tmp/rclone731763524) 2026/02/23 01:16:02 DEBUG : three.txt: size = 5 (ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst) 2026/02/23 01:16:02 DEBUG : three.txt: Sizes differ 2026/02/23 01:16:02 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: dial("tcp","172.17.0.9:30405") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: > dial: conn=172.17.0.1:37878->172.17.0.9:30405, err= 2026/02/23 01:16:02 DEBUG : three.txt.a30b1e7a.partial: size = 6 OK 2026/02/23 01:16:02 DEBUG : three.txt.a30b1e7a.partial: renamed to: three.txt 2026/02/23 01:16:02 INFO : three.txt: Copied (new) 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:35119") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:45148->172.17.0.9:35119, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:38312") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:45868->172.17.0.9:38312, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:35093") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:58004->172.17.0.9:35093, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:39399") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:58580->172.17.0.9:39399, err= 2026/02/23 01:16:02 DEBUG : one: size = 5 (Local file system at /tmp/rclone731763524) 2026/02/23 01:16:02 DEBUG : one: size = 4 (ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst) 2026/02/23 01:16:02 DEBUG : one: Sizes differ 2026/02/23 01:16:02 INFO : one.bak: Deleted 2026/02/23 01:16:02 INFO : one: Moved (server-side) to: one.bak 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: dial("tcp","172.17.0.9:32919") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: > dial: conn=172.17.0.1:37476->172.17.0.9:32919, err= 2026/02/23 01:16:02 DEBUG : one.4c47835a.partial: size = 5 OK 2026/02/23 01:16:02 DEBUG : one.4c47835a.partial: renamed to: one 2026/02/23 01:16:02 INFO : one: Copied (new) 2026/02/23 01:16:02 DEBUG : two: size = 3 OK 2026/02/23 01:16:02 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/23 01:16:02 DEBUG : two: Unchanged skipping 2026/02/23 01:16:02 DEBUG : three.txt: size = 19 (Local file system at /tmp/rclone731763524) 2026/02/23 01:16:02 DEBUG : three.txt: size = 6 (ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst) 2026/02/23 01:16:02 DEBUG : three.txt: Sizes differ 2026/02/23 01:16:02 INFO : three.txt.bak: Deleted 2026/02/23 01:16:02 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: dial("tcp","172.17.0.9:30690") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi/dst: > dial: conn=172.17.0.1:50058->172.17.0.9:30690, err= 2026/02/23 01:16:02 DEBUG : three.txt.ef54d6c9.partial: size = 19 OK 2026/02/23 01:16:02 DEBUG : three.txt.ef54d6c9.partial: renamed to: three.txt 2026/02/23 01:16:02 INFO : three.txt: Copied (new) 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:39589") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:39902->172.17.0.9:39589, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:37648") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:34716->172.17.0.9:37648, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:34710") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:33636->172.17.0.9:34710, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:32375") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:37616->172.17.0.9:32375, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:35894") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:35606->172.17.0.9:35894, err= --- PASS: TestSyncSuffix (0.07s) PASS 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: dial("tcp","172.17.0.9:37692") 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: > dial: conn=172.17.0.1:46006->172.17.0.9:37692, err= 2026/02/23 01:16:02 DEBUG : ftp://172.17.0.9:21/rclone-test-cidiras8bedi: Purge dir "" "./sync.test -test.v -test.timeout 1h0m0s -remote TestFTPPureftpd: -verbose -test.run '^(TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveWithFilter|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffixKeepExtension|TestSyncSuffix)$'" - Finished OK in 401.527608ms (try 2/5)