"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 4/5) 2026/10/02 03:30:57 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-guqukep2tewa" 2026/10/02 03:30:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/02 03:30:57 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/02 03:30:57 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/02 03:30:57 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/02 03:30:57 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/02 03:30:57 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/02 03:30:57 DEBUG : TestFTPRclone: detected overridden config - adding "{ZXPZQ}" suffix to name 2026/10/02 03:30:57 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/02 03:30:57 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/02 03:30:57 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/02 03:30:57 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: Connecting to FTP server 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:28622") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:56068->127.0.0.1:28622, err= 2026/10/02 03:30:57 DEBUG : Creating backend with remote "/tmp/rclone3237841757" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-guqukep2tewa", Local "Local file system at /tmp/rclone3237841757", Modify Window "876000h0m0s" 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:31023") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:46404->127.0.0.1:31023, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: SetModTime is not supported 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:30868") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:50080->127.0.0.1:30868, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: SetModTime is not supported 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:31727") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:48352->127.0.0.1:31727, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: SetModTime is not supported 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:30024") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:56676->127.0.0.1:30024, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: SetModTime is not supported 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:31339") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:53226->127.0.0.1:31339, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: Connecting to FTP server 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:28622") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:56084->127.0.0.1:28622, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:30219") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:60420->127.0.0.1:30219, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:30978") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:37516->127.0.0.1:30978, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:30074") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:49870->127.0.0.1:30074, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: Connecting to FTP server 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: Connecting to FTP server 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:31137") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:31372") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:53550->127.0.0.1:31137, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:35008->127.0.0.1:31372, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: SetModTime is not supported 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: SetModTime is not supported 2026/10/02 03:30:57 DEBUG : sub/deep/index.json.d305bfe3.partial: size = 139 OK 2026/10/02 03:30:57 DEBUG : sub/index.json.98a3d431.partial: size = 255 OK 2026/10/02 03:30:57 DEBUG : sub/deep/index.json.d305bfe3.partial: renamed to: sub/deep/index.json 2026/10/02 03:30:57 DEBUG : sub/index.json.98a3d431.partial: renamed to: sub/index.json 2026/10/02 03:30:57 INFO : sub/index.json: Copied (new) 2026/10/02 03:30:57 INFO : sub/deep/index.json: Copied (new) 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:28622") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:56094->127.0.0.1:28622, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:31056") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:60648->127.0.0.1:31056, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: SetModTime is not supported 2026/10/02 03:30:57 DEBUG : index.json.21899389.partial: size = 371 OK 2026/10/02 03:30:57 DEBUG : index.json.21899389.partial: renamed to: index.json 2026/10/02 03:30:57 INFO : index.json: Copied (new) 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:28622") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:56106->127.0.0.1:28622, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:31502") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:43934->127.0.0.1:31502, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: SetModTime is not supported 2026/10/02 03:30:57 DEBUG : other/index.json.2c451d0a.partial: size = 140 OK 2026/10/02 03:30:57 DEBUG : other/index.json.2c451d0a.partial: renamed to: other/index.json 2026/10/02 03:30:57 INFO : other/index.json: Copied (new) 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:30824") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:57716->127.0.0.1:30824, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: SetModTime is not supported 2026/10/02 03:30:57 INFO : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: Partial index: listing 3 directories and walking 0 changed directories 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:30900") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:36420->127.0.0.1:30900, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:31612") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:52278->127.0.0.1:31612, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:30869") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:44414->127.0.0.1:30869, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:31517") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:52064->127.0.0.1:31517, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:31833") 2026/10/02 03:30:57 DEBUG : index.json: Unchanged skipping 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:58914->127.0.0.1:31833, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: SetModTime is not supported 2026/10/02 03:30:57 DEBUG : sub/deep/index.json.b8dd4fb9.partial: size = 275 OK 2026/10/02 03:30:57 DEBUG : sub/deep/index.json.b8dd4fb9.partial: renamed to: sub/deep/index.json 2026/10/02 03:30:57 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:31350") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:35834->127.0.0.1:31350, err= 2026/10/02 03:30:57 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/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:31565") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:60978->127.0.0.1:31565, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:31654") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:41310->127.0.0.1:31654, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:31563") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:53342->127.0.0.1:31563, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:30359") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:41572->127.0.0.1:30359, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:30729") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:39100->127.0.0.1:30729, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:30909") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:42496->127.0.0.1:30909, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: dial("tcp","127.0.0.1:31156") 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: > dial: conn=127.0.0.1:51160->127.0.0.1:31156, err= 2026/10/02 03:30:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-guqukep2tewa: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 156.583597ms (try 4/5): exit status 1: Failed [TestIndexChangedDirTime]