"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCopyDeleteBefore|TestCopyRedownload|TestCopyWithDepth|TestCopyWithFilesFrom|TestMoveOverSelf|TestMoveWithDeleteEmptySrcDirs|TestMoveWithIgnoreExisting|TestMoveWithoutDeleteEmptySrcDirs|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveLocal|TestServerSideMoveOverSelf|TestServerSideMoveOverlap|TestServerSideMoveWithFilter|TestSyncAfterChangingContentsOnly|TestSyncAfterChangingFilesSizeOnly|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncAfterRemovingAFileAndAddingAFileDryRun|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncCompareDest|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncCopyDest|TestSyncDeleteBefore|TestSyncDeleteDuring|TestSyncEmptyDirectories|TestSyncIgnoreCase|TestSyncImmutable|TestSyncMultipleCompareDest|TestSyncNoEmptyDirectories|TestSyncOverlap|TestSyncReplaceDirModTime|TestSyncReplaceDirModTimeWithEmptyDirs|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncUTFNorm|TestSyncWithExclude|TestSyncWithExcludeAndDeleteExcluded|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime|TestSyncWithUpdateOlder)$|^TestMaxTransfer$/^(Cautious|Hard|Soft)$'" - Starting (try 2/5) 2026/07/30 02:53:58 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-fecakiw9wode" 2026/07/30 02:53:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/30 02:53:58 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Using cached web token 2026/07/30 02:53:59 DEBUG : Creating backend with remote "/tmp/rclone1350656208" === RUN TestCopyWithDepth run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 02:53:59 DEBUG : hello world2: Need to transfer - File not found at Destination 2026/07/30 02:53:59 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 02:53:59 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 02:54:03 DEBUG : hello world2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 02:54:03 DEBUG : hello world2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 02:54:04 DEBUG : hello world2: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 02:54:04 DEBUG : hello world2: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 02:54:05 DEBUG : hello world2: size = 12 OK 2026/07/30 02:54:05 INFO : hello world2: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/07/30 02:54:08 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:54:08 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 02:54:08 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:54:08 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 02:54:09 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:54:09 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 02:54:10 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:54:10 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 02:54:11 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:54:13 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:54:15 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:54:17 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:54:20 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 02:54:21 DEBUG : pacer: Reducing sleep to 1.125s fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestCopyWithDepth (28.60s) === RUN TestCopyWithFilesFrom run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 02:54:28 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 02:54:28 DEBUG : potato2: Need to transfer - File not found at Destination 2026/07/30 02:54:28 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 02:54:28 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 02:54:29 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 02:54:30 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 02:54:31 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 02:54:32 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 02:54:32 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 02:54:32 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 02:54:32 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 02:54:32 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 02:54:32 DEBUG : potato2: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 02:54:33 DEBUG : potato2: size = 11 OK 2026/07/30 02:54:33 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 --- PASS: TestCopyWithFilesFrom (15.12s) === RUN TestSyncEmptyDirectories run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 02:54:43 DEBUG : sub dir2: Making directory with metadata 2026/07/30 02:54:43 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/30 02:54:43 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/30 02:54:43 INFO : sub dir2: Making directory 2026/07/30 02:54:44 INFO : sub dir: Making directory 2026/07/30 02:54:46 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/30 02:54:46 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 02:54:46 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 02:54:48 DEBUG : sub dir/hello world: size = 11 OK 2026/07/30 02:54:48 INFO : sub dir/hello world: Copied (new) 2026/07/30 02:54:48 DEBUG : Waiting for deletions to finish 2026/07/30 02:54:48 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (11.06s) === RUN TestSyncNoEmptyDirectories run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 02:54:54 INFO : sub dir2: Making directory 2026/07/30 02:54:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/30 02:54:54 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 02:54:54 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 02:54:57 DEBUG : sub dir/hello world: size = 11 OK 2026/07/30 02:54:57 INFO : sub dir/hello world: Copied (new) 2026/07/30 02:54:57 DEBUG : Waiting for deletions to finish 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: TestSyncNoEmptyDirectories (10.70s) === RUN TestServerSideCopyOverSelf run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 02:55:08 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 02:55:08 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 02:55:08 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 02:55:10 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-wekojay5kaza" 2026/07/30 02:55:10 DEBUG : Linkbox root 'rclone-test-wekojay5kaza': Using cached web token sync_test.go:656: Server side copy (if possible) Linkbox root 'rclone-test-fecakiw9wode' -> Linkbox root 'rclone-test-wekojay5kaza' 2026/07/30 02:55:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/30 02:55:11 DEBUG : Linkbox root 'rclone-test-wekojay5kaza': Waiting for checks to finish 2026/07/30 02:55:11 DEBUG : Linkbox root 'rclone-test-wekojay5kaza': Waiting for transfers to finish 2026/07/30 02:55:11 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 1151h52m34.748491622s different from this computer 2026/07/30 02:55:11 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:55:11 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 02:55:11 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:55:11 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 02:55:12 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:55:12 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 02:55:13 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:55:13 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 02:55:14 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:55:16 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:55:18 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:55:21 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 02:55:24 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 02:55:24 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 02:55:26 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 02:55:26 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 02:55:27 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 02:55:29 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 02:55:29 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 02:55:29 DEBUG : sub dir/hello world: size = 11 OK 2026/07/30 02:55:29 INFO : sub dir/hello world: Copied (new) 2026/07/30 02:55:29 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 02:55:30 DEBUG : sub dir/hello world: Update: removing old file 2026/07/30 02:55:33 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/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 2026/07/30 02:55:42 DEBUG : sub dir/hello world: size = 17 (Linkbox root 'rclone-test-fecakiw9wode') 2026/07/30 02:55:42 DEBUG : sub dir/hello world: size = 11 (Linkbox root 'rclone-test-wekojay5kaza') 2026/07/30 02:55:42 DEBUG : sub dir/hello world: Sizes differ 2026/07/30 02:55:42 DEBUG : Linkbox root 'rclone-test-wekojay5kaza': Waiting for checks to finish 2026/07/30 02:55:42 DEBUG : Linkbox root 'rclone-test-wekojay5kaza': Waiting for transfers to finish 2026/07/30 02:55:42 DEBUG : sub dir/hello world: Update: removing old file 2026/07/30 02:55:45 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 02:55:45 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 02:55:46 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 02:55:46 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 02:55:47 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/30 02:55:49 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/30 02:55:52 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/30 02:55:59 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/30 02:56:12 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:56:12 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 02:56:12 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:56:12 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 02:56:13 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:56:13 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 02:56:13 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:56:13 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 02:56:15 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:56:17 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:56:19 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:56:21 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 02:56:21 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/30 02:56:47 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 02:56:47 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 51.2s (10/10) 2026/07/30 02:57:38 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/07/30 02:57:38 DEBUG : Linkbox root 'rclone-test-wekojay5kaza': Purge remote 2026/07/30 02:57:39 DEBUG : pacer: Reducing sleep to 843.75ms run.go:186: removing dir "sub dir" failed - try 1/3: sleeping 1s: directory not empty --- FAIL: TestServerSideCopyOverSelf (159.93s) === RUN TestMoveOverSelf run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 02:57:49 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone1350656208) 2026/07/30 02:57:49 DEBUG : sub dir/hello world: size = 11 (Linkbox root 'rclone-test-fecakiw9wode') 2026/07/30 02:57:49 DEBUG : sub dir/hello world: Sizes differ 2026/07/30 02:57:49 DEBUG : sub dir/hello world: Update: removing old file 2026/07/30 02:57:49 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 02:57:49 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 02:57:52 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 02:57:52 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 02:57:52 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 02:57:53 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 02:57:54 DEBUG : sub dir/hello world: size = 17 OK 2026/07/30 02:57:54 INFO : sub dir/hello world: Copied (replaced existing) 2026/07/30 02:57:54 INFO : sub dir/hello world: Deleted 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 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: TestMoveOverSelf (24.51s) === RUN TestServerSideMoveOverSelf run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 02:58:12 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 02:58:13 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 02:58:13 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-moyahuh4gujo" 2026/07/30 02:58:13 DEBUG : Config file has changed externally - reloading 2026/07/30 02:58:13 DEBUG : Linkbox root 'rclone-test-moyahuh4gujo': Using cached web token sync_test.go:701: Server side copy (if possible) Linkbox root 'rclone-test-fecakiw9wode' -> Linkbox root 'rclone-test-moyahuh4gujo' 2026/07/30 02:58:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/30 02:58:15 DEBUG : Linkbox root 'rclone-test-moyahuh4gujo': Waiting for checks to finish 2026/07/30 02:58:15 DEBUG : Linkbox root 'rclone-test-moyahuh4gujo': Waiting for transfers to finish 2026/07/30 02:58:19 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:58:19 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 02:58:19 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:58:19 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 02:58:19 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:58:19 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 02:58:20 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:58:20 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 02:58:22 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 02:58:25 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 02:58:26 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 02:58:26 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 02:58:28 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 02:58:28 DEBUG : sub dir/hello world: size = 11 OK 2026/07/30 02:58:28 INFO : sub dir/hello world: Copied (new) 2026/07/30 02:58:29 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 02:58:29 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 02:58:30 DEBUG : sub dir/hello world: Update: removing old file 2026/07/30 02:58:33 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 02:58:33 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 02:58:33 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 02:58:34 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 02:58:35 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/30 02:58:37 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/30 02:58:40 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/30 02:58:47 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/30 02:59:00 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/30 02:59:26 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 51.2s (10/10) run.go:303: Failed to put "sub dir/hello world" to "Linkbox root 'rclone-test-fecakiw9wode'": object not found 2026/07/30 03:00:17 DEBUG : Linkbox root 'rclone-test-moyahuh4gujo': Purge remote 2026/07/30 03:00:18 DEBUG : pacer: Reducing sleep to 355.957031ms 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 --- FAIL: TestServerSideMoveOverSelf (136.21s) === RUN TestCopyRedownload run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:00:29 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:00:29 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:00:29 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:00:30 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:00:32 DEBUG : Added delayed dir = "sub dir", newDst= 2026/07/30 03:00:32 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/30 03:00:32 DEBUG : Local file system at /tmp/rclone1350656208: Waiting for checks to finish 2026/07/30 03:00:32 DEBUG : Local file system at /tmp/rclone1350656208: Waiting for transfers to finish 2026/07/30 03:00:32 DEBUG : sub dir/hello world.9076d4bf.partial: size = 11 OK 2026/07/30 03:00:32 DEBUG : sub dir/hello world.9076d4bf.partial: renamed to: sub dir/hello world 2026/07/30 03:00:32 INFO : sub dir/hello world: Copied (new) 2026/07/30 03:00:32 INFO : sub dir: Set directory modification time (using DirSetModTime) 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: TestCopyRedownload (13.62s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:00:41 DEBUG : potato: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:00:41 DEBUG : potato: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:00:41 DEBUG : potato: Trying to read object after upload: try again in 400ms (3/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 2026/07/30 03:00:50 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:00:50 DEBUG : potato: size = 21 (Local file system at /tmp/rclone1350656208) 2026/07/30 03:00:50 DEBUG : potato: size = 60 (Linkbox root 'rclone-test-fecakiw9wode') 2026/07/30 03:00:50 DEBUG : potato: Sizes differ 2026/07/30 03:00:50 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:00:50 DEBUG : potato: Update: removing old file 2026/07/30 03:00:53 DEBUG : potato: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:00:54 DEBUG : potato: size = 21 OK 2026/07/30 03:00:54 INFO : potato: Copied (replaced existing) 2026/07/30 03:00:54 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 2026/07/30 03:00:55 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:00:55 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:00:55 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:00:55 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:00:55 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:00:55 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:00:56 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:00:56 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:00:58 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:01:00 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:01:02 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:01:04 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:01:06 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:01:08 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:01:08 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value 2026/07/30 03:01:10 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:01:12 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:01:14 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:01:16 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:01:18 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:01:20 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:01:22 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:01:24 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:01:26 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:01:28 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:01:31 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:01:31 DEBUG : pacer: Reducing sleep to 632.8125ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/07/30 03:01:32 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:01:33 DEBUG : pacer: Reducing sleep to 355.957031ms fstest.go:292: Sleeping for 6s just to make sure --- FAIL: TestSyncAfterChangingFilesSizeOnly (60.18s) === RUN TestSyncAfterChangingContentsOnly run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" sync_test.go:1136: ModTimeNotSupported so forcing file to be a different size 2026/07/30 03:01:39 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:01:39 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:01:41 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:01:41 DEBUG : potato: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:01:42 DEBUG : potato: size = 21 (Local file system at /tmp/rclone1350656208) 2026/07/30 03:01:42 DEBUG : potato: size = 36 (Linkbox root 'rclone-test-fecakiw9wode') 2026/07/30 03:01:42 DEBUG : potato: Sizes differ 2026/07/30 03:01:42 DEBUG : potato: Update: removing old file 2026/07/30 03:01:42 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:01:42 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:01:45 DEBUG : potato: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:01:45 DEBUG : potato: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:01:45 DEBUG : potato: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:01:46 DEBUG : potato: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:01:47 DEBUG : potato: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/30 03:01:49 DEBUG : potato: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/30 03:01:52 DEBUG : potato: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/30 03:01:59 DEBUG : potato: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/30 03:02:12 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:02:12 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:02:12 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:02:12 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:02:12 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:02:12 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:02:13 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:02:13 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:02:15 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:02:17 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:02:19 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:02:21 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:02:23 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:02:25 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:02:25 DEBUG : potato: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/30 03:02:51 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:02:51 DEBUG : potato: Trying to read object after upload: try again in 51.2s (10/10) 2026/07/30 03:03:42 ERROR : potato: Failed to copy: object not found 2026/07/30 03:03:42 ERROR : Linkbox root 'rclone-test-fecakiw9wode': not deleting files as there were IO errors 2026/07/30 03:03:42 ERROR : Linkbox root 'rclone-test-fecakiw9wode': not deleting directories as there were IO errors sync_test.go:1148: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1148 Error: Received unexpected error: object not found Test: TestSyncAfterChangingContentsOnly 2026/07/30 03:03:42 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:03:45 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:03:45 DEBUG : pacer: Reducing sleep to 474.609375ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/07/30 03:03:46 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:03:46 DEBUG : pacer: Reducing sleep to 266.967773ms fstest.go:292: Sleeping for 6s just to make sure --- FAIL: TestSyncAfterChangingContentsOnly (133.73s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:03:53 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:03:53 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:03:57 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:03:57 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:03:57 DEBUG : empty space: Need to transfer - File not found at Destination 2026/07/30 03:03:57 DEBUG : potato2: Need to transfer - File not found at Destination 2026/07/30 03:03:57 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:03:57 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2026/07/30 03:03:57 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2026/07/30 03:03:57 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:03:57 DEBUG : Waiting for deletions to finish 2026/07/30 03:03:57 NOTICE: potato: Skipped delete as --dry-run is set (size 21) 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: TestSyncAfterRemovingAFileAndAddingAFileDryRun (15.89s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:04:08 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:08 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:04:09 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:09 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:04:09 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:09 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:04:10 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:10 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:04:11 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:13 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:15 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:17 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:19 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:21 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:23 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:25 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:28 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:04:31 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:04:31 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:04:32 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:04:33 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:04:35 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:04:35 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:04:35 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:04:36 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:04:36 DEBUG : potato2: Need to transfer - File not found at Destination 2026/07/30 03:04:36 DEBUG : empty space: size = 1 OK 2026/07/30 03:04:36 DEBUG : empty space: Sizes identical 2026/07/30 03:04:36 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:04:36 DEBUG : empty space: Unchanged skipping 2026/07/30 03:04:36 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:04:38 DEBUG : potato2: size = 60 OK 2026/07/30 03:04:38 INFO : potato2: Copied (new) 2026/07/30 03:04:38 DEBUG : Waiting for deletions to finish 2026/07/30 03:04:39 INFO : potato: Deleted run.go:172: removing file "potato" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato" failed: could not Remove: Linkbox error 403: not permit --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (37.17s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:04:49 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:49 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:04:49 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:49 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:04:49 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:49 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:04:50 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:50 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:04:52 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:54 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:56 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:04:58 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:00 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:02 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:299: Retry Put of "b/potato" to Linkbox root 'rclone-test-fecakiw9wode': 1/10 (Update failed to read object: getting files failed: invalid character '<' looking for beginning of value) 2026/07/30 03:05:04 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:06 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:08 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:10 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:12 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:14 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:16 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:18 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:20 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:22 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:24 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:26 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:28 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:05:31 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:05:32 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:05:33 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:05:35 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:05:35 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:05:35 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:05:37 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:05:37 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:05:37 DEBUG : c/non empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:05:37 INFO : d: Making directory 2026/07/30 03:05:39 INFO : d/e: Making directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/07/30 03:05:47 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:47 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:05:47 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:47 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:05:47 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:47 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:05:48 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:48 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:05:50 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:52 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:54 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:56 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:05:58 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:00 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:02 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:04 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:06 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:08 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:10 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:12 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:14 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:16 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:18 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:20 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:22 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:24 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:26 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:28 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:06:30 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:06:31 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:06:32 DEBUG : pacer: Reducing sleep to 632.8125ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:378 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1217 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1262 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir Messages: listing wrong, want b/potato (21), c/non empty space (5) got b/potato (21), b/potato(1) (21), c/non empty space (5) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:378 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1217 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1262 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir Messages: Unexpected file "b/potato(1)" 2026/07/30 03:06:37 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:06:37 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:06:37 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/07/30 03:06:38 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:06:38 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:06:38 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:06:38 DEBUG : c/non empty space: size = 5 OK 2026/07/30 03:06:38 DEBUG : c/non empty space: Sizes identical 2026/07/30 03:06:38 DEBUG : c/non empty space: Unchanged skipping 2026/07/30 03:06:40 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:06:40 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:06:42 DEBUG : a/potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:06:42 DEBUG : a/potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:06:43 DEBUG : a/potato2: size = 60 OK 2026/07/30 03:06:43 INFO : a/potato2: Copied (new) 2026/07/30 03:06:43 DEBUG : Waiting for deletions to finish 2026/07/30 03:06:44 INFO : b/potato(1): Deleted 2026/07/30 03:06:44 INFO : b/potato: Deleted 2026/07/30 03:06:44 INFO : d/e: Removing directory 2026/07/30 03:06:46 INFO : d: Removing directory 2026/07/30 03:06:48 INFO : b: Removing directory 2026/07/30 03:06:48 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:48 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:06:48 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:48 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:06:48 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:48 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:06:49 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:49 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:06:51 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:53 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:55 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:57 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:06:59 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:07:01 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:07:01 DEBUG : b: Failed to Rmdir: getting files failed: invalid character '<' looking for beginning of value 2026/07/30 03:07:01 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': failed to delete 1 directories 2026/07/30 03:07:01 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': deleted 2 directories 2026/07/30 03:07:03 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:07:05 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:07:07 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:07:09 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:07:11 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:07:13 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:07:15 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:07:17 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:07:19 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:07:21 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:07:21 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value 2026/07/30 03:07:23 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:07:25 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:07:27 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:07:29 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:07:31 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:07:32 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:07:33 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:07:35 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:07:37 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:07:37 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:07:38 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:07:39 DEBUG : pacer: Reducing sleep to 200ms --- FAIL: TestSyncAfterRemovingAFileAndAddingAFileSubDir (176.02s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:07:45 DEBUG : b/potato: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:07:45 DEBUG : b/potato: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:07:49 DEBUG : c/non empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:07:50 INFO : d: Making directory 2026/07/30 03:07:52 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/07/30 03:07:54 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:07:54 DEBUG : c/non empty space: size = 5 OK 2026/07/30 03:07:54 DEBUG : c/non empty space: Sizes identical 2026/07/30 03:07:54 DEBUG : c/non empty space: Unchanged skipping 2026/07/30 03:07:54 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:07:56 DEBUG : a/potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:07:57 DEBUG : a/potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:07:57 DEBUG : a/potato2: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:07:58 DEBUG : a/potato2: size = 60 OK 2026/07/30 03:07:58 INFO : a/potato2: Copied (new) 2026/07/30 03:07:58 ERROR : Linkbox root 'rclone-test-fecakiw9wode': not deleting files as there were IO errors 2026/07/30 03:07:58 ERROR : Linkbox root 'rclone-test-fecakiw9wode': not deleting directories as there were IO errors 2026/07/30 03:08:05 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:08:05 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:08:05 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:08:05 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:08:06 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:08:06 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:08:07 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:08:07 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:08:08 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:08:10 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:08:12 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:08:14 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:08:16 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:08:18 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:186: removing dir "c" failed - try 1/3: sleeping 1s: purge error: invalid character '<' looking for beginning of value 2026/07/30 03:08:20 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:08:22 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:08:24 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:08:26 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:08:28 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:08:32 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:08:32 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:08:34 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:08:34 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:08:36 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:08:36 DEBUG : pacer: Reducing sleep to 266.967773ms --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (54.85s) === RUN TestSyncDeleteDuring run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:08:37 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:08:37 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:08:39 DEBUG : potato: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:08:39 DEBUG : potato: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:08:39 DEBUG : potato: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:08:42 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/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 2026/07/30 03:08:50 DEBUG : Waiting for deletions to finish 2026/07/30 03:08:50 DEBUG : potato2: Need to transfer - File not found at Destination 2026/07/30 03:08:50 DEBUG : empty space: size = 1 OK 2026/07/30 03:08:50 DEBUG : empty space: Sizes identical 2026/07/30 03:08:50 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:08:50 DEBUG : empty space: Unchanged skipping 2026/07/30 03:08:50 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:08:52 INFO : potato: Deleted 2026/07/30 03:08:52 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:08:53 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:08:53 DEBUG : potato2: size = 60 OK 2026/07/30 03:08:53 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:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure --- PASS: TestSyncDeleteDuring (31.19s) === RUN TestSyncDeleteBefore run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:09:10 DEBUG : potato: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:09:10 DEBUG : potato: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:09:10 DEBUG : potato: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:09:13 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:09:13 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:09:14 DEBUG : Waiting for deletions to finish 2026/07/30 03:09:14 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:09:14 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:09:15 INFO : potato: Deleted 2026/07/30 03:09:16 DEBUG : potato2: Need to transfer - File not found at Destination 2026/07/30 03:09:16 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:09:16 DEBUG : empty space: size = 1 OK 2026/07/30 03:09:16 DEBUG : empty space: Sizes identical 2026/07/30 03:09:16 DEBUG : empty space: Unchanged skipping 2026/07/30 03:09:16 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:09:18 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:09:18 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:09:18 DEBUG : potato2: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:09:19 DEBUG : potato2: size = 60 OK 2026/07/30 03:09:19 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 2026/07/30 03:09:27 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:09:27 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:09:27 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:09:27 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:09:27 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:09:27 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:09:28 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:09:28 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:09:30 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:09:33 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:09:34 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:09:35 DEBUG : pacer: Reducing sleep to 632.8125ms --- PASS: TestSyncDeleteBefore (27.11s) === RUN TestCopyDeleteBefore run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:09:36 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:09:36 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:09:38 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:09:38 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:09:38 DEBUG : potato: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:09:38 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:09:38 DEBUG : potato: 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 2026/07/30 03:09:47 DEBUG : potato2: Need to transfer - File not found at Destination 2026/07/30 03:09:47 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:09:47 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:09:49 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:09:49 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:09:49 DEBUG : potato2: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:09:50 DEBUG : potato2: size = 19 OK 2026/07/30 03:09:50 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 --- PASS: TestCopyDeleteBefore (26.17s) === RUN TestSyncWithExclude run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:10:03 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:10:05 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:10:06 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:10:06 DEBUG : enormous: Excluded (Size Filter) 2026/07/30 03:10:06 DEBUG : enormous: Excluded 2026/07/30 03:10:06 DEBUG : potato2: Excluded (Size Filter) 2026/07/30 03:10:06 DEBUG : potato2: Excluded 2026/07/30 03:10:07 DEBUG : potato2: Excluded (Size Filter) 2026/07/30 03:10:07 DEBUG : potato2: Excluded 2026/07/30 03:10:07 DEBUG : empty space: Need to transfer - File not found at Destination 2026/07/30 03:10:07 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:10:07 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:10:07 DEBUG : empty space: Update: removing old file 2026/07/30 03:10:10 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:10:10 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:10:11 DEBUG : empty space: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:10:11 DEBUG : empty space: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:10:12 DEBUG : empty space: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/30 03:10:14 DEBUG : empty space: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/30 03:10:17 DEBUG : empty space: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/30 03:10:24 DEBUG : empty space: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/30 03:10:37 DEBUG : empty space: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/30 03:11:03 DEBUG : empty space: Trying to read object after upload: try again in 51.2s (10/10) 2026/07/30 03:11:54 ERROR : empty space: Failed to copy: object not found 2026/07/30 03:11:54 ERROR : Linkbox root 'rclone-test-fecakiw9wode': not deleting files as there were IO errors 2026/07/30 03:11:54 ERROR : Linkbox root 'rclone-test-fecakiw9wode': not deleting directories as there were IO errors sync_test.go:1401: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1401 Error: Received unexpected error: object not found Test: TestSyncWithExclude 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 --- FAIL: TestSyncWithExclude (123.89s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:12:07 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:12:09 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:12:10 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:12:10 DEBUG : empty space: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:12:13 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:12:13 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:12:13 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:12:13 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:12:13 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:12:13 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:12:14 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:12:14 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:12:16 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:12:18 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:12:20 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:12:22 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:12:24 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:12:26 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:299: Retry Put of "enormous" to Linkbox root 'rclone-test-fecakiw9wode': 1/10 (Update failed to read object: getting files failed: invalid character '<' looking for beginning of value) 2026/07/30 03:12:28 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:12:30 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:12:32 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:12:34 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:12:34 DEBUG : enormous: Update: removing old file 2026/07/30 03:12:37 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:12:37 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:12:40 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:12:40 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:12:40 DEBUG : enormous: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:12:40 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:12:41 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:12:41 DEBUG : enormous: Excluded (Size Filter) 2026/07/30 03:12:41 DEBUG : enormous: Excluded 2026/07/30 03:12:41 DEBUG : potato2: Excluded (Size Filter) 2026/07/30 03:12:41 DEBUG : potato2: Excluded 2026/07/30 03:12:41 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:12:41 DEBUG : empty space: size = 1 OK 2026/07/30 03:12:41 DEBUG : empty space: Sizes identical 2026/07/30 03:12:41 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:12:41 DEBUG : empty space: Unchanged skipping 2026/07/30 03:12:41 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:12:41 DEBUG : Waiting for deletions to finish 2026/07/30 03:12:43 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:12:43 INFO : enormous: Deleted 2026/07/30 03:12:43 INFO : potato2: Deleted 2026/07/30 03:12:43 INFO : There was nothing to transfer fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure 2026/07/30 03:12:54 DEBUG : empty space: size = 1 OK 2026/07/30 03:12:54 DEBUG : empty space: Sizes identical 2026/07/30 03:12:54 DEBUG : empty space: Unchanged skipping 2026/07/30 03:12:54 DEBUG : Local file system at /tmp/rclone1350656208: Waiting for checks to finish 2026/07/30 03:12:54 DEBUG : Local file system at /tmp/rclone1350656208: Waiting for transfers to finish 2026/07/30 03:12:54 DEBUG : Waiting for deletions to finish 2026/07/30 03:12:54 INFO : potato2: Deleted 2026/07/30 03:12:54 INFO : enormous: Deleted 2026/07/30 03:12:54 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (51.38s) === RUN TestSyncWithUpdateOlder run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" sync_test.go:1454: Can't run this test on fs which doesn't support mod time --- SKIP: TestSyncWithUpdateOlder (0.41s) === RUN TestSyncWithTrackRenames run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" sync_test.go:1569: Can track renames: false 2026/07/30 03:12:57 ERROR : Linkbox root 'rclone-test-fecakiw9wode': Ignoring --track-renames as the destination does not support server-side move or copy 2026/07/30 03:12:57 ERROR : Linkbox root 'rclone-test-fecakiw9wode': Ignoring --track-renames as the source and destination do not have a common hash 2026/07/30 03:12:57 DEBUG : potato: Need to transfer - File not found at Destination 2026/07/30 03:12:57 DEBUG : yam: Need to transfer - File not found at Destination 2026/07/30 03:12:57 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:12:57 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:12:59 DEBUG : potato: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:12:59 DEBUG : yam: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:12:59 DEBUG : potato: size = 14 OK 2026/07/30 03:12:59 INFO : potato: Copied (new) 2026/07/30 03:13:00 DEBUG : yam: size = 11 OK 2026/07/30 03:13:00 INFO : yam: Copied (new) 2026/07/30 03:13:00 DEBUG : Waiting for deletions to finish 2026/07/30 03:13:00 ERROR : Linkbox root 'rclone-test-fecakiw9wode': Ignoring --track-renames as the destination does not support server-side move or copy 2026/07/30 03:13:00 ERROR : Linkbox root 'rclone-test-fecakiw9wode': Ignoring --track-renames as the source and destination do not have a common hash 2026/07/30 03:13:00 DEBUG : potato: size = 14 OK 2026/07/30 03:13:00 DEBUG : potato: Sizes identical 2026/07/30 03:13:00 DEBUG : yaml: Need to transfer - File not found at Destination 2026/07/30 03:13:00 DEBUG : potato: Unchanged skipping 2026/07/30 03:13:00 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:13:00 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:13:02 DEBUG : yaml: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:13:02 DEBUG : yaml: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:13:03 DEBUG : yaml: size = 11 OK 2026/07/30 03:13:03 INFO : yaml: Copied (new) 2026/07/30 03:13:03 DEBUG : Waiting for deletions to finish 2026/07/30 03:13:04 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 2026/07/30 03:13:15 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:13:15 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:13:15 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:13:15 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:13:15 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:13:15 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:13:16 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:13:16 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:13:18 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:13:20 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:13:22 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:13:24 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:13:26 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:13:28 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:13:28 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value --- FAIL: TestSyncWithTrackRenames (31.26s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" sync_test.go:1641: Can track renames: false 2026/07/30 03:13:28 ERROR : Linkbox root 'rclone-test-fecakiw9wode': Ignoring --track-renames as the destination does not support server-side move or copy 2026/07/30 03:13:28 ERROR : Linkbox root 'rclone-test-fecakiw9wode': Ignoring --track-renames as either the source or destination do not support modtime 2026/07/30 03:13:30 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:13:32 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:13:34 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:13:34 DEBUG : potato: Need to transfer - File not found at Destination 2026/07/30 03:13:34 DEBUG : yam: Need to transfer - File not found at Destination 2026/07/30 03:13:34 DEBUG : yaml: Need to transfer - File not found at Destination 2026/07/30 03:13:34 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:13:34 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:13:36 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:13:37 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:13:39 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:13:39 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:13:40 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:13:41 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:13:42 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:13:42 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:13:42 DEBUG : yam: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:13:43 DEBUG : potato: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:13:43 DEBUG : yaml: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:13:43 DEBUG : yam: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:13:43 DEBUG : potato: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:13:43 DEBUG : yaml: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:13:44 DEBUG : yam: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:13:44 DEBUG : potato: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:13:44 DEBUG : yaml: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:13:44 DEBUG : yam: size = 11 OK 2026/07/30 03:13:44 INFO : yam: Copied (new) 2026/07/30 03:13:44 DEBUG : potato: size = 14 OK 2026/07/30 03:13:44 INFO : potato: Copied (new) 2026/07/30 03:13:45 DEBUG : yaml: size = 11 OK 2026/07/30 03:13:45 INFO : yaml: Copied (new) 2026/07/30 03:13:45 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:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1651 Error: Should be true Test: TestSyncWithTrackRenamesStrategyModtime Messages: listing wrong, want potato (14), yam (11) got potato (14), yam (11), yaml (11) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1651 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncWithTrackRenamesStrategyModtime Messages: Unexpected file "yaml" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:352 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1652 Error: Should be true Test: TestSyncWithTrackRenamesStrategyModtime Messages: listing wrong, want potato (14), yam (11) got potato (14), yam (11), yaml (11) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:352 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1652 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncWithTrackRenamesStrategyModtime Messages: Unexpected file "yaml" 2026/07/30 03:14:00 ERROR : Linkbox root 'rclone-test-fecakiw9wode': Ignoring --track-renames as the destination does not support server-side move or copy 2026/07/30 03:14:00 ERROR : Linkbox root 'rclone-test-fecakiw9wode': Ignoring --track-renames as either the source or destination do not support modtime 2026/07/30 03:14:01 DEBUG : potato: size = 14 OK 2026/07/30 03:14:01 DEBUG : potato: Sizes identical 2026/07/30 03:14:01 DEBUG : yaml: size = 11 OK 2026/07/30 03:14:01 DEBUG : yaml: Sizes identical 2026/07/30 03:14:01 DEBUG : potato: Unchanged skipping 2026/07/30 03:14:01 DEBUG : yaml: Unchanged skipping 2026/07/30 03:14:01 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:14:01 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:14:01 DEBUG : Waiting for deletions to finish 2026/07/30 03:14:02 INFO : yam: Deleted 2026/07/30 03:14:02 INFO : There was nothing to transfer 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/07/30 03:14:11 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:14:11 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:14:12 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:14:12 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:14:12 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:14:12 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:14:13 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:14:13 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:14:14 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:14:16 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:14:18 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:14:20 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:14:22 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:14:24 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:172: removing file "yaml" failed - try 1/3: sleeping 1s: could not Remove: invalid character '<' looking for beginning of value 2026/07/30 03:14:26 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:14:28 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:14:30 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:14:32 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:14:36 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:14:37 DEBUG : pacer: Reducing sleep to 1.125s --- FAIL: TestSyncWithTrackRenamesStrategyModtime (68.82s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" sync_test.go:1680: Can track renames: false 2026/07/30 03:14:37 ERROR : Linkbox root 'rclone-test-fecakiw9wode': Ignoring --track-renames as the destination does not support server-side move or copy 2026/07/30 03:14:38 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:14:38 DEBUG : potato: Need to transfer - File not found at Destination 2026/07/30 03:14:38 DEBUG : sub/yam: Need to transfer - File not found at Destination 2026/07/30 03:14:38 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:14:38 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:14:39 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:14:40 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:14:41 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:14:42 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:14:42 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:14:43 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:14:44 DEBUG : potato: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:14:44 DEBUG : potato: size = 14 OK 2026/07/30 03:14:44 INFO : potato: Copied (new) 2026/07/30 03:14:45 DEBUG : sub/yam: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:14:45 DEBUG : sub/yam: size = 11 OK 2026/07/30 03:14:45 INFO : sub/yam: Copied (new) 2026/07/30 03:14:45 DEBUG : Waiting for deletions to finish 2026/07/30 03:14:45 ERROR : Linkbox root 'rclone-test-fecakiw9wode': Ignoring --track-renames as the destination does not support server-side move or copy 2026/07/30 03:14:46 DEBUG : yam: Need to transfer - File not found at Destination 2026/07/30 03:14:46 DEBUG : potato: size = 14 OK 2026/07/30 03:14:46 DEBUG : potato: Sizes identical 2026/07/30 03:14:46 DEBUG : potato: Unchanged skipping 2026/07/30 03:14:46 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:14:46 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:14:48 DEBUG : yam: size = 11 OK 2026/07/30 03:14:48 INFO : yam: Copied (new) 2026/07/30 03:14:48 DEBUG : Waiting for deletions to finish 2026/07/30 03:14:49 INFO : sub/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 --- PASS: TestSyncWithTrackRenamesStrategyLeaf (25.47s) === RUN TestServerSideMoveLocal run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:15:02 DEBUG : Creating backend with remote "/tmp/rclone1350656208/dir1" 2026/07/30 03:15:02 DEBUG : Config file has changed externally - reloading 2026/07/30 03:15:02 DEBUG : Creating backend with remote "/tmp/rclone1350656208/dir2" 2026/07/30 03:15:02 DEBUG : Local file system at /tmp/rclone1350656208/dir2: Using server-side directory move 2026/07/30 03:15:02 INFO : Local file system at /tmp/rclone1350656208/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/07/30 03:15:02 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/07/30 03:15:02 DEBUG : Local file system at /tmp/rclone1350656208/dir2: Waiting for checks to finish 2026/07/30 03:15:02 DEBUG : Local file system at /tmp/rclone1350656208/dir2: Waiting for transfers to finish 2026/07/30 03:15:02 INFO : file1.txt: Moved (server-side) --- PASS: TestServerSideMoveLocal (0.41s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:15:03 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/30 03:15:03 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/07/30 03:15:03 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:15:03 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:15:08 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:15:08 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:15:08 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:15:08 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:15:08 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:15:08 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:15:09 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:15:09 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:15:11 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:15:13 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:15:15 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:15:17 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:15:19 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:15:21 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:15:23 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:15:25 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:15:27 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:15:29 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:15:31 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:15:33 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:15:35 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:15:38 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:15:38 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:15:40 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:15:40 DEBUG : sub dir/hello world: size = 11 OK 2026/07/30 03:15:40 INFO : sub dir/hello world: Copied (new) 2026/07/30 03:15:40 INFO : sub dir/hello world: Deleted 2026/07/30 03:15:42 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:15:42 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:15:42 DEBUG : nested/sub dir/file: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:15:42 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:15:42 DEBUG : nested/sub dir/file: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:15:43 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:15:43 DEBUG : nested/sub dir/file: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:15:43 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:15:43 DEBUG : nested/sub dir/file: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:15:44 DEBUG : nested/sub dir/file: size = 6 OK 2026/07/30 03:15:44 INFO : nested/sub dir/file: Copied (new) 2026/07/30 03:15:44 INFO : nested/sub dir/file: Deleted 2026/07/30 03:15:44 INFO : sub dir: Removing directory 2026/07/30 03:15:44 INFO : nested/sub dir: Removing directory 2026/07/30 03:15:44 INFO : nested: Removing directory 2026/07/30 03:15:44 DEBUG : Local file system at /tmp/rclone1350656208: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (51.09s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:15:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/30 03:15:54 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/07/30 03:15:54 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:15:54 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:16:01 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:16:01 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:16:01 DEBUG : nested/sub dir/file: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:16:01 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:16:01 DEBUG : nested/sub dir/file: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:16:02 DEBUG : nested/sub dir/file: size = 6 OK 2026/07/30 03:16:02 INFO : nested/sub dir/file: Copied (new) 2026/07/30 03:16:02 INFO : nested/sub dir/file: Deleted 2026/07/30 03:16:02 DEBUG : sub dir/hello world: size = 11 OK 2026/07/30 03:16:02 INFO : sub dir/hello world: Copied (new) 2026/07/30 03:16:02 INFO : sub dir/hello world: Deleted 2026/07/30 03:16:04 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:04 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:16:04 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:04 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:16:04 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:04 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:16:05 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:05 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:16:07 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:09 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:11 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:13 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:15 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:17 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:172: removing file "sub dir/hello world" failed - try 1/3: sleeping 1s: could not Remove: invalid character '<' looking for beginning of value 2026/07/30 03:16:19 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:21 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:23 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:25 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:27 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:29 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:31 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:33 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:35 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:16:38 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:16:39 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:16:41 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:16:42 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:16:44 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:16:44 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:16:45 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:16:46 DEBUG : pacer: Reducing sleep to 200.225829ms run.go:186: removing dir "nested" failed - try 1/3: sleeping 1s: directory not empty 2026/07/30 03:16:47 DEBUG : pacer: Reducing sleep to 200ms 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: TestMoveWithoutDeleteEmptySrcDirs (62.52s) === RUN TestMoveWithIgnoreExisting run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:16:56 DEBUG : existing-b: Need to transfer - File not found at Destination 2026/07/30 03:16:56 DEBUG : existing: Need to transfer - File not found at Destination 2026/07/30 03:16:56 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:16:56 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:16:59 DEBUG : existing-b: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:16:59 DEBUG : existing: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:16:59 DEBUG : existing-b: size = 6 OK 2026/07/30 03:16:59 INFO : existing-b: Copied (new) 2026/07/30 03:16:59 INFO : existing-b: Deleted 2026/07/30 03:16:59 DEBUG : existing: size = 6 OK 2026/07/30 03:16:59 INFO : existing: Copied (new) 2026/07/30 03:16:59 INFO : existing: 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/07/30 03:17:07 DEBUG : existing: Destination exists, skipping 2026/07/30 03:17:07 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2026/07/30 03:17:07 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:17:07 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:17:07 INFO : There was nothing to transfer fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 7s just to make sure --- PASS: TestMoveWithIgnoreExisting (25.85s) === RUN TestServerSideMove run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:17:22 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-hucuhek5kitu" 2026/07/30 03:17:22 DEBUG : Config file has changed externally - reloading 2026/07/30 03:17:22 DEBUG : Linkbox root 'rclone-test-hucuhek5kitu': Using cached web token 2026/07/30 03:17:23 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:17:23 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:17:23 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:17:23 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:17:23 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:17:23 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:17:24 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:17:24 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:17:26 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:17:28 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:17:30 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:17:32 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:17:34 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:17:36 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) sync_test.go:1722: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1722 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1912 Error: Received unexpected error: getting files failed: invalid character '<' looking for beginning of value Test: TestServerSideMove 2026/07/30 03:17:36 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:17:36 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:17:36 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:17:36 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:17:36 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:17:36 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:17:37 DEBUG : pacer: Reducing sleep to 1.2s 2026/07/30 03:17:39 DEBUG : pacer: Reducing sleep to 900ms --- FAIL: TestServerSideMove (16.80s) === RUN TestServerSideMoveWithFilter run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:17:39 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-vufurur6tobu" 2026/07/30 03:17:39 DEBUG : Linkbox root 'rclone-test-vufurur6tobu': Using cached web token 2026/07/30 03:17:40 DEBUG : pacer: Reducing sleep to 675ms 2026/07/30 03:17:41 DEBUG : pacer: Reducing sleep to 506.25ms 2026/07/30 03:17:43 DEBUG : pacer: Reducing sleep to 379.6875ms 2026/07/30 03:17:43 DEBUG : pacer: Reducing sleep to 284.765625ms 2026/07/30 03:17:43 DEBUG : pacer: Reducing sleep to 213.574218ms 2026/07/30 03:17:44 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:17:45 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:17:46 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:17:48 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/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-fecakiw9wode' -> Linkbox root 'rclone-test-vufurur6tobu' 2026/07/30 03:18:02 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:18:02 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:18:03 DEBUG : potato3: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:18:04 DEBUG : empty space: Excluded (Size Filter) 2026/07/30 03:18:04 DEBUG : empty space: Excluded 2026/07/30 03:18:04 DEBUG : empty space: Excluded (Size Filter) 2026/07/30 03:18:04 DEBUG : empty space: Excluded 2026/07/30 03:18:04 DEBUG : potato2: Need to transfer - File not found at Destination 2026/07/30 03:18:04 DEBUG : potato3: size = 68 (Linkbox root 'rclone-test-fecakiw9wode') 2026/07/30 03:18:04 DEBUG : potato3: size = 60 (Linkbox root 'rclone-test-vufurur6tobu') 2026/07/30 03:18:04 DEBUG : potato3: Sizes differ 2026/07/30 03:18:04 DEBUG : Linkbox root 'rclone-test-vufurur6tobu': Waiting for checks to finish 2026/07/30 03:18:04 DEBUG : Linkbox root 'rclone-test-vufurur6tobu': Waiting for transfers to finish 2026/07/30 03:18:04 DEBUG : potato3: Update: removing old file 2026/07/30 03:18:06 DEBUG : potato2: size = 60 OK 2026/07/30 03:18:06 INFO : potato2: Copied (new) 2026/07/30 03:18:07 DEBUG : potato3: size = 68 OK 2026/07/30 03:18:07 INFO : potato3: Copied (replaced existing) 2026/07/30 03:18:07 INFO : potato2: Deleted 2026/07/30 03:18:08 INFO : potato3: Deleted 2026/07/30 03:18:09 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-seheraf2made" 2026/07/30 03:18:09 DEBUG : Linkbox root 'rclone-test-seheraf2made': Using cached web token 2026/07/30 03:18:10 DEBUG : empty space: Excluded (Size Filter) 2026/07/30 03:18:10 DEBUG : empty space: Excluded 2026/07/30 03:18:10 DEBUG : potato2: Need to transfer - File not found at Destination 2026/07/30 03:18:10 DEBUG : potato3: Need to transfer - File not found at Destination 2026/07/30 03:18:10 DEBUG : Linkbox root 'rclone-test-seheraf2made': Waiting for checks to finish 2026/07/30 03:18:10 DEBUG : Linkbox root 'rclone-test-seheraf2made': Waiting for transfers to finish 2026/07/30 03:18:15 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:18:15 DEBUG : potato3: size = 68 OK 2026/07/30 03:18:15 INFO : potato3: Copied (new) 2026/07/30 03:18:15 DEBUG : potato2: size = 60 OK 2026/07/30 03:18:15 INFO : potato2: Copied (new) 2026/07/30 03:18:16 INFO : potato3: Deleted 2026/07/30 03:18:16 INFO : potato2: Deleted 2026/07/30 03:18:17 DEBUG : Linkbox root 'rclone-test-seheraf2made': Purge remote 2026/07/30 03:18:18 DEBUG : Linkbox root 'rclone-test-vufurur6tobu': Purge remote --- PASS: TestServerSideMoveWithFilter (42.38s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:18:21 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-wajuzec3fobe" 2026/07/30 03:18:21 DEBUG : Linkbox root 'rclone-test-wajuzec3fobe': Using cached web token 2026/07/30 03:18:24 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:18:24 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:18:24 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:18:24 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:18:25 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:18:25 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:18:25 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:18:25 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:18:27 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:18:29 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:18:31 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:18:33 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:18:35 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:18:37 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:299: Retry Put of "potato2" to Linkbox root 'rclone-test-fecakiw9wode': 1/10 (Update failed to read object: getting files failed: invalid character '<' looking for beginning of value) 2026/07/30 03:18:39 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:18:39 DEBUG : potato2: Update: removing old file 2026/07/30 03:18:42 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:18:43 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:18:45 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:18:45 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:18:45 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:18:46 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:18:46 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:18:47 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:18:48 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:18:50 INFO : tomatoDir: Making directory sync_test.go:1736: Server side move (if possible) Linkbox root 'rclone-test-fecakiw9wode' -> Linkbox root 'rclone-test-wajuzec3fobe' 2026/07/30 03:18:58 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:18:58 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:18:59 DEBUG : potato3: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:18:59 DEBUG : potato3: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:19:00 DEBUG : potato3: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/30 03:19:03 DEBUG : potato2: Need to transfer - File not found at Destination 2026/07/30 03:19:03 DEBUG : potato3: size = 68 (Linkbox root 'rclone-test-fecakiw9wode') 2026/07/30 03:19:03 DEBUG : potato3: size = 60 (Linkbox root 'rclone-test-wajuzec3fobe') 2026/07/30 03:19:03 DEBUG : potato3: Sizes differ 2026/07/30 03:19:03 DEBUG : empty space: size = 1 OK 2026/07/30 03:19:03 DEBUG : empty space: Sizes identical 2026/07/30 03:19:03 DEBUG : empty space: Unchanged skipping 2026/07/30 03:19:03 DEBUG : Linkbox root 'rclone-test-wajuzec3fobe': Waiting for checks to finish 2026/07/30 03:19:03 DEBUG : potato3: Update: removing old file 2026/07/30 03:19:05 INFO : empty space: Deleted 2026/07/30 03:19:05 DEBUG : Linkbox root 'rclone-test-wajuzec3fobe': Waiting for transfers to finish 2026/07/30 03:19:05 DEBUG : potato2: size = 60 OK 2026/07/30 03:19:05 INFO : potato2: Copied (new) 2026/07/30 03:19:07 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:19:07 INFO : potato2: Deleted 2026/07/30 03:19:07 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:19:07 DEBUG : potato3: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:19:08 DEBUG : potato3: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:19:09 DEBUG : potato3: size = 68 OK 2026/07/30 03:19:09 INFO : potato3: Copied (replaced existing) 2026/07/30 03:19:10 INFO : potato3: Deleted 2026/07/30 03:19:10 INFO : tomatoDir: Removing directory 2026/07/30 03:19:12 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': deleted 1 directories 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/07/30 03:19:20 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:19:20 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:19:20 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:19:20 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:19:21 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:19:21 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:19:22 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:19:22 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:19:23 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:19:25 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:19:27 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:19:29 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:19:31 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:19:33 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:19:33 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value 2026/07/30 03:19:33 DEBUG : Linkbox root 'rclone-test-wajuzec3fobe': Purge remote 2026/07/30 03:19:35 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:19:39 DEBUG : pacer: Reducing sleep to 1.5s --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (77.85s) === RUN TestServerSideMoveOverlap run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:19:39 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-fecakiw9wode/rclone-move-test" 2026/07/30 03:19:39 DEBUG : Linkbox root 'rclone-test-fecakiw9wode/rclone-move-test': Using cached web token fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Should be true Test: TestServerSideMoveOverlap Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Should be true Test: TestServerSideMoveOverlap Messages: Unexpected file "potato2" --- FAIL: TestServerSideMoveOverlap (13.51s) === RUN TestSyncOverlap run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:19:52 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-fecakiw9wode/rclone-sync-test" 2026/07/30 03:19:52 DEBUG : Linkbox root 'rclone-test-fecakiw9wode/rclone-sync-test': Using cached web token run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Should be true Test: TestSyncOverlap Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Should be true Test: TestSyncOverlap Messages: Unexpected file "potato2" --- FAIL: TestSyncOverlap (13.49s) === RUN TestSyncCompareDest run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:20:06 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-fecakiw9wode/dst" 2026/07/30 03:20:06 DEBUG : Linkbox root 'rclone-test-fecakiw9wode/dst': Using cached web token 2026/07/30 03:20:07 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-fecakiw9wode/CompareDest" 2026/07/30 03:20:07 DEBUG : Linkbox root 'rclone-test-fecakiw9wode/CompareDest': Using cached web token 2026/07/30 03:20:09 DEBUG : one: Need to transfer - File not found at Destination 2026/07/30 03:20:09 DEBUG : Linkbox root 'rclone-test-fecakiw9wode/dst': Waiting for checks to finish 2026/07/30 03:20:09 DEBUG : Linkbox root 'rclone-test-fecakiw9wode/dst': Waiting for transfers to finish 2026/07/30 03:20:13 DEBUG : one: size = 3 OK 2026/07/30 03:20:13 INFO : one: Copied (new) 2026/07/30 03:20:13 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 2026/07/30 03:20:14 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:20:14 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:20:15 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:20:15 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:20:15 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:20:15 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:20:16 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:20:16 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:20:17 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:20:19 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:20:21 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:20:23 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:20:25 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:20:27 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:20:27 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value 2026/07/30 03:20:29 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:20:31 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:20:33 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:20:35 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:20:38 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:20:40 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:20:41 DEBUG : pacer: Reducing sleep to 843.75ms run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit 2026/07/30 03:20:42 DEBUG : pacer: Reducing sleep to 632.8125ms run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit 2026/07/30 03:20:44 DEBUG : pacer: Reducing sleep to 474.609375ms run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit 2026/07/30 03:20:45 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:20:46 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:20:46 DEBUG : pacer: Reducing sleep to 200.225829ms run.go:186: removing dir "dst" failed - try 1/3: sleeping 1s: directory not empty 2026/07/30 03:20:48 DEBUG : pacer: Reducing sleep to 200ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /usr/local/go/src/testing/testing.go:1228 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:282 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2121 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /usr/local/go/src/testing/testing.go:1228 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:282 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2121 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "potato2" --- FAIL: TestSyncCompareDest (51.30s) === RUN TestSyncMultipleCompareDest run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:21:07 DEBUG : pre-dest2/2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:21:08 DEBUG : pre-dest2/2: 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 2026/07/30 03:21:10 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:10 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:21:10 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:10 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:21:11 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:11 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:21:12 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:12 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:21:13 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:15 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:17 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:19 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:21 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:23 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:23 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value 2026/07/30 03:21:25 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:27 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:29 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:31 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:33 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:35 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:37 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:21:39 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:21:41 DEBUG : pacer: Reducing sleep to 1.125s run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit 2026/07/30 03:21:43 DEBUG : pacer: Reducing sleep to 843.75ms run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit 2026/07/30 03:21:45 DEBUG : pacer: Reducing sleep to 632.8125ms run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit 2026/07/30 03:21:45 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:21:46 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:21:48 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:21:49 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:21:49 DEBUG : pacer: Reducing sleep to 200ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /usr/local/go/src/testing/testing.go:1228 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:282 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2243 Error: Should be true Test: TestSyncMultipleCompareDest Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /usr/local/go/src/testing/testing.go:1228 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:282 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2243 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncMultipleCompareDest Messages: Unexpected file "potato2" --- FAIL: TestSyncMultipleCompareDest (63.07s) === RUN TestSyncCopyDest run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" sync_test.go:2266: Skipping test as remote does not support server-side copy run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2266 Error: Should be true Test: TestSyncCopyDest Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2266 Error: Should be true Test: TestSyncCopyDest Messages: Unexpected file "potato2" --- FAIL: TestSyncCopyDest (12.05s) === RUN TestSyncBackupDir run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" sync_test.go:2379: Skipping test as remote does not support server-side move run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2379 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2458 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2379 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2458 Error: Should be true Test: TestSyncBackupDir Messages: Unexpected file "potato2" --- FAIL: TestSyncBackupDir (12.00s) === RUN TestSyncBackupDirWithSuffix run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" sync_test.go:2379: Skipping test as remote does not support server-side move run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2379 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2462 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2379 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2462 Error: Should be true Test: TestSyncBackupDirWithSuffix Messages: Unexpected file "potato2" --- FAIL: TestSyncBackupDirWithSuffix (12.02s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" sync_test.go:2379: Skipping test as remote does not support server-side move run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2379 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2379 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: Unexpected file "potato2" --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (12.40s) === RUN TestSyncBackupDirSuffixOnly run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" sync_test.go:2379: Skipping test as remote does not support server-side move run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2379 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2470 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2379 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2470 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: Unexpected file "potato2" --- FAIL: TestSyncBackupDirSuffixOnly (12.25s) === RUN TestSyncSuffix run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" sync_test.go:2480: Skipping test as remote does not support server-side move run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2480 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Should be true Test: TestSyncSuffix Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2480 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Should be true Test: TestSyncSuffix Messages: Unexpected file "potato2" --- FAIL: TestSyncSuffix (12.23s) === RUN TestSyncSuffixKeepExtension run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" sync_test.go:2480: Skipping test as remote does not support server-side move run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2480 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2555 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2480 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2555 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: Unexpected file "potato2" --- FAIL: TestSyncSuffixKeepExtension (12.13s) === RUN TestSyncUTFNorm run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2576 Error: Should be true Test: TestSyncUTFNorm Messages: listing wrong, want Testêé (18) got Testêé (18), potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2576 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "potato2" 2026/07/30 03:23:37 DEBUG : Testêé: size = 14 (Local file system at /tmp/rclone1350656208) 2026/07/30 03:23:37 DEBUG : Testêé: size = 18 (Linkbox root 'rclone-test-fecakiw9wode') 2026/07/30 03:23:37 DEBUG : Testêé: Sizes differ 2026/07/30 03:23:37 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:23:37 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:23:37 DEBUG : Testêé: Update: removing old file 2026/07/30 03:23:40 DEBUG : Testêé: size = 14 OK 2026/07/30 03:23:40 INFO : Testêé: Copied (replaced existing) to: Testêé 2026/07/30 03:23:40 DEBUG : Waiting for deletions to finish 2026/07/30 03:23:40 ERROR : potato2: Couldn't delete: could not Remove: Linkbox error 403: not permit 2026/07/30 03:23:40 ERROR : Linkbox root 'rclone-test-fecakiw9wode': not deleting directories as there were IO errors sync_test.go:2582: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2582 Error: Received unexpected error: failed to delete 1 files Test: TestSyncUTFNorm run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2582 Error: Should be true Test: TestSyncUTFNorm Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2582 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "potato2" --- FAIL: TestSyncUTFNorm (27.62s) === RUN TestSyncImmutable run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2603 Error: Should be true Test: TestSyncImmutable Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2603 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "potato2" 2026/07/30 03:24:03 DEBUG : existing: Need to transfer - File not found at Destination 2026/07/30 03:24:03 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:24:03 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:24:05 DEBUG : existing: size = 6 OK 2026/07/30 03:24:05 INFO : existing: Copied (new) 2026/07/30 03:24:05 DEBUG : Waiting for deletions to finish 2026/07/30 03:24:05 ERROR : potato2: Couldn't delete: could not Remove: Linkbox error 403: not permit 2026/07/30 03:24:05 ERROR : Linkbox root 'rclone-test-fecakiw9wode': not deleting directories as there were IO errors sync_test.go:2610: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2610 Error: Received unexpected error: failed to delete 1 files Test: TestSyncImmutable run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2610 Error: Should be true Test: TestSyncImmutable Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2610 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "potato2" --- FAIL: TestSyncImmutable (25.17s) === RUN TestSyncIgnoreCase run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" sync_test.go:2637: Skipping test as local or remote are case-insensitive run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2637 Error: Should be true Test: TestSyncIgnoreCase Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2637 Error: Should be true Test: TestSyncIgnoreCase Messages: Unexpected file "potato2" --- FAIL: TestSyncIgnoreCase (12.06s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" sync_test.go:2705: This test only runs on local run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2705 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2736 Error: Should be true Test: TestMaxTransfer/Hard Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2705 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2736 Error: Should be true Test: TestMaxTransfer/Hard Messages: Unexpected file "potato2" === RUN TestMaxTransfer/Soft run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" sync_test.go:2705: This test only runs on local run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2705 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2737 Error: Should be true Test: TestMaxTransfer/Soft Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2705 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2737 Error: Should be true Test: TestMaxTransfer/Soft Messages: Unexpected file "potato2" === RUN TestMaxTransfer/Cautious run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" sync_test.go:2705: This test only runs on local run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2705 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2738 Error: Should be true Test: TestMaxTransfer/Cautious Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2705 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2738 Error: Should be true Test: TestMaxTransfer/Cautious Messages: Unexpected file "potato2" --- FAIL: TestMaxTransfer (36.36s) --- FAIL: TestMaxTransfer/Hard (12.10s) --- FAIL: TestMaxTransfer/Soft (12.01s) --- FAIL: TestMaxTransfer/Cautious (12.25s) === RUN TestSyncConcurrentDelete run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:25:09 DEBUG : both0: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:25:09 DEBUG : both0: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:25:10 DEBUG : both0: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:25:14 DEBUG : both1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:25:21 DEBUG : only2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:25:21 DEBUG : only2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:25:28 DEBUG : both4: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:25:28 DEBUG : both4: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:25:28 DEBUG : both4: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:25:29 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:25:29 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:25:29 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:25:29 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:25:29 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:25:29 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:25:30 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:25:30 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:25:32 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:25:34 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:25:36 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:25:38 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:25:40 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:25:42 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:25:44 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:25:45 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:25:48 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:25:48 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:25:48 DEBUG : only4: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:25:49 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:25:49 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:25:49 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:25:51 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:25:51 DEBUG : both5: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:25:51 DEBUG : both5: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:25:52 DEBUG : both5: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:25:52 DEBUG : both5: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:26:00 DEBUG : only6: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:26:00 DEBUG : only6: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:26:00 DEBUG : only6: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:26:01 DEBUG : only6: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:26:02 DEBUG : only6: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/30 03:26:08 DEBUG : only7: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:26:08 DEBUG : only7: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:26:09 DEBUG : only7: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:26:09 DEBUG : only7: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:26:12 DEBUG : both8: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:26:17 DEBUG : both9: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:26:17 DEBUG : both9: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:26:18 DEBUG : both9: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:26:20 DEBUG : only9: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:26:21 DEBUG : only9: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:26:22 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:26:22 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:26:22 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:26:22 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:26:22 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:26:22 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:26:23 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:26:23 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:26:25 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:26:27 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:26:29 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:26:31 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:26:33 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:26:35 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:299: Retry Put of "both10" to Linkbox root 'rclone-test-fecakiw9wode': 1/10 (Update second step failed: invalid character '<' looking for beginning of value) 2026/07/30 03:26:37 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:26:39 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:26:41 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:26:43 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:26:45 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:26:48 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:26:48 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:26:49 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:26:49 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:26:51 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:26:51 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:26:51 DEBUG : only10: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:26:52 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:26:52 DEBUG : only10: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:26:52 DEBUG : only10: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:26:56 DEBUG : both11: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:26:56 DEBUG : both11: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:26:57 DEBUG : both11: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:26:57 DEBUG : both11: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:27:01 DEBUG : only11: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:27:01 DEBUG : only11: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:27:02 DEBUG : only11: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:27:05 DEBUG : both12: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:27:06 DEBUG : both12: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:27:06 DEBUG : both12: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:27:09 DEBUG : only12: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:27:14 DEBUG : only13: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:27:15 DEBUG : only13: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:27:15 DEBUG : only13: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:27:21 DEBUG : only14: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:27:21 DEBUG : only14: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:27:22 DEBUG : only14: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:27:23 DEBUG : only14: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:27:26 DEBUG : both15: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:27:27 DEBUG : both15: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:27:27 DEBUG : both15: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:27:28 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:27:28 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:27:28 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:27:28 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:27:29 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:27:29 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:27:29 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:27:29 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:27:31 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:27:33 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:27:35 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:27:37 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:27:39 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:27:41 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:27:43 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:27:46 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:27:47 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:27:47 DEBUG : only15: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:27:48 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:27:48 DEBUG : only15: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:27:49 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:27:49 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:27:50 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:27:51 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:27:52 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:27:52 DEBUG : both16: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:27:59 DEBUG : only17: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:28:00 DEBUG : only17: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:28:05 DEBUG : only18: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:28:05 DEBUG : only18: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:28:06 DEBUG : only18: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:28:09 DEBUG : both19: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:28:10 DEBUG : both19: 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:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2775 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2789 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncConcurrentDelete Messages: listing wrong, want both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (6), only1 (6), only10 (6), only11 (6), only12 (6), only13 (6), only14 (6), only15 (6), only16 (6), only17 (6), only18 (6), only19 (6), only2 (6), only3 (6), only4 (6), only5 (6), only6 (6), only7 (6), only8 (6), only9 (6) got both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (6), only1 (6), only10 (6), only11 (6), only12 (6), only13 (6), only14 (6), only15 (6), only16 (6), only17 (6), only18 (6), only19 (6), only2 (6), only3 (6), only4 (6), only5 (6), only6 (6), only7 (6), only8 (6), only9 (6), potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2775 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2789 Error: Should be true Test: TestSyncConcurrentDelete Messages: Unexpected file "potato2" 2026/07/30 03:28:22 DEBUG : both0: size = 6 OK 2026/07/30 03:28:22 DEBUG : both0: Sizes identical 2026/07/30 03:28:22 DEBUG : both10: size = 6 OK 2026/07/30 03:28:22 DEBUG : both11: size = 6 OK 2026/07/30 03:28:22 DEBUG : both0: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both12: size = 6 OK 2026/07/30 03:28:22 DEBUG : both13: size = 6 OK 2026/07/30 03:28:22 DEBUG : both12: Sizes identical 2026/07/30 03:28:22 DEBUG : both13: Sizes identical 2026/07/30 03:28:22 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for checks to finish 2026/07/30 03:28:22 DEBUG : both11: Sizes identical 2026/07/30 03:28:22 DEBUG : both10: Sizes identical 2026/07/30 03:28:22 DEBUG : both12: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both14: size = 6 OK 2026/07/30 03:28:22 DEBUG : both14: Sizes identical 2026/07/30 03:28:22 DEBUG : both14: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both11: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both13: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both15: size = 6 OK 2026/07/30 03:28:22 DEBUG : both15: Sizes identical 2026/07/30 03:28:22 DEBUG : both16: size = 6 OK 2026/07/30 03:28:22 DEBUG : both16: Sizes identical 2026/07/30 03:28:22 DEBUG : both17: size = 6 OK 2026/07/30 03:28:22 DEBUG : both10: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both18: size = 6 OK 2026/07/30 03:28:22 DEBUG : both18: Sizes identical 2026/07/30 03:28:22 DEBUG : both16: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both19: size = 6 OK 2026/07/30 03:28:22 DEBUG : both19: Sizes identical 2026/07/30 03:28:22 DEBUG : both19: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both1: size = 6 OK 2026/07/30 03:28:22 DEBUG : both1: Sizes identical 2026/07/30 03:28:22 DEBUG : both17: Sizes identical 2026/07/30 03:28:22 DEBUG : both1: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both2: size = 6 OK 2026/07/30 03:28:22 DEBUG : both2: Sizes identical 2026/07/30 03:28:22 DEBUG : both17: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both3: size = 6 OK 2026/07/30 03:28:22 DEBUG : both3: Sizes identical 2026/07/30 03:28:22 DEBUG : both18: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both4: size = 6 OK 2026/07/30 03:28:22 DEBUG : both4: Sizes identical 2026/07/30 03:28:22 DEBUG : both4: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both5: size = 6 OK 2026/07/30 03:28:22 DEBUG : both5: Sizes identical 2026/07/30 03:28:22 DEBUG : both5: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both2: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both7: size = 6 OK 2026/07/30 03:28:22 DEBUG : both7: Sizes identical 2026/07/30 03:28:22 DEBUG : both6: size = 6 OK 2026/07/30 03:28:22 DEBUG : both6: Sizes identical 2026/07/30 03:28:22 DEBUG : both15: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both8: size = 6 OK 2026/07/30 03:28:22 DEBUG : both8: Sizes identical 2026/07/30 03:28:22 DEBUG : both3: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both9: size = 6 OK 2026/07/30 03:28:22 DEBUG : both9: Sizes identical 2026/07/30 03:28:22 DEBUG : both7: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both6: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both8: Unchanged skipping 2026/07/30 03:28:22 DEBUG : both9: Unchanged skipping 2026/07/30 03:28:22 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Waiting for transfers to finish 2026/07/30 03:28:22 DEBUG : Waiting for deletions to finish 2026/07/30 03:28:24 INFO : only5: Deleted 2026/07/30 03:28:24 INFO : only11: Deleted 2026/07/30 03:28:24 INFO : only7: Deleted 2026/07/30 03:28:24 INFO : only8: Deleted 2026/07/30 03:28:25 INFO : only15: Deleted 2026/07/30 03:28:25 ERROR : potato2: Couldn't delete: could not Remove: Linkbox error 403: not permit 2026/07/30 03:28:25 INFO : only16: Deleted 2026/07/30 03:28:25 INFO : only19: Deleted 2026/07/30 03:28:26 INFO : only4: Deleted 2026/07/30 03:28:26 INFO : only3: Deleted 2026/07/30 03:28:27 INFO : only0: Deleted 2026/07/30 03:28:27 INFO : only10: Deleted 2026/07/30 03:28:27 INFO : only12: Deleted 2026/07/30 03:28:28 INFO : only13: Deleted 2026/07/30 03:28:28 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:28:28 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:28:28 DEBUG : pacer: Reducing sleep to 300ms 2026/07/30 03:28:28 INFO : only17: Deleted 2026/07/30 03:28:28 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:28:28 DEBUG : pacer: Rate limited, increasing sleep to 600ms 2026/07/30 03:28:28 DEBUG : pacer: Reducing sleep to 450ms 2026/07/30 03:28:28 INFO : only1: Deleted 2026/07/30 03:28:28 DEBUG : pacer: Reducing sleep to 337.5ms 2026/07/30 03:28:28 INFO : only2: Deleted 2026/07/30 03:28:28 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:28:28 DEBUG : pacer: Rate limited, increasing sleep to 675ms 2026/07/30 03:28:29 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:28:29 DEBUG : pacer: Rate limited, increasing sleep to 1.35s 2026/07/30 03:28:29 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:28:29 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:28:31 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:28:33 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:28:35 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:28:37 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:28:39 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:28:41 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:28:43 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:28:46 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:28:46 INFO : only6: Deleted 2026/07/30 03:28:48 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:28:48 INFO : only9: Deleted 2026/07/30 03:28:50 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:28:50 INFO : only18: Deleted 2026/07/30 03:28:51 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:28:51 INFO : only14: Deleted 2026/07/30 03:28:51 ERROR : Linkbox root 'rclone-test-fecakiw9wode': not deleting directories as there were IO errors sync_test.go:2783: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2783 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2789 Error: Received unexpected error: failed to delete 1 files Test: TestSyncConcurrentDelete Messages: Sync must not return a error sync_test.go:2784: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2784 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2789 Error: Should be false Test: TestSyncConcurrentDelete Messages: Low level errors must not have happened 2026/07/30 03:28:51 DEBUG : pacer: Reducing sleep to 474.609375ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/07/30 03:28:52 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:28:53 DEBUG : pacer: Reducing sleep to 266.967773ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/07/30 03:28:55 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:28:55 DEBUG : pacer: Reducing sleep to 200ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2785 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2789 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncConcurrentDelete Messages: listing wrong, want both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6) got both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2785 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2789 Error: Should be true Test: TestSyncConcurrentDelete Messages: Unexpected file "potato2" run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Should be true Test: TestSyncConcurrentDelete Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Should be true Test: TestSyncConcurrentDelete Messages: Unexpected file "potato2" --- FAIL: TestSyncConcurrentDelete (271.47s) === RUN TestSyncConcurrentTruncate run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:29:40 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:29:40 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:29:41 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:29:41 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:29:41 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:29:41 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:29:42 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:29:42 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:29:43 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:29:46 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:29:48 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:29:49 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:29:51 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:29:52 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:29:52 DEBUG : only0: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:29:52 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:29:53 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:29:53 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:29:54 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:29:55 DEBUG : both1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:29:55 DEBUG : both1: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:29:55 DEBUG : both1: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:29:56 DEBUG : both1: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:29:59 DEBUG : only1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:30:01 DEBUG : both2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:30:02 DEBUG : both2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:30:02 DEBUG : both2: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:30:03 DEBUG : both2: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:30:08 DEBUG : both3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:30:08 DEBUG : both3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:30:08 DEBUG : both3: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:30:09 DEBUG : both3: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:30:12 DEBUG : only3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:30:12 DEBUG : only3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:30:13 DEBUG : only3: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:30:13 DEBUG : only3: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:30:17 DEBUG : both4: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:30:17 DEBUG : both4: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:30:17 DEBUG : both4: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:30:20 DEBUG : only4: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:30:20 DEBUG : only4: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:30:21 DEBUG : only4: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:30:24 DEBUG : both5: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:30:26 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:30:26 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:30:26 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:30:26 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:30:26 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:30:26 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:30:27 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:30:27 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:30:29 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:30:31 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:30:33 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:30:35 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:30:37 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:30:39 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:299: Retry Put of "only5" to Linkbox root 'rclone-test-fecakiw9wode': 1/10 (Update failed to read object: getting files failed: invalid character '<' looking for beginning of value) 2026/07/30 03:30:41 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:30:43 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:30:45 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:30:47 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:30:47 DEBUG : only5: Update: removing old file 2026/07/30 03:30:50 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:30:50 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:30:53 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:30:53 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:30:53 DEBUG : only5: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:30:53 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:30:53 DEBUG : only5: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:30:54 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:30:54 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:30:55 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:30:56 DEBUG : both6: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:30:56 DEBUG : both6: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:30:57 DEBUG : both6: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:30:57 DEBUG : both6: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:31:01 DEBUG : only6: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:31:03 DEBUG : both7: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:31:03 DEBUG : both7: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:31:04 DEBUG : both7: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:31:06 DEBUG : only7: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:31:07 DEBUG : only7: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:31:09 DEBUG : both8: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:31:10 DEBUG : both8: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:31:10 DEBUG : both8: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:31:11 DEBUG : both8: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:31:16 DEBUG : both9: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:31:16 DEBUG : both9: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:31:19 DEBUG : only9: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:31:19 DEBUG : only9: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:31:19 DEBUG : only9: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:31:22 DEBUG : both10: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:31:23 DEBUG : both10: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:31:23 DEBUG : both10: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:31:26 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:31:26 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:31:26 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:31:26 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:31:26 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:31:26 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:31:27 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:31:27 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:31:29 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:31:31 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:31:33 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:31:35 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:31:37 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:31:39 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:299: Retry Put of "only10" to Linkbox root 'rclone-test-fecakiw9wode': 1/10 (Update failed to read object: getting files failed: invalid character '<' looking for beginning of value) 2026/07/30 03:31:41 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:31:43 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:31:45 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:31:47 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:31:47 DEBUG : only10: Update: removing old file 2026/07/30 03:31:50 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:31:50 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:31:53 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:31:53 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:31:53 DEBUG : only10: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:31:54 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:31:54 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:31:54 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:31:56 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:31:58 DEBUG : only11: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:32:01 DEBUG : both12: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:32:02 DEBUG : both12: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:32:03 DEBUG : both12: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:32:06 DEBUG : only12: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:32:06 DEBUG : only12: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:32:10 DEBUG : both13: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:32:10 DEBUG : both13: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:32:11 DEBUG : both13: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:32:14 DEBUG : only13: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:32:17 DEBUG : both14: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:32:17 DEBUG : both14: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:32:20 DEBUG : only14: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:32:21 DEBUG : only14: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:32:24 DEBUG : both15: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:32:24 DEBUG : both15: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:32:27 DEBUG : only15: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:32:30 DEBUG : both16: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:32:30 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:32:30 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:32:30 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:32:30 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:32:31 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:32:31 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:32:31 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:32:31 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:32:33 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:32:35 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:32:37 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:32:39 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:32:41 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:32:43 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:299: Retry Put of "both16" to Linkbox root 'rclone-test-fecakiw9wode': 1/10 (Update failed to read object: getting files failed: invalid character '<' looking for beginning of value) 2026/07/30 03:32:45 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:32:47 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:32:47 DEBUG : both16: Update: removing old file 2026/07/30 03:32:50 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:32:51 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:32:53 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:32:53 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:32:53 DEBUG : both16: Trying to read object after upload: try again in 100ms (1/10) 2026/07/30 03:32:54 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:32:54 DEBUG : both16: Trying to read object after upload: try again in 200ms (2/10) 2026/07/30 03:32:55 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:32:55 DEBUG : both16: Trying to read object after upload: try again in 400ms (3/10) 2026/07/30 03:32:55 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:32:55 DEBUG : both16: Trying to read object after upload: try again in 800ms (4/10) 2026/07/30 03:32:57 DEBUG : pacer: Reducing sleep to 200ms 2026/07/30 03:32:57 DEBUG : both16: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/30 03:32:59 DEBUG : both16: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/30 03:33:02 DEBUG : both16: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/30 03:33:09 DEBUG : both16: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/30 03:33:22 DEBUG : both16: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/30 03:33:48 DEBUG : both16: Trying to read object after upload: try again in 51.2s (10/10) run.go:303: Failed to put "both16" to "Linkbox root 'rclone-test-fecakiw9wode'": object not found run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /usr/local/go/src/testing/testing.go:1228 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:303 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:321 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2767 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2793 Error: Should be true Test: TestSyncConcurrentTruncate Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /usr/local/go/src/testing/testing.go:1228 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:303 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:321 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2767 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2793 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncConcurrentTruncate Messages: Unexpected file "potato2" --- FAIL: TestSyncConcurrentTruncate (356.95s) === RUN TestSyncReplaceDirModTime run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:35:35 DEBUG : empty_dir: Making directory with metadata 2026/07/30 03:35:35 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/30 03:35:35 DEBUG : empty_on_remote: Making directory with metadata 2026/07/30 03:35:35 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/30 03:35:35 INFO : empty_on_remote: Making directory 2026/07/30 03:35:37 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2026/07/30 03:35:46 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2026/07/30 03:35:46 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2026/07/30 03:35:46 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2026/07/30 03:35:46 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2857 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2864 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncReplaceDirModTime Messages: listing wrong, want file1 (5), test_dir1/file2 (5), test_dir2/sub_dir/file3 (5) got file1 (5), potato2 (60), test_dir1/file2 (5), test_dir2/sub_dir/file3 (5) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2857 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2864 Error: Should be true Test: TestSyncReplaceDirModTime Messages: Unexpected file "potato2" run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit run.go:186: removing dir "test_dir2/sub_dir" 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:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Should be true Test: TestSyncReplaceDirModTime Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Should be true Test: TestSyncReplaceDirModTime Messages: Unexpected file "potato2" --- FAIL: TestSyncReplaceDirModTime (46.67s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:36:22 DEBUG : empty_dir: Making directory with metadata 2026/07/30 03:36:22 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/30 03:36:22 DEBUG : empty_on_remote: Making directory with metadata 2026/07/30 03:36:22 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/30 03:36:22 INFO : empty_on_remote: Making directory 2026/07/30 03:36:24 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2026/07/30 03:36:35 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2026/07/30 03:36:35 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2026/07/30 03:36:35 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2026/07/30 03:36:35 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2026/07/30 03:36:35 INFO : empty_dir: Set directory modification time (using DirSetModTime) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2857 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2868 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncReplaceDirModTimeWithEmptyDirs Messages: listing wrong, want file1 (5), test_dir1/file2 (5), test_dir2/sub_dir/file3 (5) got file1 (5), potato2 (60), test_dir1/file2 (5), test_dir2/sub_dir/file3 (5) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2857 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2868 Error: Should be true Test: TestSyncReplaceDirModTimeWithEmptyDirs Messages: Unexpected file "potato2" run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit run.go:186: removing dir "test_dir2/sub_dir" failed - try 1/3: sleeping 1s: directory not empty run.go:186: removing dir "test_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:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Should be true Test: TestSyncReplaceDirModTimeWithEmptyDirs Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Should be true Test: TestSyncReplaceDirModTimeWithEmptyDirs Messages: Unexpected file "potato2" --- FAIL: TestSyncReplaceDirModTimeWithEmptyDirs (53.94s) === RUN TestNothingToTransferWithEmptyDirs run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:37:16 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/30 03:37:16 INFO : sub dir: Making directory 2026/07/30 03:37:18 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC 2026/07/30 03:37:48 ERROR : sub dir2/very/very/very/very/very: error listing: getting files failed: invalid character '<' looking for beginning of value fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value 2026/07/30 03:37:50 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:37:52 DEBUG : pacer: Reducing sleep to 1.125s run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit 2026/07/30 03:37:54 DEBUG : pacer: Reducing sleep to 843.75ms run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit 2026/07/30 03:37:55 DEBUG : pacer: Reducing sleep to 632.8125ms run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit 2026/07/30 03:37:56 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:37:57 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:37:57 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:37:59 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:37:59 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/very/nested" failed - try 1/3: sleeping 1s: directory not empty run.go:186: removing dir "sub dir2/very/very/very/very/very" 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/very" failed - try 2/3: sleeping 1.5s: 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 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /usr/local/go/src/testing/testing.go:1228 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:282 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2904 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2977 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestNothingToTransferWithEmptyDirs Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /usr/local/go/src/testing/testing.go:1228 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:282 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2904 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2977 Error: Should be true Test: TestNothingToTransferWithEmptyDirs Messages: Unexpected file "potato2" --- FAIL: TestNothingToTransferWithEmptyDirs (78.32s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:198: Remote "Linkbox root 'rclone-test-fecakiw9wode'", Local "Local file system at /tmp/rclone1350656208", Modify Window "876000h0m0s" 2026/07/30 03:38:34 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/30 03:38:34 INFO : sub dir: Making directory 2026/07/30 03:38:36 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2904 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2981 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestNothingToTransferWithoutEmptyDirs Messages: listing wrong, want sub dir/hello world (11), sub dir2/very/very/very/very/very/nested/subdir/hello world (11) got potato2 (60), sub dir/hello world (11), sub dir2/very/very/very/very/very/nested/subdir/hello world (11) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2904 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2981 Error: Should be true Test: TestNothingToTransferWithoutEmptyDirs Messages: Unexpected file "potato2" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2926 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2981 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestNothingToTransferWithoutEmptyDirs Messages: listing wrong, want sub dir/hello world (11), sub dir2/very/very/very/very/very/nested/subdir/hello world (11) got potato2 (60), sub dir/hello world (11), sub dir2/very/very/very/very/very/nested/subdir/hello world (11) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2926 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2981 Error: Should be true Test: TestNothingToTransferWithoutEmptyDirs Messages: Unexpected file "potato2" run.go:172: removing file "potato2" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato2" failed: could not Remove: Linkbox error 403: not permit 2026/07/30 03:39:28 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:39:28 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/30 03:39:28 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:39:28 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/30 03:39:28 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:39:28 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/30 03:39:29 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:39:29 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/30 03:39:31 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:39:33 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:39:35 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:39:37 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:39:39 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:39:41 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:172: removing file "sub dir/hello world" failed - try 1/3: sleeping 1s: could not Remove: invalid character '<' looking for beginning of value 2026/07/30 03:39:43 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:39:45 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:39:47 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:39:49 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/30 03:39:52 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/30 03:39:53 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/30 03:39:54 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/30 03:39:55 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/30 03:39:56 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/30 03:39:57 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/30 03:39:57 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/30 03:39:59 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/30 03:39:59 DEBUG : pacer: Reducing sleep to 200ms 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" 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:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Should be true Test: TestNothingToTransferWithoutEmptyDirs Messages: listing wrong, want got potato2 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Should be true Test: TestNothingToTransferWithoutEmptyDirs Messages: Unexpected file "potato2" --- FAIL: TestNothingToTransferWithoutEmptyDirs (112.38s) FAIL 2026/07/30 03:40:26 DEBUG : Linkbox root 'rclone-test-fecakiw9wode': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCopyDeleteBefore|TestCopyRedownload|TestCopyWithDepth|TestCopyWithFilesFrom|TestMoveOverSelf|TestMoveWithDeleteEmptySrcDirs|TestMoveWithIgnoreExisting|TestMoveWithoutDeleteEmptySrcDirs|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveLocal|TestServerSideMoveOverSelf|TestServerSideMoveOverlap|TestServerSideMoveWithFilter|TestSyncAfterChangingContentsOnly|TestSyncAfterChangingFilesSizeOnly|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncAfterRemovingAFileAndAddingAFileDryRun|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncCompareDest|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncCopyDest|TestSyncDeleteBefore|TestSyncDeleteDuring|TestSyncEmptyDirectories|TestSyncIgnoreCase|TestSyncImmutable|TestSyncMultipleCompareDest|TestSyncNoEmptyDirectories|TestSyncOverlap|TestSyncReplaceDirModTime|TestSyncReplaceDirModTimeWithEmptyDirs|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncUTFNorm|TestSyncWithExclude|TestSyncWithExcludeAndDeleteExcluded|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime|TestSyncWithUpdateOlder)$|^TestMaxTransfer$/^(Cautious|Hard|Soft)$'" - Finished ERROR in 46m30.352586049s (try 2/5): exit status 1: Failed [TestServerSideCopyOverSelf TestServerSideMoveOverSelf TestSyncAfterChangingFilesSizeOnly TestSyncAfterChangingContentsOnly TestSyncAfterRemovingAFileAndAddingAFileSubDir TestSyncWithExclude TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestServerSideMove TestServerSideMoveDeleteEmptySourceDirs TestServerSideMoveOverlap TestSyncOverlap TestSyncCompareDest TestSyncMultipleCompareDest TestSyncCopyDest TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension TestSyncUTFNorm TestSyncImmutable TestSyncIgnoreCase TestMaxTransfer/Hard TestMaxTransfer/Soft TestMaxTransfer/Cautious TestSyncConcurrentDelete TestSyncConcurrentTruncate TestSyncReplaceDirModTime TestSyncReplaceDirModTimeWithEmptyDirs TestNothingToTransferWithEmptyDirs TestNothingToTransferWithoutEmptyDirs]