"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestMoveOverSelf|TestSyncCompareDest)$'" - Starting (try 5/5)
2026/07/24 04:27:27 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-kofegav2bili"
2026/07/24 04:27:27 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/24 04:27:27 DEBUG : Linkbox root 'rclone-test-kofegav2bili': Using cached web token
2026/07/24 04:27:28 DEBUG : Creating backend with remote "/tmp/rclone3184438173"
=== RUN   TestMoveOverSelf
    run.go:198: Remote "Linkbox root 'rclone-test-kofegav2bili'", Local "Local file system at /tmp/rclone3184438173", Modify Window "876000h0m0s"
2026/07/24 04:27:33 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10)
2026/07/24 04:27:34 DEBUG : Linkbox root 'rclone-test-kofegav2bili': Waiting for checks to finish
2026/07/24 04:27:34 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone3184438173)
2026/07/24 04:27:34 DEBUG : sub dir/hello world: size = 11 (Linkbox root 'rclone-test-kofegav2bili')
2026/07/24 04:27:34 DEBUG : sub dir/hello world: Sizes differ
2026/07/24 04:27:34 DEBUG : Linkbox root 'rclone-test-kofegav2bili': Waiting for transfers to finish
2026/07/24 04:27:34 DEBUG : sub dir/hello world: Update: removing old file
2026/07/24 04:27:37 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10)
2026/07/24 04:27:38 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10)
2026/07/24 04:27:38 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10)
2026/07/24 04:27:39 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 800ms (4/10)
2026/07/24 04:27:40 DEBUG : sub dir/hello world: size = 17 OK
2026/07/24 04:27:40 INFO  : sub dir/hello world: Copied (replaced existing)
2026/07/24 04:27:40 INFO  : sub dir/hello world: Deleted
--- PASS: TestMoveOverSelf (15.84s)
=== RUN   TestSyncCompareDest
    run.go:198: Remote "Linkbox root 'rclone-test-kofegav2bili'", Local "Local file system at /tmp/rclone3184438173", Modify Window "876000h0m0s"
2026/07/24 04:27:44 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-kofegav2bili/dst"
2026/07/24 04:27:44 DEBUG : Linkbox root 'rclone-test-kofegav2bili/dst': Using cached web token
2026/07/24 04:27:45 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-kofegav2bili/CompareDest"
2026/07/24 04:27:45 DEBUG : Linkbox root 'rclone-test-kofegav2bili/CompareDest': Using cached web token
2026/07/24 04:27:46 DEBUG : one: Need to transfer - File not found at Destination
2026/07/24 04:27:46 DEBUG : Linkbox root 'rclone-test-kofegav2bili/dst': Waiting for checks to finish
2026/07/24 04:27:46 DEBUG : Linkbox root 'rclone-test-kofegav2bili/dst': Waiting for transfers to finish
2026/07/24 04:27:50 DEBUG : one: Trying to read object after upload: try again in 100ms (1/10)
2026/07/24 04:27:50 DEBUG : one: size = 3 OK
2026/07/24 04:27:50 INFO  : one: Copied (new)
2026/07/24 04:27:50 DEBUG : Waiting for deletions to finish
2026/07/24 04:27:51 DEBUG : one: size = 5 (Local file system at /tmp/rclone3184438173)
2026/07/24 04:27:51 DEBUG : one: size = 3 (Linkbox root 'rclone-test-kofegav2bili/dst')
2026/07/24 04:27:51 DEBUG : one: Sizes differ
2026/07/24 04:27:51 DEBUG : Linkbox root 'rclone-test-kofegav2bili/dst': Waiting for checks to finish
2026/07/24 04:27:52 DEBUG : Linkbox root 'rclone-test-kofegav2bili/dst': Waiting for transfers to finish
2026/07/24 04:27:52 DEBUG : one: Update: removing old file
2026/07/24 04:27:55 DEBUG : one: size = 5 OK
2026/07/24 04:27:55 INFO  : one: Copied (replaced existing)
2026/07/24 04:27:55 DEBUG : Waiting for deletions to finish
2026/07/24 04:27:55 DEBUG : dst/one: Update: removing old file
2026/07/24 04:27:59 DEBUG : dst/one: Trying to read object after upload: try again in 100ms (1/10)
2026/07/24 04:28:02 DEBUG : CompareDest/one: Trying to read object after upload: try again in 100ms (1/10)
2026/07/24 04:28:03 DEBUG : CompareDest/one: Trying to read object after upload: try again in 200ms (2/10)
2026/07/24 04:28:04 DEBUG : Linkbox root 'rclone-test-kofegav2bili/dst': Waiting for checks to finish
2026/07/24 04:28:04 DEBUG : one: size = 5 (Local file system at /tmp/rclone3184438173)
2026/07/24 04:28:04 DEBUG : one: size = 3 (Linkbox root 'rclone-test-kofegav2bili/dst')
2026/07/24 04:28:04 DEBUG : one: Sizes differ
2026/07/24 04:28:04 DEBUG : one: size = 5 OK
2026/07/24 04:28:04 DEBUG : one: Sizes identical
2026/07/24 04:28:04 DEBUG : one: Destination found in --compare-dest, skipping
2026/07/24 04:28:04 DEBUG : Linkbox root 'rclone-test-kofegav2bili/dst': Waiting for transfers to finish
2026/07/24 04:28:04 DEBUG : Waiting for deletions to finish
2026/07/24 04:28:04 INFO  : There was nothing to transfer
2026/07/24 04:28:08 DEBUG : one: size = 5 (Local file system at /tmp/rclone3184438173)
2026/07/24 04:28:08 DEBUG : one: size = 3 (Linkbox root 'rclone-test-kofegav2bili/dst')
2026/07/24 04:28:08 DEBUG : one: Sizes differ
2026/07/24 04:28:08 DEBUG : two: size = 3 OK
2026/07/24 04:28:08 DEBUG : two: Sizes identical
2026/07/24 04:28:08 DEBUG : two: Destination found in --compare-dest, skipping
2026/07/24 04:28:08 DEBUG : Linkbox root 'rclone-test-kofegav2bili/dst': Waiting for checks to finish
2026/07/24 04:28:08 DEBUG : one: size = 5 OK
2026/07/24 04:28:08 DEBUG : one: Sizes identical
2026/07/24 04:28:08 DEBUG : one: Destination found in --compare-dest, skipping
2026/07/24 04:28:08 DEBUG : Linkbox root 'rclone-test-kofegav2bili/dst': Waiting for transfers to finish
2026/07/24 04:28:08 DEBUG : Waiting for deletions to finish
2026/07/24 04:28:08 INFO  : There was nothing to transfer
2026/07/24 04:28:09 DEBUG : one: size = 5 (Local file system at /tmp/rclone3184438173)
2026/07/24 04:28:09 DEBUG : one: size = 3 (Linkbox root 'rclone-test-kofegav2bili/dst')
2026/07/24 04:28:09 DEBUG : one: Sizes differ
2026/07/24 04:28:09 DEBUG : two: size = 3 OK
2026/07/24 04:28:09 DEBUG : two: Sizes identical
2026/07/24 04:28:09 DEBUG : two: Destination found in --compare-dest, skipping
2026/07/24 04:28:09 DEBUG : Linkbox root 'rclone-test-kofegav2bili/dst': Waiting for checks to finish
2026/07/24 04:28:09 DEBUG : one: size = 5 OK
2026/07/24 04:28:09 DEBUG : one: Sizes identical
2026/07/24 04:28:09 DEBUG : one: Destination found in --compare-dest, skipping
2026/07/24 04:28:09 DEBUG : Linkbox root 'rclone-test-kofegav2bili/dst': Waiting for transfers to finish
2026/07/24 04:28:09 DEBUG : Waiting for deletions to finish
2026/07/24 04:28:09 INFO  : There was nothing to transfer
    sync_test.go:2206: No hash on uploaded file so skipping compare timestamp test
