"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 4/5) 2026/10/05 03:06:06 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-tonuzud9depa" 2026/10/05 03:06:06 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/05 03:06:06 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/05 03:06:06 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/05 03:06:06 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/05 03:06:06 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/05 03:06:06 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/05 03:06:06 DEBUG : TestFTPRclone: detected overridden config - adding "{EC7lr}" suffix to name 2026/10/05 03:06:06 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/05 03:06:06 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/05 03:06:06 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/05 03:06:06 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: Connecting to FTP server 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:28622") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:44166->127.0.0.1:28622, err= 2026/10/05 03:06:06 DEBUG : Creating backend with remote "/tmp/rclone3883580376" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-tonuzud9depa", Local "Local file system at /tmp/rclone3883580376", Modify Window "876000h0m0s" 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31928") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:58974->127.0.0.1:31928, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: SetModTime is not supported 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:30320") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:44892->127.0.0.1:30320, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: SetModTime is not supported 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31756") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:45344->127.0.0.1:31756, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: SetModTime is not supported 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:30151") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:40162->127.0.0.1:30151, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: SetModTime is not supported 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:30702") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:58392->127.0.0.1:30702, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: Connecting to FTP server 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:28622") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:30742") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:44174->127.0.0.1:28622, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:35800->127.0.0.1:30742, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31348") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:41084->127.0.0.1:31348, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31680") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:45238->127.0.0.1:31680, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: Connecting to FTP server 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: Connecting to FTP server 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31980") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:30326") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:58912->127.0.0.1:31980, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:38202->127.0.0.1:30326, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: SetModTime is not supported 2026/10/05 03:06:06 DEBUG : sub/index.json.87f7b54d.partial: size = 255 OK 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: SetModTime is not supported 2026/10/05 03:06:06 DEBUG : other/index.json.de5d4db1.partial: size = 140 OK 2026/10/05 03:06:06 DEBUG : sub/index.json.87f7b54d.partial: renamed to: sub/index.json 2026/10/05 03:06:06 INFO : sub/index.json: Copied (new) 2026/10/05 03:06:06 DEBUG : other/index.json.de5d4db1.partial: renamed to: other/index.json 2026/10/05 03:06:06 INFO : other/index.json: Copied (new) 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:28622") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:44182->127.0.0.1:28622, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31881") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:32958->127.0.0.1:31881, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: SetModTime is not supported 2026/10/05 03:06:06 DEBUG : sub/deep/index.json.8941a744.partial: size = 139 OK 2026/10/05 03:06:06 DEBUG : sub/deep/index.json.8941a744.partial: renamed to: sub/deep/index.json 2026/10/05 03:06:06 INFO : sub/deep/index.json: Copied (new) 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:28622") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:44194->127.0.0.1:28622, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31182") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:58754->127.0.0.1:31182, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: SetModTime is not supported 2026/10/05 03:06:06 DEBUG : index.json.a50cfcf2.partial: size = 371 OK 2026/10/05 03:06:06 DEBUG : index.json.a50cfcf2.partial: renamed to: index.json 2026/10/05 03:06:06 INFO : index.json: Copied (new) 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31983") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:47180->127.0.0.1:31983, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: SetModTime is not supported 2026/10/05 03:06:06 INFO : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: Partial index: listing 3 directories and walking 0 changed directories 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:30083") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:46862->127.0.0.1:30083, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31170") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:45640->127.0.0.1:31170, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31800") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:33562->127.0.0.1:31800, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31263") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:49876->127.0.0.1:31263, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:30226") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:45490->127.0.0.1:30226, err= 2026/10/05 03:06:06 DEBUG : index.json: Unchanged skipping 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: SetModTime is not supported 2026/10/05 03:06:06 DEBUG : sub/deep/index.json.a368a362.partial: size = 275 OK 2026/10/05 03:06:06 DEBUG : sub/deep/index.json.a368a362.partial: renamed to: sub/deep/index.json 2026/10/05 03:06:06 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31239") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:50304->127.0.0.1:31239, err= 2026/10/05 03:06:06 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/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31569") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:48370->127.0.0.1:31569, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31754") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:54028->127.0.0.1:31754, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31712") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:30707") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:46166->127.0.0.1:31712, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:57680->127.0.0.1:30707, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31741") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:36346->127.0.0.1:31741, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:30150") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:44170->127.0.0.1:30150, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: dial("tcp","127.0.0.1:31893") 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: > dial: conn=127.0.0.1:53462->127.0.0.1:31893, err= 2026/10/05 03:06:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-tonuzud9depa: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 161.849537ms (try 4/5): exit status 1: Failed [TestIndexChangedDirTime]