"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCopyFileCompareDest|TestIndex)$'" - Starting (try 4/5)
2026/10/10 06:14:04 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-zefadit4qiye"
2026/10/10 06:14:04 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/10 06:14:04 DEBUG : Linkbox root 'rclone-test-zefadit4qiye': Using cached web token
2026/10/10 06:14:05 DEBUG : Creating backend with remote "/tmp/rclone2478952552"
=== RUN   TestCopyFileCompareDest
    run.go:198: Remote "Linkbox root 'rclone-test-zefadit4qiye'", Local "Local file system at /tmp/rclone2478952552", Modify Window "876000h0m0s"
2026/10/10 06:14:05 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-zefadit4qiye/dst"
2026/10/10 06:14:05 DEBUG : Linkbox root 'rclone-test-zefadit4qiye/dst': Using cached web token
2026/10/10 06:14:06 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-zefadit4qiye/CompareDest"
2026/10/10 06:14:06 DEBUG : Linkbox root 'rclone-test-zefadit4qiye/CompareDest': Using cached web token
2026/10/10 06:14:07 DEBUG : one: Need to transfer - File not found at Destination
2026/10/10 06:14:13 DEBUG : one: size = 3 OK
2026/10/10 06:14:13 INFO  : one: Copied (new)
2026/10/10 06:14:13 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:13 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/10/10 06:14:13 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:13 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/10/10 06:14:14 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:14 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/10/10 06:14:15 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:15 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/10/10 06:14:16 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:18 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:20 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:22 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:24 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:26 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:26 ERROR : dst: error listing: getting files failed: invalid character '<' looking for beginning of value
    fstest.go:283: Error listing: getting files failed: invalid character '<' looking for beginning of value
2026/10/10 06:14:28 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:30 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:32 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:34 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:36 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:38 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:40 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:42 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:44 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:14:46 DEBUG : pacer: Reducing sleep to 1.5s
2026/10/10 06:14:48 DEBUG : pacer: Reducing sleep to 1.125s
2026/10/10 06:14:51 DEBUG : pacer: Reducing sleep to 843.75ms
2026/10/10 06:14:51 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/10/10 06:14:53 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/10/10 06:14:53 DEBUG : pacer: Reducing sleep to 355.957031ms
--- FAIL: TestCopyFileCompareDest (48.88s)
=== RUN   TestIndex
    run.go:198: Remote "Linkbox root 'rclone-test-zefadit4qiye'", Local "Local file system at /tmp/rclone2478952552", Modify Window "876000h0m0s"
