"./bisync.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestBisyncLocalRemote$/^resync_modes$'" - Starting (try 2/5) 2026/08/23 04:17:41 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-jogapax1pazu" 2026/08/23 04:17:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/23 04:17:41 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/08/23 04:17:41 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/08/23 04:17:41 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/08/23 04:17:41 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/08/23 04:17:41 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/08/23 04:17:41 DEBUG : TestFTPRclone: detected overridden config - adding "{HdcCt}" suffix to name 2026/08/23 04:17:41 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/08/23 04:17:41 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/08/23 04:17:41 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/08/23 04:17:41 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-jogapax1pazu: Connecting to FTP server 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-jogapax1pazu: dial("tcp","127.0.0.1:28622") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-jogapax1pazu: > dial: conn=127.0.0.1:51644->127.0.0.1:28622, err= 2026/08/23 04:17:41 DEBUG : Creating backend with remote "/tmp/rclone2984383337" === RUN TestBisyncLocalRemote 2026/08/23 04:17:41 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-sagisuw1zahe" 2026/08/23 04:17:41 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/08/23 04:17:41 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/08/23 04:17:41 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/08/23 04:17:41 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/08/23 04:17:41 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/08/23 04:17:41 DEBUG : TestFTPRclone: detected overridden config - adding "{HdcCt}" suffix to name 2026/08/23 04:17:41 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/08/23 04:17:41 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/08/23 04:17:41 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/08/23 04:17:41 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: Connecting to FTP server 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: dial("tcp","127.0.0.1:28622") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: > dial: conn=127.0.0.1:51646->127.0.0.1:28622, err= 2026/08/23 04:17:41 NOTICE: remote: TestFTPRclone:rclone-test-sagisuw1zahe === RUN TestBisyncLocalRemote/resync_modes 2026/08/23 04:17:41 DEBUG : Creating backend with remote "/tmp/041741zebefah5" 2026/08/23 04:17:41 DEBUG : Creating backend with remote "/tmp/041741zebefah5/resync_modes/path1" 2026/08/23 04:17:41 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-sagisuw1zahe/041741zebefah5" 2026/08/23 04:17:41 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/08/23 04:17:41 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/08/23 04:17:41 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/08/23 04:17:41 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/08/23 04:17:41 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/08/23 04:17:41 DEBUG : TestFTPRclone: detected overridden config - adding "{HdcCt}" suffix to name 2026/08/23 04:17:41 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/08/23 04:17:41 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/08/23 04:17:41 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/08/23 04:17:41 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5: Connecting to FTP server 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5: dial("tcp","127.0.0.1:28622") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5: > dial: conn=127.0.0.1:51660->127.0.0.1:28622, err= 2026/08/23 04:17:41 DEBUG : fs cache: renaming cache item "TestFTPRclone:rclone-test-sagisuw1zahe/041741zebefah5" to be canonical "TestFTPRclone{HdcCt}:rclone-test-sagisuw1zahe/041741zebefah5" 2026/08/23 04:17:41 INFO : path2: Making directory 2026/08/23 04:17:41 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2" 2026/08/23 04:17:41 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/08/23 04:17:41 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/08/23 04:17:41 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/08/23 04:17:41 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/08/23 04:17:41 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/08/23 04:17:41 DEBUG : TestFTPRclone: detected overridden config - adding "{HdcCt}" suffix to name 2026/08/23 04:17:41 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/08/23 04:17:41 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/08/23 04:17:41 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/08/23 04:17:41 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: Connecting to FTP server 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: dial("tcp","127.0.0.1:28622") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: > dial: conn=127.0.0.1:51664->127.0.0.1:28622, err= 2026/08/23 04:17:41 DEBUG : fs cache: renaming cache item "TestFTPRclone:rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2" to be canonical "TestFTPRclone{HdcCt}:rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2" 2026/08/23 04:17:41 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial" 2026/08/23 04:17:41 DEBUG : Creating backend with remote "/tmp/041741zebefah5/initdir/test_resync_modes-xunitip8" 2026/08/23 04:17:41 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/08/23 04:17:41 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/08/23 04:17:41 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/08/23 04:17:41 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/08/23 04:17:41 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/08/23 04:17:41 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/08/23 04:17:41 INFO : file2.txt: Copied (new) 2026/08/23 04:17:41 DEBUG : Local file system at /tmp/041741zebefah5/initdir/test_resync_modes-xunitip8: Waiting for checks to finish 2026/08/23 04:17:41 DEBUG : Local file system at /tmp/041741zebefah5/initdir/test_resync_modes-xunitip8: Waiting for transfers to finish 2026/08/23 04:17:41 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/08/23 04:17:41 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/08/23 04:17:41 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/08/23 04:17:41 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/08/23 04:17:41 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/08/23 04:17:41 INFO : file1.txt: Copied (new) 2026/08/23 04:17:41 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/08/23 04:17:41 INFO : RCLONE_TEST: Copied (new) 2026/08/23 04:17:41 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles" 2026/08/23 04:17:41 DEBUG : Creating backend with remote "/tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3" 2026/08/23 04:17:41 DEBUG : file1L.txt: Need to transfer - File not found at Destination 2026/08/23 04:17:41 DEBUG : file1R.txt: Need to transfer - File not found at Destination 2026/08/23 04:17:41 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/08/23 04:17:41 DEBUG : file1L.txt.684d937d.partial: size = 33 OK 2026/08/23 04:17:41 DEBUG : file2.txt.ac4156f2.partial: size = 37 OK 2026/08/23 04:17:41 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK 2026/08/23 04:17:41 DEBUG : file2.txt.ac4156f2.partial: renamed to: file2.txt 2026/08/23 04:17:41 INFO : file2.txt: Copied (new) 2026/08/23 04:17:41 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK 2026/08/23 04:17:41 DEBUG : file1L.txt.684d937d.partial: renamed to: file1L.txt 2026/08/23 04:17:41 INFO : file1L.txt: Copied (new) 2026/08/23 04:17:41 DEBUG : Local file system at /tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3: Waiting for checks to finish 2026/08/23 04:17:41 DEBUG : Local file system at /tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3: Waiting for transfers to finish 2026/08/23 04:17:41 DEBUG : file1R.txt.d467e63a.partial: size = 42 OK 2026/08/23 04:17:41 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK 2026/08/23 04:17:41 DEBUG : file1R.txt.d467e63a.partial: renamed to: file1R.txt 2026/08/23 04:17:41 INFO : file1R.txt: Copied (new) 2026/08/23 04:17:41 DEBUG : Waiting for deletions to finish 2026/08/23 04:17:41 ERROR : error listing: directory not found 2026/08/23 04:17:41 ERROR : Local file system at /tmp/041741zebefah5/resync_modes/path1: Failed to list "": directory not found 2026/08/23 04:17:41 DEBUG : removing 1 level 0 directories 2026/08/23 04:17:41 INFO : Local file system at /tmp/041741zebefah5/resync_modes/path1: Removing directory 2026/08/23 04:17:41 ERROR : Failed to rmdir: stat /tmp/041741zebefah5/resync_modes/path1: no such file or directory 2026/08/23 04:17:41 DEBUG : Waiting for deletions to finish 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: dial("tcp","127.0.0.1:30488") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: > dial: conn=127.0.0.1:40718->127.0.0.1:30488, err= 2026/08/23 04:17:41 ERROR : error listing: directory not found 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: dial("tcp","127.0.0.1:30278") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: > dial: conn=127.0.0.1:56888->127.0.0.1:30278, err= 2026/08/23 04:17:41 ERROR : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: Failed to list "": directory not found 2026/08/23 04:17:41 DEBUG : removing 1 level 0 directories 2026/08/23 04:17:41 INFO : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: Removing directory 2026/08/23 04:17:41 ERROR : Failed to rmdir: directory not found 2026/08/23 04:17:41 ERROR : error listing: directory not found 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: dial("tcp","127.0.0.1:30796") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: > dial: conn=127.0.0.1:58144->127.0.0.1:30796, err= 2026/08/23 04:17:41 ERROR : error listing: directory not found 2026/08/23 04:17:41 NOTICE: checking initFs Local file system at /tmp/041741zebefah5/initdir/test_resync_modes-xunitip8 2026/08/23 04:17:41 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/08/23 04:17:41 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/08/23 04:17:41 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/08/23 04:17:41 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/08/23 04:17:41 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/08/23 04:17:41 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/08/23 04:17:41 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/08/23 04:17:41 INFO : file1.txt: Copied (new) 2026/08/23 04:17:41 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/08/23 04:17:41 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/08/23 04:17:41 INFO : file2.txt: Copied (new) 2026/08/23 04:17:41 DEBUG : Local file system at /tmp/041741zebefah5/resync_modes/path1: Waiting for checks to finish 2026/08/23 04:17:41 DEBUG : Local file system at /tmp/041741zebefah5/resync_modes/path1: Waiting for transfers to finish 2026/08/23 04:17:41 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/08/23 04:17:41 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/08/23 04:17:41 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/08/23 04:17:41 INFO : RCLONE_TEST: Copied (new) 2026/08/23 04:17:41 NOTICE: checking Path1 Local file system at /tmp/041741zebefah5/resync_modes/path1 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: dial("tcp","127.0.0.1:30583") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: > dial: conn=127.0.0.1:40592->127.0.0.1:30583, err= 2026/08/23 04:17:41 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/08/23 04:17:41 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/08/23 04:17:41 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: Connecting to FTP server 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: dial("tcp","127.0.0.1:28622") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: Connecting to FTP server 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: > dial: conn=127.0.0.1:38380->127.0.0.1:28622, err= 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: Waiting for checks to finish 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: Waiting for transfers to finish 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: dial("tcp","127.0.0.1:31050") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: dial("tcp","127.0.0.1:31688") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: > dial: conn=127.0.0.1:48992->127.0.0.1:31688, err= 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: > dial: conn=127.0.0.1:48730->127.0.0.1:31050, err= 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: SetModTime is not supported 2026/08/23 04:17:41 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: dial("tcp","127.0.0.1:28622") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: SetModTime is not supported 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: > dial: conn=127.0.0.1:38396->127.0.0.1:28622, err= 2026/08/23 04:17:41 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: dial("tcp","127.0.0.1:30670") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: > dial: conn=127.0.0.1:52236->127.0.0.1:30670, err= 2026/08/23 04:17:41 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/08/23 04:17:41 INFO : RCLONE_TEST: Copied (new) 2026/08/23 04:17:41 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/08/23 04:17:41 INFO : file1.txt: Copied (new) 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: SetModTime is not supported 2026/08/23 04:17:41 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/08/23 04:17:41 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/08/23 04:17:41 INFO : file2.txt: Copied (new) 2026/08/23 04:17:41 NOTICE: checking path2 ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: dial("tcp","127.0.0.1:31235") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: > dial: conn=127.0.0.1:43892->127.0.0.1:31235, err= 2026/08/23 04:17:41 NOTICE: (01) : test resync-mode 2026/08/23 04:17:41 NOTICE: (02) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2026/08/23 04:17:41 NOTICE: (03) : touch-glob 2001-01-02 /tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3/ file1R.txt 2026/08/23 04:17:41 DEBUG : Creating backend with remote "/tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3/" 2026/08/23 04:17:41 DEBUG : fs cache: renaming cache item "/tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3/" to be canonical "/tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3" 2026/08/23 04:17:41 DEBUG : file1R.txt: Set modification time 2001-01-02 2026/08/23 04:17:41 NOTICE: (04) : copy-as /tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3/file1R.txt TestFTPRclone,host='127.0.0.1',pass='9G6I1ZMpkkEAjgQpohu3C7XzFjbKr8OsGJ1OipggEnWr',port='28622',user='rclone':rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2/ file1.txt 2026/08/23 04:17:41 DEBUG : copyFile "/tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3/file1R.txt" to "TestFTPRclone,host='127.0.0.1',pass='9G6I1ZMpkkEAjgQpohu3C7XzFjbKr8OsGJ1OipggEnWr',port='28622',user='rclone':rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2/" as "file1.txt" 2026/08/23 04:17:41 DEBUG : Creating backend with remote "/tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3/file1R.txt" 2026/08/23 04:17:41 DEBUG : Creating backend with remote "TestFTPRclone,host='127.0.0.1',pass='9G6I1ZMpkkEAjgQpohu3C7XzFjbKr8OsGJ1OipggEnWr',port='28622',user='rclone':rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2/" 2026/08/23 04:17:41 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/08/23 04:17:41 DEBUG : TestFTPRclone: detected overridden config - adding "{HdcCt}" suffix to name 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: Connecting to FTP server 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: dial("tcp","127.0.0.1:28622") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: > dial: conn=127.0.0.1:38408->127.0.0.1:28622, err= 2026/08/23 04:17:41 DEBUG : operations.CopyFile "file1R.txt" to "ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2" as "file1.txt" 2026/08/23 04:17:41 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3) 2026/08/23 04:17:41 DEBUG : file1.txt: size = 0 (ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2) 2026/08/23 04:17:41 DEBUG : file1R.txt: Sizes differ 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: dial("tcp","127.0.0.1:31948") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: > dial: conn=127.0.0.1:43872->127.0.0.1:31948, err= 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: SetModTime is not supported 2026/08/23 04:17:41 DEBUG : file1.txt.91f01f3f.partial: size = 42 OK 2026/08/23 04:17:41 DEBUG : file1.txt.91f01f3f.partial: renamed to: file1.txt 2026/08/23 04:17:41 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2026/08/23 04:17:41 NOTICE: (05) : touch-glob 2001-03-04 /tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3/ file1L.txt 2026/08/23 04:17:41 DEBUG : fs cache: switching user supplied name "/tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3/" for canonical name "/tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3" 2026/08/23 04:17:41 DEBUG : file1L.txt: Set modification time 2001-03-04 2026/08/23 04:17:41 NOTICE: (06) : copy-as /tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3/file1L.txt /tmp/041741zebefah5/resync_modes/path1/ file1.txt 2026/08/23 04:17:41 DEBUG : copyFile "/tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3/file1L.txt" to "/tmp/041741zebefah5/resync_modes/path1/" as "file1.txt" 2026/08/23 04:17:41 DEBUG : Creating backend with remote "/tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3/file1L.txt" 2026/08/23 04:17:41 DEBUG : Creating backend with remote "/tmp/041741zebefah5/resync_modes/path1/" 2026/08/23 04:17:41 DEBUG : operations.CopyFile "file1L.txt" to "Local file system at /tmp/041741zebefah5/resync_modes/path1" as "file1.txt" 2026/08/23 04:17:41 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/041741zebefah5/datadir/test_resync_modes-tufukaj3) 2026/08/23 04:17:41 DEBUG : file1.txt: size = 0 (Local file system at /tmp/041741zebefah5/resync_modes/path1) 2026/08/23 04:17:41 DEBUG : file1L.txt: Sizes differ 2026/08/23 04:17:41 DEBUG : file1.txt.4e37ab47.partial: size = 33 OK 2026/08/23 04:17:41 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK 2026/08/23 04:17:41 DEBUG : file1.txt.4e37ab47.partial: renamed to: file1.txt 2026/08/23 04:17:41 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2026/08/23 04:17:41 NOTICE: (07) : test bisync run with --resync-mode=newer 2026/08/23 04:17:41 NOTICE: (08) : bisync resync resync-mode=newer bisync_test.go:1017: skipping test as at least one remote does not support setting modtime 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: dial("tcp","127.0.0.1:31299") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: > dial: conn=127.0.0.1:51578->127.0.0.1:31299, err= 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: dial("tcp","127.0.0.1:31502") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: > dial: conn=127.0.0.1:49784->127.0.0.1:31502, err= 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: Connecting to FTP server 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: dial("tcp","127.0.0.1:28622") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: > dial: conn=127.0.0.1:38420->127.0.0.1:28622, err= 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: dial("tcp","127.0.0.1:30990") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: > dial: conn=127.0.0.1:49514->127.0.0.1:30990, err= 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: dial("tcp","127.0.0.1:30516") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: > dial: conn=127.0.0.1:33662->127.0.0.1:30516, err= 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: dial("tcp","127.0.0.1:31895") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: Purge object "041741zebefah5/resync_modes/path2/RCLONE_TEST" 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: > dial: conn=127.0.0.1:41768->127.0.0.1:31895, err= 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: Purge object "041741zebefah5/resync_modes/path2/file1.txt" 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: Purge object "041741zebefah5/resync_modes/path2/file2.txt" 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: Purge dir "041741zebefah5/resync_modes/path2" 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: Purge dir "041741zebefah5/resync_modes" 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: Purge dir "041741zebefah5/path2" 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: Purge dir "041741zebefah5" 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe: Purge dir "" --- PASS: TestBisyncLocalRemote (0.15s) --- SKIP: TestBisyncLocalRemote/resync_modes (0.12s) PASS 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-jogapax1pazu: dial("tcp","127.0.0.1:30126") 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-jogapax1pazu: > dial: conn=127.0.0.1:47892->127.0.0.1:30126, err= 2026/08/23 04:17:41 ERROR : error listing: directory not found 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-jogapax1pazu: Purge dir "" 2026/08/23 04:17:41 NOTICE: purge failed to rmdir "": directory not found 2026/08/23 04:17:41 NOTICE: purge failed: directory not found 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5: closing 1 unused connections 2026/08/23 04:17:41 DEBUG : ftp://127.0.0.1:28622/rclone-test-sagisuw1zahe/041741zebefah5/resync_modes/path2: closing 3 unused connections "./bisync.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestBisyncLocalRemote$/^resync_modes$'" - Finished OK in 322.791874ms (try 2/5)