"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCopyOverSelf|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestServerSideMoveDeleteEmptySourceDirs|TestSyncDeleteBefore|TestSyncDeleteDuring|TestSyncWithTrackRenamesStrategyModtime)$'" - Starting (try 4/5) 2026/08/07 03:47:45 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-sagiwas5rota" 2026/08/07 03:47:45 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/07 03:47:45 DEBUG : Linkbox root 'rclone-test-sagiwas5rota': Using cached web token 2026/08/07 03:47:46 DEBUG : Creating backend with remote "/tmp/rclone339294497" === RUN TestCopyOverSelf run.go:198: Remote "Linkbox root 'rclone-test-sagiwas5rota'", Local "Local file system at /tmp/rclone339294497", Modify Window "876000h0m0s" 2026/08/07 03:47:51 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 03:47:51 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/08/07 03:47:52 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10) 2026/08/07 03:47:53 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone339294497) 2026/08/07 03:47:53 DEBUG : sub dir/hello world: size = 11 (Linkbox root 'rclone-test-sagiwas5rota') 2026/08/07 03:47:53 DEBUG : sub dir/hello world: Sizes differ 2026/08/07 03:47:53 DEBUG : Linkbox root 'rclone-test-sagiwas5rota': Waiting for checks to finish 2026/08/07 03:47:53 DEBUG : Linkbox root 'rclone-test-sagiwas5rota': Waiting for transfers to finish 2026/08/07 03:47:53 DEBUG : sub dir/hello world: Update: removing old file 2026/08/07 03:47:56 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 03:47:57 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/08/07 03:47:57 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10) 2026/08/07 03:47:58 DEBUG : sub dir/hello world: size = 17 OK 2026/08/07 03:47:58 INFO : sub dir/hello world: Copied (replaced existing) run.go:186: removing dir "sub dir" failed - try 1/3: sleeping 1s: directory not empty run.go:186: removing dir "sub dir" failed - try 2/3: sleeping 1.5s: directory not empty --- PASS: TestCopyOverSelf (18.63s) === RUN TestSyncDeleteDuring run.go:198: Remote "Linkbox root 'rclone-test-sagiwas5rota'", Local "Local file system at /tmp/rclone339294497", Modify Window "876000h0m0s" 2026/08/07 03:48:06 DEBUG : potato: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 03:48:09 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 03:48:09 DEBUG : Waiting for deletions to finish 2026/08/07 03:48:09 DEBUG : potato2: Need to transfer - File not found at Destination 2026/08/07 03:48:09 DEBUG : empty space: size = 1 OK 2026/08/07 03:48:09 DEBUG : empty space: Sizes identical 2026/08/07 03:48:09 DEBUG : empty space: Unchanged skipping 2026/08/07 03:48:09 DEBUG : Linkbox root 'rclone-test-sagiwas5rota': Waiting for checks to finish 2026/08/07 03:48:09 DEBUG : Linkbox root 'rclone-test-sagiwas5rota': Waiting for transfers to finish 2026/08/07 03:48:11 INFO : potato: Deleted 2026/08/07 03:48:12 DEBUG : potato2: size = 60 OK 2026/08/07 03:48:12 INFO : potato2: Copied (new) 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 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: TestSyncDeleteDuring (25.78s) === RUN TestSyncDeleteBefore run.go:198: Remote "Linkbox root 'rclone-test-sagiwas5rota'", Local "Local file system at /tmp/rclone339294497", Modify Window "876000h0m0s" 2026/08/07 03:48:32 DEBUG : potato: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 03:48:33 DEBUG : potato: Trying to read object after upload: try again in 200ms (2/10) 2026/08/07 03:48:33 DEBUG : potato: Trying to read object after upload: try again in 400ms (3/10) 2026/08/07 03:48:36 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 03:48:36 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) 2026/08/07 03:48:37 DEBUG : Waiting for deletions to finish 2026/08/07 03:48:37 DEBUG : Linkbox root 'rclone-test-sagiwas5rota': Waiting for checks to finish 2026/08/07 03:48:37 DEBUG : Linkbox root 'rclone-test-sagiwas5rota': Waiting for transfers to finish 2026/08/07 03:48:38 INFO : potato: Deleted 2026/08/07 03:48:38 DEBUG : potato2: Need to transfer - File not found at Destination 2026/08/07 03:48:38 DEBUG : empty space: size = 1 OK 2026/08/07 03:48:38 DEBUG : empty space: Sizes identical 2026/08/07 03:48:38 DEBUG : empty space: Unchanged skipping 2026/08/07 03:48:38 DEBUG : Linkbox root 'rclone-test-sagiwas5rota': Waiting for checks to finish 2026/08/07 03:48:38 DEBUG : Linkbox root 'rclone-test-sagiwas5rota': Waiting for transfers to finish 2026/08/07 03:48:40 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/07 03:48:40 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/07 03:48:40 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/07 03:48:40 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/07 03:48:41 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/07 03:48:41 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/07 03:48:42 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/07 03:48:42 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/07 03:48:43 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/07 03:48:43 DEBUG : potato2: size = 60 OK 2026/08/07 03:48:43 INFO : potato2: Copied (new) 2026/08/07 03:48:45 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/07 03:48:47 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/07 03:48:49 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/08/07 03:48:50 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/08/07 03:48:51 DEBUG : pacer: Reducing sleep to 355.957031ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/08/07 03:48:52 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/08/07 03:48:52 DEBUG : pacer: Reducing sleep to 200.225829ms fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestSyncDeleteBefore (28.09s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:198: Remote "Linkbox root 'rclone-test-sagiwas5rota'", Local "Local file system at /tmp/rclone339294497", Modify Window "876000h0m0s" sync_test.go:1641: Can track renames: false 2026/08/07 03:48:58 ERROR : Linkbox root 'rclone-test-sagiwas5rota': Ignoring --track-renames as the destination does not support server-side move or copy 2026/08/07 03:48:58 ERROR : Linkbox root 'rclone-test-sagiwas5rota': Ignoring --track-renames as either the source or destination do not support modtime 2026/08/07 03:48:58 DEBUG : pacer: Reducing sleep to 200ms 2026/08/07 03:48:58 DEBUG : potato: Need to transfer - File not found at Destination 2026/08/07 03:48:58 DEBUG : yam: Need to transfer - File not found at Destination 2026/08/07 03:48:58 DEBUG : Linkbox root 'rclone-test-sagiwas5rota': Waiting for checks to finish 2026/08/07 03:48:58 DEBUG : Linkbox root 'rclone-test-sagiwas5rota': Waiting for transfers to finish 2026/08/07 03:49:01 DEBUG : potato: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 03:49:01 DEBUG : yam: size = 11 OK 2026/08/07 03:49:01 INFO : yam: Copied (new) 2026/08/07 03:49:01 DEBUG : potato: Trying to read object after upload: try again in 200ms (2/10) 2026/08/07 03:49:02 DEBUG : potato: size = 14 OK 2026/08/07 03:49:02 INFO : potato: Copied (new) 2026/08/07 03:49:02 DEBUG : Waiting for deletions to finish 2026/08/07 03:49:02 ERROR : Linkbox root 'rclone-test-sagiwas5rota': Ignoring --track-renames as the destination does not support server-side move or copy 2026/08/07 03:49:02 ERROR : Linkbox root 'rclone-test-sagiwas5rota': Ignoring --track-renames as either the source or destination do not support modtime 2026/08/07 03:49:02 DEBUG : yaml: Need to transfer - File not found at Destination 2026/08/07 03:49:02 DEBUG : potato: size = 14 OK 2026/08/07 03:49:02 DEBUG : potato: Sizes identical 2026/08/07 03:49:02 DEBUG : potato: Unchanged skipping 2026/08/07 03:49:02 DEBUG : Linkbox root 'rclone-test-sagiwas5rota': Waiting for checks to finish 2026/08/07 03:49:02 DEBUG : Linkbox root 'rclone-test-sagiwas5rota': Waiting for transfers to finish 2026/08/07 03:49:04 DEBUG : yaml: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 03:49:05 DEBUG : yaml: Trying to read object after upload: try again in 200ms (2/10) 2026/08/07 03:49:05 DEBUG : yaml: Trying to read object after upload: try again in 400ms (3/10) 2026/08/07 03:49:06 DEBUG : yaml: size = 11 OK 2026/08/07 03:49:06 INFO : yaml: Copied (new) 2026/08/07 03:49:06 DEBUG : Waiting for deletions to finish 2026/08/07 03:49:07 INFO : yam: 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 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: TestSyncWithTrackRenamesStrategyModtime (27.55s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:198: Remote "Linkbox root 'rclone-test-sagiwas5rota'", Local "Local file system at /tmp/rclone339294497", Modify Window "876000h0m0s" 2026/08/07 03:49:26 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-howizis2dena" 2026/08/07 03:49:26 DEBUG : Config file has changed externally - reloading 2026/08/07 03:49:26 DEBUG : Linkbox root 'rclone-test-howizis2dena': Using cached web token 2026/08/07 03:49:31 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 03:49:31 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/07 03:49:31 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/07 03:49:31 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/07 03:49:31 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/07 03:49:32 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/07 03:49:32 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/07 03:49:32 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/07 03:49:32 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/07 03:49:34 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/08/07 03:49:36 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/08/07 03:49:38 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/08/07 03:49:40 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/08/07 03:49:42 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/07 03:49:44 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/07 03:49:46 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/07 03:49:48 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/08/07 03:49:48 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/08/07 03:49:48 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 03:49:49 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/07 03:49:49 INFO : tomatoDir: Making directory 2026/08/07 03:49:49 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/08/07 03:49:51 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/08/07 03:49:51 DEBUG : pacer: Reducing sleep to 200ms sync_test.go:1736: Server side move (if possible) Linkbox root 'rclone-test-sagiwas5rota' -> Linkbox root 'rclone-test-howizis2dena' 2026/08/07 03:49:55 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 03:49:57 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 03:49:58 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/08/07 03:49:58 DEBUG : potato3: Trying to read object after upload: try again in 400ms (3/10) 2026/08/07 03:49:59 DEBUG : potato3: Trying to read object after upload: try again in 800ms (4/10) 2026/08/07 03:50:00 DEBUG : potato2: Need to transfer - File not found at Destination 2026/08/07 03:50:00 DEBUG : empty space: size = 1 OK 2026/08/07 03:50:00 DEBUG : empty space: Sizes identical 2026/08/07 03:50:00 DEBUG : empty space: Unchanged skipping 2026/08/07 03:50:00 DEBUG : potato3: size = 68 (Linkbox root 'rclone-test-sagiwas5rota') 2026/08/07 03:50:00 DEBUG : potato3: size = 60 (Linkbox root 'rclone-test-howizis2dena') 2026/08/07 03:50:00 DEBUG : potato3: Sizes differ 2026/08/07 03:50:00 DEBUG : Linkbox root 'rclone-test-howizis2dena': Waiting for checks to finish 2026/08/07 03:50:00 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 1344h40m23.845559859s different from this computer 2026/08/07 03:50:01 DEBUG : potato3: Update: removing old file 2026/08/07 03:50:02 INFO : empty space: Deleted 2026/08/07 03:50:02 DEBUG : Linkbox root 'rclone-test-howizis2dena': Waiting for transfers to finish 2026/08/07 03:50:03 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 03:50:03 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/08/07 03:50:03 DEBUG : potato2: Trying to read object after upload: try again in 400ms (3/10) 2026/08/07 03:50:04 DEBUG : potato2: size = 60 OK 2026/08/07 03:50:04 INFO : potato2: Copied (new) 2026/08/07 03:50:04 DEBUG : potato3: size = 68 (Linkbox root 'rclone-test-sagiwas5rota') 2026/08/07 03:50:04 DEBUG : potato3: size = 60 (Linkbox root 'rclone-test-howizis2dena') 2026/08/07 03:50:04 ERROR : potato3: corrupted on transfer: sizes differ src(Linkbox root 'rclone-test-sagiwas5rota') 68 vs dst(Linkbox root 'rclone-test-howizis2dena') 60 2026/08/07 03:50:04 INFO : potato3: Removing failed copy 2026/08/07 03:50:04 INFO : potato3: Failed to remove failed copy: could not Remove: Linkbox error 403: not permit 2026/08/07 03:50:04 ERROR : potato3: Not deleting source as copy failed: corrupted on transfer: sizes differ src(Linkbox root 'rclone-test-sagiwas5rota') 68 vs dst(Linkbox root 'rclone-test-howizis2dena') 60 2026/08/07 03:50:05 INFO : potato2: Deleted 2026/08/07 03:50:05 ERROR : Linkbox root 'rclone-test-sagiwas5rota': not deleting directories as there were IO errors sync_test.go:1747: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1747 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1932 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestServerSideMoveDeleteEmptySourceDirs 2026/08/07 03:50:05 DEBUG : Linkbox root 'rclone-test-howizis2dena': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (44.41s) === RUN TestNothingToTransferWithEmptyDirs run.go:198: Remote "Linkbox root 'rclone-test-sagiwas5rota'", Local "Local file system at /tmp/rclone339294497", Modify Window "876000h0m0s" 2026/08/07 03:50:10 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/08/07 03:50:10 INFO : sub dir: Making directory 2026/08/07 03:50:12 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 run.go:186: removing dir "sub dir2/very/very/very/very/very/nested" failed - try 1/3: sleeping 1s: directory not empty run.go:186: removing dir "sub dir2/very/very/very" failed - try 1/3: sleeping 1s: directory not empty run.go:186: removing dir "sub dir2/very/very" failed - try 1/3: sleeping 1s: directory not empty run.go:186: removing dir "sub dir2/very" failed - try 1/3: sleeping 1s: directory not empty --- PASS: TestNothingToTransferWithEmptyDirs (64.97s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:198: Remote "Linkbox root 'rclone-test-sagiwas5rota'", Local "Local file system at /tmp/rclone339294497", Modify Window "876000h0m0s" 2026/08/07 03:51:15 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/08/07 03:51:15 INFO : sub dir: Making directory 2026/08/07 03:51:17 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC 2026/08/07 03:51:49 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/07 03:51:50 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/08/07 03:51:50 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/08/07 03:51:50 DEBUG : pacer: Reducing sleep to 200ms run.go:186: removing dir "sub dir2/very/very/very/very/very/nested/subdir" failed - try 1/3: sleeping 1s: directory not empty run.go:186: removing dir "sub dir2/very/very/very/very" failed - try 1/3: sleeping 1s: directory not empty run.go:186: removing dir "sub dir2/very/very/very" failed - try 1/3: sleeping 1s: directory not empty run.go:186: removing dir "sub dir2/very/very" failed - try 1/3: sleeping 1s: directory not empty run.go:186: removing dir "sub dir2/very" failed - try 1/3: sleeping 1s: directory not empty run.go:186: removing dir "sub dir2" failed - try 1/3: sleeping 1s: directory not empty 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: TestNothingToTransferWithoutEmptyDirs (68.49s) FAIL 2026/08/07 03:52:24 DEBUG : Linkbox root 'rclone-test-sagiwas5rota': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCopyOverSelf|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestServerSideMoveDeleteEmptySourceDirs|TestSyncDeleteBefore|TestSyncDeleteDuring|TestSyncWithTrackRenamesStrategyModtime)$'" - Finished ERROR in 4m40.318251015s (try 4/5): exit status 1: Failed [TestServerSideMoveDeleteEmptySourceDirs]