"./sync.test -test.v -test.timeout 1h0m0s -remote TestFTPPureftpd: -verbose -test.run '^(TestServerSideMoveDeleteEmptySourceDirs|TestSyncOverlap|TestSyncOverlapWithFilter)$'" - Starting (try 2/5) 2026/05/29 01:34:56 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-gemaguy1vune" 2026/05/29 01:34:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/29 01:34:56 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/29 01:34:56 DEBUG : Setting host="172.17.0.4" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/29 01:34:56 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/29 01:34:56 DEBUG : Setting pass="nakLAThtSdmzMZQlJ7FFgzoMhEeLtaecJmYgHApecFs" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/29 01:34:56 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/29 01:34:56 DEBUG : TestFTPPureftpd: detected overridden config - adding "{1krN2}" suffix to name 2026/05/29 01:34:56 DEBUG : Setting host="172.17.0.4" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/29 01:34:56 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/29 01:34:56 DEBUG : Setting pass="nakLAThtSdmzMZQlJ7FFgzoMhEeLtaecJmYgHApecFs" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/29 01:34:56 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/29 01:34:56 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: Connecting to FTP server 2026/05/29 01:34:56 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:21") 2026/05/29 01:34:56 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:59518->172.17.0.4:21, err= 2026/05/29 01:34:58 DEBUG : Creating backend with remote "/tmp/rclone345444598" === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:198: Remote "ftp://172.17.0.4:21/rclone-test-gemaguy1vune", Local "Local file system at /tmp/rclone345444598", Modify Window "1s" 2026/05/29 01:34:58 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-fumatek3boja" 2026/05/29 01:34:58 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/29 01:34:58 DEBUG : Setting host="172.17.0.4" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/29 01:34:58 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/29 01:34:58 DEBUG : Setting pass="nakLAThtSdmzMZQlJ7FFgzoMhEeLtaecJmYgHApecFs" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/29 01:34:58 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/29 01:34:58 DEBUG : TestFTPPureftpd: detected overridden config - adding "{1krN2}" suffix to name 2026/05/29 01:34:58 DEBUG : Setting host="172.17.0.4" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/29 01:34:58 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/29 01:34:58 DEBUG : Setting pass="nakLAThtSdmzMZQlJ7FFgzoMhEeLtaecJmYgHApecFs" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/29 01:34:58 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/29 01:34:58 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: Connecting to FTP server 2026/05/29 01:34:58 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: dial("tcp","172.17.0.4:21") 2026/05/29 01:34:58 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: > dial: conn=172.17.0.1:59542->172.17.0.4:21, err= 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:39978") 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:51200->172.17.0.4:39978, err= 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:36072") 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:58424->172.17.0.4:36072, err= 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:38735") 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:54532->172.17.0.4:38735, err= 2026/05/29 01:35:01 INFO : tomatoDir: Making directory 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:39684") 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:55298->172.17.0.4:39684, err= 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:33600") 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:54150->172.17.0.4:33600, err= sync_test.go:1736: Server side move (if possible) ftp://172.17.0.4:21/rclone-test-gemaguy1vune -> ftp://172.17.0.4:21/rclone-test-fumatek3boja 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: dial("tcp","172.17.0.4:36416") 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: > dial: conn=172.17.0.1:46728->172.17.0.4:36416, err= 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: dial("tcp","172.17.0.4:34355") 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: > dial: conn=172.17.0.1:50770->172.17.0.4:34355, err= 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: dial("tcp","172.17.0.4:38902") 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: > dial: conn=172.17.0.1:60712->172.17.0.4:38902, err= 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: Using server-side directory move 2026/05/29 01:35:01 INFO : ftp://172.17.0.4:21/rclone-test-fumatek3boja: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:38440") 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:45948->172.17.0.4:38440, err= 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: dial("tcp","172.17.0.4:39823") 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: > dial: conn=172.17.0.1:52752->172.17.0.4:39823, err= 2026/05/29 01:35:01 DEBUG : potato2: Need to transfer - File not found at Destination 2026/05/29 01:35:01 DEBUG : empty space: size = 1 OK 2026/05/29 01:35:01 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2026/05/29 01:35:01 DEBUG : empty space: Unchanged skipping 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: Connecting to FTP server 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:21") 2026/05/29 01:35:01 DEBUG : potato3: size = 68 (ftp://172.17.0.4:21/rclone-test-gemaguy1vune) 2026/05/29 01:35:01 DEBUG : potato3: size = 60 (ftp://172.17.0.4:21/rclone-test-fumatek3boja) 2026/05/29 01:35:01 DEBUG : potato3: Sizes differ 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: Connecting to FTP server 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: dial("tcp","172.17.0.4:21") 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: > dial: conn=172.17.0.1:59564->172.17.0.4:21, err= 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:59562->172.17.0.4:21, err= 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:36641") 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:43484->172.17.0.4:36641, err= 2026/05/29 01:35:01 INFO : potato2: Moved (server-side) 2026/05/29 01:35:01 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: Waiting for checks to finish 2026/05/29 01:35:03 INFO : empty space: Deleted 2026/05/29 01:35:03 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: Waiting for transfers to finish 2026/05/29 01:35:03 INFO : potato3: Deleted 2026/05/29 01:35:03 INFO : potato3: Moved (server-side) 2026/05/29 01:35:03 INFO : tomatoDir: Removing directory 2026/05/29 01:35:03 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: deleted 1 directories 2026/05/29 01:35:03 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:35752") 2026/05/29 01:35:03 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:51152->172.17.0.4:35752, err= 2026/05/29 01:35:03 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:34362") 2026/05/29 01:35:03 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:60716->172.17.0.4:34362, err= 2026/05/29 01:35:03 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: dial("tcp","172.17.0.4:39782") 2026/05/29 01:35:03 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: > dial: conn=172.17.0.1:47312->172.17.0.4:39782, err= 2026/05/29 01:35:03 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-depumoy3jesi" 2026/05/29 01:35:03 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/29 01:35:03 DEBUG : Setting host="172.17.0.4" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/29 01:35:03 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/29 01:35:03 DEBUG : Setting pass="nakLAThtSdmzMZQlJ7FFgzoMhEeLtaecJmYgHApecFs" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/29 01:35:03 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/29 01:35:03 DEBUG : TestFTPPureftpd: detected overridden config - adding "{1krN2}" suffix to name 2026/05/29 01:35:03 DEBUG : Setting host="172.17.0.4" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/29 01:35:03 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/29 01:35:03 DEBUG : Setting pass="nakLAThtSdmzMZQlJ7FFgzoMhEeLtaecJmYgHApecFs" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/29 01:35:03 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/29 01:35:03 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: Connecting to FTP server 2026/05/29 01:35:03 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: dial("tcp","172.17.0.4:21") 2026/05/29 01:35:03 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: > dial: conn=172.17.0.1:39344->172.17.0.4:21, err= 2026/05/29 01:35:06 INFO : tomatoDir: Making directory 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: Using server-side directory move 2026/05/29 01:35:06 INFO : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: Server side directory move succeeded 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: dial("tcp","172.17.0.4:30233") 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: > dial: conn=172.17.0.1:49834->172.17.0.4:30233, err= 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: dial("tcp","172.17.0.4:39991") 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: > dial: conn=172.17.0.1:47792->172.17.0.4:39991, err= 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: dial("tcp","172.17.0.4:34719") 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: > dial: conn=172.17.0.1:53130->172.17.0.4:34719, err= 2026/05/29 01:35:06 ERROR : error listing: directory not found 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: dial("tcp","172.17.0.4:39212") 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: > dial: conn=172.17.0.1:47638->172.17.0.4:39212, err= 2026/05/29 01:35:06 ERROR : error listing: directory not found 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: dial("tcp","172.17.0.4:35549") 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: > dial: conn=172.17.0.1:50964->172.17.0.4:35549, err= 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: Purge object "empty space" 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: Purge object "potato2" 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: Purge object "potato3" 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: dial("tcp","172.17.0.4:36504") 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: > dial: conn=172.17.0.1:47664->172.17.0.4:36504, err= 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: Purge dir "tomatoDir" 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-depumoy3jesi: Purge dir "" 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: dial("tcp","172.17.0.4:31128") 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: > dial: conn=172.17.0.1:57130->172.17.0.4:31128, err= 2026/05/29 01:35:06 ERROR : error listing: directory not found 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-fumatek3boja: Purge dir "" 2026/05/29 01:35:06 NOTICE: purge failed to rmdir "": directory not found 2026/05/29 01:35:06 NOTICE: purge failed: directory not found 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:39075") 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:57586->172.17.0.4:39075, err= 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:32643") 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:49636->172.17.0.4:32643, err= --- PASS: TestServerSideMoveDeleteEmptySourceDirs (7.34s) === RUN TestSyncOverlap run.go:198: Remote "ftp://172.17.0.4:21/rclone-test-gemaguy1vune", Local "Local file system at /tmp/rclone345444598", Modify Window "1s" 2026/05/29 01:35:06 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-gemaguy1vune/rclone-sync-test" 2026/05/29 01:35:06 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/29 01:35:06 DEBUG : Setting host="172.17.0.4" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/29 01:35:06 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/29 01:35:06 DEBUG : Setting pass="nakLAThtSdmzMZQlJ7FFgzoMhEeLtaecJmYgHApecFs" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/29 01:35:06 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/29 01:35:06 DEBUG : TestFTPPureftpd: detected overridden config - adding "{1krN2}" suffix to name 2026/05/29 01:35:06 DEBUG : Setting host="172.17.0.4" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/29 01:35:06 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/29 01:35:06 DEBUG : Setting pass="nakLAThtSdmzMZQlJ7FFgzoMhEeLtaecJmYgHApecFs" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/29 01:35:06 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test: Connecting to FTP server 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test: dial("tcp","172.17.0.4:21") 2026/05/29 01:35:06 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test: > dial: conn=172.17.0.1:39370->172.17.0.4:21, err= 2026/05/29 01:35:08 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:32115") 2026/05/29 01:35:08 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:37076->172.17.0.4:32115, err= 2026/05/29 01:35:08 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:38545") 2026/05/29 01:35:08 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:53748->172.17.0.4:38545, err= --- PASS: TestSyncOverlap (2.40s) === RUN TestSyncOverlapWithFilter run.go:198: Remote "ftp://172.17.0.4:21/rclone-test-gemaguy1vune", Local "Local file system at /tmp/rclone345444598", Modify Window "1s" 2026/05/29 01:35:08 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-gemaguy1vune/rclone-sync-test" 2026/05/29 01:35:08 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/29 01:35:08 DEBUG : Setting host="172.17.0.4" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/29 01:35:08 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/29 01:35:08 DEBUG : Setting pass="nakLAThtSdmzMZQlJ7FFgzoMhEeLtaecJmYgHApecFs" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/29 01:35:08 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/29 01:35:08 DEBUG : TestFTPPureftpd: detected overridden config - adding "{1krN2}" suffix to name 2026/05/29 01:35:08 DEBUG : Setting host="172.17.0.4" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/29 01:35:08 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/29 01:35:08 DEBUG : Setting pass="nakLAThtSdmzMZQlJ7FFgzoMhEeLtaecJmYgHApecFs" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/29 01:35:08 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/29 01:35:08 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test: Connecting to FTP server 2026/05/29 01:35:08 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test: dial("tcp","172.17.0.4:21") 2026/05/29 01:35:08 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test: > dial: conn=172.17.0.1:39386->172.17.0.4:21, err= 2026/05/29 01:35:10 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-gemaguy1vune/rclone-sync-test-include/layer2" 2026/05/29 01:35:10 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/29 01:35:10 DEBUG : Setting host="172.17.0.4" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/29 01:35:10 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/29 01:35:10 DEBUG : Setting pass="nakLAThtSdmzMZQlJ7FFgzoMhEeLtaecJmYgHApecFs" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/29 01:35:10 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/29 01:35:10 DEBUG : TestFTPPureftpd: detected overridden config - adding "{1krN2}" suffix to name 2026/05/29 01:35:10 DEBUG : Setting host="172.17.0.4" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/29 01:35:10 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/29 01:35:10 DEBUG : Setting pass="nakLAThtSdmzMZQlJ7FFgzoMhEeLtaecJmYgHApecFs" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/29 01:35:10 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/29 01:35:10 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test-include/layer2: Connecting to FTP server 2026/05/29 01:35:10 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test-include/layer2: dial("tcp","172.17.0.4:21") 2026/05/29 01:35:10 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test-include/layer2: > dial: conn=172.17.0.1:39398->172.17.0.4:21, err= 2026/05/29 01:35:13 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-gemaguy1vune/rclone-sync-test-ignore-file" 2026/05/29 01:35:13 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/29 01:35:13 DEBUG : Setting host="172.17.0.4" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/29 01:35:13 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/29 01:35:13 DEBUG : Setting pass="nakLAThtSdmzMZQlJ7FFgzoMhEeLtaecJmYgHApecFs" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/29 01:35:13 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/29 01:35:13 DEBUG : TestFTPPureftpd: detected overridden config - adding "{1krN2}" suffix to name 2026/05/29 01:35:13 DEBUG : Setting host="172.17.0.4" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/29 01:35:13 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/29 01:35:13 DEBUG : Setting pass="nakLAThtSdmzMZQlJ7FFgzoMhEeLtaecJmYgHApecFs" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/29 01:35:13 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/29 01:35:13 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test-ignore-file: Connecting to FTP server 2026/05/29 01:35:13 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test-ignore-file: dial("tcp","172.17.0.4:21") 2026/05/29 01:35:13 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test-ignore-file: > dial: conn=172.17.0.1:44686->172.17.0.4:21, err= 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:33790") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:39906->172.17.0.4:33790, err= 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:33229") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:57598->172.17.0.4:33229, err= 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test: dial("tcp","172.17.0.4:33385") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test: > dial: conn=172.17.0.1:55602->172.17.0.4:33385, err= 2026/05/29 01:35:15 DEBUG : rclone-sync-test: Excluded 2026/05/29 01:35:15 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:36238") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:36958->172.17.0.4:36238, err= 2026/05/29 01:35:15 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test: Waiting for checks to finish 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test: Waiting for transfers to finish 2026/05/29 01:35:15 DEBUG : Waiting for deletions to finish 2026/05/29 01:35:15 INFO : There was nothing to transfer 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:32870") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:46408->172.17.0.4:32870, err= 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test: dial("tcp","172.17.0.4:36469") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test: > dial: conn=172.17.0.1:36936->172.17.0.4:36469, err= 2026/05/29 01:35:15 DEBUG : rclone-sync-test: Excluded 2026/05/29 01:35:15 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:39363") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:33016->172.17.0.4:39363, err= 2026/05/29 01:35:15 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: Waiting for checks to finish 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: Waiting for transfers to finish 2026/05/29 01:35:15 DEBUG : Waiting for deletions to finish 2026/05/29 01:35:15 INFO : rclone-sync-test-include: Removing directory 2026/05/29 01:35:15 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not found 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: failed to delete 1 directories 2026/05/29 01:35:15 INFO : There was nothing to transfer 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:31785") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test-include/layer2: dial("tcp","172.17.0.4:35881") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:51978->172.17.0.4:31785, err= 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test-include/layer2: > dial: conn=172.17.0.1:54680->172.17.0.4:35881, err= 2026/05/29 01:35:15 DEBUG : rclone-sync-test: Excluded 2026/05/29 01:35:15 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:37950") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:37452->172.17.0.4:37950, err= 2026/05/29 01:35:15 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test-include/layer2: Waiting for checks to finish 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test-include/layer2: Waiting for transfers to finish 2026/05/29 01:35:15 DEBUG : Waiting for deletions to finish 2026/05/29 01:35:15 INFO : There was nothing to transfer 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:34618") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:33732->172.17.0.4:34618, err= 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test-include/layer2: dial("tcp","172.17.0.4:35486") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test-include/layer2: > dial: conn=172.17.0.1:42556->172.17.0.4:35486, err= 2026/05/29 01:35:15 DEBUG : rclone-sync-test: Excluded 2026/05/29 01:35:15 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:36474") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:56580->172.17.0.4:36474, err= 2026/05/29 01:35:15 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: Waiting for checks to finish 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: Waiting for transfers to finish 2026/05/29 01:35:15 DEBUG : Waiting for deletions to finish 2026/05/29 01:35:15 INFO : rclone-sync-test-include: Removing directory 2026/05/29 01:35:15 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not found 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: failed to delete 1 directories 2026/05/29 01:35:15 INFO : There was nothing to transfer 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test-ignore-file: dial("tcp","172.17.0.4:39581") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:30076") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test-ignore-file: > dial: conn=172.17.0.1:60806->172.17.0.4:39581, err= 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:42204->172.17.0.4:30076, err= 2026/05/29 01:35:15 DEBUG : Excluded 2026/05/29 01:35:15 DEBUG : rclone-sync-test: Excluded 2026/05/29 01:35:15 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:31086") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:33104->172.17.0.4:31086, err= 2026/05/29 01:35:15 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test-ignore-file: Waiting for checks to finish 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune/rclone-sync-test-ignore-file: Waiting for transfers to finish 2026/05/29 01:35:15 DEBUG : Waiting for deletions to finish 2026/05/29 01:35:15 INFO : There was nothing to transfer 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:39310") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:32952->172.17.0.4:39310, err= 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: Connecting to FTP server 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:21") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:44692->172.17.0.4:21, err= 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:39100") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:38979") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:50916->172.17.0.4:39100, err= 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:60882->172.17.0.4:38979, err= 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:33875") 2026/05/29 01:35:15 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:33852->172.17.0.4:33875, err= 2026/05/29 01:35:17 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:39458") 2026/05/29 01:35:17 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:51036->172.17.0.4:39458, err= 2026/05/29 01:35:17 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:35676") 2026/05/29 01:35:17 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:54592->172.17.0.4:35676, err= --- PASS: TestSyncOverlapWithFilter (8.87s) PASS 2026/05/29 01:35:17 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: dial("tcp","172.17.0.4:37980") 2026/05/29 01:35:17 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: > dial: conn=172.17.0.1:42616->172.17.0.4:37980, err= 2026/05/29 01:35:17 DEBUG : ftp://172.17.0.4:21/rclone-test-gemaguy1vune: Purge dir "" "./sync.test -test.v -test.timeout 1h0m0s -remote TestFTPPureftpd: -verbose -test.run '^(TestServerSideMoveDeleteEmptySourceDirs|TestSyncOverlap|TestSyncOverlapWithFilter)$'" - Finished OK in 20.971473515s (try 2/5)