"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 3/5) 2026/10/03 01:24:06 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-yuyebog4hasa" 2026/10/03 01:24:06 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/03 01:24:06 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/03 01:24:06 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/03 01:24:06 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/03 01:24:06 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/03 01:24:06 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/03 01:24:06 DEBUG : TestFTPRclone: detected overridden config - adding "{xFbr4}" suffix to name 2026/10/03 01:24:06 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/03 01:24:06 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/03 01:24:06 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/03 01:24:06 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: Connecting to FTP server 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:28622") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:52164->127.0.0.1:28622, err= 2026/10/03 01:24:06 DEBUG : Creating backend with remote "/tmp/rclone4036392617" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa", Local "Local file system at /tmp/rclone4036392617", Modify Window "876000h0m0s" 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:31171") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:45608->127.0.0.1:31171, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: SetModTime is not supported 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:31655") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:43328->127.0.0.1:31655, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: SetModTime is not supported 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:30325") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:59218->127.0.0.1:30325, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: SetModTime is not supported 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:31739") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:47156->127.0.0.1:31739, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: SetModTime is not supported 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:31712") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:39558->127.0.0.1:31712, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: Connecting to FTP server 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:28622") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:52180->127.0.0.1:28622, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:31069") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:56296->127.0.0.1:31069, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:30926") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:57588->127.0.0.1:30926, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:30578") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:39638->127.0.0.1:30578, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: Connecting to FTP server 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: Connecting to FTP server 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:30856") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:31846") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:57364->127.0.0.1:30856, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:50526->127.0.0.1:31846, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: SetModTime is not supported 2026/10/03 01:24:06 DEBUG : sub/deep/index.json.27d693bf.partial: size = 139 OK 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: SetModTime is not supported 2026/10/03 01:24:06 DEBUG : index.json.8e1220f6.partial: size = 371 OK 2026/10/03 01:24:06 DEBUG : sub/deep/index.json.27d693bf.partial: renamed to: sub/deep/index.json 2026/10/03 01:24:06 INFO : sub/deep/index.json: Copied (new) 2026/10/03 01:24:06 DEBUG : index.json.8e1220f6.partial: renamed to: index.json 2026/10/03 01:24:06 INFO : index.json: Copied (new) 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:28622") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:52188->127.0.0.1:28622, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:31025") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:59106->127.0.0.1:31025, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: SetModTime is not supported 2026/10/03 01:24:06 DEBUG : sub/index.json.b9c7ba22.partial: size = 255 OK 2026/10/03 01:24:06 DEBUG : sub/index.json.b9c7ba22.partial: renamed to: sub/index.json 2026/10/03 01:24:06 INFO : sub/index.json: Copied (new) 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:28622") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:52192->127.0.0.1:28622, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:30352") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:55838->127.0.0.1:30352, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: SetModTime is not supported 2026/10/03 01:24:06 DEBUG : other/index.json.093d9008.partial: size = 140 OK 2026/10/03 01:24:06 DEBUG : other/index.json.093d9008.partial: renamed to: other/index.json 2026/10/03 01:24:06 INFO : other/index.json: Copied (new) 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:31038") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:48044->127.0.0.1:31038, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: SetModTime is not supported 2026/10/03 01:24:06 INFO : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: Partial index: listing 3 directories and walking 0 changed directories 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:31021") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:43674->127.0.0.1:31021, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:31616") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:60294->127.0.0.1:31616, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:30957") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:44076->127.0.0.1:30957, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:30194") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:39958->127.0.0.1:30194, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:31153") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:42028->127.0.0.1:31153, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: SetModTime is not supported 2026/10/03 01:24:06 DEBUG : sub/deep/index.json.456ddf0d.partial: size = 275 OK 2026/10/03 01:24:06 DEBUG : index.json: Unchanged skipping 2026/10/03 01:24:06 DEBUG : sub/deep/index.json.456ddf0d.partial: renamed to: sub/deep/index.json 2026/10/03 01:24:06 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:31481") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:44568->127.0.0.1:31481, err= 2026/10/03 01:24:06 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/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:31410") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:42172->127.0.0.1:31410, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:31965") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:52478->127.0.0.1:31965, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:30984") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:30147") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:40430->127.0.0.1:30984, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:51546->127.0.0.1:30147, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:30053") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:32942->127.0.0.1:30053, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:30426") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:56094->127.0.0.1:30426, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: dial("tcp","127.0.0.1:30071") 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: > dial: conn=127.0.0.1:60938->127.0.0.1:30071, err= 2026/10/03 01:24:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-yuyebog4hasa: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 174.336615ms (try 3/5): exit status 1: Failed [TestIndexChangedDirTime]