"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestMoveOverSelf|TestNothingToTransferWithEmptyDirs|TestServerSideCopyOverSelf|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncIgnoreTimes|TestSyncUTFNorm)$'" - Starting (try 3/5)
2026/08/08 05:11:27 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-mugicaw3veso"
2026/08/08 05:11:27 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/08/08 05:11:27 DEBUG : Linkbox root 'rclone-test-mugicaw3veso': Using cached web token
2026/08/08 05:11:28 DEBUG : Creating backend with remote "/tmp/rclone3602545165"
=== RUN   TestServerSideCopyOverSelf
    run.go:198: Remote "Linkbox root 'rclone-test-mugicaw3veso'", Local "Local file system at /tmp/rclone3602545165", Modify Window "876000h0m0s"
2026/08/08 05:11:33 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:11:34 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-lupenat9kusu"
2026/08/08 05:11:34 DEBUG : Linkbox root 'rclone-test-lupenat9kusu': Using cached web token
    sync_test.go:656: Server side copy (if possible) Linkbox root 'rclone-test-mugicaw3veso' -> Linkbox root 'rclone-test-lupenat9kusu'
2026/08/08 05:11:35 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/08/08 05:11:35 DEBUG : Linkbox root 'rclone-test-lupenat9kusu': Waiting for checks to finish
2026/08/08 05:11:35 DEBUG : Linkbox root 'rclone-test-lupenat9kusu': Waiting for transfers to finish
2026/08/08 05:11:35 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 1370h8m58.906575904s different from this computer
2026/08/08 05:11:41 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:11:41 DEBUG : sub dir/hello world: size = 11 OK
2026/08/08 05:11:41 INFO  : sub dir/hello world: Copied (new)
2026/08/08 05:11:42 DEBUG : sub dir/hello world: Update: removing old file
2026/08/08 05:11:45 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:11:45 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10)
2026/08/08 05:11:46 DEBUG : Linkbox root 'rclone-test-lupenat9kusu': Waiting for checks to finish
2026/08/08 05:11:46 DEBUG : sub dir/hello world: size = 17 (Linkbox root 'rclone-test-mugicaw3veso')
2026/08/08 05:11:46 DEBUG : sub dir/hello world: size = 11 (Linkbox root 'rclone-test-lupenat9kusu')
2026/08/08 05:11:46 DEBUG : sub dir/hello world: Sizes differ
2026/08/08 05:11:46 DEBUG : Linkbox root 'rclone-test-lupenat9kusu': Waiting for transfers to finish
2026/08/08 05:11:46 DEBUG : sub dir/hello world: Update: removing old file
2026/08/08 05:11:50 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:11:50 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10)
2026/08/08 05:11:50 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10)
2026/08/08 05:11:51 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 800ms (4/10)
2026/08/08 05:11:52 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 1.6s (5/10)
2026/08/08 05:11:54 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 3.2s (6/10)
2026/08/08 05:11:57 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 6.4s (7/10)
2026/08/08 05:12:04 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 12.8s (8/10)
2026/08/08 05:12:17 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 25.6s (9/10)
2026/08/08 05:12:43 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 51.2s (10/10)
2026/08/08 05:13:34 ERROR : sub dir/hello world: Failed to copy: object not found
    sync_test.go:669: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:669
        	Error:      	Received unexpected error:
        	            	object not found
        	Test:       	TestServerSideCopyOverSelf
2026/08/08 05:13:34 DEBUG : Linkbox root 'rclone-test-lupenat9kusu': Purge remote
    run.go:186: removing dir "sub dir" failed - try 1/3: sleeping 1s: directory not empty
--- FAIL: TestServerSideCopyOverSelf (132.99s)
=== RUN   TestMoveOverSelf
    run.go:198: Remote "Linkbox root 'rclone-test-mugicaw3veso'", Local "Local file system at /tmp/rclone3602545165", Modify Window "876000h0m0s"
