"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 2/5) 2026/10/03 01:24:04 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-xiyixey0xuzi" 2026/10/03 01:24:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/03 01:24:04 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/03 01:24:04 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/03 01:24:04 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/03 01:24:04 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/03 01:24:04 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/03 01:24:04 DEBUG : TestFTPRclone: detected overridden config - adding "{S6kOU}" suffix to name 2026/10/03 01:24:04 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/03 01:24:04 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/03 01:24:04 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/03 01:24:04 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: Connecting to FTP server 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:28622") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:52124->127.0.0.1:28622, err= 2026/10/03 01:24:04 DEBUG : Creating backend with remote "/tmp/rclone787318111" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi", Local "Local file system at /tmp/rclone787318111", Modify Window "876000h0m0s" 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:31817") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:50300->127.0.0.1:31817, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: SetModTime is not supported 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:30822") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:35448->127.0.0.1:30822, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: SetModTime is not supported 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:30974") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:40190->127.0.0.1:30974, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: SetModTime is not supported 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:31564") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:55078->127.0.0.1:31564, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: SetModTime is not supported 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:31586") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:51594->127.0.0.1:31586, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: Connecting to FTP server 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:28622") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:30963") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:52128->127.0.0.1:28622, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:36220->127.0.0.1:30963, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:30485") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:36474->127.0.0.1:30485, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:31207") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:57514->127.0.0.1:31207, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: Connecting to FTP server 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: Connecting to FTP server 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:30594") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:31344") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:51022->127.0.0.1:30594, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:46154->127.0.0.1:31344, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: SetModTime is not supported 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: SetModTime is not supported 2026/10/03 01:24:04 DEBUG : sub/index.json.44326456.partial: size = 255 OK 2026/10/03 01:24:04 DEBUG : sub/deep/index.json.bbcc555c.partial: size = 139 OK 2026/10/03 01:24:04 DEBUG : sub/index.json.44326456.partial: renamed to: sub/index.json 2026/10/03 01:24:04 INFO : sub/index.json: Copied (new) 2026/10/03 01:24:04 DEBUG : sub/deep/index.json.bbcc555c.partial: renamed to: sub/deep/index.json 2026/10/03 01:24:04 INFO : sub/deep/index.json: Copied (new) 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:28622") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:52134->127.0.0.1:28622, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:30070") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:43836->127.0.0.1:30070, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: SetModTime is not supported 2026/10/03 01:24:04 DEBUG : index.json.bb0e9414.partial: size = 371 OK 2026/10/03 01:24:04 DEBUG : index.json.bb0e9414.partial: renamed to: index.json 2026/10/03 01:24:04 INFO : index.json: Copied (new) 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:28622") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:52150->127.0.0.1:28622, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:30786") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:60118->127.0.0.1:30786, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: SetModTime is not supported 2026/10/03 01:24:04 DEBUG : other/index.json.92132bbb.partial: size = 140 OK 2026/10/03 01:24:04 DEBUG : other/index.json.92132bbb.partial: renamed to: other/index.json 2026/10/03 01:24:04 INFO : other/index.json: Copied (new) 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:30808") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:52378->127.0.0.1:30808, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: SetModTime is not supported 2026/10/03 01:24:04 INFO : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: Partial index: listing 3 directories and walking 0 changed directories 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:30593") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:34700->127.0.0.1:30593, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:30365") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:57936->127.0.0.1:30365, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:30914") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:55076->127.0.0.1:30914, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:30955") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:39452->127.0.0.1:30955, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:31744") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:38608->127.0.0.1:31744, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: SetModTime is not supported 2026/10/03 01:24:04 DEBUG : index.json: Unchanged skipping 2026/10/03 01:24:04 DEBUG : sub/deep/index.json.e3a5b20c.partial: size = 275 OK 2026/10/03 01:24:04 DEBUG : sub/deep/index.json.e3a5b20c.partial: renamed to: sub/deep/index.json 2026/10/03 01:24:04 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:30001") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:43386->127.0.0.1:30001, err= 2026/10/03 01:24:04 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:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:31704") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:39970->127.0.0.1:31704, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:31164") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:53528->127.0.0.1:31164, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:30161") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:47950->127.0.0.1:30161, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:31910") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:47960->127.0.0.1:31910, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:30018") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:55758->127.0.0.1:30018, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:31721") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:36312->127.0.0.1:31721, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: dial("tcp","127.0.0.1:30802") 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: > dial: conn=127.0.0.1:52382->127.0.0.1:30802, err= 2026/10/03 01:24:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-xiyixey0xuzi: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 156.863262ms (try 2/5): exit status 1: Failed [TestIndexChangedDirTime]