"./bisync.test -test.v -test.timeout 1h0m0s -remote TestFTPPureftpd: -verbose -test.run '^TestBisyncLocalRemote$/^max_delete_path1$'" - Starting (try 2/5) 2026/05/18 04:29:36 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-muwunew0qulo" 2026/05/18 04:29:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/18 04:29:36 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/18 04:29:36 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/18 04:29:36 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/18 04:29:36 DEBUG : Setting pass="4M_sTH2stLLF4h1KSJaHtCJjhY9a36D-tGe3OSWvSok" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/18 04:29:36 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/18 04:29:36 DEBUG : TestFTPPureftpd: detected overridden config - adding "{-J5bn}" suffix to name 2026/05/18 04:29:36 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/18 04:29:36 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/18 04:29:36 DEBUG : Setting pass="4M_sTH2stLLF4h1KSJaHtCJjhY9a36D-tGe3OSWvSok" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/18 04:29:36 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/18 04:29:36 DEBUG : ftp://172.17.0.3:21/rclone-test-muwunew0qulo: Connecting to FTP server 2026/05/18 04:29:36 DEBUG : ftp://172.17.0.3:21/rclone-test-muwunew0qulo: dial("tcp","172.17.0.3:21") 2026/05/18 04:29:36 DEBUG : ftp://172.17.0.3:21/rclone-test-muwunew0qulo: > dial: conn=172.17.0.1:53168->172.17.0.3:21, err= 2026/05/18 04:29:38 DEBUG : Creating backend with remote "/tmp/rclone3472014925" === RUN TestBisyncLocalRemote 2026/05/18 04:29:38 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-jayiber4yede" 2026/05/18 04:29:38 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/18 04:29:38 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/18 04:29:38 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/18 04:29:38 DEBUG : Setting pass="4M_sTH2stLLF4h1KSJaHtCJjhY9a36D-tGe3OSWvSok" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/18 04:29:38 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/18 04:29:38 DEBUG : TestFTPPureftpd: detected overridden config - adding "{-J5bn}" suffix to name 2026/05/18 04:29:38 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/18 04:29:38 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/18 04:29:38 DEBUG : Setting pass="4M_sTH2stLLF4h1KSJaHtCJjhY9a36D-tGe3OSWvSok" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/18 04:29:38 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/18 04:29:38 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: Connecting to FTP server 2026/05/18 04:29:38 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: dial("tcp","172.17.0.3:21") 2026/05/18 04:29:38 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: > dial: conn=172.17.0.1:53170->172.17.0.3:21, err= 2026/05/18 04:29:41 NOTICE: remote: TestFTPPureftpd:rclone-test-jayiber4yede === RUN TestBisyncLocalRemote/max_delete_path1 2026/05/18 04:29:41 DEBUG : Creating backend with remote "/tmp/042941nagulib1" 2026/05/18 04:29:41 DEBUG : Config file has changed externally - reloading 2026/05/18 04:29:41 DEBUG : Creating backend with remote "/tmp/042941nagulib1/max_delete_path1/path1" 2026/05/18 04:29:41 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-jayiber4yede/042941nagulib1" 2026/05/18 04:29:41 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/18 04:29:41 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/18 04:29:41 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/18 04:29:41 DEBUG : Setting pass="4M_sTH2stLLF4h1KSJaHtCJjhY9a36D-tGe3OSWvSok" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/18 04:29:41 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/18 04:29:41 DEBUG : TestFTPPureftpd: detected overridden config - adding "{-J5bn}" suffix to name 2026/05/18 04:29:41 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/18 04:29:41 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/18 04:29:41 DEBUG : Setting pass="4M_sTH2stLLF4h1KSJaHtCJjhY9a36D-tGe3OSWvSok" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/18 04:29:41 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/18 04:29:41 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1: Connecting to FTP server 2026/05/18 04:29:41 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1: dial("tcp","172.17.0.3:21") 2026/05/18 04:29:41 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1: > dial: conn=172.17.0.1:53192->172.17.0.3:21, err= 2026/05/18 04:29:43 DEBUG : fs cache: renaming cache item "TestFTPPureftpd:rclone-test-jayiber4yede/042941nagulib1" to be canonical "TestFTPPureftpd{-J5bn}:rclone-test-jayiber4yede/042941nagulib1" 2026/05/18 04:29:43 INFO : path2: Making directory 2026/05/18 04:29:43 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2" 2026/05/18 04:29:43 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/18 04:29:43 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/18 04:29:43 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/18 04:29:43 DEBUG : Setting pass="4M_sTH2stLLF4h1KSJaHtCJjhY9a36D-tGe3OSWvSok" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/18 04:29:43 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/18 04:29:43 DEBUG : TestFTPPureftpd: detected overridden config - adding "{-J5bn}" suffix to name 2026/05/18 04:29:43 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/18 04:29:43 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/18 04:29:43 DEBUG : Setting pass="4M_sTH2stLLF4h1KSJaHtCJjhY9a36D-tGe3OSWvSok" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/18 04:29:43 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/18 04:29:43 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: Connecting to FTP server 2026/05/18 04:29:43 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:21") 2026/05/18 04:29:43 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:57276->172.17.0.3:21, err= 2026/05/18 04:29:45 DEBUG : fs cache: renaming cache item "TestFTPPureftpd:rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2" to be canonical "TestFTPPureftpd{-J5bn}:rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2" 2026/05/18 04:29:45 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_max_delete_path1/initial" 2026/05/18 04:29:45 DEBUG : Creating backend with remote "/tmp/042941nagulib1/initdir/test_max_delete_path1-yucaqom5" 2026/05/18 04:29:45 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file9.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : Local file system at /tmp/042941nagulib1/initdir/test_max_delete_path1-yucaqom5: Waiting for checks to finish 2026/05/18 04:29:45 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/05/18 04:29:45 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/05/18 04:29:45 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/05/18 04:29:45 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/05/18 04:29:45 INFO : file2.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/05/18 04:29:45 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/05/18 04:29:45 INFO : file3.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/05/18 04:29:45 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/05/18 04:29:45 INFO : file4.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : file7.txt.9f102165.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/05/18 04:29:45 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2026/05/18 04:29:45 INFO : file5.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : file8.txt.b300b322.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/05/18 04:29:45 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2026/05/18 04:29:45 INFO : file6.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : file9.txt.84a50ab9.partial: size = 109 OK 2026/05/18 04:29:45 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/05/18 04:29:45 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2026/05/18 04:29:45 INFO : file7.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/05/18 04:29:45 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2026/05/18 04:29:45 INFO : file8.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/05/18 04:29:45 DEBUG : file9.txt.84a50ab9.partial: renamed to: file9.txt 2026/05/18 04:29:45 INFO : file9.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : Local file system at /tmp/042941nagulib1/initdir/test_max_delete_path1-yucaqom5: Waiting for transfers to finish 2026/05/18 04:29:45 INFO : file1.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_max_delete_path1/modfiles" 2026/05/18 04:29:45 DEBUG : Creating backend with remote "/tmp/042941nagulib1/datadir/test_max_delete_path1-bivaquz8" 2026/05/18 04:29:45 DEBUG : hold.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : hold.txt.83686dab.partial: size = 59 OK 2026/05/18 04:29:45 DEBUG : Local file system at /tmp/042941nagulib1/datadir/test_max_delete_path1-bivaquz8: Waiting for checks to finish 2026/05/18 04:29:45 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK 2026/05/18 04:29:45 DEBUG : hold.txt.83686dab.partial: renamed to: hold.txt 2026/05/18 04:29:45 INFO : hold.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : Local file system at /tmp/042941nagulib1/datadir/test_max_delete_path1-bivaquz8: Waiting for transfers to finish 2026/05/18 04:29:45 DEBUG : Waiting for deletions to finish 2026/05/18 04:29:45 ERROR : error listing: directory not found 2026/05/18 04:29:45 ERROR : Local file system at /tmp/042941nagulib1/max_delete_path1/path1: Failed to list "": directory not found 2026/05/18 04:29:45 DEBUG : removing 1 level 0 directories 2026/05/18 04:29:45 INFO : Local file system at /tmp/042941nagulib1/max_delete_path1/path1: Removing directory 2026/05/18 04:29:45 ERROR : Failed to rmdir: stat /tmp/042941nagulib1/max_delete_path1/path1: no such file or directory 2026/05/18 04:29:45 DEBUG : Waiting for deletions to finish 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:35968") 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:35830->172.17.0.3:35968, err= 2026/05/18 04:29:45 ERROR : error listing: directory not found 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:31389") 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:56286->172.17.0.3:31389, err= 2026/05/18 04:29:45 ERROR : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: Failed to list "": directory not found 2026/05/18 04:29:45 DEBUG : removing 1 level 0 directories 2026/05/18 04:29:45 INFO : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: Removing directory 2026/05/18 04:29:45 ERROR : Failed to rmdir: directory not found 2026/05/18 04:29:45 ERROR : error listing: directory not found 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:36924") 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:44596->172.17.0.3:36924, err= 2026/05/18 04:29:45 ERROR : error listing: directory not found 2026/05/18 04:29:45 NOTICE: checking initFs Local file system at /tmp/042941nagulib1/initdir/test_max_delete_path1-yucaqom5 2026/05/18 04:29:45 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file9.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/05/18 04:29:45 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/05/18 04:29:45 INFO : file1.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : Local file system at /tmp/042941nagulib1/max_delete_path1/path1: Waiting for checks to finish 2026/05/18 04:29:45 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/05/18 04:29:45 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/05/18 04:29:45 INFO : file2.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/05/18 04:29:45 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/05/18 04:29:45 DEBUG : Local file system at /tmp/042941nagulib1/max_delete_path1/path1: Waiting for transfers to finish 2026/05/18 04:29:45 INFO : file4.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/05/18 04:29:45 DEBUG : file7.txt.9f102165.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/05/18 04:29:45 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2026/05/18 04:29:45 INFO : file6.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2026/05/18 04:29:45 INFO : file7.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : file8.txt.b300b322.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/05/18 04:29:45 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/05/18 04:29:45 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/05/18 04:29:45 DEBUG : file9.txt.84a50ab9.partial: size = 109 OK 2026/05/18 04:29:45 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/05/18 04:29:45 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2026/05/18 04:29:45 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2026/05/18 04:29:45 INFO : file8.txt: Copied (new) 2026/05/18 04:29:45 INFO : file3.txt: Copied (new) 2026/05/18 04:29:45 INFO : file5.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/05/18 04:29:45 DEBUG : file9.txt.84a50ab9.partial: renamed to: file9.txt 2026/05/18 04:29:45 INFO : file9.txt: Copied (new) 2026/05/18 04:29:45 NOTICE: checking Path1 Local file system at /tmp/042941nagulib1/max_delete_path1/path1 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:37503") 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:46382->172.17.0.3:37503, err= 2026/05/18 04:29:45 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file5.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file6.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file7.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file8.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : file9.txt: Need to transfer - File not found at Destination 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: Waiting for checks to finish 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: Waiting for transfers to finish 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: Connecting to FTP server 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:21") 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: Connecting to FTP server 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: Connecting to FTP server 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:57284->172.17.0.3:21, err= 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:36873") 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:54656->172.17.0.3:36873, err= 2026/05/18 04:29:45 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/05/18 04:29:45 INFO : file1.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:31888") 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:37118->172.17.0.3:31888, err= 2026/05/18 04:29:45 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2026/05/18 04:29:45 INFO : file5.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:37161") 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:41314->172.17.0.3:37161, err= 2026/05/18 04:29:45 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2026/05/18 04:29:45 INFO : file6.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:35232") 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:35142->172.17.0.3:35232, err= 2026/05/18 04:29:45 DEBUG : file7.txt.9f102165.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2026/05/18 04:29:45 INFO : file7.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:35979") 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:56622->172.17.0.3:35979, err= 2026/05/18 04:29:45 DEBUG : file8.txt.b300b322.partial: size = 0 OK 2026/05/18 04:29:45 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2026/05/18 04:29:45 INFO : file8.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:32682") 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:49896->172.17.0.3:32682, err= 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:21") 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:57292->172.17.0.3:21, err= 2026/05/18 04:29:45 DEBUG : file9.txt.84a50ab9.partial: size = 109 OK 2026/05/18 04:29:45 DEBUG : file9.txt.84a50ab9.partial: renamed to: file9.txt 2026/05/18 04:29:45 INFO : file9.txt: Copied (new) 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:21") 2026/05/18 04:29:45 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:57300->172.17.0.3:21, err= 2026/05/18 04:29:48 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:30861") 2026/05/18 04:29:48 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:37728->172.17.0.3:30861, err= 2026/05/18 04:29:48 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/05/18 04:29:48 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/05/18 04:29:48 INFO : file4.txt: Copied (new) 2026/05/18 04:29:48 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:30390") 2026/05/18 04:29:48 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:50376->172.17.0.3:30390, err= 2026/05/18 04:29:48 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/05/18 04:29:48 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/05/18 04:29:48 INFO : file3.txt: Copied (new) 2026/05/18 04:29:48 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:33667") 2026/05/18 04:29:48 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:38378->172.17.0.3:33667, err= 2026/05/18 04:29:48 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/05/18 04:29:48 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/05/18 04:29:48 INFO : file2.txt: Copied (new) 2026/05/18 04:29:48 NOTICE: checking path2 ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2 2026/05/18 04:29:48 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:38605") 2026/05/18 04:29:48 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:38974->172.17.0.3:38605, err= 2026/05/18 04:29:48 NOTICE: (01) : test max-delete-path1 2026/05/18 04:29:48 NOTICE: (02) : test initial bisync 2026/05/18 04:29:48 NOTICE: (03) : bisync resync 2026/05/18 04:29:48 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:33622") 2026/05/18 04:29:48 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:37124->172.17.0.3:33622, err= 2026/05/18 04:29:50 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/05/18 04:29:50 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/05/18 04:29:50 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2026/05/18 04:29:50 INFO : Synching Path1 "/tmp/042941nagulib1/max_delete_path1/path1/" with Path2 "TestFTPPureftpd{-J5bn}:rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2/" 2026/05/18 04:29:50 INFO : Copying Path2 files to Path1 2026/05/18 04:29:50 INFO : - Path2 Resync is copying files to - Path1 2026/05/18 04:29:50 INFO : There was nothing to transfer 2026/05/18 04:29:50 INFO : - Path1 Resync is copying files to - Path2 2026/05/18 04:29:50 INFO : There was nothing to transfer 2026/05/18 04:29:50 INFO : Resync updating listings 2026/05/18 04:29:50 INFO : Validating listings for Path1 "/tmp/042941nagulib1/max_delete_path1/path1/" vs Path2 "TestFTPPureftpd{-J5bn}:rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2/" 2026/05/18 04:29:50 INFO : Bisync successful 2026/05/18 04:29:50 NOTICE: (04) : test delete >50% of local files 2026/05/18 04:29:50 NOTICE: (05) : delete-file /tmp/042941nagulib1/max_delete_path1/path1/file1.txt 2026/05/18 04:29:50 DEBUG : Creating backend with remote "/tmp/042941nagulib1/max_delete_path1/path1/" 2026/05/18 04:29:50 DEBUG : fs cache: renaming cache item "/tmp/042941nagulib1/max_delete_path1/path1/" to be canonical "/tmp/042941nagulib1/max_delete_path1/path1" 2026/05/18 04:29:50 INFO : file1.txt: Deleted 2026/05/18 04:29:50 NOTICE: (06) : delete-file /tmp/042941nagulib1/max_delete_path1/path1/file2.txt 2026/05/18 04:29:50 DEBUG : fs cache: switching user supplied name "/tmp/042941nagulib1/max_delete_path1/path1/" for canonical name "/tmp/042941nagulib1/max_delete_path1/path1" 2026/05/18 04:29:50 INFO : file2.txt: Deleted 2026/05/18 04:29:50 NOTICE: (07) : delete-file /tmp/042941nagulib1/max_delete_path1/path1/file3.txt 2026/05/18 04:29:50 DEBUG : fs cache: switching user supplied name "/tmp/042941nagulib1/max_delete_path1/path1/" for canonical name "/tmp/042941nagulib1/max_delete_path1/path1" 2026/05/18 04:29:50 INFO : file3.txt: Deleted 2026/05/18 04:29:50 NOTICE: (08) : delete-file /tmp/042941nagulib1/max_delete_path1/path1/file4.txt 2026/05/18 04:29:50 DEBUG : fs cache: switching user supplied name "/tmp/042941nagulib1/max_delete_path1/path1/" for canonical name "/tmp/042941nagulib1/max_delete_path1/path1" 2026/05/18 04:29:50 INFO : file4.txt: Deleted 2026/05/18 04:29:50 NOTICE: (09) : delete-file /tmp/042941nagulib1/max_delete_path1/path1/file5.txt 2026/05/18 04:29:50 DEBUG : fs cache: switching user supplied name "/tmp/042941nagulib1/max_delete_path1/path1/" for canonical name "/tmp/042941nagulib1/max_delete_path1/path1" 2026/05/18 04:29:50 INFO : file5.txt: Deleted 2026/05/18 04:29:50 NOTICE: (10) : test sync should fail due to too many local deletes 2026/05/18 04:29:50 NOTICE: (11) : bisync 2026/05/18 04:29:50 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:31884") 2026/05/18 04:29:50 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:56966->172.17.0.3:31884, err= 2026/05/18 04:29:52 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/05/18 04:29:52 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/05/18 04:29:52 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2026/05/18 04:29:52 INFO : Synching Path1 "/tmp/042941nagulib1/max_delete_path1/path1/" with Path2 "TestFTPPureftpd{-J5bn}:rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2/" 2026/05/18 04:29:52 INFO : Building Path1 and Path2 listings 2026/05/18 04:29:52 INFO : Path1 checking for diffs 2026/05/18 04:29:52 INFO : - Path1 File was deleted - file1.txt 2026/05/18 04:29:52 INFO : - Path1 File was deleted - file2.txt 2026/05/18 04:29:52 INFO : - Path1 File was deleted - file3.txt 2026/05/18 04:29:52 INFO : - Path1 File was deleted - file4.txt 2026/05/18 04:29:52 INFO : - Path1 File was deleted - file5.txt 2026/05/18 04:29:52 INFO : Path1: 5 changes:  0 new,  0 modified,  5 deleted 2026/05/18 04:29:52 INFO : Path2 checking for diffs 2026/05/18 04:29:52 ERROR : Safety abort: too many deletes (>50%, 5 of 9) on Path1 "/tmp/042941nagulib1/max_delete_path1/path1/". Run with --force if desired. 2026/05/18 04:29:52 NOTICE: Bisync aborted. Please try again. 2026/05/18 04:29:52 NOTICE: Bisync error: too many deletes 2026/05/18 04:29:52 NOTICE: (12) : copy-listings initial-fail 2026/05/18 04:29:52 NOTICE: (13) : test change max-delete limit to 60%. sync should run. 2026/05/18 04:29:52 NOTICE: (14) : bisync max-delete=60 2026/05/18 04:29:52 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:34534") 2026/05/18 04:29:52 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:40874->172.17.0.3:34534, err= 2026/05/18 04:29:54 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/05/18 04:29:54 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/05/18 04:29:54 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2026/05/18 04:29:54 INFO : Synching Path1 "/tmp/042941nagulib1/max_delete_path1/path1/" with Path2 "TestFTPPureftpd{-J5bn}:rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2/" 2026/05/18 04:29:54 INFO : Building Path1 and Path2 listings 2026/05/18 04:29:54 INFO : Path1 checking for diffs 2026/05/18 04:29:54 INFO : - Path1 File was deleted - file1.txt 2026/05/18 04:29:54 INFO : - Path1 File was deleted - file2.txt 2026/05/18 04:29:54 INFO : - Path1 File was deleted - file3.txt 2026/05/18 04:29:54 INFO : - Path1 File was deleted - file4.txt 2026/05/18 04:29:54 INFO : - Path1 File was deleted - file5.txt 2026/05/18 04:29:54 INFO : Path1: 5 changes:  0 new,  0 modified,  5 deleted 2026/05/18 04:29:54 INFO : Path2 checking for diffs 2026/05/18 04:29:54 INFO : Applying changes 2026/05/18 04:29:54 INFO : - Path2 Queue delete - TestFTPPureftpd{-J5bn}:rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2/file1.txt 2026/05/18 04:29:54 INFO : - Path2 Queue delete - TestFTPPureftpd{-J5bn}:rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2/file2.txt 2026/05/18 04:29:54 INFO : - Path2 Queue delete - TestFTPPureftpd{-J5bn}:rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2/file3.txt 2026/05/18 04:29:54 INFO : - Path2 Queue delete - TestFTPPureftpd{-J5bn}:rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2/file4.txt 2026/05/18 04:29:54 INFO : - Path2 Queue delete - TestFTPPureftpd{-J5bn}:rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2/file5.txt 2026/05/18 04:29:54 INFO : - Path1 Do queued copies to - Path2 2026/05/18 04:29:54 INFO : file2.txt: Deleted 2026/05/18 04:29:54 INFO : file3.txt: Deleted 2026/05/18 04:29:54 INFO : file1.txt: Deleted 2026/05/18 04:29:54 INFO : file5.txt: Deleted 2026/05/18 04:29:56 INFO : file4.txt: Deleted 2026/05/18 04:29:56 INFO : There was nothing to transfer 2026/05/18 04:29:56 INFO : Updating listings 2026/05/18 04:29:56 INFO : Validating listings for Path1 "/tmp/042941nagulib1/max_delete_path1/path1/" vs Path2 "TestFTPPureftpd{-J5bn}:rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2/" 2026/05/18 04:29:56 INFO : Bisync successful 2026/05/18 04:29:56 DEBUG : Waiting for deletions to finish 2026/05/18 04:29:56 INFO : file6.txt: Deleted 2026/05/18 04:29:56 INFO : file7.txt: Deleted 2026/05/18 04:29:56 INFO : file8.txt: Deleted 2026/05/18 04:29:56 INFO : file9.txt: Deleted 2026/05/18 04:29:56 DEBUG : removing 1 level 0 directories 2026/05/18 04:29:56 INFO : Local file system at /tmp/042941nagulib1/max_delete_path1/path1: Removing directory 2026/05/18 04:29:56 DEBUG : Waiting for deletions to finish 2026/05/18 04:29:56 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:37264") 2026/05/18 04:29:56 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:44016->172.17.0.3:37264, err= 2026/05/18 04:29:56 INFO : file9.txt: Deleted 2026/05/18 04:29:56 INFO : file8.txt: Deleted 2026/05/18 04:29:56 INFO : file6.txt: Deleted 2026/05/18 04:29:56 INFO : file7.txt: Deleted 2026/05/18 04:29:56 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: dial("tcp","172.17.0.3:36105") 2026/05/18 04:29:56 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: > dial: conn=172.17.0.1:49750->172.17.0.3:36105, err= 2026/05/18 04:29:56 DEBUG : removing 1 level 0 directories 2026/05/18 04:29:56 INFO : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: Removing directory bisync_test.go:610: TEST max_delete_path1 PASSED 2026/05/18 04:29:56 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: dial("tcp","172.17.0.3:35801") 2026/05/18 04:29:56 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: > dial: conn=172.17.0.1:42100->172.17.0.3:35801, err= 2026/05/18 04:29:56 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: dial("tcp","172.17.0.3:31435") 2026/05/18 04:29:56 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: > dial: conn=172.17.0.1:50100->172.17.0.3:31435, err= 2026/05/18 04:29:56 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: Connecting to FTP server 2026/05/18 04:29:56 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: dial("tcp","172.17.0.3:21") 2026/05/18 04:29:56 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: > dial: conn=172.17.0.1:57250->172.17.0.3:21, err= 2026/05/18 04:29:56 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: dial("tcp","172.17.0.3:36068") 2026/05/18 04:29:56 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: > dial: conn=172.17.0.1:48114->172.17.0.3:36068, err= 2026/05/18 04:29:58 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: dial("tcp","172.17.0.3:32497") 2026/05/18 04:29:58 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: > dial: conn=172.17.0.1:45298->172.17.0.3:32497, err= 2026/05/18 04:29:58 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: Purge dir "042941nagulib1/path2" 2026/05/18 04:29:58 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: Purge dir "042941nagulib1/max_delete_path1" 2026/05/18 04:29:58 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: Purge dir "042941nagulib1" 2026/05/18 04:29:58 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede: Purge dir "" --- PASS: TestBisyncLocalRemote (19.91s) --- PASS: TestBisyncLocalRemote/max_delete_path1 (15.43s) PASS 2026/05/18 04:29:58 DEBUG : ftp://172.17.0.3:21/rclone-test-muwunew0qulo: dial("tcp","172.17.0.3:37789") 2026/05/18 04:29:58 DEBUG : ftp://172.17.0.3:21/rclone-test-muwunew0qulo: > dial: conn=172.17.0.1:56308->172.17.0.3:37789, err= 2026/05/18 04:29:58 ERROR : error listing: directory not found 2026/05/18 04:29:58 DEBUG : ftp://172.17.0.3:21/rclone-test-muwunew0qulo: Purge dir "" 2026/05/18 04:29:58 NOTICE: purge failed to rmdir "": directory not found 2026/05/18 04:29:58 NOTICE: purge failed: directory not found 2026/05/18 04:29:58 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1/max_delete_path1/path2: closing 5 unused connections 2026/05/18 04:29:58 DEBUG : ftp://172.17.0.3:21/rclone-test-jayiber4yede/042941nagulib1: closing 1 unused connections "./bisync.test -test.v -test.timeout 1h0m0s -remote TestFTPPureftpd: -verbose -test.run '^TestBisyncLocalRemote$/^max_delete_path1$'" - Finished OK in 22.226397761s (try 2/5)