"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 5/5) 2026/10/05 03:06:07 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-wawawac7cazu" 2026/10/05 03:06:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/05 03:06:07 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/05 03:06:07 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/05 03:06:07 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/05 03:06:07 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/05 03:06:07 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/05 03:06:07 DEBUG : TestFTPRclone: detected overridden config - adding "{NecEw}" suffix to name 2026/10/05 03:06:07 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/05 03:06:07 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/05 03:06:07 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/05 03:06:07 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: Connecting to FTP server 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:28622") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:44212->127.0.0.1:28622, err= 2026/10/05 03:06:07 DEBUG : Creating backend with remote "/tmp/rclone2954137828" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-wawawac7cazu", Local "Local file system at /tmp/rclone2954137828", Modify Window "876000h0m0s" 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:30530") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:44992->127.0.0.1:30530, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: SetModTime is not supported 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:31250") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:57828->127.0.0.1:31250, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: SetModTime is not supported 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:30425") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:36018->127.0.0.1:30425, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: SetModTime is not supported 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:31186") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:43644->127.0.0.1:31186, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: SetModTime is not supported 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:31650") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:49596->127.0.0.1:31650, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: Connecting to FTP server 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:28622") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:44214->127.0.0.1:28622, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:31737") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:46686->127.0.0.1:31737, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:31277") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:48446->127.0.0.1:31277, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:30221") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:43258->127.0.0.1:30221, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: Connecting to FTP server 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: Connecting to FTP server 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:30386") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:54184->127.0.0.1:30386, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:30819") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:44056->127.0.0.1:30819, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: SetModTime is not supported 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: SetModTime is not supported 2026/10/05 03:06:07 DEBUG : other/index.json.94bb7ca0.partial: size = 140 OK 2026/10/05 03:06:07 DEBUG : index.json.801e0af4.partial: size = 371 OK 2026/10/05 03:06:07 DEBUG : other/index.json.94bb7ca0.partial: renamed to: other/index.json 2026/10/05 03:06:07 INFO : other/index.json: Copied (new) 2026/10/05 03:06:07 DEBUG : index.json.801e0af4.partial: renamed to: index.json 2026/10/05 03:06:07 INFO : index.json: Copied (new) 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:28622") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:44216->127.0.0.1:28622, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:30477") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:38532->127.0.0.1:30477, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: SetModTime is not supported 2026/10/05 03:06:07 DEBUG : sub/index.json.04910b61.partial: size = 255 OK 2026/10/05 03:06:07 DEBUG : sub/index.json.04910b61.partial: renamed to: sub/index.json 2026/10/05 03:06:07 INFO : sub/index.json: Copied (new) 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:28622") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:44222->127.0.0.1:28622, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:30179") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:40856->127.0.0.1:30179, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: SetModTime is not supported 2026/10/05 03:06:07 DEBUG : sub/deep/index.json.f3eb4467.partial: size = 139 OK 2026/10/05 03:06:07 DEBUG : sub/deep/index.json.f3eb4467.partial: renamed to: sub/deep/index.json 2026/10/05 03:06:07 INFO : sub/deep/index.json: Copied (new) 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:30273") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:54314->127.0.0.1:30273, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: SetModTime is not supported 2026/10/05 03:06:07 INFO : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: Partial index: listing 3 directories and walking 0 changed directories 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:31188") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:39026->127.0.0.1:31188, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:30572") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:46840->127.0.0.1:30572, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:31507") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:35182->127.0.0.1:31507, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:30021") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:59150->127.0.0.1:30021, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:31890") 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:37614->127.0.0.1:31890, err= 2026/10/05 03:06:07 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: SetModTime is not supported 2026/10/05 03:06:07 DEBUG : sub/index.json: Unchanged skipping 2026/10/05 03:06:07 DEBUG : sub/deep/index.json.c9f22bca.partial: size = 275 OK 2026/10/05 03:06:07 DEBUG : sub/deep/index.json.c9f22bca.partial: renamed to: sub/deep/index.json 2026/10/05 03:06:07 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:31580") 2026/10/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:45878->127.0.0.1:31580, err= 2026/10/05 03:06:08 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/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:31448") 2026/10/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:54206->127.0.0.1:31448, err= 2026/10/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:31719") 2026/10/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:51554->127.0.0.1:31719, err= 2026/10/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:30958") 2026/10/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:31594") 2026/10/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:53222->127.0.0.1:30958, err= 2026/10/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:44738->127.0.0.1:31594, err= 2026/10/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:31433") 2026/10/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:56180->127.0.0.1:31433, err= 2026/10/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:31273") 2026/10/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:52694->127.0.0.1:31273, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: dial("tcp","127.0.0.1:30486") 2026/10/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: > dial: conn=127.0.0.1:60444->127.0.0.1:30486, err= 2026/10/05 03:06:08 DEBUG : ftp://127.0.0.1:28622/rclone-test-wawawac7cazu: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 168.791812ms (try 5/5): exit status 1: Failed [TestIndexChangedDirTime]