2026/08/08 05:13:45 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:13:45 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10)
2026/08/08 05:13:45 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10)
2026/08/08 05:13:47 DEBUG : Linkbox root 'rclone-test-mugicaw3veso': Waiting for checks to finish
2026/08/08 05:13:47 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone3602545165)
2026/08/08 05:13:47 DEBUG : sub dir/hello world: size = 11 (Linkbox root 'rclone-test-mugicaw3veso')
2026/08/08 05:13:47 DEBUG : sub dir/hello world: Sizes differ
2026/08/08 05:13:47 DEBUG : Linkbox root 'rclone-test-mugicaw3veso': Waiting for transfers to finish
2026/08/08 05:13:47 DEBUG : sub dir/hello world: Update: removing old file
2026/08/08 05:13:50 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:13:50 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10)
2026/08/08 05:13:51 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10)
2026/08/08 05:13:52 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 800ms (4/10)
2026/08/08 05:13:53 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 1.6s (5/10)
2026/08/08 05:13:54 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 3.2s (6/10)
2026/08/08 05:13:58 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 6.4s (7/10)
2026/08/08 05:14:04 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 12.8s (8/10)
2026/08/08 05:14:17 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 25.6s (9/10)
2026/08/08 05:14:43 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 51.2s (10/10)
2026/08/08 05:15:34 ERROR : sub dir/hello world: Failed to copy: object not found
2026/08/08 05:15:34 ERROR : sub dir/hello world: Not deleting source as copy failed: object not found
    sync_test.go:685: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:685
        	Error:      	Received unexpected error:
        	            	object not found
        	Test:       	TestMoveOverSelf
    run.go:186: removing dir "sub dir" failed - try 1/3: sleeping 1s: directory not empty
--- FAIL: TestMoveOverSelf (118.39s)
=== RUN   TestServerSideMoveOverSelf
    run.go:198: Remote "Linkbox root 'rclone-test-mugicaw3veso'", Local "Local file system at /tmp/rclone3602545165", Modify Window "876000h0m0s"
2026/08/08 05:15:43 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-piyubim9wuce"
2026/08/08 05:15:43 DEBUG : Config file has changed externally - reloading
2026/08/08 05:15:43 DEBUG : Linkbox root 'rclone-test-piyubim9wuce': Using cached web token
    sync_test.go:701: Server side copy (if possible) Linkbox root 'rclone-test-mugicaw3veso' -> Linkbox root 'rclone-test-piyubim9wuce'
