"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 3/5) 2026/10/04 01:25:54 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-xigayir5mibe" 2026/10/04 01:25:54 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/04 01:25:54 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/04 01:25:54 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/04 01:25:54 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/04 01:25:54 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/04 01:25:54 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/04 01:25:54 DEBUG : TestFTPRclone: detected overridden config - adding "{2be20}" suffix to name 2026/10/04 01:25:54 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/04 01:25:54 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/04 01:25:54 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/04 01:25:54 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: Connecting to FTP server 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:28622") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:60406->127.0.0.1:28622, err= 2026/10/04 01:25:54 DEBUG : Creating backend with remote "/tmp/rclone124338755" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-xigayir5mibe", Local "Local file system at /tmp/rclone124338755", Modify Window "876000h0m0s" 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30604") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:35828->127.0.0.1:30604, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: SetModTime is not supported 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30629") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:36688->127.0.0.1:30629, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: SetModTime is not supported 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30892") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:49322->127.0.0.1:30892, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: SetModTime is not supported 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30786") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:37190->127.0.0.1:30786, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: SetModTime is not supported 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:31278") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:42014->127.0.0.1:31278, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: Connecting to FTP server 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:28622") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30220") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:60412->127.0.0.1:28622, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:35942->127.0.0.1:30220, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:31925") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:39216->127.0.0.1:31925, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30544") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:41288->127.0.0.1:30544, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: Connecting to FTP server 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: Connecting to FTP server 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30976") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:57554->127.0.0.1:30976, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30750") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:59722->127.0.0.1:30750, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: SetModTime is not supported 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: SetModTime is not supported 2026/10/04 01:25:54 DEBUG : index.json.12b97b6b.partial: size = 371 OK 2026/10/04 01:25:54 DEBUG : sub/deep/index.json.f7416f4d.partial: size = 139 OK 2026/10/04 01:25:54 DEBUG : index.json.12b97b6b.partial: renamed to: index.json 2026/10/04 01:25:54 INFO : index.json: Copied (new) 2026/10/04 01:25:54 DEBUG : sub/deep/index.json.f7416f4d.partial: renamed to: sub/deep/index.json 2026/10/04 01:25:54 INFO : sub/deep/index.json: Copied (new) 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:28622") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:60416->127.0.0.1:28622, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30500") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:53754->127.0.0.1:30500, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: SetModTime is not supported 2026/10/04 01:25:54 DEBUG : sub/index.json.da25b05e.partial: size = 255 OK 2026/10/04 01:25:54 DEBUG : sub/index.json.da25b05e.partial: renamed to: sub/index.json 2026/10/04 01:25:54 INFO : sub/index.json: Copied (new) 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:28622") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:60430->127.0.0.1:28622, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:31990") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:43726->127.0.0.1:31990, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: SetModTime is not supported 2026/10/04 01:25:54 DEBUG : other/index.json.d5df5a32.partial: size = 140 OK 2026/10/04 01:25:54 DEBUG : other/index.json.d5df5a32.partial: renamed to: other/index.json 2026/10/04 01:25:54 INFO : other/index.json: Copied (new) 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:31993") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:47362->127.0.0.1:31993, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: SetModTime is not supported 2026/10/04 01:25:54 INFO : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: Partial index: listing 3 directories and walking 0 changed directories 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30676") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:53468->127.0.0.1:30676, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30704") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:53524->127.0.0.1:30704, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:31014") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:57908->127.0.0.1:31014, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:31915") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:37666->127.0.0.1:31915, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:31461") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:34662->127.0.0.1:31461, err= 2026/10/04 01:25:54 DEBUG : sub/index.json: Unchanged skipping 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: SetModTime is not supported 2026/10/04 01:25:54 DEBUG : sub/deep/index.json.2e4a99ca.partial: size = 275 OK 2026/10/04 01:25:54 DEBUG : sub/deep/index.json.2e4a99ca.partial: renamed to: sub/deep/index.json 2026/10/04 01:25:54 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:31947") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:49144->127.0.0.1:31947, err= 2026/10/04 01:25:54 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/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30347") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:45206->127.0.0.1:30347, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30300") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:59266->127.0.0.1:30300, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30806") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:31633") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:40420->127.0.0.1:31633, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:51212->127.0.0.1:30806, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30202") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:49344->127.0.0.1:30202, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30723") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:47910->127.0.0.1:30723, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: dial("tcp","127.0.0.1:30514") 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: > dial: conn=127.0.0.1:39454->127.0.0.1:30514, err= 2026/10/04 01:25:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-xigayir5mibe: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 165.739595ms (try 3/5): exit status 1: Failed [TestIndexChangedDirTime]