"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 5/5) 2026/09/30 02:51:11 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-wugisak3wece" 2026/09/30 02:51:11 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/30 02:51:11 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/09/30 02:51:11 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/09/30 02:51:11 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/09/30 02:51:11 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/09/30 02:51:11 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/09/30 02:51:11 DEBUG : TestFTPRclone: detected overridden config - adding "{1JvEI}" suffix to name 2026/09/30 02:51:11 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/09/30 02:51:11 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/09/30 02:51:11 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/09/30 02:51:11 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: Connecting to FTP server 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:28622") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:33082->127.0.0.1:28622, err= 2026/09/30 02:51:11 DEBUG : Creating backend with remote "/tmp/rclone1637214677" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-wugisak3wece", Local "Local file system at /tmp/rclone1637214677", Modify Window "876000h0m0s" 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:30909") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:33178->127.0.0.1:30909, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: SetModTime is not supported 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:30388") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:33342->127.0.0.1:30388, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: SetModTime is not supported 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:31545") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:56792->127.0.0.1:31545, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: SetModTime is not supported 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:31205") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:36714->127.0.0.1:31205, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: SetModTime is not supported 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:31244") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:56938->127.0.0.1:31244, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: Connecting to FTP server 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:28622") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:31787") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:33096->127.0.0.1:28622, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:55240->127.0.0.1:31787, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:31072") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:54490->127.0.0.1:31072, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:30722") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:46018->127.0.0.1:30722, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: Connecting to FTP server 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: Connecting to FTP server 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:30478") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:30026") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:51066->127.0.0.1:30026, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:51084->127.0.0.1:30478, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: SetModTime is not supported 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: SetModTime is not supported 2026/09/30 02:51:11 DEBUG : other/index.json.c9087c1a.partial: size = 140 OK 2026/09/30 02:51:11 DEBUG : sub/deep/index.json.ce5fde6e.partial: size = 139 OK 2026/09/30 02:51:11 DEBUG : other/index.json.c9087c1a.partial: renamed to: other/index.json 2026/09/30 02:51:11 INFO : other/index.json: Copied (new) 2026/09/30 02:51:11 DEBUG : sub/deep/index.json.ce5fde6e.partial: renamed to: sub/deep/index.json 2026/09/30 02:51:11 INFO : sub/deep/index.json: Copied (new) 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:28622") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:33106->127.0.0.1:28622, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:30454") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:40986->127.0.0.1:30454, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: SetModTime is not supported 2026/09/30 02:51:11 DEBUG : sub/index.json.aa48fe45.partial: size = 255 OK 2026/09/30 02:51:11 DEBUG : sub/index.json.aa48fe45.partial: renamed to: sub/index.json 2026/09/30 02:51:11 INFO : sub/index.json: Copied (new) 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:28622") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:33114->127.0.0.1:28622, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:31219") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:58492->127.0.0.1:31219, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: SetModTime is not supported 2026/09/30 02:51:11 DEBUG : index.json.868c5988.partial: size = 371 OK 2026/09/30 02:51:11 DEBUG : index.json.868c5988.partial: renamed to: index.json 2026/09/30 02:51:11 INFO : index.json: Copied (new) 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:31054") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:34588->127.0.0.1:31054, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: SetModTime is not supported 2026/09/30 02:51:11 INFO : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: Partial index: listing 3 directories and walking 0 changed directories 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:30982") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:60882->127.0.0.1:30982, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:30654") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:39994->127.0.0.1:30654, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:30320") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:55868->127.0.0.1:30320, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:31009") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:60144->127.0.0.1:31009, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: SetModTime is not supported 2026/09/30 02:51:11 DEBUG : sub/deep/index.json.a199654f.partial: size = 275 OK 2026/09/30 02:51:11 DEBUG : sub/deep/index.json.a199654f.partial: renamed to: sub/deep/index.json 2026/09/30 02:51:11 INFO : sub/deep/index.json: Copied (replaced existing) 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:30337") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:33610->127.0.0.1:30337, err= 2026/09/30 02:51:11 DEBUG : index.json: Unchanged skipping 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:30342") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:43586->127.0.0.1:30342, err= 2026/09/30 02:51:11 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/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:30674") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:58458->127.0.0.1:30674, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:30472") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:39238->127.0.0.1:30472, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:30902") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:51006->127.0.0.1:30902, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:31275") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:51104->127.0.0.1:31275, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:30081") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:50372->127.0.0.1:30081, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:31790") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:59898->127.0.0.1:31790, err= --- FAIL: TestIndexChangedDirTime (0.07s) FAIL 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: dial("tcp","127.0.0.1:31973") 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: > dial: conn=127.0.0.1:41196->127.0.0.1:31973, err= 2026/09/30 02:51:11 DEBUG : ftp://127.0.0.1:28622/rclone-test-wugisak3wece: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 129.710275ms (try 5/5): exit status 1: Failed [TestIndexChangedDirTime]