2026/08/08 05:15:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/08/08 05:15:45 DEBUG : Linkbox root 'rclone-test-piyubim9wuce': Waiting for checks to finish
2026/08/08 05:15:45 DEBUG : Linkbox root 'rclone-test-piyubim9wuce': Waiting for transfers to finish
2026/08/08 05:15:51 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:15:51 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10)
2026/08/08 05:15:52 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10)
2026/08/08 05:15:52 DEBUG : sub dir/hello world: size = 11 OK
2026/08/08 05:15:52 INFO  : sub dir/hello world: Copied (new)
2026/08/08 05:15:53 DEBUG : sub dir/hello world: Update: removing old file
2026/08/08 05:15:56 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:15:57 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10)
2026/08/08 05:15:58 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:15:58 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/08 05:15:58 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:15:58 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/08 05:15:58 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:15:58 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/08 05:15:58 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:15:58 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/08 05:15:58 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:15:58 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/08 05:15:58 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:15:58 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/08 05:15:59 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:15:59 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/08 05:15:59 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:15:59 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/08 05:16:01 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:16:01 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:16:03 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:16:03 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:16:05 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:16:05 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:16:07 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/08 05:16:07 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/08 05:16:09 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/08 05:16:09 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/08 05:16:09 DEBUG : sub dir/hello world: size = 17 (Linkbox root 'rclone-test-mugicaw3veso')
2026/08/08 05:16:09 DEBUG : sub dir/hello world: size = 11 (Linkbox root 'rclone-test-piyubim9wuce')
2026/08/08 05:16:09 DEBUG : sub dir/hello world: Sizes differ
2026/08/08 05:16:09 DEBUG : Linkbox root 'rclone-test-piyubim9wuce': Waiting for checks to finish
2026/08/08 05:16:09 DEBUG : Linkbox root 'rclone-test-piyubim9wuce': Waiting for transfers to finish
2026/08/08 05:16:10 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/08 05:16:10 DEBUG : sub dir/hello world: Update: removing old file
2026/08/08 05:16:11 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/08 05:16:12 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/08 05:16:14 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/08 05:16:14 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/08 05:16:14 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:16:14 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/08 05:16:14 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10)
2026/08/08 05:16:15 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/08 05:16:15 DEBUG : sub dir/hello world: size = 17 OK
2026/08/08 05:16:15 INFO  : sub dir/hello world: Copied (replaced existing)
2026/08/08 05:16:16 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/08 05:16:16 INFO  : sub dir/hello world: Deleted
2026/08/08 05:16:16 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/08 05:16:17 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/08 05:16:17 DEBUG : pacer: Reducing sleep to 200ms
2026/08/08 05:16:18 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/08 05:16:18 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/08 05:16:19 DEBUG : pacer: Reducing sleep to 200ms
2026/08/08 05:16:20 DEBUG : testing file moves
2026/08/08 05:16:20 DEBUG : Linkbox root 'rclone-test-piyubim9wuce': Waiting for checks to finish
2026/08/08 05:16:20 DEBUG : sub dir/hello world: size = 24 (Linkbox root 'rclone-test-mugicaw3veso')
2026/08/08 05:16:20 DEBUG : sub dir/hello world: size = 17 (Linkbox root 'rclone-test-piyubim9wuce')
2026/08/08 05:16:20 DEBUG : sub dir/hello world: Sizes differ
2026/08/08 05:16:20 DEBUG : Linkbox root 'rclone-test-piyubim9wuce': Waiting for transfers to finish
2026/08/08 05:16:20 DEBUG : sub dir/hello world: Update: removing old file
2026/08/08 05:16:24 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:16:24 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10)
2026/08/08 05:16:24 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10)
2026/08/08 05:16:25 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 800ms (4/10)
2026/08/08 05:16:26 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 1.6s (5/10)
2026/08/08 05:16:28 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 3.2s (6/10)
2026/08/08 05:16:31 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 6.4s (7/10)
2026/08/08 05:16:38 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 12.8s (8/10)
2026/08/08 05:16:51 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 25.6s (9/10)
2026/08/08 05:17:17 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 51.2s (10/10)
2026/08/08 05:18:08 ERROR : sub dir/hello world: Failed to copy: object not found
2026/08/08 05:18:08 ERROR : sub dir/hello world: Not deleting source as copy failed: object not found
    sync_test.go:726: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:726
        	Error:      	Received unexpected error:
        	            	object not found
        	Test:       	TestServerSideMoveOverSelf
2026/08/08 05:18:08 DEBUG : Linkbox root 'rclone-test-piyubim9wuce': Purge remote
--- FAIL: TestServerSideMoveOverSelf (154.07s)
=== RUN   TestSyncIgnoreTimes
    run.go:198: Remote "Linkbox root 'rclone-test-mugicaw3veso'", Local "Local file system at /tmp/rclone3602545165", Modify Window "876000h0m0s"
2026/08/08 05:18:15 DEBUG : existing: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:18:16 DEBUG : existing: Trying to read object after upload: try again in 200ms (2/10)
2026/08/08 05:18:16 DEBUG : existing: size = 6 OK
2026/08/08 05:18:16 DEBUG : existing: Sizes identical
2026/08/08 05:18:16 DEBUG : existing: Unchanged skipping
2026/08/08 05:18:16 DEBUG : Linkbox root 'rclone-test-mugicaw3veso': Waiting for checks to finish
2026/08/08 05:18:16 DEBUG : Linkbox root 'rclone-test-mugicaw3veso': Waiting for transfers to finish
2026/08/08 05:18:16 DEBUG : Waiting for deletions to finish
2026/08/08 05:18:16 INFO  : There was nothing to transfer
2026/08/08 05:18:17 DEBUG : existing: Transferring unconditionally as --ignore-times is in use
2026/08/08 05:18:17 DEBUG : Linkbox root 'rclone-test-mugicaw3veso': Waiting for checks to finish
2026/08/08 05:18:17 DEBUG : Linkbox root 'rclone-test-mugicaw3veso': Waiting for transfers to finish
2026/08/08 05:18:17 DEBUG : existing: Update: removing old file
2026/08/08 05:18:20 DEBUG : existing: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:18:20 DEBUG : existing: size = 6 OK
2026/08/08 05:18:20 INFO  : existing: Copied (replaced existing)
2026/08/08 05:18:20 DEBUG : Waiting for deletions to finish
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestSyncIgnoreTimes (16.71s)
=== RUN   TestServerSideMoveWithFilter
    run.go:198: Remote "Linkbox root 'rclone-test-mugicaw3veso'", Local "Local file system at /tmp/rclone3602545165", Modify Window "876000h0m0s"
