"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 2/5) 2026/10/04 01:25:53 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-sajusij9rujo" 2026/10/04 01:25:53 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/04 01:25:53 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/04 01:25:53 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/04 01:25:53 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/04 01:25:53 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/04 01:25:53 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/04 01:25:53 DEBUG : TestFTPRclone: detected overridden config - adding "{iZObn}" suffix to name 2026/10/04 01:25:53 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/04 01:25:53 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/04 01:25:53 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/04 01:25:53 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: Connecting to FTP server 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:28622") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:60358->127.0.0.1:28622, err= 2026/10/04 01:25:53 DEBUG : Creating backend with remote "/tmp/rclone672964994" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-sajusij9rujo", Local "Local file system at /tmp/rclone672964994", Modify Window "876000h0m0s" 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:30011") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:59176->127.0.0.1:30011, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: SetModTime is not supported 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:30841") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:59516->127.0.0.1:30841, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: SetModTime is not supported 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:31096") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:41028->127.0.0.1:31096, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: SetModTime is not supported 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:31687") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:49662->127.0.0.1:31687, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: SetModTime is not supported 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:31615") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:43046->127.0.0.1:31615, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: Connecting to FTP server 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:28622") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:60366->127.0.0.1:28622, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:30963") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:50844->127.0.0.1:30963, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:31440") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:52596->127.0.0.1:31440, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:30535") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:49626->127.0.0.1:30535, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: Connecting to FTP server 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:31767") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: Connecting to FTP server 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:44318->127.0.0.1:31767, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:30036") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:50814->127.0.0.1:30036, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: SetModTime is not supported 2026/10/04 01:25:53 DEBUG : index.json.fbb16bba.partial: size = 371 OK 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: SetModTime is not supported 2026/10/04 01:25:53 DEBUG : sub/deep/index.json.9f0d249a.partial: size = 139 OK 2026/10/04 01:25:53 DEBUG : index.json.fbb16bba.partial: renamed to: index.json 2026/10/04 01:25:53 INFO : index.json: Copied (new) 2026/10/04 01:25:53 DEBUG : sub/deep/index.json.9f0d249a.partial: renamed to: sub/deep/index.json 2026/10/04 01:25:53 INFO : sub/deep/index.json: Copied (new) 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:28622") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:60376->127.0.0.1:28622, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:31374") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:59594->127.0.0.1:31374, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: SetModTime is not supported 2026/10/04 01:25:53 DEBUG : other/index.json.66a9d434.partial: size = 140 OK 2026/10/04 01:25:53 DEBUG : other/index.json.66a9d434.partial: renamed to: other/index.json 2026/10/04 01:25:53 INFO : other/index.json: Copied (new) 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:28622") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:60386->127.0.0.1:28622, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:31486") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:49928->127.0.0.1:31486, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: SetModTime is not supported 2026/10/04 01:25:53 DEBUG : sub/index.json.35845329.partial: size = 255 OK 2026/10/04 01:25:53 DEBUG : sub/index.json.35845329.partial: renamed to: sub/index.json 2026/10/04 01:25:53 INFO : sub/index.json: Copied (new) 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:30649") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:55098->127.0.0.1:30649, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: SetModTime is not supported 2026/10/04 01:25:53 INFO : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: Partial index: listing 3 directories and walking 0 changed directories 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:31332") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:55744->127.0.0.1:31332, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:31660") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:42214->127.0.0.1:31660, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:30510") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:38634->127.0.0.1:30510, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:31313") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:56364->127.0.0.1:31313, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:31156") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:41590->127.0.0.1:31156, err= 2026/10/04 01:25:53 DEBUG : index.json: Unchanged skipping 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: SetModTime is not supported 2026/10/04 01:25:53 DEBUG : sub/deep/index.json.bbd737e8.partial: size = 275 OK 2026/10/04 01:25:53 DEBUG : sub/deep/index.json.bbd737e8.partial: renamed to: sub/deep/index.json 2026/10/04 01:25:53 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:30313") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:34730->127.0.0.1:30313, err= 2026/10/04 01:25:53 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/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:31058") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:59992->127.0.0.1:31058, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:30291") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:53888->127.0.0.1:30291, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:31520") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:30456") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:60850->127.0.0.1:31520, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:46662->127.0.0.1:30456, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:31505") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:38080->127.0.0.1:31505, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:31042") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:59992->127.0.0.1:31042, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: dial("tcp","127.0.0.1:31329") 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: > dial: conn=127.0.0.1:52060->127.0.0.1:31329, err= 2026/10/04 01:25:53 DEBUG : ftp://127.0.0.1:28622/rclone-test-sajusij9rujo: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 163.669087ms (try 2/5): exit status 1: Failed [TestIndexChangedDirTime]