"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 3/5) 2026/10/05 03:06:05 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-vuvasat7bove" 2026/10/05 03:06:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/05 03:06:05 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/05 03:06:05 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/05 03:06:05 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/05 03:06:05 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/05 03:06:05 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/05 03:06:05 DEBUG : TestFTPRclone: detected overridden config - adding "{rB_c-}" suffix to name 2026/10/05 03:06:05 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/05 03:06:05 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/05 03:06:05 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/05 03:06:05 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: Connecting to FTP server 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:28622") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:44136->127.0.0.1:28622, err= 2026/10/05 03:06:05 DEBUG : Creating backend with remote "/tmp/rclone2630805925" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-vuvasat7bove", Local "Local file system at /tmp/rclone2630805925", Modify Window "876000h0m0s" 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:30522") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:49158->127.0.0.1:30522, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: SetModTime is not supported 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:31910") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:33126->127.0.0.1:31910, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: SetModTime is not supported 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:30687") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:54672->127.0.0.1:30687, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: SetModTime is not supported 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:31363") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:39626->127.0.0.1:31363, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: SetModTime is not supported 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:30922") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:58664->127.0.0.1:30922, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: Connecting to FTP server 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:28622") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:31128") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:44138->127.0.0.1:28622, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:57858->127.0.0.1:31128, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:30961") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:44152->127.0.0.1:30961, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:31918") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:37930->127.0.0.1:31918, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: Connecting to FTP server 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: Connecting to FTP server 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:30111") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:30867") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:44076->127.0.0.1:30867, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:46078->127.0.0.1:30111, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: SetModTime is not supported 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: SetModTime is not supported 2026/10/05 03:06:05 DEBUG : other/index.json.1b84e5f4.partial: size = 140 OK 2026/10/05 03:06:05 DEBUG : sub/index.json.32add506.partial: size = 255 OK 2026/10/05 03:06:05 DEBUG : sub/index.json.32add506.partial: renamed to: sub/index.json 2026/10/05 03:06:05 INFO : sub/index.json: Copied (new) 2026/10/05 03:06:05 DEBUG : other/index.json.1b84e5f4.partial: renamed to: other/index.json 2026/10/05 03:06:05 INFO : other/index.json: Copied (new) 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:28622") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:44144->127.0.0.1:28622, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:31122") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:58686->127.0.0.1:31122, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: SetModTime is not supported 2026/10/05 03:06:05 DEBUG : sub/deep/index.json.fb32ffcc.partial: size = 139 OK 2026/10/05 03:06:05 DEBUG : sub/deep/index.json.fb32ffcc.partial: renamed to: sub/deep/index.json 2026/10/05 03:06:05 INFO : sub/deep/index.json: Copied (new) 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:28622") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:44152->127.0.0.1:28622, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:30957") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:40990->127.0.0.1:30957, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: SetModTime is not supported 2026/10/05 03:06:05 DEBUG : index.json.6f2a9903.partial: size = 371 OK 2026/10/05 03:06:05 DEBUG : index.json.6f2a9903.partial: renamed to: index.json 2026/10/05 03:06:05 INFO : index.json: Copied (new) 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:31443") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:34584->127.0.0.1:31443, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: SetModTime is not supported 2026/10/05 03:06:05 INFO : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: Partial index: listing 3 directories and walking 0 changed directories 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:31562") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:57138->127.0.0.1:31562, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:30662") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:56646->127.0.0.1:30662, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:30261") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:41926->127.0.0.1:30261, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:31561") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:45272->127.0.0.1:31561, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:30157") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:43226->127.0.0.1:30157, err= 2026/10/05 03:06:05 DEBUG : sub/index.json: Unchanged skipping 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: SetModTime is not supported 2026/10/05 03:06:05 DEBUG : sub/deep/index.json.21bc98f9.partial: size = 275 OK 2026/10/05 03:06:05 DEBUG : sub/deep/index.json.21bc98f9.partial: renamed to: sub/deep/index.json 2026/10/05 03:06:05 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:31687") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:37016->127.0.0.1:31687, err= 2026/10/05 03:06:05 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/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:31780") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:49922->127.0.0.1:31780, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:31446") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:59748->127.0.0.1:31446, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:31920") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:30458") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:46038->127.0.0.1:31920, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:49666->127.0.0.1:30458, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:31475") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:46488->127.0.0.1:31475, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:31218") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:48950->127.0.0.1:31218, err= --- FAIL: TestIndexChangedDirTime (0.09s) FAIL 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: dial("tcp","127.0.0.1:30149") 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: > dial: conn=127.0.0.1:39068->127.0.0.1:30149, err= 2026/10/05 03:06:05 DEBUG : ftp://127.0.0.1:28622/rclone-test-vuvasat7bove: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 160.058952ms (try 3/5): exit status 1: Failed [TestIndexChangedDirTime]