"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 5/5) 2026/10/02 03:30:58 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-leqabeh2jico" 2026/10/02 03:30:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/02 03:30:58 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/02 03:30:58 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/02 03:30:58 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/02 03:30:58 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/02 03:30:58 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/02 03:30:58 DEBUG : TestFTPRclone: detected overridden config - adding "{m3MYU}" suffix to name 2026/10/02 03:30:58 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/02 03:30:58 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/02 03:30:58 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/02 03:30:58 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: Connecting to FTP server 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:28622") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:56120->127.0.0.1:28622, err= 2026/10/02 03:30:58 DEBUG : Creating backend with remote "/tmp/rclone2247646872" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-leqabeh2jico", Local "Local file system at /tmp/rclone2247646872", Modify Window "876000h0m0s" 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30543") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:60520->127.0.0.1:30543, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: SetModTime is not supported 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:31188") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:40998->127.0.0.1:31188, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: SetModTime is not supported 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30893") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:47636->127.0.0.1:30893, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: SetModTime is not supported 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30558") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:33350->127.0.0.1:30558, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: SetModTime is not supported 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30048") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:33538->127.0.0.1:30048, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: Connecting to FTP server 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:28622") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:56122->127.0.0.1:28622, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30392") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:56892->127.0.0.1:30392, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:31960") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:44372->127.0.0.1:31960, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30481") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:41050->127.0.0.1:30481, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: Connecting to FTP server 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: Connecting to FTP server 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:31995") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30651") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:44106->127.0.0.1:31995, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:56372->127.0.0.1:30651, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: SetModTime is not supported 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: SetModTime is not supported 2026/10/02 03:30:58 DEBUG : sub/deep/index.json.bc397041.partial: size = 139 OK 2026/10/02 03:30:58 DEBUG : other/index.json.8c61bc54.partial: size = 140 OK 2026/10/02 03:30:58 DEBUG : other/index.json.8c61bc54.partial: renamed to: other/index.json 2026/10/02 03:30:58 INFO : other/index.json: Copied (new) 2026/10/02 03:30:58 DEBUG : sub/deep/index.json.bc397041.partial: renamed to: sub/deep/index.json 2026/10/02 03:30:58 INFO : sub/deep/index.json: Copied (new) 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:28622") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:56130->127.0.0.1:28622, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30528") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:37154->127.0.0.1:30528, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: SetModTime is not supported 2026/10/02 03:30:58 DEBUG : index.json.9bfffe11.partial: size = 371 OK 2026/10/02 03:30:58 DEBUG : index.json.9bfffe11.partial: renamed to: index.json 2026/10/02 03:30:58 INFO : index.json: Copied (new) 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:28622") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:56146->127.0.0.1:28622, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30357") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:33418->127.0.0.1:30357, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: SetModTime is not supported 2026/10/02 03:30:58 DEBUG : sub/index.json.0d8e1346.partial: size = 255 OK 2026/10/02 03:30:58 DEBUG : sub/index.json.0d8e1346.partial: renamed to: sub/index.json 2026/10/02 03:30:58 INFO : sub/index.json: Copied (new) 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30607") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:60524->127.0.0.1:30607, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: SetModTime is not supported 2026/10/02 03:30:58 INFO : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: Partial index: listing 3 directories and walking 0 changed directories 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30404") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:44460->127.0.0.1:30404, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:31960") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:44380->127.0.0.1:31960, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30886") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:46086->127.0.0.1:30886, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30444") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:33644->127.0.0.1:30444, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30065") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:36028->127.0.0.1:30065, err= 2026/10/02 03:30:58 DEBUG : sub/index.json: Unchanged skipping 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: SetModTime is not supported 2026/10/02 03:30:58 DEBUG : sub/deep/index.json.31d11d0b.partial: size = 275 OK 2026/10/02 03:30:58 DEBUG : sub/deep/index.json.31d11d0b.partial: renamed to: sub/deep/index.json 2026/10/02 03:30:58 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30332") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:50350->127.0.0.1:30332, err= 2026/10/02 03:30:58 DEBUG : 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:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:31735") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:53408->127.0.0.1:31735, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:31656") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:47390->127.0.0.1:31656, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30505") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:54420->127.0.0.1:30505, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30623") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:48396->127.0.0.1:30623, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30365") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:39374->127.0.0.1:30365, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:30060") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:48104->127.0.0.1:30060, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: dial("tcp","127.0.0.1:31141") 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: > dial: conn=127.0.0.1:54000->127.0.0.1:31141, err= 2026/10/02 03:30:58 DEBUG : ftp://127.0.0.1:28622/rclone-test-leqabeh2jico: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 159.346375ms (try 5/5): exit status 1: Failed [TestIndexChangedDirTime]