"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 2/5) 2026/10/01 02:20:06 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-levogit3sugo" 2026/10/01 02:20:06 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/01 02:20:06 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/01 02:20:06 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/01 02:20:06 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/01 02:20:06 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/01 02:20:06 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/01 02:20:06 DEBUG : TestFTPRclone: detected overridden config - adding "{mDlo2}" suffix to name 2026/10/01 02:20:06 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/01 02:20:06 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/01 02:20:06 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/01 02:20:06 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: Connecting to FTP server 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:28622") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:40008->127.0.0.1:28622, err= 2026/10/01 02:20:06 DEBUG : Creating backend with remote "/tmp/rclone3401985176" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-levogit3sugo", Local "Local file system at /tmp/rclone3401985176", Modify Window "876000h0m0s" 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:31402") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:42334->127.0.0.1:31402, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: SetModTime is not supported 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:31824") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:58186->127.0.0.1:31824, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: SetModTime is not supported 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:30147") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:57296->127.0.0.1:30147, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: SetModTime is not supported 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:31101") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:36836->127.0.0.1:31101, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: SetModTime is not supported 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:31494") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:56252->127.0.0.1:31494, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: Connecting to FTP server 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:28622") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:31288") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:40024->127.0.0.1:28622, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:48416->127.0.0.1:31288, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:30470") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:51816->127.0.0.1:30470, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:31026") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:49706->127.0.0.1:31026, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: Connecting to FTP server 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: Connecting to FTP server 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:31699") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:30209") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:41834->127.0.0.1:31699, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:57406->127.0.0.1:30209, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: SetModTime is not supported 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: SetModTime is not supported 2026/10/01 02:20:06 DEBUG : sub/deep/index.json.35466d82.partial: size = 139 OK 2026/10/01 02:20:06 DEBUG : index.json.36834b3d.partial: size = 371 OK 2026/10/01 02:20:06 DEBUG : sub/deep/index.json.35466d82.partial: renamed to: sub/deep/index.json 2026/10/01 02:20:06 INFO : sub/deep/index.json: Copied (new) 2026/10/01 02:20:06 DEBUG : index.json.36834b3d.partial: renamed to: index.json 2026/10/01 02:20:06 INFO : index.json: Copied (new) 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:28622") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:40036->127.0.0.1:28622, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:31135") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:37818->127.0.0.1:31135, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: SetModTime is not supported 2026/10/01 02:20:06 DEBUG : sub/index.json.f697cab3.partial: size = 255 OK 2026/10/01 02:20:06 DEBUG : sub/index.json.f697cab3.partial: renamed to: sub/index.json 2026/10/01 02:20:06 INFO : sub/index.json: Copied (new) 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:28622") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:40038->127.0.0.1:28622, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:31196") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:43870->127.0.0.1:31196, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: SetModTime is not supported 2026/10/01 02:20:06 DEBUG : other/index.json.8bdd9d0c.partial: size = 140 OK 2026/10/01 02:20:06 DEBUG : other/index.json.8bdd9d0c.partial: renamed to: other/index.json 2026/10/01 02:20:06 INFO : other/index.json: Copied (new) 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:30756") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:36874->127.0.0.1:30756, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: SetModTime is not supported 2026/10/01 02:20:06 INFO : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: Partial index: listing 3 directories and walking 0 changed directories 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:30232") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:39564->127.0.0.1:30232, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:30508") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:34894->127.0.0.1:30508, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:30750") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:60668->127.0.0.1:30750, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:30349") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:40420->127.0.0.1:30349, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:31154") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: SetModTime is not supported 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:46680->127.0.0.1:31154, err= 2026/10/01 02:20:06 DEBUG : sub/deep/index.json.a8c9f434.partial: size = 275 OK 2026/10/01 02:20:06 DEBUG : index.json: Unchanged skipping 2026/10/01 02:20:06 DEBUG : sub/deep/index.json.a8c9f434.partial: renamed to: sub/deep/index.json 2026/10/01 02:20:06 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:30023") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:35084->127.0.0.1:30023, err= 2026/10/01 02:20: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/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:31487") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:56970->127.0.0.1:31487, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:30152") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:53224->127.0.0.1:30152, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:31429") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:35022->127.0.0.1:31429, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:31846") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:45274->127.0.0.1:31846, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:30116") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:51518->127.0.0.1:30116, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:31329") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:34604->127.0.0.1:31329, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: dial("tcp","127.0.0.1:30792") 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: > dial: conn=127.0.0.1:36400->127.0.0.1:30792, err= 2026/10/01 02:20:06 DEBUG : ftp://127.0.0.1:28622/rclone-test-levogit3sugo: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 154.317582ms (try 2/5): exit status 1: Failed [TestIndexChangedDirTime]