"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 3/5) 2026/09/30 02:51:09 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-qolefod4wetu" 2026/09/30 02:51:09 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/30 02:51:09 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/09/30 02:51:09 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/09/30 02:51:09 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/09/30 02:51:09 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/09/30 02:51:09 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/09/30 02:51:09 DEBUG : TestFTPRclone: detected overridden config - adding "{iOrhT}" suffix to name 2026/09/30 02:51:09 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/09/30 02:51:09 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/09/30 02:51:09 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/09/30 02:51:09 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: Connecting to FTP server 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:28622") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:55268->127.0.0.1:28622, err= 2026/09/30 02:51:09 DEBUG : Creating backend with remote "/tmp/rclone529405478" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-qolefod4wetu", Local "Local file system at /tmp/rclone529405478", Modify Window "876000h0m0s" 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:30550") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:59224->127.0.0.1:30550, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: SetModTime is not supported 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:31188") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:33532->127.0.0.1:31188, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: SetModTime is not supported 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:31232") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:58300->127.0.0.1:31232, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: SetModTime is not supported 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:30677") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:54652->127.0.0.1:30677, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: SetModTime is not supported 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:31131") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:52976->127.0.0.1:31131, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: Connecting to FTP server 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:28622") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:55276->127.0.0.1:28622, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:30788") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:36456->127.0.0.1:30788, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:30345") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:54732->127.0.0.1:30345, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:30423") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:53022->127.0.0.1:30423, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: Connecting to FTP server 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: Connecting to FTP server 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:31919") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:42882->127.0.0.1:31919, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:30691") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:45752->127.0.0.1:30691, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: SetModTime is not supported 2026/09/30 02:51:09 DEBUG : sub/deep/index.json.0b37928f.partial: size = 139 OK 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: SetModTime is not supported 2026/09/30 02:51:09 DEBUG : other/index.json.5154f3c5.partial: size = 140 OK 2026/09/30 02:51:09 DEBUG : sub/deep/index.json.0b37928f.partial: renamed to: sub/deep/index.json 2026/09/30 02:51:09 INFO : sub/deep/index.json: Copied (new) 2026/09/30 02:51:09 DEBUG : other/index.json.5154f3c5.partial: renamed to: other/index.json 2026/09/30 02:51:09 INFO : other/index.json: Copied (new) 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:28622") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:55282->127.0.0.1:28622, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:31538") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:50902->127.0.0.1:31538, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: SetModTime is not supported 2026/09/30 02:51:09 DEBUG : sub/index.json.55cdd857.partial: size = 255 OK 2026/09/30 02:51:09 DEBUG : sub/index.json.55cdd857.partial: renamed to: sub/index.json 2026/09/30 02:51:09 INFO : sub/index.json: Copied (new) 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:28622") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:55294->127.0.0.1:28622, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:30733") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:55094->127.0.0.1:30733, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: SetModTime is not supported 2026/09/30 02:51:09 DEBUG : index.json.5e2e8633.partial: size = 371 OK 2026/09/30 02:51:09 DEBUG : index.json.5e2e8633.partial: renamed to: index.json 2026/09/30 02:51:09 INFO : index.json: Copied (new) 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:30570") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:39656->127.0.0.1:30570, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: SetModTime is not supported 2026/09/30 02:51:09 INFO : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: Partial index: listing 3 directories and walking 0 changed directories 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:31428") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:49612->127.0.0.1:31428, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:31625") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:39948->127.0.0.1:31625, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:30436") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:36904->127.0.0.1:30436, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:30135") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:57318->127.0.0.1:30135, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:30887") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:36750->127.0.0.1:30887, err= 2026/09/30 02:51:09 DEBUG : sub/index.json: Unchanged skipping 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: SetModTime is not supported 2026/09/30 02:51:09 DEBUG : sub/deep/index.json.7ddd5631.partial: size = 275 OK 2026/09/30 02:51:09 DEBUG : sub/deep/index.json.7ddd5631.partial: renamed to: sub/deep/index.json 2026/09/30 02:51:09 INFO : sub/deep/index.json: Copied (replaced existing) 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:31719") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:50470->127.0.0.1:31719, err= 2026/09/30 02:51:09 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/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:30982") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:58346->127.0.0.1:30982, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:30799") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:39732->127.0.0.1:30799, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:30239") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:33548->127.0.0.1:30239, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:31641") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:57940->127.0.0.1:31641, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:31417") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:45340->127.0.0.1:31417, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:31204") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:56186->127.0.0.1:31204, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: dial("tcp","127.0.0.1:30477") 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: > dial: conn=127.0.0.1:37596->127.0.0.1:30477, err= 2026/09/30 02:51:09 DEBUG : ftp://127.0.0.1:28622/rclone-test-qolefod4wetu: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 168.279087ms (try 3/5): exit status 1: Failed [TestIndexChangedDirTime]