2026/07/24 04:28:11 DEBUG : one: size = 5 (Local file system at /tmp/rclone3184438173)
2026/07/24 04:28:11 DEBUG : one: size = 3 (Linkbox root 'rclone-test-kofegav2bili/dst')
2026/07/24 04:28:11 DEBUG : one: Sizes differ
2026/07/24 04:28:11 DEBUG : two: size = 5 (Local file system at /tmp/rclone3184438173)
2026/07/24 04:28:11 DEBUG : two: size = 3 (Linkbox root 'rclone-test-kofegav2bili/CompareDest')
2026/07/24 04:28:11 DEBUG : two: Sizes differ
2026/07/24 04:28:11 DEBUG : two: Need to transfer - File not found at Destination
2026/07/24 04:28:11 DEBUG : Linkbox root 'rclone-test-kofegav2bili/dst': Waiting for checks to finish
2026/07/24 04:28:11 DEBUG : one: size = 5 OK
2026/07/24 04:28:11 DEBUG : one: Sizes identical
2026/07/24 04:28:11 DEBUG : one: Destination found in --compare-dest, skipping
2026/07/24 04:28:11 DEBUG : Linkbox root 'rclone-test-kofegav2bili/dst': Waiting for transfers to finish
2026/07/24 04:28:13 DEBUG : two: size = 5 OK
2026/07/24 04:28:13 INFO  : two: Copied (new)
2026/07/24 04:28:13 DEBUG : Waiting for deletions to finish
2026/07/24 04:28:13 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/24 04:28:13 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/24 04:28:13 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/24 04:28:13 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/24 04:28:14 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/24 04:28:14 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/24 04:28:14 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/24 04:28:14 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/24 04:28:16 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/24 04:28:18 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/24 04:28:20 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/24 04:28:22 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/24 04:28:24 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/24 04:28:26 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/24 04:28:27 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/24 04:28:28 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/24 04:28:28 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/24 04:28:30 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/07/24 04:28:31 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/24 04:28:33 DEBUG : pacer: Reducing sleep to 200ms
--- PASS: TestSyncCompareDest (53.55s)
PASS
2026/07/24 04:28:37 DEBUG : Linkbox root 'rclone-test-kofegav2bili': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestMoveOverSelf|TestSyncCompareDest)$'" - Finished OK in 1m11.379110101s (try 5/5)