"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 5/5) 2026/10/06 04:55:13 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-macayud1yofa" 2026/10/06 04:55:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/06 04:55:13 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/06 04:55:13 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/06 04:55:13 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/06 04:55:13 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/06 04:55:13 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/06 04:55:13 DEBUG : TestFTPRclone: detected overridden config - adding "{FFwhK}" suffix to name 2026/10/06 04:55:13 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/06 04:55:13 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/06 04:55:13 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/06 04:55:13 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: Connecting to FTP server 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:28622") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:51038->127.0.0.1:28622, err= 2026/10/06 04:55:13 DEBUG : Creating backend with remote "/tmp/rclone2862682115" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-macayud1yofa", Local "Local file system at /tmp/rclone2862682115", Modify Window "876000h0m0s" 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:31847") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:47970->127.0.0.1:31847, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: SetModTime is not supported 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:30260") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:46286->127.0.0.1:30260, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: SetModTime is not supported 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:30015") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:41006->127.0.0.1:30015, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: SetModTime is not supported 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:30148") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:48212->127.0.0.1:30148, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: SetModTime is not supported 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:30155") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:37532->127.0.0.1:30155, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: Connecting to FTP server 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:28622") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:51046->127.0.0.1:28622, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:31481") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:60620->127.0.0.1:31481, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:31273") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:57292->127.0.0.1:31273, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:30126") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:46496->127.0.0.1:30126, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: Connecting to FTP server 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: Connecting to FTP server 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:31836") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:30511") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:58454->127.0.0.1:31836, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:39864->127.0.0.1:30511, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: SetModTime is not supported 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: SetModTime is not supported 2026/10/06 04:55:13 DEBUG : other/index.json.6f0f887a.partial: size = 140 OK 2026/10/06 04:55:13 DEBUG : index.json.0877300a.partial: size = 371 OK 2026/10/06 04:55:13 DEBUG : other/index.json.6f0f887a.partial: renamed to: other/index.json 2026/10/06 04:55:13 INFO : other/index.json: Copied (new) 2026/10/06 04:55:13 DEBUG : index.json.0877300a.partial: renamed to: index.json 2026/10/06 04:55:13 INFO : index.json: Copied (new) 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:28622") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:51062->127.0.0.1:28622, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:30516") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:39352->127.0.0.1:30516, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: SetModTime is not supported 2026/10/06 04:55:13 DEBUG : sub/deep/index.json.266a4cfc.partial: size = 139 OK 2026/10/06 04:55:13 DEBUG : sub/deep/index.json.266a4cfc.partial: renamed to: sub/deep/index.json 2026/10/06 04:55:13 INFO : sub/deep/index.json: Copied (new) 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:28622") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:51078->127.0.0.1:28622, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:31808") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:45496->127.0.0.1:31808, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: SetModTime is not supported 2026/10/06 04:55:13 DEBUG : sub/index.json.7cf44e17.partial: size = 255 OK 2026/10/06 04:55:13 DEBUG : sub/index.json.7cf44e17.partial: renamed to: sub/index.json 2026/10/06 04:55:13 INFO : sub/index.json: Copied (new) 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:31255") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:50732->127.0.0.1:31255, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: SetModTime is not supported 2026/10/06 04:55:13 INFO : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: Partial index: listing 3 directories and walking 0 changed directories 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:31678") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:52070->127.0.0.1:31678, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:30443") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:56178->127.0.0.1:30443, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:30245") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:47896->127.0.0.1:30245, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:31228") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:30701") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:54550->127.0.0.1:30701, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:33994->127.0.0.1:31228, err= 2026/10/06 04:55:13 DEBUG : index.json: Unchanged skipping 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: SetModTime is not supported 2026/10/06 04:55:13 DEBUG : sub/deep/index.json.ceee3129.partial: size = 275 OK 2026/10/06 04:55:13 DEBUG : sub/deep/index.json.ceee3129.partial: renamed to: sub/deep/index.json 2026/10/06 04:55:13 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:31283") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:40234->127.0.0.1:31283, err= 2026/10/06 04:55:13 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:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:31953") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:57038->127.0.0.1:31953, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:31220") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:56662->127.0.0.1:31220, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:30697") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:31416") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:33878->127.0.0.1:31416, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:40214->127.0.0.1:30697, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:30244") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:51904->127.0.0.1:30244, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:30787") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:49536->127.0.0.1:30787, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: dial("tcp","127.0.0.1:30632") 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: > dial: conn=127.0.0.1:50394->127.0.0.1:30632, err= 2026/10/06 04:55:13 DEBUG : ftp://127.0.0.1:28622/rclone-test-macayud1yofa: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 164.352503ms (try 5/5): exit status 1: Failed [TestIndexChangedDirTime]