2026/08/08 05:18:30 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-xepodok0deko"
2026/08/08 05:18:30 DEBUG : Config file has changed externally - reloading
2026/08/08 05:18:30 DEBUG : Linkbox root 'rclone-test-xepodok0deko': Using cached web token
2026/08/08 05:18:35 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:18:35 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10)
2026/08/08 05:18:38 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:18:38 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10)
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
    sync_test.go:1736: Server side move (if possible) Linkbox root 'rclone-test-mugicaw3veso' -> Linkbox root 'rclone-test-xepodok0deko'
2026/08/08 05:18:52 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:18:53 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10)
2026/08/08 05:18:53 DEBUG : potato3: Trying to read object after upload: try again in 400ms (3/10)
2026/08/08 05:18:54 DEBUG : potato3: Trying to read object after upload: try again in 800ms (4/10)
2026/08/08 05:18:55 DEBUG : potato3: Trying to read object after upload: try again in 1.6s (5/10)
2026/08/08 05:18:57 DEBUG : empty space: Excluded (Size Filter)
2026/08/08 05:18:57 DEBUG : empty space: Excluded
2026/08/08 05:18:57 DEBUG : empty space: Excluded (Size Filter)
2026/08/08 05:18:57 DEBUG : empty space: Excluded
2026/08/08 05:18:57 DEBUG : potato2: Need to transfer - File not found at Destination
2026/08/08 05:18:57 DEBUG : potato3: size = 68 (Linkbox root 'rclone-test-mugicaw3veso')
2026/08/08 05:18:57 DEBUG : potato3: size = 60 (Linkbox root 'rclone-test-xepodok0deko')
2026/08/08 05:18:57 DEBUG : potato3: Sizes differ
2026/08/08 05:18:57 DEBUG : Linkbox root 'rclone-test-xepodok0deko': Waiting for checks to finish
2026/08/08 05:18:57 DEBUG : Linkbox root 'rclone-test-xepodok0deko': Waiting for transfers to finish
2026/08/08 05:18:57 DEBUG : potato3: Update: removing old file
2026/08/08 05:18:58 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:18:58 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/08 05:18:58 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:18:58 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/08 05:18:58 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:18:58 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/08 05:18:59 DEBUG : pacer: Reducing sleep to 1.2s
2026/08/08 05:18:59 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:18:59 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/08 05:19:00 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:19:02 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:19:04 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:19:06 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:19:08 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:19:10 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:19:12 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:19:14 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:19:16 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:19:20 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/08 05:19:21 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/08 05:19:22 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/08 05:19:22 DEBUG : potato2: size = 60 OK
2026/08/08 05:19:22 INFO  : potato2: Copied (new)
2026/08/08 05:19:23 INFO  : potato2: Deleted
2026/08/08 05:19:24 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/08 05:19:25 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/08 05:19:25 DEBUG : potato3: size = 68 OK
2026/08/08 05:19:25 INFO  : potato3: Copied (replaced existing)
2026/08/08 05:19:26 INFO  : potato3: Deleted
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
2026/08/08 05:19:34 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/08 05:19:34 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-vewagag7rigu"
2026/08/08 05:19:34 DEBUG : Linkbox root 'rclone-test-vewagag7rigu': Using cached web token
2026/08/08 05:19:35 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/08 05:19:35 DEBUG : empty space: Excluded (Size Filter)
2026/08/08 05:19:35 DEBUG : empty space: Excluded
2026/08/08 05:19:36 DEBUG : potato2: Need to transfer - File not found at Destination
2026/08/08 05:19:36 DEBUG : potato3: Need to transfer - File not found at Destination
2026/08/08 05:19:36 DEBUG : Linkbox root 'rclone-test-vewagag7rigu': Waiting for checks to finish
2026/08/08 05:19:36 DEBUG : Linkbox root 'rclone-test-vewagag7rigu': Waiting for transfers to finish
2026/08/08 05:19:36 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/08 05:19:36 DEBUG : pacer: Reducing sleep to 200ms
2026/08/08 05:19:40 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:19:40 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:19:40 DEBUG : potato2: size = 60 OK
2026/08/08 05:19:40 INFO  : potato2: Copied (new)
2026/08/08 05:19:40 DEBUG : potato3: size = 68 OK
2026/08/08 05:19:40 INFO  : potato3: Copied (new)
2026/08/08 05:19:41 INFO  : potato2: Deleted
2026/08/08 05:19:42 INFO  : potato3: Deleted
2026/08/08 05:19:42 DEBUG : Linkbox root 'rclone-test-vewagag7rigu': Purge remote
2026/08/08 05:19:43 DEBUG : Linkbox root 'rclone-test-xepodok0deko': Purge remote
--- PASS: TestServerSideMoveWithFilter (76.53s)
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:198: Remote "Linkbox root 'rclone-test-mugicaw3veso'", Local "Local file system at /tmp/rclone3602545165", Modify Window "876000h0m0s"
2026/08/08 05:19:46 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-kojalim8vute"
2026/08/08 05:19:46 DEBUG : Linkbox root 'rclone-test-kojalim8vute': Using cached web token
2026/08/08 05:19:55 INFO  : tomatoDir: Making directory
    sync_test.go:1736: Server side move (if possible) Linkbox root 'rclone-test-mugicaw3veso' -> Linkbox root 'rclone-test-kojalim8vute'