2026/10/10 06:14:54 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/10/10 06:14:54 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/10/10 06:14:56 DEBUG : pacer: Reducing sleep to 200ms
2026/10/10 06:15:07 DEBUG : index.html: size = 12147 OK
2026/10/10 06:15:07 INFO  : index.html: Copied (new)
2026/10/10 06:15:07 DEBUG : index.json: size = 213 OK
2026/10/10 06:15:07 INFO  : index.json: Copied (new)
2026/10/10 06:15:08 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/10 06:15:08 INFO  : sub/deep/index.html: Copied (new)
2026/10/10 06:15:08 DEBUG : caddy.json: size = 259 OK
2026/10/10 06:15:08 INFO  : caddy.json: Copied (new)
2026/10/10 06:15:10 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/10 06:15:10 INFO  : sub/deep/index.json: Copied (new)
2026/10/10 06:15:10 DEBUG : sub/index.html: size = 12306 OK
2026/10/10 06:15:10 INFO  : sub/index.html: Copied (new)
2026/10/10 06:15:11 DEBUG : sub/deep/caddy.json: size = 131 OK
2026/10/10 06:15:11 INFO  : sub/deep/caddy.json: Copied (new)
2026/10/10 06:15:11 DEBUG : sub/index.json: size = 215 OK
2026/10/10 06:15:11 INFO  : sub/index.json: Copied (new)
2026/10/10 06:15:12 DEBUG : sub/caddy.json: size = 261 OK
2026/10/10 06:15:12 INFO  : sub/caddy.json: Copied (new)
2026/10/10 06:15:13 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 240h20m3.551387453s different from this computer
2026/10/10 06:15:15 DEBUG : index.html: Unchanged skipping
2026/10/10 06:15:15 DEBUG : sub/deep/index.html: Unchanged skipping
2026/10/10 06:15:15 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 06:15:16 DEBUG : index.json: Unchanged skipping
2026/10/10 06:15:16 DEBUG : sub/deep/index.json: Unchanged skipping
2026/10/10 06:15:16 DEBUG : sub/index.json: Unchanged skipping
2026/10/10 06:15:16 DEBUG : caddy.json: Unchanged skipping
2026/10/10 06:15:16 DEBUG : sub/deep/caddy.json: Unchanged skipping
2026/10/10 06:15:17 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/10 06:15:17 DEBUG : index.html: Update: removing old file
2026/10/10 06:15:17 DEBUG : index.json: Update: removing old file
2026/10/10 06:15:17 DEBUG : caddy.json: Update: removing old file
2026/10/10 06:15:17 DEBUG : sub/deep/index.html: Update: removing old file
2026/10/10 06:15:21 DEBUG : index.html: size = 12147 OK
2026/10/10 06:15:21 INFO  : index.html: Copied (replaced existing)
2026/10/10 06:15:21 DEBUG : sub/deep/index.json: Update: removing old file
2026/10/10 06:15:21 DEBUG : index.json: size = 213 OK
2026/10/10 06:15:21 INFO  : index.json: Copied (replaced existing)
2026/10/10 06:15:21 DEBUG : sub/index.html: Update: removing old file
2026/10/10 06:15:21 DEBUG : caddy.json: size = 259 OK
2026/10/10 06:15:21 INFO  : caddy.json: Copied (replaced existing)
2026/10/10 06:15:21 DEBUG : sub/deep/caddy.json: Update: removing old file
2026/10/10 06:15:22 DEBUG : sub/deep/index.html: size = 12087 OK
2026/10/10 06:15:22 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/10 06:15:22 DEBUG : sub/index.json: Update: removing old file
2026/10/10 06:15:24 DEBUG : sub/deep/index.json: size = 119 OK
2026/10/10 06:15:24 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/10 06:15:24 DEBUG : sub/caddy.json: Update: removing old file
2026/10/10 06:15:24 DEBUG : sub/index.html: size = 12306 OK
2026/10/10 06:15:24 INFO  : sub/index.html: Copied (replaced existing)
2026/10/10 06:15:25 DEBUG : sub/deep/caddy.json: size = 131 OK
2026/10/10 06:15:25 INFO  : sub/deep/caddy.json: Copied (replaced existing)
2026/10/10 06:15:25 DEBUG : sub/index.json: size = 215 OK
2026/10/10 06:15:25 INFO  : sub/index.json: Copied (replaced existing)
2026/10/10 06:15:27 DEBUG : sub/caddy.json: size = 261 OK
2026/10/10 06:15:27 INFO  : sub/caddy.json: Copied (replaced existing)
2026/10/10 06:15:27 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:15:27 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/10/10 06:15:28 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:15:28 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/10/10 06:15:28 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:15:28 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/10/10 06:15:29 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:15:29 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/10/10 06:15:30 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:15:32 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:15:34 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:15:36 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:15:38 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:15:40 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:15:42 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:15:44 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/10/10 06:15:47 DEBUG : pacer: Reducing sleep to 1.5s
2026/10/10 06:15:50 DEBUG : pacer: Reducing sleep to 1.125s
2026/10/10 06:15:50 DEBUG : pacer: Reducing sleep to 843.75ms
2026/10/10 06:15:51 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/10/10 06:15:52 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/10/10 06:15:53 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/10/10 06:15:53 DEBUG : sub/deep/index.html: Update: removing old file
2026/10/10 06:15:53 DEBUG : sub/deep/caddy.json: Update: removing old file
2026/10/10 06:15:53 DEBUG : sub/deep/index.json: Update: removing old file
2026/10/10 06:15:53 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/10/10 06:15:53 DEBUG : index.html: Unchanged skipping
2026/10/10 06:15:53 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/10/10 06:15:53 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 06:15:54 DEBUG : pacer: Reducing sleep to 200ms
2026/10/10 06:15:54 DEBUG : index.json: Unchanged skipping
2026/10/10 06:15:54 DEBUG : sub/index.json: Unchanged skipping
2026/10/10 06:15:55 DEBUG : caddy.json: Unchanged skipping
2026/10/10 06:15:55 DEBUG : sub/caddy.json: Unchanged skipping
2026/10/10 06:15:57 DEBUG : sub/deep/index.html: size = 12346 OK
2026/10/10 06:15:57 INFO  : sub/deep/index.html: Copied (replaced existing)
2026/10/10 06:15:57 DEBUG : sub/deep/caddy.json: size = 260 OK
2026/10/10 06:15:57 INFO  : sub/deep/caddy.json: Copied (replaced existing)
2026/10/10 06:15:57 DEBUG : sub/deep/index.json: size = 235 OK
2026/10/10 06:15:57 INFO  : sub/deep/index.json: Copied (replaced existing)
2026/10/10 06:16:01 NOTICE: sub/deep/index.html: Skipped copy as --dry-run is set (size 12.309Ki)
2026/10/10 06:16:01 NOTICE: sub/deep/index.json: Skipped copy as --dry-run is set (size 351)
2026/10/10 06:16:01 NOTICE: sub/deep/caddy.json: Skipped copy as --dry-run is set (size 389)
2026/10/10 06:16:01 DEBUG : index.html: Unchanged skipping
2026/10/10 06:16:01 DEBUG : sub/index.html: Unchanged skipping
2026/10/10 06:16:01 DEBUG : index.json: Unchanged skipping
2026/10/10 06:16:01 DEBUG : sub/index.json: Unchanged skipping
2026/10/10 06:16:01 DEBUG : caddy.json: Unchanged skipping
2026/10/10 06:16:02 DEBUG : sub/caddy.json: Unchanged skipping
--- PASS: TestIndex (91.10s)
FAIL
2026/10/10 06:16:25 DEBUG : Linkbox root 'rclone-test-zefadit4qiye': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCopyFileCompareDest|TestIndex)$'" - Finished ERROR in 2m22.370963259s (try 4/5): exit status 1: Failed [TestCopyFileCompareDest]