"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 5/5) 2026/10/03 01:24:08 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-rolamox6daha" 2026/10/03 01:24:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/03 01:24:08 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/03 01:24:08 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/03 01:24:08 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/03 01:24:08 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/03 01:24:08 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/03 01:24:08 DEBUG : TestFTPRclone: detected overridden config - adding "{oyKNr}" suffix to name 2026/10/03 01:24:08 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/03 01:24:08 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/03 01:24:08 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/03 01:24:08 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: Connecting to FTP server 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:28622") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:52234->127.0.0.1:28622, err= 2026/10/03 01:24:08 DEBUG : Creating backend with remote "/tmp/rclone1718220557" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-rolamox6daha", Local "Local file system at /tmp/rclone1718220557", Modify Window "876000h0m0s" 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:30385") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:52888->127.0.0.1:30385, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: SetModTime is not supported 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:31615") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:60898->127.0.0.1:31615, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: SetModTime is not supported 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:30051") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:59784->127.0.0.1:30051, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: SetModTime is not supported 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:30593") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:34706->127.0.0.1:30593, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: SetModTime is not supported 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:30178") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:37432->127.0.0.1:30178, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: Connecting to FTP server 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:28622") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:52248->127.0.0.1:28622, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:31891") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:44764->127.0.0.1:31891, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:30835") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:51040->127.0.0.1:30835, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:30258") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:60154->127.0.0.1:30258, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: Connecting to FTP server 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: Connecting to FTP server 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:30796") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:31929") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:60740->127.0.0.1:30796, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:33418->127.0.0.1:31929, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: SetModTime is not supported 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: SetModTime is not supported 2026/10/03 01:24:08 DEBUG : sub/index.json.14d3ed35.partial: size = 255 OK 2026/10/03 01:24:08 DEBUG : other/index.json.49599b02.partial: size = 140 OK 2026/10/03 01:24:08 DEBUG : sub/index.json.14d3ed35.partial: renamed to: sub/index.json 2026/10/03 01:24:08 INFO : sub/index.json: Copied (new) 2026/10/03 01:24:08 DEBUG : other/index.json.49599b02.partial: renamed to: other/index.json 2026/10/03 01:24:08 INFO : other/index.json: Copied (new) 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:28622") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:52262->127.0.0.1:28622, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:30369") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:58952->127.0.0.1:30369, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: SetModTime is not supported 2026/10/03 01:24:08 DEBUG : sub/deep/index.json.660b4d8f.partial: size = 139 OK 2026/10/03 01:24:08 DEBUG : sub/deep/index.json.660b4d8f.partial: renamed to: sub/deep/index.json 2026/10/03 01:24:08 INFO : sub/deep/index.json: Copied (new) 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:28622") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:52270->127.0.0.1:28622, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:31847") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:58598->127.0.0.1:31847, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: SetModTime is not supported 2026/10/03 01:24:08 DEBUG : index.json.ada13729.partial: size = 371 OK 2026/10/03 01:24:08 DEBUG : index.json.ada13729.partial: renamed to: index.json 2026/10/03 01:24:08 INFO : index.json: Copied (new) 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:31760") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:50588->127.0.0.1:31760, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: SetModTime is not supported 2026/10/03 01:24:08 INFO : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: Partial index: listing 3 directories and walking 0 changed directories 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:30341") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:53868->127.0.0.1:30341, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:30173") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:50482->127.0.0.1:30173, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:30124") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:38464->127.0.0.1:30124, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:30758") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:42998->127.0.0.1:30758, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: SetModTime is not supported 2026/10/03 01:24:08 DEBUG : sub/deep/index.json.92b3c56d.partial: size = 275 OK 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:31070") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:52262->127.0.0.1:31070, err= 2026/10/03 01:24:08 DEBUG : sub/deep/index.json.92b3c56d.partial: renamed to: sub/deep/index.json 2026/10/03 01:24:08 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/03 01:24:08 DEBUG : index.json: Unchanged skipping 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:30219") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:56750->127.0.0.1:30219, err= 2026/10/03 01:24:08 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/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:31329") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:48092->127.0.0.1:31329, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:31615") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:60900->127.0.0.1:31615, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:31536") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:45830->127.0.0.1:31536, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:30763") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:55020->127.0.0.1:30763, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:31697") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:42628->127.0.0.1:31697, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:30121") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:40676->127.0.0.1:30121, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: dial("tcp","127.0.0.1:31952") 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: > dial: conn=127.0.0.1:54900->127.0.0.1:31952, err= 2026/10/03 01:24:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-rolamox6daha: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 139.413052ms (try 5/5): exit status 1: Failed [TestIndexChangedDirTime]