"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 5/5) 2026/10/04 01:25:57 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-hareqam1boko" 2026/10/04 01:25:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/04 01:25:57 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/04 01:25:57 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/04 01:25:57 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/04 01:25:57 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/04 01:25:57 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/04 01:25:57 DEBUG : TestFTPRclone: detected overridden config - adding "{_4N1v}" suffix to name 2026/10/04 01:25:57 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/04 01:25:57 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/04 01:25:57 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/04 01:25:57 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: Connecting to FTP server 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:28622") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:60520->127.0.0.1:28622, err= 2026/10/04 01:25:57 DEBUG : Creating backend with remote "/tmp/rclone1154150486" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-hareqam1boko", Local "Local file system at /tmp/rclone1154150486", Modify Window "876000h0m0s" 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31094") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:38688->127.0.0.1:31094, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: SetModTime is not supported 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31355") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:40822->127.0.0.1:31355, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: SetModTime is not supported 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31805") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:42724->127.0.0.1:31805, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: SetModTime is not supported 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31119") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:59996->127.0.0.1:31119, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: SetModTime is not supported 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:30207") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:42690->127.0.0.1:30207, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: Connecting to FTP server 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:28622") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31891") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:60532->127.0.0.1:28622, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:34114->127.0.0.1:31891, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:30502") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:43682->127.0.0.1:30502, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31660") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:42216->127.0.0.1:31660, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: Connecting to FTP server 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: Connecting to FTP server 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31210") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:42926->127.0.0.1:31210, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:30121") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:43202->127.0.0.1:30121, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: SetModTime is not supported 2026/10/04 01:25:57 DEBUG : index.json.adc361ec.partial: size = 371 OK 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: SetModTime is not supported 2026/10/04 01:25:57 DEBUG : sub/index.json.5ce79445.partial: size = 255 OK 2026/10/04 01:25:57 DEBUG : index.json.adc361ec.partial: renamed to: index.json 2026/10/04 01:25:57 INFO : index.json: Copied (new) 2026/10/04 01:25:57 DEBUG : sub/index.json.5ce79445.partial: renamed to: sub/index.json 2026/10/04 01:25:57 INFO : sub/index.json: Copied (new) 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:28622") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:60542->127.0.0.1:28622, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31631") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:34510->127.0.0.1:31631, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: SetModTime is not supported 2026/10/04 01:25:57 DEBUG : sub/deep/index.json.181d7688.partial: size = 139 OK 2026/10/04 01:25:57 DEBUG : sub/deep/index.json.181d7688.partial: renamed to: sub/deep/index.json 2026/10/04 01:25:57 INFO : sub/deep/index.json: Copied (new) 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:28622") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:60546->127.0.0.1:28622, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:30040") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:48886->127.0.0.1:30040, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: SetModTime is not supported 2026/10/04 01:25:57 DEBUG : other/index.json.ad040816.partial: size = 140 OK 2026/10/04 01:25:57 DEBUG : other/index.json.ad040816.partial: renamed to: other/index.json 2026/10/04 01:25:57 INFO : other/index.json: Copied (new) 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31501") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:53054->127.0.0.1:31501, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: SetModTime is not supported 2026/10/04 01:25:57 INFO : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: Partial index: listing 3 directories and walking 0 changed directories 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:30060") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:55944->127.0.0.1:30060, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31229") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:57582->127.0.0.1:31229, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:30494") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:39932->127.0.0.1:30494, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:30930") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:60184->127.0.0.1:30930, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31326") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:53878->127.0.0.1:31326, err= 2026/10/04 01:25:57 DEBUG : index.json: Unchanged skipping 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: SetModTime is not supported 2026/10/04 01:25:57 DEBUG : sub/deep/index.json.15d1f2dc.partial: size = 275 OK 2026/10/04 01:25:57 DEBUG : sub/deep/index.json.15d1f2dc.partial: renamed to: sub/deep/index.json 2026/10/04 01:25:57 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31631") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:34522->127.0.0.1:31631, err= 2026/10/04 01:25:57 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:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:30067") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:38306->127.0.0.1:30067, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31936") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:53950->127.0.0.1:31936, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31911") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:44726->127.0.0.1:31911, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:30904") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:45022->127.0.0.1:30904, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31700") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:54352->127.0.0.1:31700, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31906") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:50894->127.0.0.1:31906, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: dial("tcp","127.0.0.1:31120") 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: > dial: conn=127.0.0.1:43938->127.0.0.1:31120, err= 2026/10/04 01:25:57 DEBUG : ftp://127.0.0.1:28622/rclone-test-hareqam1boko: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 182.878119ms (try 5/5): exit status 1: Failed [TestIndexChangedDirTime]