"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 3/5) 2026/10/01 02:20:07 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-vigexab3qoca" 2026/10/01 02:20:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/01 02:20:07 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/01 02:20:07 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/01 02:20:07 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/01 02:20:07 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/01 02:20:07 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/01 02:20:07 DEBUG : TestFTPRclone: detected overridden config - adding "{xNvZO}" suffix to name 2026/10/01 02:20:07 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/01 02:20:07 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/01 02:20:07 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/01 02:20:07 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: Connecting to FTP server 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:28622") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:40054->127.0.0.1:28622, err= 2026/10/01 02:20:07 DEBUG : Creating backend with remote "/tmp/rclone1858327776" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-vigexab3qoca", Local "Local file system at /tmp/rclone1858327776", Modify Window "876000h0m0s" 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:31446") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:36926->127.0.0.1:31446, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: SetModTime is not supported 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:31170") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:40308->127.0.0.1:31170, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: SetModTime is not supported 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:31442") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:42448->127.0.0.1:31442, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: SetModTime is not supported 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:31228") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:34196->127.0.0.1:31228, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: SetModTime is not supported 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:31265") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:33934->127.0.0.1:31265, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: Connecting to FTP server 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:28622") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:30589") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:40070->127.0.0.1:28622, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:35074->127.0.0.1:30589, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:30548") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:42578->127.0.0.1:30548, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:30693") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:49394->127.0.0.1:30693, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: Connecting to FTP server 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: Connecting to FTP server 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:30278") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:30711") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:38966->127.0.0.1:30278, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:33628->127.0.0.1:30711, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: SetModTime is not supported 2026/10/01 02:20:07 DEBUG : sub/index.json.937c7c04.partial: size = 255 OK 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: SetModTime is not supported 2026/10/01 02:20:07 DEBUG : index.json.cf2fce3d.partial: size = 371 OK 2026/10/01 02:20:07 DEBUG : sub/index.json.937c7c04.partial: renamed to: sub/index.json 2026/10/01 02:20:07 INFO : sub/index.json: Copied (new) 2026/10/01 02:20:07 DEBUG : index.json.cf2fce3d.partial: renamed to: index.json 2026/10/01 02:20:07 INFO : index.json: Copied (new) 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:28622") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:40084->127.0.0.1:28622, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:30871") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:57394->127.0.0.1:30871, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: SetModTime is not supported 2026/10/01 02:20:07 DEBUG : other/index.json.6df9e235.partial: size = 140 OK 2026/10/01 02:20:07 DEBUG : other/index.json.6df9e235.partial: renamed to: other/index.json 2026/10/01 02:20:07 INFO : other/index.json: Copied (new) 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:28622") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:40094->127.0.0.1:28622, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:30455") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:53968->127.0.0.1:30455, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: SetModTime is not supported 2026/10/01 02:20:07 DEBUG : sub/deep/index.json.24150bfd.partial: size = 139 OK 2026/10/01 02:20:07 DEBUG : sub/deep/index.json.24150bfd.partial: renamed to: sub/deep/index.json 2026/10/01 02:20:07 INFO : sub/deep/index.json: Copied (new) 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:30539") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:52714->127.0.0.1:30539, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: SetModTime is not supported 2026/10/01 02:20:07 INFO : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: Partial index: listing 3 directories and walking 0 changed directories 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:30060") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:43714->127.0.0.1:30060, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:30279") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:46572->127.0.0.1:30279, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:31561") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:52874->127.0.0.1:31561, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:31958") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:39848->127.0.0.1:31958, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:31672") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:36754->127.0.0.1:31672, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: SetModTime is not supported 2026/10/01 02:20:07 DEBUG : index.json: Unchanged skipping 2026/10/01 02:20:07 DEBUG : sub/deep/index.json.5f6c8950.partial: size = 275 OK 2026/10/01 02:20:07 DEBUG : sub/deep/index.json.5f6c8950.partial: renamed to: sub/deep/index.json 2026/10/01 02:20:07 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:30421") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:51536->127.0.0.1:30421, err= 2026/10/01 02:20: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/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:30941") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:45664->127.0.0.1:30941, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:31485") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:33406->127.0.0.1:31485, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:31309") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:31721") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:56910->127.0.0.1:31309, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:34986->127.0.0.1:31721, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:30560") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:53500->127.0.0.1:30560, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:30188") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:42802->127.0.0.1:30188, err= --- FAIL: TestIndexChangedDirTime (0.07s) FAIL 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: dial("tcp","127.0.0.1:30063") 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: > dial: conn=127.0.0.1:59162->127.0.0.1:30063, err= 2026/10/01 02:20:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-vigexab3qoca: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 147.170524ms (try 3/5): exit status 1: Failed [TestIndexChangedDirTime]