"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 5/5) 2026/10/01 02:20:10 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-luzedum5cohu" 2026/10/01 02:20:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/01 02:20:10 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/01 02:20:10 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/01 02:20:10 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/01 02:20:10 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/01 02:20:10 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/01 02:20:10 DEBUG : TestFTPRclone: detected overridden config - adding "{JnBZ6}" suffix to name 2026/10/01 02:20:10 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/01 02:20:10 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/01 02:20:10 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/01 02:20:10 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: Connecting to FTP server 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:28622") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:40164->127.0.0.1:28622, err= 2026/10/01 02:20:10 DEBUG : Creating backend with remote "/tmp/rclone4084136358" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-luzedum5cohu", Local "Local file system at /tmp/rclone4084136358", Modify Window "876000h0m0s" 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:30757") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:45786->127.0.0.1:30757, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: SetModTime is not supported 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:30352") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:54058->127.0.0.1:30352, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: SetModTime is not supported 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:31547") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:43592->127.0.0.1:31547, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: SetModTime is not supported 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:31025") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:59370->127.0.0.1:31025, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: SetModTime is not supported 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:30974") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:41294->127.0.0.1:30974, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: Connecting to FTP server 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:28622") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:40170->127.0.0.1:28622, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:31865") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:60840->127.0.0.1:31865, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:31990") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:30695") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:57006->127.0.0.1:31990, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:44370->127.0.0.1:30695, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: Connecting to FTP server 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: Connecting to FTP server 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:31548") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:31668") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:36074->127.0.0.1:31668, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:37580->127.0.0.1:31548, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: SetModTime is not supported 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: SetModTime is not supported 2026/10/01 02:20:10 DEBUG : sub/index.json.f8670945.partial: size = 255 OK 2026/10/01 02:20:10 DEBUG : sub/deep/index.json.26c38c0f.partial: size = 139 OK 2026/10/01 02:20:10 DEBUG : sub/deep/index.json.26c38c0f.partial: renamed to: sub/deep/index.json 2026/10/01 02:20:10 INFO : sub/deep/index.json: Copied (new) 2026/10/01 02:20:10 DEBUG : sub/index.json.f8670945.partial: renamed to: sub/index.json 2026/10/01 02:20:10 INFO : sub/index.json: Copied (new) 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:28622") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:40176->127.0.0.1:28622, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:30277") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:41502->127.0.0.1:30277, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: SetModTime is not supported 2026/10/01 02:20:10 DEBUG : other/index.json.d84e263e.partial: size = 140 OK 2026/10/01 02:20:10 DEBUG : other/index.json.d84e263e.partial: renamed to: other/index.json 2026/10/01 02:20:10 INFO : other/index.json: Copied (new) 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:28622") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:40190->127.0.0.1:28622, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:30337") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:58614->127.0.0.1:30337, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: SetModTime is not supported 2026/10/01 02:20:10 DEBUG : index.json.25910ace.partial: size = 371 OK 2026/10/01 02:20:10 DEBUG : index.json.25910ace.partial: renamed to: index.json 2026/10/01 02:20:10 INFO : index.json: Copied (new) 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:31615") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:59664->127.0.0.1:31615, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: SetModTime is not supported 2026/10/01 02:20:10 INFO : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: Partial index: listing 3 directories and walking 0 changed directories 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:30999") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:41862->127.0.0.1:30999, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:30029") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:57788->127.0.0.1:30029, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:30987") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:37788->127.0.0.1:30987, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:30894") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:56650->127.0.0.1:30894, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:30848") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:42748->127.0.0.1:30848, err= 2026/10/01 02:20:10 DEBUG : sub/index.json: Unchanged skipping 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: SetModTime is not supported 2026/10/01 02:20:10 DEBUG : sub/deep/index.json.524d201e.partial: size = 275 OK 2026/10/01 02:20:10 DEBUG : sub/deep/index.json.524d201e.partial: renamed to: sub/deep/index.json 2026/10/01 02:20:10 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:31357") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:55410->127.0.0.1:31357, err= 2026/10/01 02:20:10 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/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:30802") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:57100->127.0.0.1:30802, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:31810") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:42428->127.0.0.1:31810, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:31478") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:34060->127.0.0.1:31478, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:31183") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:44684->127.0.0.1:31183, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:30837") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:60510->127.0.0.1:30837, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:31846") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:45282->127.0.0.1:31846, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: dial("tcp","127.0.0.1:31862") 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: > dial: conn=127.0.0.1:58064->127.0.0.1:31862, err= 2026/10/01 02:20:10 DEBUG : ftp://127.0.0.1:28622/rclone-test-luzedum5cohu: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 150.701327ms (try 5/5): exit status 1: Failed [TestIndexChangedDirTime]