"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 2/5) 2026/10/06 04:55:10 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-xacoviq4mopu" 2026/10/06 04:55:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/06 04:55:10 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/06 04:55:10 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/06 04:55:10 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/06 04:55:10 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/06 04:55:10 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/06 04:55:10 DEBUG : TestFTPRclone: detected overridden config - adding "{1fl9U}" suffix to name 2026/10/06 04:55:10 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/06 04:55:10 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/06 04:55:10 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/06 04:55:10 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: Connecting to FTP server 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:28622") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:54846->127.0.0.1:28622, err= 2026/10/06 04:55:10 DEBUG : Creating backend with remote "/tmp/rclone1808477412" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu", Local "Local file system at /tmp/rclone1808477412", Modify Window "876000h0m0s" 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:30114") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:52594->127.0.0.1:30114, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: SetModTime is not supported 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:31955") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:35604->127.0.0.1:31955, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: SetModTime is not supported 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:31610") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:32836->127.0.0.1:31610, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: SetModTime is not supported 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:30741") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:36862->127.0.0.1:30741, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: SetModTime is not supported 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:30935") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:36280->127.0.0.1:30935, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: Connecting to FTP server 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:28622") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:30722") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:54858->127.0.0.1:28622, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:58702->127.0.0.1:30722, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:31920") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:49744->127.0.0.1:31920, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:31801") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:35638->127.0.0.1:31801, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: Connecting to FTP server 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: Connecting to FTP server 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:30179") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:30247") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:47858->127.0.0.1:30247, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:56694->127.0.0.1:30179, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: SetModTime is not supported 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: SetModTime is not supported 2026/10/06 04:55:10 DEBUG : sub/index.json.ec4c6932.partial: size = 255 OK 2026/10/06 04:55:10 DEBUG : sub/deep/index.json.b6b9c86d.partial: size = 139 OK 2026/10/06 04:55:10 DEBUG : sub/deep/index.json.b6b9c86d.partial: renamed to: sub/deep/index.json 2026/10/06 04:55:10 DEBUG : sub/index.json.ec4c6932.partial: renamed to: sub/index.json 2026/10/06 04:55:10 INFO : sub/deep/index.json: Copied (new) 2026/10/06 04:55:10 INFO : sub/index.json: Copied (new) 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:28622") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:54864->127.0.0.1:28622, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:31977") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:47110->127.0.0.1:31977, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: SetModTime is not supported 2026/10/06 04:55:10 DEBUG : other/index.json.7bd74b5b.partial: size = 140 OK 2026/10/06 04:55:10 DEBUG : other/index.json.7bd74b5b.partial: renamed to: other/index.json 2026/10/06 04:55:10 INFO : other/index.json: Copied (new) 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:28622") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:54878->127.0.0.1:28622, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:31487") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:33332->127.0.0.1:31487, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: SetModTime is not supported 2026/10/06 04:55:10 DEBUG : index.json.6bcb89f4.partial: size = 371 OK 2026/10/06 04:55:10 DEBUG : index.json.6bcb89f4.partial: renamed to: index.json 2026/10/06 04:55:10 INFO : index.json: Copied (new) 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:30127") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:57990->127.0.0.1:30127, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: SetModTime is not supported 2026/10/06 04:55:10 INFO : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: Partial index: listing 3 directories and walking 0 changed directories 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:31682") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:44688->127.0.0.1:31682, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:31357") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:45030->127.0.0.1:31357, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:31384") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:47192->127.0.0.1:31384, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:30750") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:31638") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:43032->127.0.0.1:30750, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:60748->127.0.0.1:31638, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: SetModTime is not supported 2026/10/06 04:55:10 DEBUG : sub/index.json: Unchanged skipping 2026/10/06 04:55:10 DEBUG : sub/deep/index.json.f0b495e0.partial: size = 275 OK 2026/10/06 04:55:10 DEBUG : sub/deep/index.json.f0b495e0.partial: renamed to: sub/deep/index.json 2026/10/06 04:55:10 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:30250") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:48054->127.0.0.1:30250, err= 2026/10/06 04:55:10 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/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:30842") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:47074->127.0.0.1:30842, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:30360") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:53476->127.0.0.1:30360, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:31283") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:30405") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:57046->127.0.0.1:31283, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:57122->127.0.0.1:30405, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:31609") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:51392->127.0.0.1:31609, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:31792") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:44936->127.0.0.1:31792, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: dial("tcp","127.0.0.1:31438") 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: > dial: conn=127.0.0.1:36644->127.0.0.1:31438, err= 2026/10/06 04:55:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-xacoviq4mopu: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 141.984927ms (try 2/5): exit status 1: Failed [TestIndexChangedDirTime]