"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 2/5) 2026/10/05 03:06:03 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-vowibob9zike" 2026/10/05 03:06:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/05 03:06:03 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/05 03:06:03 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/05 03:06:03 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/05 03:06:03 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/05 03:06:03 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/05 03:06:03 DEBUG : TestFTPRclone: detected overridden config - adding "{faKKL}" suffix to name 2026/10/05 03:06:03 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/05 03:06:03 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/05 03:06:03 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/05 03:06:03 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/05 03:06:03 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: Connecting to FTP server 2026/10/05 03:06:03 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:28622") 2026/10/05 03:06:03 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:44088->127.0.0.1:28622, err= 2026/10/05 03:06:03 DEBUG : Creating backend with remote "/tmp/rclone1987831189" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-vowibob9zike", Local "Local file system at /tmp/rclone1987831189", Modify Window "876000h0m0s" 2026/10/05 03:06:03 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:31685") 2026/10/05 03:06:03 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:35216->127.0.0.1:31685, err= 2026/10/05 03:06:03 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: SetModTime is not supported 2026/10/05 03:06:03 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:31500") 2026/10/05 03:06:03 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:47228->127.0.0.1:31500, err= 2026/10/05 03:06:03 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: SetModTime is not supported 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:31872") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:50396->127.0.0.1:31872, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: SetModTime is not supported 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:30109") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:36964->127.0.0.1:30109, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: SetModTime is not supported 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:31036") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:34264->127.0.0.1:31036, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: Connecting to FTP server 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:28622") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:44100->127.0.0.1:28622, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:30921") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:57714->127.0.0.1:30921, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:30454") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:50174->127.0.0.1:30454, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:31867") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:50336->127.0.0.1:31867, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: Connecting to FTP server 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: Connecting to FTP server 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:30409") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:31816") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:38502->127.0.0.1:30409, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:49160->127.0.0.1:31816, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: SetModTime is not supported 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: SetModTime is not supported 2026/10/05 03:06:04 DEBUG : other/index.json.8dc9c19e.partial: size = 140 OK 2026/10/05 03:06:04 DEBUG : sub/deep/index.json.f0d48213.partial: size = 139 OK 2026/10/05 03:06:04 DEBUG : other/index.json.8dc9c19e.partial: renamed to: other/index.json 2026/10/05 03:06:04 INFO : other/index.json: Copied (new) 2026/10/05 03:06:04 DEBUG : sub/deep/index.json.f0d48213.partial: renamed to: sub/deep/index.json 2026/10/05 03:06:04 INFO : sub/deep/index.json: Copied (new) 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:28622") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:44116->127.0.0.1:28622, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:30845") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:42614->127.0.0.1:30845, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: SetModTime is not supported 2026/10/05 03:06:04 DEBUG : index.json.68b09b4d.partial: size = 371 OK 2026/10/05 03:06:04 DEBUG : index.json.68b09b4d.partial: renamed to: index.json 2026/10/05 03:06:04 INFO : index.json: Copied (new) 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:28622") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:44118->127.0.0.1:28622, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:30979") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:43822->127.0.0.1:30979, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: SetModTime is not supported 2026/10/05 03:06:04 DEBUG : sub/index.json.a04e2ba9.partial: size = 255 OK 2026/10/05 03:06:04 DEBUG : sub/index.json.a04e2ba9.partial: renamed to: sub/index.json 2026/10/05 03:06:04 INFO : sub/index.json: Copied (new) 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:31602") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:55092->127.0.0.1:31602, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: SetModTime is not supported 2026/10/05 03:06:04 INFO : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: Partial index: listing 3 directories and walking 0 changed directories 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:31731") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:42664->127.0.0.1:31731, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:30378") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:41352->127.0.0.1:30378, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:30720") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:39558->127.0.0.1:30720, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:30724") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:45930->127.0.0.1:30724, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:30307") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:45638->127.0.0.1:30307, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: SetModTime is not supported 2026/10/05 03:06:04 DEBUG : index.json: Unchanged skipping 2026/10/05 03:06:04 DEBUG : sub/deep/index.json.c0b899bc.partial: size = 275 OK 2026/10/05 03:06:04 DEBUG : sub/deep/index.json.c0b899bc.partial: renamed to: sub/deep/index.json 2026/10/05 03:06:04 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:31593") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:38000->127.0.0.1:31593, err= 2026/10/05 03:06:04 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:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:30011") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:37040->127.0.0.1:30011, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:31737") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:46674->127.0.0.1:31737, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:30947") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:33554->127.0.0.1:30947, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:30789") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:40316->127.0.0.1:30789, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:31688") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:43678->127.0.0.1:31688, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:30565") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:37028->127.0.0.1:30565, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: dial("tcp","127.0.0.1:31260") 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: > dial: conn=127.0.0.1:59702->127.0.0.1:31260, err= 2026/10/05 03:06:04 DEBUG : ftp://127.0.0.1:28622/rclone-test-vowibob9zike: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 154.477471ms (try 2/5): exit status 1: Failed [TestIndexChangedDirTime]