"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 4/5) 2026/10/04 01:25:56 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-nasetum1wilu" 2026/10/04 01:25:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/04 01:25:56 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/04 01:25:56 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/04 01:25:56 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/04 01:25:56 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/04 01:25:56 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/04 01:25:56 DEBUG : TestFTPRclone: detected overridden config - adding "{N1stz}" suffix to name 2026/10/04 01:25:56 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/04 01:25:56 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/04 01:25:56 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/04 01:25:56 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: Connecting to FTP server 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:28622") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:60456->127.0.0.1:28622, err= 2026/10/04 01:25:56 DEBUG : Creating backend with remote "/tmp/rclone3978855569" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-nasetum1wilu", Local "Local file system at /tmp/rclone3978855569", Modify Window "876000h0m0s" 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:31235") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:54032->127.0.0.1:31235, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: SetModTime is not supported 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:30078") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:44664->127.0.0.1:30078, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: SetModTime is not supported 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:30673") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:33638->127.0.0.1:30673, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: SetModTime is not supported 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:30993") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:43420->127.0.0.1:30993, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: SetModTime is not supported 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:30355") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:59230->127.0.0.1:30355, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: Connecting to FTP server 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:28622") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:31244") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:60472->127.0.0.1:28622, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:38506->127.0.0.1:31244, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:31088") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:39624->127.0.0.1:31088, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:30843") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:35982->127.0.0.1:30843, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: Connecting to FTP server 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: Connecting to FTP server 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:30729") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:31299") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:41376->127.0.0.1:30729, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:41998->127.0.0.1:31299, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: SetModTime is not supported 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: SetModTime is not supported 2026/10/04 01:25:56 DEBUG : other/index.json.6c8a168d.partial: size = 140 OK 2026/10/04 01:25:56 DEBUG : sub/deep/index.json.46ea3035.partial: size = 139 OK 2026/10/04 01:25:56 DEBUG : other/index.json.6c8a168d.partial: renamed to: other/index.json 2026/10/04 01:25:56 INFO : other/index.json: Copied (new) 2026/10/04 01:25:56 DEBUG : sub/deep/index.json.46ea3035.partial: renamed to: sub/deep/index.json 2026/10/04 01:25:56 INFO : sub/deep/index.json: Copied (new) 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:28622") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:60486->127.0.0.1:28622, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:31284") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:32780->127.0.0.1:31284, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: SetModTime is not supported 2026/10/04 01:25:56 DEBUG : sub/index.json.b011a27f.partial: size = 255 OK 2026/10/04 01:25:56 DEBUG : sub/index.json.b011a27f.partial: renamed to: sub/index.json 2026/10/04 01:25:56 INFO : sub/index.json: Copied (new) 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:28622") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:60500->127.0.0.1:28622, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:30680") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:58732->127.0.0.1:30680, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: SetModTime is not supported 2026/10/04 01:25:56 DEBUG : index.json.1ca3c76e.partial: size = 371 OK 2026/10/04 01:25:56 DEBUG : index.json.1ca3c76e.partial: renamed to: index.json 2026/10/04 01:25:56 INFO : index.json: Copied (new) 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:30406") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:40910->127.0.0.1:30406, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: SetModTime is not supported 2026/10/04 01:25:56 INFO : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: Partial index: listing 3 directories and walking 0 changed directories 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:30767") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:51466->127.0.0.1:30767, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:31453") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:45382->127.0.0.1:31453, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:31883") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:53840->127.0.0.1:31883, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:30592") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:58584->127.0.0.1:30592, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:30365") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:32858->127.0.0.1:30365, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: SetModTime is not supported 2026/10/04 01:25:56 DEBUG : sub/deep/index.json.c7669e8e.partial: size = 275 OK 2026/10/04 01:25:56 DEBUG : index.json: Unchanged skipping 2026/10/04 01:25:56 DEBUG : sub/deep/index.json.c7669e8e.partial: renamed to: sub/deep/index.json 2026/10/04 01:25:56 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:31590") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:41292->127.0.0.1:31590, err= 2026/10/04 01:25:56 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/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:30424") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:45744->127.0.0.1:30424, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:31830") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:44692->127.0.0.1:31830, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:30678") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:40288->127.0.0.1:30678, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:31156") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:41604->127.0.0.1:31156, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:31902") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:36810->127.0.0.1:31902, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:31217") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:54802->127.0.0.1:31217, err= --- FAIL: TestIndexChangedDirTime (0.07s) FAIL 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: dial("tcp","127.0.0.1:30185") 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: > dial: conn=127.0.0.1:33640->127.0.0.1:30185, err= 2026/10/04 01:25:56 DEBUG : ftp://127.0.0.1:28622/rclone-test-nasetum1wilu: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 143.555813ms (try 4/5): exit status 1: Failed [TestIndexChangedDirTime]