"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 3/5) 2026/10/06 04:55:11 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-ripimek0noji" 2026/10/06 04:55:11 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/06 04:55:11 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/06 04:55:11 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/06 04:55:11 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/06 04:55:11 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/06 04:55:11 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/06 04:55:11 DEBUG : TestFTPRclone: detected overridden config - adding "{_zFt1}" suffix to name 2026/10/06 04:55:11 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/06 04:55:11 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/06 04:55:11 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/06 04:55:11 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: Connecting to FTP server 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:28622") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:54896->127.0.0.1:28622, err= 2026/10/06 04:55:11 DEBUG : Creating backend with remote "/tmp/rclone2707839384" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-ripimek0noji", Local "Local file system at /tmp/rclone2707839384", Modify Window "876000h0m0s" 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:31214") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:58994->127.0.0.1:31214, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: SetModTime is not supported 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:31126") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:45080->127.0.0.1:31126, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: SetModTime is not supported 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:30986") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:48120->127.0.0.1:30986, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: SetModTime is not supported 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:31837") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:44360->127.0.0.1:31837, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: SetModTime is not supported 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:30625") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:52434->127.0.0.1:30625, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: Connecting to FTP server 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:28622") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:54904->127.0.0.1:28622, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:31287") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:44326->127.0.0.1:31287, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:31148") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:56540->127.0.0.1:31148, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:30636") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:52244->127.0.0.1:30636, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: Connecting to FTP server 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: Connecting to FTP server 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:30006") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:55012->127.0.0.1:30006, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:30723") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:42544->127.0.0.1:30723, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: SetModTime is not supported 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: SetModTime is not supported 2026/10/06 04:55:11 DEBUG : other/index.json.b7fd5c00.partial: size = 140 OK 2026/10/06 04:55:11 DEBUG : index.json.0327979c.partial: size = 371 OK 2026/10/06 04:55:11 DEBUG : other/index.json.b7fd5c00.partial: renamed to: other/index.json 2026/10/06 04:55:11 INFO : other/index.json: Copied (new) 2026/10/06 04:55:11 DEBUG : index.json.0327979c.partial: renamed to: index.json 2026/10/06 04:55:11 INFO : index.json: Copied (new) 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:28622") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:54920->127.0.0.1:28622, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:30532") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:35426->127.0.0.1:30532, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: SetModTime is not supported 2026/10/06 04:55:11 DEBUG : sub/deep/index.json.b9b1dd62.partial: size = 139 OK 2026/10/06 04:55:11 DEBUG : sub/deep/index.json.b9b1dd62.partial: renamed to: sub/deep/index.json 2026/10/06 04:55:11 INFO : sub/deep/index.json: Copied (new) 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:28622") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:54928->127.0.0.1:28622, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:31042") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:43450->127.0.0.1:31042, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: SetModTime is not supported 2026/10/06 04:55:11 DEBUG : sub/index.json.6d591f39.partial: size = 255 OK 2026/10/06 04:55:11 DEBUG : sub/index.json.6d591f39.partial: renamed to: sub/index.json 2026/10/06 04:55:11 INFO : sub/index.json: Copied (new) 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:31366") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:56732->127.0.0.1:31366, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: SetModTime is not supported 2026/10/06 04:55:11 INFO : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: Partial index: listing 3 directories and walking 0 changed directories 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:30466") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:38306->127.0.0.1:30466, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:31499") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:48850->127.0.0.1:31499, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:31194") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:50328->127.0.0.1:31194, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:30883") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:40354->127.0.0.1:30883, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: SetModTime is not supported 2026/10/06 04:55:11 DEBUG : sub/deep/index.json.6e374b63.partial: size = 275 OK 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:31160") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:41624->127.0.0.1:31160, err= 2026/10/06 04:55:11 DEBUG : index.json: Unchanged skipping 2026/10/06 04:55:11 DEBUG : sub/deep/index.json.6e374b63.partial: renamed to: sub/deep/index.json 2026/10/06 04:55:11 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:30036") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:54778->127.0.0.1:30036, err= 2026/10/06 04:55:11 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/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:30414") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:49446->127.0.0.1:30414, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:31624") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:50454->127.0.0.1:31624, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:31391") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:48134->127.0.0.1:31391, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:30646") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:45170->127.0.0.1:30646, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:31975") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:35740->127.0.0.1:31975, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:30218") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:55452->127.0.0.1:30218, err= --- FAIL: TestIndexChangedDirTime (0.07s) FAIL 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: dial("tcp","127.0.0.1:30555") 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: > dial: conn=127.0.0.1:41750->127.0.0.1:30555, err= 2026/10/06 04:55:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-ripimek0noji: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 134.498975ms (try 3/5): exit status 1: Failed [TestIndexChangedDirTime]