"./bisync.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestBisyncLocalRemote$/^rmdirs$'" - Starting (try 2/5) 2026/01/24 01:00:54 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-voleqox6boba" 2026/01/24 01:00:54 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/24 01:00:54 DEBUG : Setting type="ftp" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/01/24 01:00:54 DEBUG : Setting host="127.0.0.1" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/01/24 01:00:54 DEBUG : Setting user="rclone" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/01/24 01:00:54 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/01/24 01:00:54 DEBUG : Setting pass="4sLegJhkOdYa6Z-OhetSiQjpRDUAolvInL_rBaK8FyD5" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/01/24 01:00:54 DEBUG : TestFTPRclone: detected overridden config - adding "{BLHUF}" suffix to name 2026/01/24 01:00:54 DEBUG : Setting host="127.0.0.1" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/01/24 01:00:54 DEBUG : Setting user="rclone" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/01/24 01:00:54 DEBUG : Setting pass="4sLegJhkOdYa6Z-OhetSiQjpRDUAolvInL_rBaK8FyD5" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/01/24 01:00:54 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-voleqox6boba: Connecting to FTP server 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-voleqox6boba: dial("tcp","127.0.0.1:28622") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-voleqox6boba: > dial: conn=127.0.0.1:51140->127.0.0.1:28622, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-voleqox6boba: dial("tcp","127.0.0.1:31687") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-voleqox6boba: > dial: conn=127.0.0.1:44588->127.0.0.1:31687, err= 2026/01/24 01:00:54 DEBUG : Creating backend with remote "/tmp/rclone549036211" === RUN TestBisyncLocalRemote 2026/01/24 01:00:54 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-puniciv9doke" 2026/01/24 01:00:54 DEBUG : Setting type="ftp" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/01/24 01:00:54 DEBUG : Setting host="127.0.0.1" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/01/24 01:00:54 DEBUG : Setting user="rclone" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/01/24 01:00:54 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/01/24 01:00:54 DEBUG : Setting pass="4sLegJhkOdYa6Z-OhetSiQjpRDUAolvInL_rBaK8FyD5" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/01/24 01:00:54 DEBUG : TestFTPRclone: detected overridden config - adding "{BLHUF}" suffix to name 2026/01/24 01:00:54 DEBUG : Setting host="127.0.0.1" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/01/24 01:00:54 DEBUG : Setting user="rclone" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/01/24 01:00:54 DEBUG : Setting pass="4sLegJhkOdYa6Z-OhetSiQjpRDUAolvInL_rBaK8FyD5" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/01/24 01:00:54 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: Connecting to FTP server 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:28622") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:51148->127.0.0.1:28622, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:30702") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:43282->127.0.0.1:30702, err= 2026/01/24 01:00:54 NOTICE: remote: TestFTPRclone:rclone-test-puniciv9doke === RUN TestBisyncLocalRemote/rmdirs 2026/01/24 01:00:54 DEBUG : Creating backend with remote "/tmp/010054qo" 2026/01/24 01:00:54 DEBUG : Creating backend with remote "/tmp/010054qo/rmdirs/path1" 2026/01/24 01:00:54 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-puniciv9doke/010054qo" 2026/01/24 01:00:54 DEBUG : Setting type="ftp" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/01/24 01:00:54 DEBUG : Setting host="127.0.0.1" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/01/24 01:00:54 DEBUG : Setting user="rclone" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/01/24 01:00:54 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/01/24 01:00:54 DEBUG : Setting pass="4sLegJhkOdYa6Z-OhetSiQjpRDUAolvInL_rBaK8FyD5" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/01/24 01:00:54 DEBUG : TestFTPRclone: detected overridden config - adding "{BLHUF}" suffix to name 2026/01/24 01:00:54 DEBUG : Setting host="127.0.0.1" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/01/24 01:00:54 DEBUG : Setting user="rclone" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/01/24 01:00:54 DEBUG : Setting pass="4sLegJhkOdYa6Z-OhetSiQjpRDUAolvInL_rBaK8FyD5" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/01/24 01:00:54 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo: Connecting to FTP server 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo: dial("tcp","127.0.0.1:28622") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo: > dial: conn=127.0.0.1:51164->127.0.0.1:28622, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo: dial("tcp","127.0.0.1:30274") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo: > dial: conn=127.0.0.1:44712->127.0.0.1:30274, err= 2026/01/24 01:00:54 DEBUG : fs cache: renaming cache item "TestFTPRclone:rclone-test-puniciv9doke/010054qo" to be canonical "TestFTPRclone{BLHUF}:rclone-test-puniciv9doke/010054qo" 2026/01/24 01:00:54 INFO : path2: Making directory 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo: dial("tcp","127.0.0.1:31826") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo: > dial: conn=127.0.0.1:48166->127.0.0.1:31826, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo: dial("tcp","127.0.0.1:31426") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo: > dial: conn=127.0.0.1:49046->127.0.0.1:31426, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo: dial("tcp","127.0.0.1:31507") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo: > dial: conn=127.0.0.1:59338->127.0.0.1:31507, err= 2026/01/24 01:00:54 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-puniciv9doke/010054qo/rmdirs/path2" 2026/01/24 01:00:54 DEBUG : Setting type="ftp" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/01/24 01:00:54 DEBUG : Setting host="127.0.0.1" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/01/24 01:00:54 DEBUG : Setting user="rclone" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/01/24 01:00:54 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/01/24 01:00:54 DEBUG : Setting pass="4sLegJhkOdYa6Z-OhetSiQjpRDUAolvInL_rBaK8FyD5" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/01/24 01:00:54 DEBUG : TestFTPRclone: detected overridden config - adding "{BLHUF}" suffix to name 2026/01/24 01:00:54 DEBUG : Setting host="127.0.0.1" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/01/24 01:00:54 DEBUG : Setting user="rclone" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/01/24 01:00:54 DEBUG : Setting pass="4sLegJhkOdYa6Z-OhetSiQjpRDUAolvInL_rBaK8FyD5" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/01/24 01:00:54 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: Connecting to FTP server 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:28622") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:51174->127.0.0.1:28622, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30049") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:38022->127.0.0.1:30049, err= 2026/01/24 01:00:54 DEBUG : fs cache: renaming cache item "TestFTPRclone:rclone-test-puniciv9doke/010054qo/rmdirs/path2" to be canonical "TestFTPRclone{BLHUF}:rclone-test-puniciv9doke/010054qo/rmdirs/path2" 2026/01/24 01:00:54 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial" 2026/01/24 01:00:54 DEBUG : Creating backend with remote "/tmp/010054qo/initdir/test_rmdirs-yukeboc2" 2026/01/24 01:00:54 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : subdir: Making directory with metadata 2026/01/24 01:00:54 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK 2026/01/24 01:00:54 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/01/24 01:00:54 DEBUG : Added delayed dir = "subdir", newDst=subdir 2026/01/24 01:00:54 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/01/24 01:00:54 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt 2026/01/24 01:00:54 INFO : file1.copy2.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/01/24 01:00:54 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/01/24 01:00:54 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt 2026/01/24 01:00:54 INFO : file1.copy4.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt 2026/01/24 01:00:54 INFO : file1.copy1.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : Local file system at /tmp/010054qo/initdir/test_rmdirs-yukeboc2: Waiting for checks to finish 2026/01/24 01:00:54 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/01/24 01:00:54 DEBUG : Local file system at /tmp/010054qo/initdir/test_rmdirs-yukeboc2: Waiting for transfers to finish 2026/01/24 01:00:54 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt 2026/01/24 01:00:54 INFO : file1.copy5.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/01/24 01:00:54 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2026/01/24 01:00:54 INFO : subdir/file20.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/01/24 01:00:54 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/01/24 01:00:54 INFO : file1.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/01/24 01:00:54 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/01/24 01:00:54 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/01/24 01:00:54 INFO : RCLONE_TEST: Copied (new) 2026/01/24 01:00:54 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/01/24 01:00:54 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt 2026/01/24 01:00:54 INFO : file1.copy3.txt: Copied (new) 2026/01/24 01:00:54 INFO : subdir: Set directory modification time (using SetModTime) 2026/01/24 01:00:54 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles" 2026/01/24 01:00:54 DEBUG : Creating backend with remote "/tmp/010054qo/datadir/test_rmdirs-xuvehev3" 2026/01/24 01:00:54 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : Local file system at /tmp/010054qo/datadir/test_rmdirs-xuvehev3: Waiting for checks to finish 2026/01/24 01:00:54 DEBUG : Local file system at /tmp/010054qo/datadir/test_rmdirs-xuvehev3: Waiting for transfers to finish 2026/01/24 01:00:54 DEBUG : file1.txt.f41fd5ee.partial: size = 19 OK 2026/01/24 01:00:54 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2026/01/24 01:00:54 DEBUG : file1.txt.f41fd5ee.partial: renamed to: file1.txt 2026/01/24 01:00:54 INFO : file1.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : Waiting for deletions to finish 2026/01/24 01:00:54 ERROR : error listing: directory not found 2026/01/24 01:00:54 ERROR : Local file system at /tmp/010054qo/rmdirs/path1: Failed to list "": directory not found 2026/01/24 01:00:54 DEBUG : removing 1 level 0 directories 2026/01/24 01:00:54 INFO : Local file system at /tmp/010054qo/rmdirs/path1: Removing directory 2026/01/24 01:00:54 ERROR : Failed to rmdir: stat /tmp/010054qo/rmdirs/path1: no such file or directory 2026/01/24 01:00:54 DEBUG : Waiting for deletions to finish 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31973") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:47824->127.0.0.1:31973, err= 2026/01/24 01:00:54 ERROR : error listing: directory not found 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30902") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:44144->127.0.0.1:30902, err= 2026/01/24 01:00:54 ERROR : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: Failed to list "": directory not found 2026/01/24 01:00:54 DEBUG : removing 1 level 0 directories 2026/01/24 01:00:54 INFO : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: Removing directory 2026/01/24 01:00:54 ERROR : Failed to rmdir: directory not found 2026/01/24 01:00:54 ERROR : error listing: directory not found 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30046") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:52468->127.0.0.1:30046, err= 2026/01/24 01:00:54 ERROR : error listing: directory not found 2026/01/24 01:00:54 NOTICE: checking initFs Local file system at /tmp/010054qo/initdir/test_rmdirs-yukeboc2 2026/01/24 01:00:54 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : subdir: Making directory with metadata 2026/01/24 01:00:54 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/01/24 01:00:54 DEBUG : Added delayed dir = "subdir", newDst=subdir 2026/01/24 01:00:54 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/01/24 01:00:54 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/01/24 01:00:54 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt 2026/01/24 01:00:54 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK 2026/01/24 01:00:54 INFO : file1.copy2.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : Local file system at /tmp/010054qo/rmdirs/path1: Waiting for checks to finish 2026/01/24 01:00:54 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/01/24 01:00:54 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/01/24 01:00:54 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt 2026/01/24 01:00:54 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : Local file system at /tmp/010054qo/rmdirs/path1: Waiting for transfers to finish 2026/01/24 01:00:54 INFO : file1.copy3.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/01/24 01:00:54 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt 2026/01/24 01:00:54 INFO : file1.copy1.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/01/24 01:00:54 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/01/24 01:00:54 INFO : RCLONE_TEST: Copied (new) 2026/01/24 01:00:54 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt 2026/01/24 01:00:54 INFO : file1.copy4.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/01/24 01:00:54 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt 2026/01/24 01:00:54 INFO : file1.copy5.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/01/24 01:00:54 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/01/24 01:00:54 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/01/24 01:00:54 INFO : file1.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2026/01/24 01:00:54 INFO : subdir/file20.txt: Copied (new) 2026/01/24 01:00:54 INFO : subdir: Set directory modification time (using SetModTime) 2026/01/24 01:00:54 NOTICE: checking Path1 Local file system at /tmp/010054qo/rmdirs/path1 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30076") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:52372->127.0.0.1:30076, err= 2026/01/24 01:00:54 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: Connecting to FTP server 2026/01/24 01:00:54 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: Connecting to FTP server 2026/01/24 01:00:54 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: Connecting to FTP server 2026/01/24 01:00:54 INFO : subdir: Making directory 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: Connecting to FTP server 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:28622") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31005") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:51180->127.0.0.1:28622, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:49412->127.0.0.1:31005, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30364") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:60126->127.0.0.1:30364, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30392") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:45576->127.0.0.1:30392, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31109") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31857") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:37382->127.0.0.1:31109, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:52092->127.0.0.1:31857, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30026") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:53652->127.0.0.1:30026, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31667") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:33918->127.0.0.1:31667, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31510") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:57288->127.0.0.1:31510, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: SetModTime is not supported 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: SetModTime is not supported 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31295") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:37898->127.0.0.1:31295, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31541") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:47180->127.0.0.1:31541, err= 2026/01/24 01:00:54 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/01/24 01:00:54 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31527") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31306") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:33552->127.0.0.1:31527, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:52452->127.0.0.1:31306, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30135") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:60912->127.0.0.1:30135, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31353") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:42218->127.0.0.1:31353, err= 2026/01/24 01:00:54 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt 2026/01/24 01:00:54 INFO : file1.copy1.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/01/24 01:00:54 INFO : RCLONE_TEST: Copied (new) 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:28622") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30918") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:51184->127.0.0.1:28622, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31400") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:49102->127.0.0.1:31400, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:51096->127.0.0.1:30918, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30074") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:35358->127.0.0.1:30074, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30440") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: SetModTime is not supported 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31975") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:50078->127.0.0.1:31975, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31269") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:51942->127.0.0.1:31269, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:45902->127.0.0.1:30440, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31325") 2026/01/24 01:00:54 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:46202->127.0.0.1:31325, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30702") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:43294->127.0.0.1:30702, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: SetModTime is not supported 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: SetModTime is not supported 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31300") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:41326->127.0.0.1:31300, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30390") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:41044->127.0.0.1:30390, err= 2026/01/24 01:00:54 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31325") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:46206->127.0.0.1:31325, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30412") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:49622->127.0.0.1:30412, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31206") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:42834->127.0.0.1:31206, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31748") 2026/01/24 01:00:54 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt 2026/01/24 01:00:54 INFO : file1.copy4.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:43390->127.0.0.1:31748, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30161") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31678") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:52970->127.0.0.1:30161, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:39654->127.0.0.1:31678, err= 2026/01/24 01:00:54 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt 2026/01/24 01:00:54 INFO : file1.copy5.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt 2026/01/24 01:00:54 INFO : file1.copy2.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31622") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:47326->127.0.0.1:31622, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: SetModTime is not supported 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30804") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:41878->127.0.0.1:30804, err= 2026/01/24 01:00:54 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30066") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:49462->127.0.0.1:30066, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:28622") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:51196->127.0.0.1:28622, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31942") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:34870->127.0.0.1:31942, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31974") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:57828->127.0.0.1:31974, err= 2026/01/24 01:00:54 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/01/24 01:00:54 INFO : file1.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31427") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:32854->127.0.0.1:31427, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: SetModTime is not supported 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31243") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:39128->127.0.0.1:31243, err= 2026/01/24 01:00:54 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31348") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:40078->127.0.0.1:31348, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30583") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:51500->127.0.0.1:30583, err= 2026/01/24 01:00:54 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt 2026/01/24 01:00:54 INFO : file1.copy3.txt: Copied (new) 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:28622") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:51212->127.0.0.1:28622, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31350") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:42436->127.0.0.1:31350, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30351") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:58476->127.0.0.1:30351, err= 2026/01/24 01:00:54 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: Waiting for checks to finish 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: Waiting for transfers to finish 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30462") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:34170->127.0.0.1:30462, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30081") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:58092->127.0.0.1:30081, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: SetModTime is not supported 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31893") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:58804->127.0.0.1:31893, err= 2026/01/24 01:00:54 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:31574") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:57374->127.0.0.1:31574, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30040") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:46342->127.0.0.1:30040, err= 2026/01/24 01:00:54 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2026/01/24 01:00:54 INFO : subdir/file20.txt: Copied (new) 2026/01/24 01:00:54 NOTICE: checking path2 ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30417") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:37198->127.0.0.1:30417, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: dial("tcp","127.0.0.1:30601") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: > dial: conn=127.0.0.1:51130->127.0.0.1:30601, err= 2026/01/24 01:00:54 NOTICE: (01) : test rmdirs 2026/01/24 01:00:54 NOTICE: (02) : test initial bisync 2026/01/24 01:00:54 NOTICE: (03) : bisync resync bisync_test.go:1015: skipping test as at least one remote does not support setting modtime 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:31890") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:39558->127.0.0.1:31890, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:30666") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:53216->127.0.0.1:30666, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: Connecting to FTP server 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:28622") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:51218->127.0.0.1:28622, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:31973") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:47828->127.0.0.1:31973, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:30459") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:37472->127.0.0.1:30459, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:31656") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:33110->127.0.0.1:31656, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: Purge object "010054qo/rmdirs/path2/RCLONE_TEST" 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:30790") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:30699") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:52608->127.0.0.1:30790, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:44868->127.0.0.1:30699, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: Purge object "010054qo/rmdirs/path2/file1.copy1.txt" 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:31893") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:58812->127.0.0.1:31893, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: Purge object "010054qo/rmdirs/path2/file1.copy2.txt" 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:30710") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:33112->127.0.0.1:30710, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: Purge object "010054qo/rmdirs/path2/file1.copy3.txt" 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:30259") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:41912->127.0.0.1:30259, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: Purge object "010054qo/rmdirs/path2/file1.copy4.txt" 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:30776") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:45622->127.0.0.1:30776, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: Purge object "010054qo/rmdirs/path2/file1.copy5.txt" 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:31451") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:35206->127.0.0.1:31451, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: Purge object "010054qo/rmdirs/path2/file1.txt" 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:31132") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:33360->127.0.0.1:31132, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:31552") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:52414->127.0.0.1:31552, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: Purge object "010054qo/rmdirs/path2/subdir/file20.txt" 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: dial("tcp","127.0.0.1:30068") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: > dial: conn=127.0.0.1:43646->127.0.0.1:30068, err= 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: Purge dir "010054qo/rmdirs/path2/subdir" 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: Purge dir "010054qo/rmdirs/path2" 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: Purge dir "010054qo/rmdirs" 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: Purge dir "010054qo/path2" 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: Purge dir "010054qo" 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke: Purge dir "" --- PASS: TestBisyncLocalRemote (0.11s) --- SKIP: TestBisyncLocalRemote/rmdirs (0.09s) PASS 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-voleqox6boba: dial("tcp","127.0.0.1:31932") 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-voleqox6boba: > dial: conn=127.0.0.1:46228->127.0.0.1:31932, err= 2026/01/24 01:00:54 ERROR : error listing: directory not found 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-voleqox6boba: Purge dir "" 2026/01/24 01:00:54 NOTICE: purge failed to rmdir "": directory not found 2026/01/24 01:00:54 NOTICE: purge failed: directory not found 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo: closing 1 unused connections 2026/01/24 01:00:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-puniciv9doke/010054qo/rmdirs/path2: closing 5 unused connections "./bisync.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestBisyncLocalRemote$/^rmdirs$'" - Finished OK in 153.039522ms (try 2/5)