"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 4/5) 2026/10/03 01:24:07 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-wacuhok5hise" 2026/10/03 01:24:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/03 01:24:07 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/03 01:24:07 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/03 01:24:07 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/03 01:24:07 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/03 01:24:07 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/03 01:24:07 DEBUG : TestFTPRclone: detected overridden config - adding "{cy4KH}" suffix to name 2026/10/03 01:24:07 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/03 01:24:07 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/03 01:24:07 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/03 01:24:07 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: Connecting to FTP server 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:28622") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:52212->127.0.0.1:28622, err= 2026/10/03 01:24:07 DEBUG : Creating backend with remote "/tmp/rclone98892383" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-wacuhok5hise", Local "Local file system at /tmp/rclone98892383", Modify Window "876000h0m0s" 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:31993") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:39038->127.0.0.1:31993, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: SetModTime is not supported 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:30403") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:33912->127.0.0.1:30403, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: SetModTime is not supported 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:31296") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:47642->127.0.0.1:31296, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: SetModTime is not supported 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:30403") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:33920->127.0.0.1:30403, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: SetModTime is not supported 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:30912") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:50584->127.0.0.1:30912, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: Connecting to FTP server 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:28622") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:31007") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:52216->127.0.0.1:28622, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:46996->127.0.0.1:31007, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:31943") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:43826->127.0.0.1:31943, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:30086") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:56532->127.0.0.1:30086, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: Connecting to FTP server 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: Connecting to FTP server 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:30697") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:44016->127.0.0.1:30697, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:30689") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:40920->127.0.0.1:30689, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: SetModTime is not supported 2026/10/03 01:24:07 DEBUG : sub/deep/index.json.75293e22.partial: size = 139 OK 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: SetModTime is not supported 2026/10/03 01:24:07 DEBUG : other/index.json.cef48171.partial: size = 140 OK 2026/10/03 01:24:07 DEBUG : sub/deep/index.json.75293e22.partial: renamed to: sub/deep/index.json 2026/10/03 01:24:07 INFO : sub/deep/index.json: Copied (new) 2026/10/03 01:24:07 DEBUG : other/index.json.cef48171.partial: renamed to: other/index.json 2026/10/03 01:24:07 INFO : other/index.json: Copied (new) 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:28622") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:52224->127.0.0.1:28622, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:31279") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:56718->127.0.0.1:31279, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: SetModTime is not supported 2026/10/03 01:24:07 DEBUG : sub/index.json.41d5e2b8.partial: size = 255 OK 2026/10/03 01:24:07 DEBUG : sub/index.json.41d5e2b8.partial: renamed to: sub/index.json 2026/10/03 01:24:07 INFO : sub/index.json: Copied (new) 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:28622") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:52226->127.0.0.1:28622, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:30573") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:44278->127.0.0.1:30573, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: SetModTime is not supported 2026/10/03 01:24:07 DEBUG : index.json.19ebc33b.partial: size = 371 OK 2026/10/03 01:24:07 DEBUG : index.json.19ebc33b.partial: renamed to: index.json 2026/10/03 01:24:07 INFO : index.json: Copied (new) 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:30387") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:35148->127.0.0.1:30387, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: SetModTime is not supported 2026/10/03 01:24:07 INFO : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: Partial index: listing 3 directories and walking 0 changed directories 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:31345") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:52492->127.0.0.1:31345, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:30643") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:59652->127.0.0.1:30643, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:31847") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:58596->127.0.0.1:31847, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:30681") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:32914->127.0.0.1:30681, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: SetModTime is not supported 2026/10/03 01:24:07 DEBUG : sub/deep/index.json.4ed06965.partial: size = 275 OK 2026/10/03 01:24:07 DEBUG : sub/deep/index.json.4ed06965.partial: renamed to: sub/deep/index.json 2026/10/03 01:24:07 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:30172") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:56716->127.0.0.1:30172, err= 2026/10/03 01:24:07 DEBUG : index.json: Unchanged skipping 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:30264") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:41584->127.0.0.1:30264, err= 2026/10/03 01:24:07 DEBUG : sub/index.json: Unchanged skipping index_test.go:540: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/index_test.go:540 Error: Not equal: expected: 3 actual : 1 Test: TestIndexChangedDirTime 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:31796") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:43720->127.0.0.1:31796, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:31158") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:52282->127.0.0.1:31158, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:30207") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:31375") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:51422->127.0.0.1:31375, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:37490->127.0.0.1:30207, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:31777") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:56744->127.0.0.1:31777, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:31048") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:40292->127.0.0.1:31048, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: dial("tcp","127.0.0.1:30887") 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: > dial: conn=127.0.0.1:57196->127.0.0.1:30887, err= 2026/10/03 01:24:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wacuhok5hise: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 151.970496ms (try 4/5): exit status 1: Failed [TestIndexChangedDirTime]