"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 4/5) 2026/10/01 02:20:08 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-woloqaj5nene" 2026/10/01 02:20:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/01 02:20:08 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/01 02:20:08 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/01 02:20:08 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/01 02:20:08 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/01 02:20:08 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/01 02:20:08 DEBUG : TestFTPRclone: detected overridden config - adding "{I3kvJ}" suffix to name 2026/10/01 02:20:08 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/01 02:20:08 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/01 02:20:08 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/01 02:20:08 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: Connecting to FTP server 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:28622") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:40116->127.0.0.1:28622, err= 2026/10/01 02:20:08 DEBUG : Creating backend with remote "/tmp/rclone2622209002" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-woloqaj5nene", Local "Local file system at /tmp/rclone2622209002", Modify Window "876000h0m0s" 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:31146") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:55580->127.0.0.1:31146, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: SetModTime is not supported 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:30906") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:36384->127.0.0.1:30906, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: SetModTime is not supported 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:31186") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:60208->127.0.0.1:31186, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: SetModTime is not supported 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:31361") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:57234->127.0.0.1:31361, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: SetModTime is not supported 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:30600") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:43906->127.0.0.1:30600, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: Connecting to FTP server 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:28622") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:40124->127.0.0.1:28622, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:30173") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:48938->127.0.0.1:30173, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:31106") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:31112") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:47160->127.0.0.1:31106, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:32898->127.0.0.1:31112, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: Connecting to FTP server 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: Connecting to FTP server 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:30211") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:30330") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:35754->127.0.0.1:30211, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:32886->127.0.0.1:30330, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: SetModTime is not supported 2026/10/01 02:20:08 DEBUG : sub/deep/index.json.daa88191.partial: size = 139 OK 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: SetModTime is not supported 2026/10/01 02:20:08 DEBUG : sub/index.json.4f1806a6.partial: size = 255 OK 2026/10/01 02:20:08 DEBUG : sub/deep/index.json.daa88191.partial: renamed to: sub/deep/index.json 2026/10/01 02:20:08 INFO : sub/deep/index.json: Copied (new) 2026/10/01 02:20:08 DEBUG : sub/index.json.4f1806a6.partial: renamed to: sub/index.json 2026/10/01 02:20:08 INFO : sub/index.json: Copied (new) 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:28622") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:40136->127.0.0.1:28622, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:31617") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:34986->127.0.0.1:31617, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: SetModTime is not supported 2026/10/01 02:20:08 DEBUG : index.json.582b50be.partial: size = 371 OK 2026/10/01 02:20:08 DEBUG : index.json.582b50be.partial: renamed to: index.json 2026/10/01 02:20:08 INFO : index.json: Copied (new) 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:28622") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:40144->127.0.0.1:28622, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:30313") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:41938->127.0.0.1:30313, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: SetModTime is not supported 2026/10/01 02:20:08 DEBUG : other/index.json.d713235a.partial: size = 140 OK 2026/10/01 02:20:08 DEBUG : other/index.json.d713235a.partial: renamed to: other/index.json 2026/10/01 02:20:08 INFO : other/index.json: Copied (new) 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:30807") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:39174->127.0.0.1:30807, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: SetModTime is not supported 2026/10/01 02:20:08 INFO : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: Partial index: listing 3 directories and walking 0 changed directories 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:31695") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:60722->127.0.0.1:31695, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:30585") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:37122->127.0.0.1:30585, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:31175") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:44146->127.0.0.1:31175, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:31084") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:54354->127.0.0.1:31084, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: SetModTime is not supported 2026/10/01 02:20:08 DEBUG : sub/deep/index.json.96ddfcd3.partial: size = 275 OK 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:31751") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:53172->127.0.0.1:31751, err= 2026/10/01 02:20:08 DEBUG : index.json: Unchanged skipping 2026/10/01 02:20:08 DEBUG : sub/deep/index.json.96ddfcd3.partial: renamed to: sub/deep/index.json 2026/10/01 02:20:08 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:31684") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:47386->127.0.0.1:31684, err= 2026/10/01 02:20:08 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:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:30866") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:48822->127.0.0.1:30866, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:31797") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:39670->127.0.0.1:31797, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:30007") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:46926->127.0.0.1:30007, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:30787") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:59900->127.0.0.1:30787, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:30905") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:46570->127.0.0.1:30905, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:31477") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:44702->127.0.0.1:31477, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: dial("tcp","127.0.0.1:30597") 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: > dial: conn=127.0.0.1:37208->127.0.0.1:30597, err= 2026/10/01 02:20:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-woloqaj5nene: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 145.648106ms (try 4/5): exit status 1: Failed [TestIndexChangedDirTime]