"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 2/5) 2026/09/30 02:51:07 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-mogudic3bile" 2026/09/30 02:51:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/30 02:51:07 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/09/30 02:51:07 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/09/30 02:51:07 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/09/30 02:51:07 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/09/30 02:51:07 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/09/30 02:51:07 DEBUG : TestFTPRclone: detected overridden config - adding "{H3D2N}" suffix to name 2026/09/30 02:51:07 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/09/30 02:51:07 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/09/30 02:51:07 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/09/30 02:51:07 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: Connecting to FTP server 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:28622") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:55214->127.0.0.1:28622, err= 2026/09/30 02:51:07 DEBUG : Creating backend with remote "/tmp/rclone2353115013" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-mogudic3bile", Local "Local file system at /tmp/rclone2353115013", Modify Window "876000h0m0s" 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:30989") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:48144->127.0.0.1:30989, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: SetModTime is not supported 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:31033") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:34344->127.0.0.1:31033, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: SetModTime is not supported 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:30726") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:43276->127.0.0.1:30726, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: SetModTime is not supported 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:31327") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:41002->127.0.0.1:31327, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: SetModTime is not supported 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:31599") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:50494->127.0.0.1:31599, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: Connecting to FTP server 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:28622") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:30204") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:55228->127.0.0.1:28622, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:50612->127.0.0.1:30204, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:30061") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:58176->127.0.0.1:30061, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:31302") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:57564->127.0.0.1:31302, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: Connecting to FTP server 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: Connecting to FTP server 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:30061") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:30960") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:58180->127.0.0.1:30061, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:55466->127.0.0.1:30960, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: SetModTime is not supported 2026/09/30 02:51:07 DEBUG : sub/deep/index.json.986c8c09.partial: size = 139 OK 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: SetModTime is not supported 2026/09/30 02:51:07 DEBUG : sub/index.json.464bb707.partial: size = 255 OK 2026/09/30 02:51:07 DEBUG : sub/deep/index.json.986c8c09.partial: renamed to: sub/deep/index.json 2026/09/30 02:51:07 INFO : sub/deep/index.json: Copied (new) 2026/09/30 02:51:07 DEBUG : sub/index.json.464bb707.partial: renamed to: sub/index.json 2026/09/30 02:51:07 INFO : sub/index.json: Copied (new) 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:28622") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:55244->127.0.0.1:28622, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:31268") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:38444->127.0.0.1:31268, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: SetModTime is not supported 2026/09/30 02:51:07 DEBUG : index.json.e555961f.partial: size = 371 OK 2026/09/30 02:51:07 DEBUG : index.json.e555961f.partial: renamed to: index.json 2026/09/30 02:51:07 INFO : index.json: Copied (new) 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:28622") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:55252->127.0.0.1:28622, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:31640") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:45236->127.0.0.1:31640, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: SetModTime is not supported 2026/09/30 02:51:07 DEBUG : other/index.json.3c192aec.partial: size = 140 OK 2026/09/30 02:51:07 DEBUG : other/index.json.3c192aec.partial: renamed to: other/index.json 2026/09/30 02:51:07 INFO : other/index.json: Copied (new) 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:31763") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:46856->127.0.0.1:31763, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: SetModTime is not supported 2026/09/30 02:51:07 INFO : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: Partial index: listing 3 directories and walking 0 changed directories 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:31509") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:46672->127.0.0.1:31509, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:30774") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:33290->127.0.0.1:30774, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:31374") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:60308->127.0.0.1:31374, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:31574") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:34286->127.0.0.1:31574, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:31145") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:45420->127.0.0.1:31145, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: SetModTime is not supported 2026/09/30 02:51:07 DEBUG : sub/deep/index.json.a1f9b17d.partial: size = 275 OK 2026/09/30 02:51:07 DEBUG : index.json: Unchanged skipping 2026/09/30 02:51:07 DEBUG : sub/deep/index.json.a1f9b17d.partial: renamed to: sub/deep/index.json 2026/09/30 02:51:07 INFO : sub/deep/index.json: Copied (replaced existing) 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:31659") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:55726->127.0.0.1:31659, err= 2026/09/30 02:51:07 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/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:30230") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:35100->127.0.0.1:30230, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:30723") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:33996->127.0.0.1:30723, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:31214") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:59622->127.0.0.1:31214, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:31375") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:36118->127.0.0.1:31375, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:30342") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:36858->127.0.0.1:30342, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:31960") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:57084->127.0.0.1:31960, err= --- FAIL: TestIndexChangedDirTime (0.07s) FAIL 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: dial("tcp","127.0.0.1:31207") 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: > dial: conn=127.0.0.1:51226->127.0.0.1:31207, err= 2026/09/30 02:51:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-mogudic3bile: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 149.761108ms (try 2/5): exit status 1: Failed [TestIndexChangedDirTime]