2026/08/08 05:20:00 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:20:03 DEBUG : potato2: Need to transfer - File not found at Destination
2026/08/08 05:20:03 DEBUG : empty space: size = 1 OK
2026/08/08 05:20:03 DEBUG : empty space: Sizes identical
2026/08/08 05:20:03 DEBUG : empty space: Unchanged skipping
2026/08/08 05:20:03 DEBUG : potato3: size = 68 (Linkbox root 'rclone-test-mugicaw3veso')
2026/08/08 05:20:03 DEBUG : potato3: size = 60 (Linkbox root 'rclone-test-kojalim8vute')
2026/08/08 05:20:03 DEBUG : potato3: Sizes differ
2026/08/08 05:20:03 DEBUG : Linkbox root 'rclone-test-kojalim8vute': Waiting for checks to finish
2026/08/08 05:20:04 DEBUG : potato3: Update: removing old file
2026/08/08 05:20:05 INFO  : empty space: Deleted
2026/08/08 05:20:05 DEBUG : Linkbox root 'rclone-test-kojalim8vute': Waiting for transfers to finish
2026/08/08 05:20:06 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10)
2026/08/08 05:20:06 DEBUG : potato2: size = 60 OK
2026/08/08 05:20:06 INFO  : potato2: Copied (new)
2026/08/08 05:20:07 DEBUG : potato3: size = 68 OK
2026/08/08 05:20:07 INFO  : potato3: Copied (replaced existing)
2026/08/08 05:20:07 INFO  : potato2: Deleted
2026/08/08 05:20:09 INFO  : potato3: Deleted
2026/08/08 05:20:09 INFO  : tomatoDir: Removing directory
2026/08/08 05:20:10 DEBUG : Linkbox root 'rclone-test-mugicaw3veso': deleted 1 directories
2026/08/08 05:20:11 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-huwomav9xuna"
2026/08/08 05:20:11 DEBUG : Linkbox root 'rclone-test-huwomav9xuna': Using cached web token
2026/08/08 05:20:12 INFO  : tomatoDir: Making directory
2026/08/08 05:20:14 DEBUG : empty space: Need to transfer - File not found at Destination
2026/08/08 05:20:14 DEBUG : potato2: Need to transfer - File not found at Destination
2026/08/08 05:20:14 DEBUG : potato3: Need to transfer - File not found at Destination
2026/08/08 05:20:14 DEBUG : Linkbox root 'rclone-test-huwomav9xuna': Waiting for checks to finish
2026/08/08 05:20:14 DEBUG : Linkbox root 'rclone-test-huwomav9xuna': Waiting for transfers to finish
2026/08/08 05:20:18 DEBUG : empty space: size = 1 OK
2026/08/08 05:20:18 INFO  : empty space: Copied (new)
2026/08/08 05:20:19 DEBUG : potato2: size = 60 OK
2026/08/08 05:20:19 INFO  : potato2: Copied (new)
2026/08/08 05:20:19 DEBUG : potato3: size = 68 OK
2026/08/08 05:20:19 INFO  : potato3: Copied (new)
2026/08/08 05:20:20 INFO  : empty space: Deleted
2026/08/08 05:20:20 INFO  : potato2: Deleted
2026/08/08 05:20:20 INFO  : potato3: Deleted
2026/08/08 05:20:20 INFO  : tomatoDir: Removing directory
2026/08/08 05:20:22 DEBUG : Linkbox root 'rclone-test-kojalim8vute': deleted 1 directories
2026/08/08 05:20:22 DEBUG : Linkbox root 'rclone-test-huwomav9xuna': Purge remote
2026/08/08 05:20:24 DEBUG : Linkbox root 'rclone-test-kojalim8vute': Purge remote
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (38.87s)
=== RUN   TestSyncUTFNorm
    run.go:198: Remote "Linkbox root 'rclone-test-mugicaw3veso'", Local "Local file system at /tmp/rclone3602545165", Modify Window "876000h0m0s"
