"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 3/5) 2026/10/02 03:30:56 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-moqekez2zayo" 2026/10/02 03:30:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/02 03:30:56 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/02 03:30:56 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/02 03:30:56 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/02 03:30:56 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/02 03:30:56 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/02 03:30:56 DEBUG : TestFTPRclone: detected overridden config - adding "{kw-yd}" suffix to name 2026/10/02 03:30:56 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/02 03:30:56 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/02 03:30:56 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/02 03:30:56 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: Connecting to FTP server 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:28622") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:56032->127.0.0.1:28622, err= 2026/10/02 03:30:56 DEBUG : Creating backend with remote "/tmp/rclone3151243845" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-moqekez2zayo", Local "Local file system at /tmp/rclone3151243845", Modify Window "876000h0m0s" 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:30221") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:56576->127.0.0.1:30221, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: SetModTime is not supported 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:31918") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:35288->127.0.0.1:31918, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: SetModTime is not supported 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:30826") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:52824->127.0.0.1:30826, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: SetModTime is not supported 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:31349") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:33284->127.0.0.1:31349, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: SetModTime is not supported 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:31919") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:41708->127.0.0.1:31919, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: Connecting to FTP server 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:28622") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:31793") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:56038->127.0.0.1:28622, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:55336->127.0.0.1:31793, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:30736") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:48172->127.0.0.1:30736, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:31599") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:44192->127.0.0.1:31599, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: Connecting to FTP server 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: Connecting to FTP server 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:31126") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:34738->127.0.0.1:31126, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:31469") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:47638->127.0.0.1:31469, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: SetModTime is not supported 2026/10/02 03:30:56 DEBUG : sub/index.json.4373ef85.partial: size = 255 OK 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: SetModTime is not supported 2026/10/02 03:30:56 DEBUG : other/index.json.ff681112.partial: size = 140 OK 2026/10/02 03:30:56 DEBUG : sub/index.json.4373ef85.partial: renamed to: sub/index.json 2026/10/02 03:30:56 INFO : sub/index.json: Copied (new) 2026/10/02 03:30:56 DEBUG : other/index.json.ff681112.partial: renamed to: other/index.json 2026/10/02 03:30:56 INFO : other/index.json: Copied (new) 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:28622") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:56052->127.0.0.1:28622, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:31566") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:54506->127.0.0.1:31566, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: SetModTime is not supported 2026/10/02 03:30:56 DEBUG : sub/deep/index.json.35dfda0c.partial: size = 139 OK 2026/10/02 03:30:56 DEBUG : sub/deep/index.json.35dfda0c.partial: renamed to: sub/deep/index.json 2026/10/02 03:30:56 INFO : sub/deep/index.json: Copied (new) 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:28622") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:56058->127.0.0.1:28622, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:30791") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:51764->127.0.0.1:30791, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: SetModTime is not supported 2026/10/02 03:30:56 DEBUG : index.json.8714e1cb.partial: size = 371 OK 2026/10/02 03:30:56 DEBUG : index.json.8714e1cb.partial: renamed to: index.json 2026/10/02 03:30:56 INFO : index.json: Copied (new) 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:30880") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:40848->127.0.0.1:30880, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: SetModTime is not supported 2026/10/02 03:30:56 INFO : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: Partial index: listing 3 directories and walking 0 changed directories 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:31711") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:58306->127.0.0.1:31711, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:31493") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:56500->127.0.0.1:31493, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:31695") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:43388->127.0.0.1:31695, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:30096") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:59374->127.0.0.1:30096, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:31248") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:34270->127.0.0.1:31248, err= 2026/10/02 03:30:56 DEBUG : index.json: Unchanged skipping 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: SetModTime is not supported 2026/10/02 03:30:56 DEBUG : sub/deep/index.json.d619fa4d.partial: size = 275 OK 2026/10/02 03:30:56 DEBUG : sub/deep/index.json.d619fa4d.partial: renamed to: sub/deep/index.json 2026/10/02 03:30:56 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:30490") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:46934->127.0.0.1:30490, err= 2026/10/02 03:30:56 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/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:30155") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:36920->127.0.0.1:30155, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:31819") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:47942->127.0.0.1:31819, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:31911") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:60550->127.0.0.1:31911, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:30705") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:35436->127.0.0.1:30705, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:31959") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:49532->127.0.0.1:31959, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:31498") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:50074->127.0.0.1:31498, err= --- FAIL: TestIndexChangedDirTime (0.07s) FAIL 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: dial("tcp","127.0.0.1:30617") 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: > dial: conn=127.0.0.1:50826->127.0.0.1:30617, err= 2026/10/02 03:30:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-moqekez2zayo: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 139.258519ms (try 3/5): exit status 1: Failed [TestIndexChangedDirTime]