"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCopyCheckFirst|TestMoveWithIgnoreExisting)$'" - Starting (try 2/5)
2026/09/02 02:45:02 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-vudequf2negu"
2026/09/02 02:45:02 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/02 02:45:02 DEBUG : Linkbox root 'rclone-test-vudequf2negu': Using cached web token
2026/09/02 02:45:04 DEBUG : Creating backend with remote "/tmp/rclone3341562334"
=== RUN   TestCopyCheckFirst
    run.go:198: Remote "Linkbox root 'rclone-test-vudequf2negu'", Local "Local file system at /tmp/rclone3341562334", Modify Window "876000h0m0s"
2026/09/02 02:45:04 INFO  : Linkbox root 'rclone-test-vudequf2negu': Running all checks before starting transfers
2026/09/02 02:45:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/09/02 02:45:04 DEBUG : Linkbox root 'rclone-test-vudequf2negu': Waiting for checks to finish
2026/09/02 02:45:04 INFO  : Linkbox root 'rclone-test-vudequf2negu': Checks finished, now starting transfers
2026/09/02 02:45:04 DEBUG : Linkbox root 'rclone-test-vudequf2negu': Waiting for transfers to finish
2026/09/02 02:45:06 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/02 02:45:06 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/09/02 02:45:06 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/02 02:45:06 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/09/02 02:45:07 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/02 02:45:07 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/09/02 02:45:07 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/02 02:45:07 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/09/02 02:45:09 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/09/02 02:45:11 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/09/02 02:45:13 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/09/02 02:45:15 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/09/02 02:45:17 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/09/02 02:45:19 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
2026/09/02 02:45:19 DEBUG : sub dir/hello world: Received error: failed to make directory: CreateDir failed: invalid character '<' looking for beginning of value - low level retry 0/10
2026/09/02 02:45:21 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/09/02 02:45:23 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/09/02 02:45:25 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/09/02 02:45:27 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/09/02 02:45:29 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/09/02 02:45:31 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/09/02 02:45:33 DEBUG : pacer: Reducing sleep to 1.5s
2026/09/02 02:45:36 DEBUG : pacer: Reducing sleep to 1.125s
2026/09/02 02:45:37 DEBUG : pacer: Reducing sleep to 843.75ms
2026/09/02 02:45:38 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/09/02 02:45:40 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/09/02 02:45:40 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/09/02 02:45:40 DEBUG : sub dir/hello world: size = 11 OK
2026/09/02 02:45:40 INFO  : sub dir/hello world: Copied (new)
2026/09/02 02:45:41 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/09/02 02:45:41 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/09/02 02:45:41 DEBUG : pacer: Reducing sleep to 200ms
--- PASS: TestCopyCheckFirst (40.97s)
=== RUN   TestMoveWithIgnoreExisting
    run.go:198: Remote "Linkbox root 'rclone-test-vudequf2negu'", Local "Local file system at /tmp/rclone3341562334", Modify Window "876000h0m0s"
2026/09/02 02:45:45 DEBUG : existing-b: Need to transfer - File not found at Destination
2026/09/02 02:45:45 DEBUG : existing: Need to transfer - File not found at Destination
2026/09/02 02:45:45 DEBUG : Linkbox root 'rclone-test-vudequf2negu': Waiting for checks to finish
2026/09/02 02:45:45 DEBUG : Linkbox root 'rclone-test-vudequf2negu': Waiting for transfers to finish
2026/09/02 02:45:47 DEBUG : existing-b: size = 6 OK
2026/09/02 02:45:47 INFO  : existing-b: Copied (new)
2026/09/02 02:45:47 INFO  : existing-b: Deleted
2026/09/02 02:45:47 DEBUG : existing: size = 6 OK
2026/09/02 02:45:47 INFO  : existing: Copied (new)
2026/09/02 02:45:47 INFO  : existing: Deleted
2026/09/02 02:45:48 DEBUG : existing: Destination exists, skipping
2026/09/02 02:45:48 DEBUG : Linkbox root 'rclone-test-vudequf2negu': Waiting for checks to finish
2026/09/02 02:45:48 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set
2026/09/02 02:45:48 DEBUG : Linkbox root 'rclone-test-vudequf2negu': Waiting for transfers to finish
2026/09/02 02:45:48 INFO  : There was nothing to transfer
--- PASS: TestMoveWithIgnoreExisting (6.47s)
PASS
2026/09/02 02:45:51 DEBUG : Linkbox root 'rclone-test-vudequf2negu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCopyCheckFirst|TestMoveWithIgnoreExisting)$'" - Finished OK in 49.944755767s (try 2/5)