2026/08/08 05:20:28 DEBUG : Testêé: size = 14 (Local file system at /tmp/rclone3602545165)
2026/08/08 05:20:28 DEBUG : Testêé: size = 18 (Linkbox root 'rclone-test-mugicaw3veso')
2026/08/08 05:20:28 DEBUG : Testêé: Sizes differ
2026/08/08 05:20:28 DEBUG : Linkbox root 'rclone-test-mugicaw3veso': Waiting for checks to finish
2026/08/08 05:20:28 DEBUG : Linkbox root 'rclone-test-mugicaw3veso': Waiting for transfers to finish
2026/08/08 05:20:28 DEBUG : Testêé: Update: removing old file
2026/08/08 05:20:31 DEBUG : Testêé: size = 14 OK
2026/08/08 05:20:31 INFO  : Testêé: Copied (replaced existing) to: Testêé
2026/08/08 05:20:31 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncUTFNorm (7.44s)
=== RUN   TestNothingToTransferWithEmptyDirs
    run.go:198: Remote "Linkbox root 'rclone-test-mugicaw3veso'", Local "Local file system at /tmp/rclone3602545165", Modify Window "876000h0m0s"
2026/08/08 05:20:33 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2026/08/08 05:20:33 INFO  : sub dir: Making directory
2026/08/08 05:20:34 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
    run.go:186: removing dir "sub dir2/very/very/very/very/very/nested/subdir" failed - try 1/3: sleeping 1s: directory not empty
2026/08/08 05:21:11 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:21:11 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/08 05:21:11 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:21:11 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/08 05:21:12 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:21:12 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/08 05:21:13 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:21:13 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/08 05:21:14 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:21:16 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/08/08 05:21:18 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/08 05:21:21 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/08 05:21:22 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/08 05:21:24 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/08 05:21:24 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/08 05:21:26 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/08 05:21:26 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/08 05:21:27 DEBUG : pacer: Reducing sleep to 200.225829ms
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
2026/08/08 05:21:28 DEBUG : pacer: Reducing sleep to 200ms
    fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestNothingToTransferWithEmptyDirs (61.47s)
FAIL
2026/08/08 05:21:34 DEBUG : Linkbox root 'rclone-test-mugicaw3veso': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestMoveOverSelf|TestNothingToTransferWithEmptyDirs|TestServerSideCopyOverSelf|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncIgnoreTimes|TestSyncUTFNorm)$'" - Finished ERROR in 10m8.92643405s (try 3/5): exit status 1: Failed [TestServerSideCopyOverSelf TestMoveOverSelf TestServerSideMoveOverSelf]