"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Starting (try 2/5) 2026/10/02 03:30:54 DEBUG : Creating backend with remote "TestFTPRclone:rclone-test-faqazos7qaqu" 2026/10/02 03:30:54 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/02 03:30:54 DEBUG : Setting type=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_TYPE 2026/10/02 03:30:54 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/02 03:30:54 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/02 03:30:54 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/02 03:30:54 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/02 03:30:54 DEBUG : TestFTPRclone: detected overridden config - adding "{VRcaF}" suffix to name 2026/10/02 03:30:54 DEBUG : Setting host=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_HOST 2026/10/02 03:30:54 DEBUG : Setting user=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_USER 2026/10/02 03:30:54 DEBUG : Setting pass=XXX for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PASS 2026/10/02 03:30:54 DEBUG : Setting port="28622" for "TestFTPRclone" from environment variable RCLONE_CONFIG_TESTFTPRCLONE_PORT 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: Connecting to FTP server 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:28622") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:55992->127.0.0.1:28622, err= 2026/10/02 03:30:54 DEBUG : Creating backend with remote "/tmp/rclone3536294709" === RUN TestIndexChangedDirTime run.go:198: Remote "ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu", Local "Local file system at /tmp/rclone3536294709", Modify Window "876000h0m0s" 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:31910") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:46706->127.0.0.1:31910, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: SetModTime is not supported 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:30081") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:38996->127.0.0.1:30081, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: SetModTime is not supported 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:30502") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:33482->127.0.0.1:30502, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: SetModTime is not supported 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:31095") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:49028->127.0.0.1:31095, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: SetModTime is not supported 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:30089") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:48710->127.0.0.1:30089, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: Connecting to FTP server 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:28622") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:56008->127.0.0.1:28622, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:31676") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:36042->127.0.0.1:31676, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:30130") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:45924->127.0.0.1:30130, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:30685") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:54006->127.0.0.1:30685, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: Connecting to FTP server 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: Connecting to FTP server 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:31034") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:42126->127.0.0.1:31034, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:30810") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:37326->127.0.0.1:30810, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: SetModTime is not supported 2026/10/02 03:30:54 DEBUG : index.json.eb58bf96.partial: size = 371 OK 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: SetModTime is not supported 2026/10/02 03:30:54 DEBUG : sub/index.json.1e5a3eb1.partial: size = 255 OK 2026/10/02 03:30:54 DEBUG : index.json.eb58bf96.partial: renamed to: index.json 2026/10/02 03:30:54 INFO : index.json: Copied (new) 2026/10/02 03:30:54 DEBUG : sub/index.json.1e5a3eb1.partial: renamed to: sub/index.json 2026/10/02 03:30:54 INFO : sub/index.json: Copied (new) 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:28622") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:56020->127.0.0.1:28622, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:31935") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:60882->127.0.0.1:31935, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: SetModTime is not supported 2026/10/02 03:30:54 DEBUG : other/index.json.77779c60.partial: size = 140 OK 2026/10/02 03:30:54 DEBUG : other/index.json.77779c60.partial: renamed to: other/index.json 2026/10/02 03:30:54 INFO : other/index.json: Copied (new) 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:28622") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:56022->127.0.0.1:28622, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:31937") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:42266->127.0.0.1:31937, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: SetModTime is not supported 2026/10/02 03:30:54 DEBUG : sub/deep/index.json.d6276651.partial: size = 139 OK 2026/10/02 03:30:54 DEBUG : sub/deep/index.json.d6276651.partial: renamed to: sub/deep/index.json 2026/10/02 03:30:54 INFO : sub/deep/index.json: Copied (new) 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:30480") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:32872->127.0.0.1:30480, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: SetModTime is not supported 2026/10/02 03:30:54 INFO : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: Partial index: listing 3 directories and walking 0 changed directories 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:30190") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:57732->127.0.0.1:30190, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:31016") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:33866->127.0.0.1:31016, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:31572") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:60224->127.0.0.1:31572, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:31803") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:55610->127.0.0.1:31803, err= 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:31423") 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:58358->127.0.0.1:31423, err= 2026/10/02 03:30:54 DEBUG : sub/index.json: Unchanged skipping 2026/10/02 03:30:54 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: SetModTime is not supported 2026/10/02 03:30:54 DEBUG : sub/deep/index.json.f02df39d.partial: size = 275 OK 2026/10/02 03:30:54 DEBUG : sub/deep/index.json.f02df39d.partial: renamed to: sub/deep/index.json 2026/10/02 03:30:54 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:31473") 2026/10/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:50976->127.0.0.1:31473, err= 2026/10/02 03:30:55 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/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:30324") 2026/10/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:39562->127.0.0.1:30324, err= 2026/10/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:30707") 2026/10/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:46936->127.0.0.1:30707, err= 2026/10/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:30642") 2026/10/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:30274") 2026/10/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:33358->127.0.0.1:30642, err= 2026/10/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:37340->127.0.0.1:30274, err= 2026/10/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:31338") 2026/10/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:53618->127.0.0.1:31338, err= 2026/10/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:31349") 2026/10/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:33268->127.0.0.1:31349, err= --- FAIL: TestIndexChangedDirTime (0.08s) FAIL 2026/10/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: dial("tcp","127.0.0.1:30530") 2026/10/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: > dial: conn=127.0.0.1:51266->127.0.0.1:30530, err= 2026/10/02 03:30:55 DEBUG : ftp://127.0.0.1:28622/rclone-test-faqazos7qaqu: Purge dir "" "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPRclone: -verbose -test.run '^TestIndexChangedDirTime$'" - Finished ERROR in 168.350357ms (try 2/5): exit status 1: Failed [TestIndexChangedDirTime]