"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestMoveOverSelf|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncCompareDest|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncCopyDest|TestSyncIgnoreCase|TestSyncImmutable|TestSyncMultipleCompareDest|TestSyncReplaceDirModTime|TestSyncReplaceDirModTimeWithEmptyDirs|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncUTFNorm)$|^TestMaxTransfer$/^(Cautious|Hard|Soft)$'" - Starting (try 3/5) 2026/07/24 04:03:16 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-rarepop5hacu" 2026/07/24 04:03:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/24 04:03:16 DEBUG : Linkbox root 'rclone-test-rarepop5hacu': Using cached web token 2026/07/24 04:03:17 DEBUG : Creating backend with remote "/tmp/rclone1388196193" === RUN TestMoveOverSelf run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" 2026/07/24 04:03:22 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:03:23 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:03:24 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:03:24 DEBUG : sub dir/hello world: size = 11 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:03:24 DEBUG : sub dir/hello world: Sizes differ 2026/07/24 04:03:24 DEBUG : sub dir/hello world: Update: removing old file 2026/07/24 04:03:24 DEBUG : Linkbox root 'rclone-test-rarepop5hacu': Waiting for checks to finish 2026/07/24 04:03:24 DEBUG : Linkbox root 'rclone-test-rarepop5hacu': Waiting for transfers to finish 2026/07/24 04:03:28 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:03:28 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:03:28 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:03:29 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 800ms (4/10) 2026/07/24 04:03:30 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/24 04:03:32 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/24 04:03:35 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/24 04:03:42 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/24 04:03:55 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/24 04:04:21 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 51.2s (10/10) 2026/07/24 04:05:12 ERROR : sub dir/hello world: Failed to copy: object not found 2026/07/24 04:05:12 ERROR : sub dir/hello world: Not deleting source as copy failed: object not found sync_test.go:685: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:685 Error: Received unexpected error: object not found Test: TestMoveOverSelf --- FAIL: TestMoveOverSelf (118.83s) === RUN TestServerSideMoveOverSelf run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" 2026/07/24 04:05:20 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:05:20 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:05:21 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:05:22 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 800ms (4/10) 2026/07/24 04:05:23 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-wulutun0suze" 2026/07/24 04:05:23 DEBUG : Linkbox root 'rclone-test-wulutun0suze': Using cached web token sync_test.go:701: Server side copy (if possible) Linkbox root 'rclone-test-rarepop5hacu' -> Linkbox root 'rclone-test-wulutun0suze' 2026/07/24 04:05:25 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/24 04:05:25 DEBUG : Linkbox root 'rclone-test-wulutun0suze': Waiting for checks to finish 2026/07/24 04:05:25 DEBUG : Linkbox root 'rclone-test-wulutun0suze': Waiting for transfers to finish 2026/07/24 04:05:25 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 1009h2m48.296455672s different from this computer 2026/07/24 04:05:30 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:05:30 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:05:31 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:05:32 DEBUG : sub dir/hello world: size = 11 OK 2026/07/24 04:05:32 INFO : sub dir/hello world: Copied (new) 2026/07/24 04:05:32 DEBUG : sub dir/hello world: Update: removing old file 2026/07/24 04:05:35 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:05:36 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:05:36 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:05:37 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 800ms (4/10) 2026/07/24 04:05:38 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/24 04:05:40 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/24 04:05:43 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/24 04:05:50 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/24 04:06:03 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/24 04:06:29 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-rarepop5hacu'": object not found 2026/07/24 04:07:20 DEBUG : Linkbox root 'rclone-test-wulutun0suze': Purge remote run.go:186: removing dir "sub dir" failed - try 1/3: sleeping 1s: directory not empty --- FAIL: TestServerSideMoveOverSelf (131.54s) === RUN TestServerSideMoveWithFilter run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" 2026/07/24 04:07:27 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-cularoj9naka" 2026/07/24 04:07:27 DEBUG : Config file has changed externally - reloading 2026/07/24 04:07:27 DEBUG : Linkbox root 'rclone-test-cularoj9naka': Using cached web token 2026/07/24 04:07:31 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:07:34 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:07:34 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:07:34 DEBUG : empty space: Trying to read object after upload: try again in 400ms (3/10) sync_test.go:1736: Server side move (if possible) Linkbox root 'rclone-test-rarepop5hacu' -> Linkbox root 'rclone-test-cularoj9naka' 2026/07/24 04:07:44 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 2026/07/24 04:07:52 DEBUG : empty space: Excluded (Size Filter) 2026/07/24 04:07:52 DEBUG : empty space: Excluded 2026/07/24 04:07:53 DEBUG : empty space: Excluded (Size Filter) 2026/07/24 04:07:53 DEBUG : empty space: Excluded 2026/07/24 04:07:53 DEBUG : potato2: Need to transfer - File not found at Destination 2026/07/24 04:07:53 DEBUG : potato3: size = 68 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:07:53 DEBUG : potato3: size = 60 (Linkbox root 'rclone-test-cularoj9naka') 2026/07/24 04:07:53 DEBUG : potato3: Sizes differ 2026/07/24 04:07:53 DEBUG : Linkbox root 'rclone-test-cularoj9naka': Waiting for checks to finish 2026/07/24 04:07:53 DEBUG : Linkbox root 'rclone-test-cularoj9naka': Waiting for transfers to finish 2026/07/24 04:07:53 DEBUG : potato3: Update: removing old file 2026/07/24 04:07:55 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:07:55 DEBUG : potato2: size = 60 OK 2026/07/24 04:07:55 INFO : potato2: Copied (new) 2026/07/24 04:07:56 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:07:56 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:07:57 INFO : potato2: Deleted 2026/07/24 04:07:57 DEBUG : potato3: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:07:57 DEBUG : potato3: Trying to read object after upload: try again in 800ms (4/10) 2026/07/24 04:07:59 DEBUG : potato3: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/24 04:08:00 DEBUG : potato3: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/24 04:08:04 DEBUG : potato3: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/24 04:08:10 DEBUG : potato3: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/24 04:08:23 DEBUG : potato3: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/24 04:08:49 DEBUG : potato3: Trying to read object after upload: try again in 51.2s (10/10) 2026/07/24 04:09:40 ERROR : potato3: Failed to copy: object not found 2026/07/24 04:09:40 ERROR : potato3: Not deleting source as copy failed: object not found sync_test.go:1747: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1747 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1925 Error: Received unexpected error: object not found Test: TestServerSideMoveWithFilter 2026/07/24 04:09:40 DEBUG : Linkbox root 'rclone-test-cularoj9naka': Purge remote --- FAIL: TestServerSideMoveWithFilter (138.49s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" 2026/07/24 04:09:46 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-papuxaw4naxi" 2026/07/24 04:09:46 DEBUG : Config file has changed externally - reloading 2026/07/24 04:09:46 DEBUG : Linkbox root 'rclone-test-papuxaw4naxi': Using cached web token 2026/07/24 04:09:49 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:09:49 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:09:52 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:09:52 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:09:55 INFO : tomatoDir: Making directory sync_test.go:1736: Server side move (if possible) Linkbox root 'rclone-test-rarepop5hacu' -> Linkbox root 'rclone-test-papuxaw4naxi' 2026/07/24 04:10:03 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:10:03 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:10:03 DEBUG : potato3: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:10:05 DEBUG : potato2: Need to transfer - File not found at Destination 2026/07/24 04:10:05 DEBUG : empty space: size = 1 OK 2026/07/24 04:10:05 DEBUG : empty space: Sizes identical 2026/07/24 04:10:05 DEBUG : empty space: Unchanged skipping 2026/07/24 04:10:05 DEBUG : potato3: size = 68 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:10:05 DEBUG : potato3: size = 60 (Linkbox root 'rclone-test-papuxaw4naxi') 2026/07/24 04:10:05 DEBUG : potato3: Sizes differ 2026/07/24 04:10:05 DEBUG : Linkbox root 'rclone-test-papuxaw4naxi': Waiting for checks to finish 2026/07/24 04:10:05 DEBUG : potato3: Update: removing old file 2026/07/24 04:10:06 INFO : empty space: Deleted 2026/07/24 04:10:06 DEBUG : Linkbox root 'rclone-test-papuxaw4naxi': Waiting for transfers to finish 2026/07/24 04:10:08 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:10:08 DEBUG : potato2: size = 60 OK 2026/07/24 04:10:08 INFO : potato2: Copied (new) 2026/07/24 04:10:09 DEBUG : potato3: size = 68 OK 2026/07/24 04:10:09 INFO : potato3: Copied (replaced existing) 2026/07/24 04:10:09 INFO : potato2: Deleted 2026/07/24 04:10:10 INFO : potato3: Deleted 2026/07/24 04:10:10 INFO : tomatoDir: Removing directory 2026/07/24 04:10:12 DEBUG : Linkbox root 'rclone-test-rarepop5hacu': deleted 1 directories 2026/07/24 04:10:12 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-lajalul3jezu" 2026/07/24 04:10:12 DEBUG : Linkbox root 'rclone-test-lajalul3jezu': Using cached web token 2026/07/24 04:10:13 INFO : tomatoDir: Making directory 2026/07/24 04:10:15 DEBUG : empty space: Need to transfer - File not found at Destination 2026/07/24 04:10:15 DEBUG : potato2: Need to transfer - File not found at Destination 2026/07/24 04:10:15 DEBUG : potato3: Need to transfer - File not found at Destination 2026/07/24 04:10:15 DEBUG : Linkbox root 'rclone-test-lajalul3jezu': Waiting for checks to finish 2026/07/24 04:10:15 DEBUG : Linkbox root 'rclone-test-lajalul3jezu': Waiting for transfers to finish 2026/07/24 04:10:20 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:10:20 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:10:20 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:10:20 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:10:21 DEBUG : potato2: size = 60 OK 2026/07/24 04:10:21 INFO : potato2: Copied (new) 2026/07/24 04:10:21 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:10:21 DEBUG : empty space: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:10:21 DEBUG : potato3: size = 68 OK 2026/07/24 04:10:21 INFO : potato3: Copied (new) 2026/07/24 04:10:22 DEBUG : empty space: size = 1 OK 2026/07/24 04:10:22 INFO : empty space: Copied (new) 2026/07/24 04:10:22 INFO : potato2: Deleted 2026/07/24 04:10:22 INFO : potato3: Deleted 2026/07/24 04:10:23 INFO : empty space: Deleted 2026/07/24 04:10:23 INFO : tomatoDir: Removing directory 2026/07/24 04:10:24 DEBUG : Linkbox root 'rclone-test-papuxaw4naxi': deleted 1 directories 2026/07/24 04:10:25 DEBUG : Linkbox root 'rclone-test-lajalul3jezu': Purge remote 2026/07/24 04:10:26 DEBUG : Linkbox root 'rclone-test-papuxaw4naxi': Purge remote --- PASS: TestServerSideMoveDeleteEmptySourceDirs (42.26s) === RUN TestSyncCompareDest run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" 2026/07/24 04:10:28 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-rarepop5hacu/dst" 2026/07/24 04:10:28 DEBUG : Config file has changed externally - reloading 2026/07/24 04:10:28 DEBUG : Linkbox root 'rclone-test-rarepop5hacu/dst': Using cached web token 2026/07/24 04:10:29 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-rarepop5hacu/CompareDest" 2026/07/24 04:10:29 DEBUG : Linkbox root 'rclone-test-rarepop5hacu/CompareDest': Using cached web token 2026/07/24 04:10:31 DEBUG : one: Need to transfer - File not found at Destination 2026/07/24 04:10:31 DEBUG : Linkbox root 'rclone-test-rarepop5hacu/dst': Waiting for checks to finish 2026/07/24 04:10:31 DEBUG : Linkbox root 'rclone-test-rarepop5hacu/dst': Waiting for transfers to finish 2026/07/24 04:10:35 DEBUG : one: size = 3 OK 2026/07/24 04:10:35 INFO : one: Copied (new) 2026/07/24 04:10:35 DEBUG : Waiting for deletions to finish 2026/07/24 04:10:36 DEBUG : one: size = 5 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:10:36 DEBUG : one: size = 3 (Linkbox root 'rclone-test-rarepop5hacu/dst') 2026/07/24 04:10:36 DEBUG : one: Sizes differ 2026/07/24 04:10:36 DEBUG : Linkbox root 'rclone-test-rarepop5hacu/dst': Waiting for checks to finish 2026/07/24 04:10:36 DEBUG : Linkbox root 'rclone-test-rarepop5hacu/dst': Waiting for transfers to finish 2026/07/24 04:10:36 DEBUG : one: Update: removing old file 2026/07/24 04:10:39 DEBUG : one: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:10:39 DEBUG : one: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:10:40 DEBUG : one: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:10:40 DEBUG : one: Trying to read object after upload: try again in 800ms (4/10) 2026/07/24 04:10:41 DEBUG : one: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/24 04:10:43 DEBUG : one: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/24 04:10:47 DEBUG : one: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/24 04:10:53 DEBUG : one: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/24 04:11:06 DEBUG : one: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/24 04:11:32 DEBUG : one: Trying to read object after upload: try again in 51.2s (10/10) 2026/07/24 04:12:23 ERROR : one: Failed to copy: object not found 2026/07/24 04:12:23 ERROR : Linkbox root 'rclone-test-rarepop5hacu/dst': not deleting files as there were IO errors 2026/07/24 04:12:23 ERROR : Linkbox root 'rclone-test-rarepop5hacu/dst': not deleting directories as there were IO errors sync_test.go:2132: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2132 Error: Received unexpected error: object not found Test: TestSyncCompareDest run.go:186: removing dir "dst" failed - try 1/3: sleeping 1s: directory not empty --- FAIL: TestSyncCompareDest (120.03s) === RUN TestSyncMultipleCompareDest run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" 2026/07/24 04:12:33 DEBUG : pre-dest1/1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:12:38 DEBUG : pre-dest2/2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:12:39 DEBUG : pre-dest2/2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:12:40 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-rarepop5hacu/dest" 2026/07/24 04:12:40 DEBUG : Config file has changed externally - reloading 2026/07/24 04:12:40 DEBUG : Linkbox root 'rclone-test-rarepop5hacu/dest': Using cached web token 2026/07/24 04:12:41 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-rarepop5hacu/pre-dest1" 2026/07/24 04:12:41 DEBUG : Linkbox root 'rclone-test-rarepop5hacu/pre-dest1': Using cached web token 2026/07/24 04:12:41 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-rarepop5hacu/pre-dest2" 2026/07/24 04:12:41 DEBUG : Linkbox root 'rclone-test-rarepop5hacu/pre-dest2': Using cached web token 2026/07/24 04:12:42 DEBUG : 1: size = 1 OK 2026/07/24 04:12:42 DEBUG : 1: Sizes identical 2026/07/24 04:12:42 DEBUG : 1: Destination found in --compare-dest, skipping 2026/07/24 04:12:43 DEBUG : 2: size = 1 OK 2026/07/24 04:12:43 DEBUG : 2: Sizes identical 2026/07/24 04:12:43 DEBUG : 2: Destination found in --compare-dest, skipping 2026/07/24 04:12:43 DEBUG : 3: Need to transfer - File not found at Destination 2026/07/24 04:12:43 DEBUG : Linkbox root 'rclone-test-rarepop5hacu/dest': Waiting for checks to finish 2026/07/24 04:12:43 DEBUG : Linkbox root 'rclone-test-rarepop5hacu/dest': Waiting for transfers to finish 2026/07/24 04:12:47 DEBUG : 3: size = 1 OK 2026/07/24 04:12:47 INFO : 3: Copied (new) 2026/07/24 04:12:47 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (28.98s) === RUN TestSyncCopyDest run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" sync_test.go:2266: Skipping test as remote does not support server-side copy --- SKIP: TestSyncCopyDest (0.40s) === RUN TestSyncBackupDir run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" sync_test.go:2379: Skipping test as remote does not support server-side move --- SKIP: TestSyncBackupDir (0.40s) === RUN TestSyncBackupDirWithSuffix run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" sync_test.go:2379: Skipping test as remote does not support server-side move --- SKIP: TestSyncBackupDirWithSuffix (0.41s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" sync_test.go:2379: Skipping test as remote does not support server-side move --- SKIP: TestSyncBackupDirWithSuffixKeepExtension (0.42s) === RUN TestSyncBackupDirSuffixOnly run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" sync_test.go:2379: Skipping test as remote does not support server-side move --- SKIP: TestSyncBackupDirSuffixOnly (0.41s) === RUN TestSyncSuffix run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" sync_test.go:2480: Skipping test as remote does not support server-side move --- SKIP: TestSyncSuffix (0.40s) === RUN TestSyncSuffixKeepExtension run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" sync_test.go:2480: Skipping test as remote does not support server-side move --- SKIP: TestSyncSuffixKeepExtension (0.43s) === RUN TestSyncUTFNorm run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" 2026/07/24 04:13:02 DEBUG : Testêé: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:13:02 DEBUG : Testêé: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:13:03 DEBUG : Linkbox root 'rclone-test-rarepop5hacu': Waiting for checks to finish 2026/07/24 04:13:03 DEBUG : Testêé: size = 14 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:13:03 DEBUG : Testêé: size = 18 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:13:03 DEBUG : Testêé: Sizes differ 2026/07/24 04:13:03 DEBUG : Linkbox root 'rclone-test-rarepop5hacu': Waiting for transfers to finish 2026/07/24 04:13:03 DEBUG : Testêé: Update: removing old file 2026/07/24 04:13:06 DEBUG : Testêé: size = 14 OK 2026/07/24 04:13:06 INFO : Testêé: Copied (replaced existing) to: Testêé 2026/07/24 04:13:06 DEBUG : Waiting for deletions to finish fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestSyncUTFNorm (15.71s) === RUN TestSyncImmutable run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" 2026/07/24 04:13:16 DEBUG : existing: Need to transfer - File not found at Destination 2026/07/24 04:13:16 DEBUG : Linkbox root 'rclone-test-rarepop5hacu': Waiting for checks to finish 2026/07/24 04:13:16 DEBUG : Linkbox root 'rclone-test-rarepop5hacu': Waiting for transfers to finish 2026/07/24 04:13:18 DEBUG : existing: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:13:19 DEBUG : existing: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:13:19 DEBUG : existing: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:13:20 DEBUG : existing: size = 6 OK 2026/07/24 04:13:20 INFO : existing: Copied (new) 2026/07/24 04:13:20 DEBUG : Waiting for deletions to finish 2026/07/24 04:13:20 DEBUG : Linkbox root 'rclone-test-rarepop5hacu': Waiting for checks to finish 2026/07/24 04:13:20 DEBUG : existing: size = 8 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:13:20 DEBUG : existing: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:13:20 DEBUG : existing: Sizes differ 2026/07/24 04:13:20 ERROR : existing: Source and destination exist but do not match: immutable file modified 2026/07/24 04:13:20 DEBUG : Linkbox root 'rclone-test-rarepop5hacu': Waiting for transfers to finish 2026/07/24 04:13:20 ERROR : Linkbox root 'rclone-test-rarepop5hacu': not deleting files as there were IO errors 2026/07/24 04:13:20 ERROR : Linkbox root 'rclone-test-rarepop5hacu': not deleting directories as there were IO errors 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: TestSyncImmutable (13.96s) === RUN TestSyncIgnoreCase run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" sync_test.go:2637: Skipping test as local or remote are case-insensitive --- SKIP: TestSyncIgnoreCase (0.40s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" sync_test.go:2705: This test only runs on local === RUN TestMaxTransfer/Soft run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" sync_test.go:2705: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" sync_test.go:2705: This test only runs on local --- PASS: TestMaxTransfer (1.25s) --- SKIP: TestMaxTransfer/Hard (0.42s) --- SKIP: TestMaxTransfer/Soft (0.40s) --- SKIP: TestMaxTransfer/Cautious (0.42s) === RUN TestSyncConcurrentDelete run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" 2026/07/24 04:13:33 DEBUG : both0: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:13:34 DEBUG : both0: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:13:38 DEBUG : both1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:13:38 DEBUG : both1: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:13:41 DEBUG : only1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:13:41 DEBUG : only1: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:13:46 DEBUG : only2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:13:46 DEBUG : only2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:13:46 DEBUG : only2: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:13:51 DEBUG : only3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:13:51 DEBUG : only3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:13:52 DEBUG : only3: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:13:54 DEBUG : both4: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:13:55 DEBUG : both4: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:13:57 DEBUG : only4: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:13:57 DEBUG : only4: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:14:00 DEBUG : both5: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:14:02 DEBUG : only5: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:14:02 DEBUG : only5: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:14:05 DEBUG : both6: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:14:05 DEBUG : both6: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:14:06 DEBUG : both6: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:14:06 DEBUG : both6: Trying to read object after upload: try again in 800ms (4/10) 2026/07/24 04:14:07 DEBUG : both6: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/24 04:14:13 DEBUG : both7: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:14:15 DEBUG : only7: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:14:16 DEBUG : only7: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:14:16 DEBUG : only7: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:14:17 DEBUG : only7: Trying to read object after upload: try again in 800ms (4/10) 2026/07/24 04:14:24 DEBUG : both9: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:14:24 DEBUG : both9: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:14:24 DEBUG : both9: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:14:27 DEBUG : only9: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:14:27 DEBUG : only9: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:14:30 DEBUG : both10: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:14:30 DEBUG : both10: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:14:33 DEBUG : only10: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:14:35 DEBUG : both11: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:14:35 DEBUG : both11: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:14:38 DEBUG : only11: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:14:39 DEBUG : only11: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:14:39 DEBUG : only11: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:14:40 DEBUG : only11: Trying to read object after upload: try again in 800ms (4/10) 2026/07/24 04:14:43 DEBUG : both12: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:14:46 DEBUG : only12: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:14:47 DEBUG : only12: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:14:47 DEBUG : only12: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:14:53 DEBUG : only13: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:14:53 DEBUG : only13: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:14:54 DEBUG : only13: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:14:57 DEBUG : both14: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:14:57 DEBUG : both14: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:15:00 DEBUG : only14: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:15:01 DEBUG : only14: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:15:04 DEBUG : both15: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:15:04 DEBUG : both15: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:15:05 DEBUG : both15: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:15:10 DEBUG : both16: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:15:11 DEBUG : both16: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:15:13 DEBUG : only16: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:15:16 DEBUG : both17: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:15:19 DEBUG : only17: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:15:20 DEBUG : only17: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:15:20 DEBUG : only17: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:15:23 DEBUG : both18: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:15:26 DEBUG : only18: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:15:27 DEBUG : only18: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:15:27 DEBUG : only18: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:15:30 DEBUG : both19: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:15:31 DEBUG : both19: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:15:34 DEBUG : both0: size = 6 OK 2026/07/24 04:15:34 DEBUG : both0: Sizes identical 2026/07/24 04:15:34 DEBUG : both0: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both10: size = 6 OK 2026/07/24 04:15:34 DEBUG : both10: Sizes identical 2026/07/24 04:15:34 DEBUG : both10: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both11: size = 6 OK 2026/07/24 04:15:34 DEBUG : both11: Sizes identical 2026/07/24 04:15:34 DEBUG : both11: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both12: size = 6 OK 2026/07/24 04:15:34 DEBUG : both12: Sizes identical 2026/07/24 04:15:34 DEBUG : both12: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both13: size = 6 OK 2026/07/24 04:15:34 DEBUG : both13: Sizes identical 2026/07/24 04:15:34 DEBUG : both13: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both14: size = 6 OK 2026/07/24 04:15:34 DEBUG : both14: Sizes identical 2026/07/24 04:15:34 DEBUG : both14: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both15: size = 6 OK 2026/07/24 04:15:34 DEBUG : both15: Sizes identical 2026/07/24 04:15:34 DEBUG : both15: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both16: size = 6 OK 2026/07/24 04:15:34 DEBUG : both16: Sizes identical 2026/07/24 04:15:34 DEBUG : both16: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both17: size = 6 OK 2026/07/24 04:15:34 DEBUG : both17: Sizes identical 2026/07/24 04:15:34 DEBUG : both17: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both18: size = 6 OK 2026/07/24 04:15:34 DEBUG : both18: Sizes identical 2026/07/24 04:15:34 DEBUG : both18: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both19: size = 6 OK 2026/07/24 04:15:34 DEBUG : both19: Sizes identical 2026/07/24 04:15:34 DEBUG : both19: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both1: size = 6 OK 2026/07/24 04:15:34 DEBUG : both1: Sizes identical 2026/07/24 04:15:34 DEBUG : both1: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both2: size = 6 OK 2026/07/24 04:15:34 DEBUG : both2: Sizes identical 2026/07/24 04:15:34 DEBUG : both2: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both3: size = 6 OK 2026/07/24 04:15:34 DEBUG : both3: Sizes identical 2026/07/24 04:15:34 DEBUG : both3: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both4: size = 6 OK 2026/07/24 04:15:34 DEBUG : both4: Sizes identical 2026/07/24 04:15:34 DEBUG : both4: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both5: size = 6 OK 2026/07/24 04:15:34 DEBUG : both5: Sizes identical 2026/07/24 04:15:34 DEBUG : both5: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both6: size = 6 OK 2026/07/24 04:15:34 DEBUG : both6: Sizes identical 2026/07/24 04:15:34 DEBUG : both6: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both7: size = 6 OK 2026/07/24 04:15:34 DEBUG : both7: Sizes identical 2026/07/24 04:15:34 DEBUG : both7: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both8: size = 6 OK 2026/07/24 04:15:34 DEBUG : both8: Sizes identical 2026/07/24 04:15:34 DEBUG : both8: Unchanged skipping 2026/07/24 04:15:34 DEBUG : both9: size = 6 OK 2026/07/24 04:15:34 DEBUG : both9: Sizes identical 2026/07/24 04:15:34 DEBUG : both9: Unchanged skipping 2026/07/24 04:15:34 DEBUG : Linkbox root 'rclone-test-rarepop5hacu': Waiting for checks to finish 2026/07/24 04:15:34 DEBUG : Linkbox root 'rclone-test-rarepop5hacu': Waiting for transfers to finish 2026/07/24 04:15:34 DEBUG : Waiting for deletions to finish 2026/07/24 04:15:36 INFO : only12: Deleted 2026/07/24 04:15:36 INFO : only9: Deleted 2026/07/24 04:15:36 INFO : only0: Deleted 2026/07/24 04:15:36 INFO : only15: Deleted 2026/07/24 04:15:37 INFO : only19: Deleted 2026/07/24 04:15:37 INFO : only1: Deleted 2026/07/24 04:15:37 INFO : only5: Deleted 2026/07/24 04:15:38 INFO : only7: Deleted 2026/07/24 04:15:38 INFO : only13: Deleted 2026/07/24 04:15:38 INFO : only14: Deleted 2026/07/24 04:15:39 INFO : only16: Deleted 2026/07/24 04:15:39 INFO : only18: Deleted 2026/07/24 04:15:40 INFO : only2: Deleted 2026/07/24 04:15:40 INFO : only3: Deleted 2026/07/24 04:15:40 INFO : only4: Deleted 2026/07/24 04:15:40 INFO : only8: Deleted 2026/07/24 04:15:41 INFO : only10: Deleted 2026/07/24 04:15:41 INFO : only17: Deleted 2026/07/24 04:15:41 INFO : only6: Deleted 2026/07/24 04:15:42 INFO : only11: Deleted 2026/07/24 04:15:42 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 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: TestSyncConcurrentDelete (172.37s) === RUN TestSyncConcurrentTruncate run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" 2026/07/24 04:16:28 DEBUG : only0: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:16:28 DEBUG : only0: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:16:29 DEBUG : only0: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:16:31 DEBUG : both1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:16:32 DEBUG : both1: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:16:32 DEBUG : both1: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:16:33 DEBUG : both1: Trying to read object after upload: try again in 800ms (4/10) 2026/07/24 04:16:37 DEBUG : only1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:16:37 DEBUG : only1: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:16:37 DEBUG : only1: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:16:38 DEBUG : only1: Trying to read object after upload: try again in 800ms (4/10) 2026/07/24 04:16:41 DEBUG : both2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:16:42 DEBUG : both2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:16:42 DEBUG : both2: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:16:45 DEBUG : only2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:16:47 DEBUG : both3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:16:48 DEBUG : both3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:16:54 DEBUG : only4: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:16:57 DEBUG : both5: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:16:58 DEBUG : both5: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:16:58 DEBUG : both5: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:17:01 DEBUG : only5: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:17:03 DEBUG : both6: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:17:10 DEBUG : only7: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:17:10 DEBUG : only7: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:17:11 DEBUG : only7: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:17:11 DEBUG : only7: Trying to read object after upload: try again in 800ms (4/10) 2026/07/24 04:17:14 DEBUG : both8: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:17:15 DEBUG : both8: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:17:17 DEBUG : only8: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:17:17 DEBUG : only8: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:17:20 DEBUG : both9: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:17:20 DEBUG : both9: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:17:24 DEBUG : both10: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:17:25 DEBUG : both10: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:17:25 DEBUG : both10: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:17:30 DEBUG : both11: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:17:37 DEBUG : only12: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:17:38 DEBUG : only12: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:17:41 DEBUG : both13: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:17:44 DEBUG : only13: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:17:49 DEBUG : only14: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:17:52 DEBUG : both15: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:17:54 DEBUG : only15: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:17:55 DEBUG : only15: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:17:55 DEBUG : only15: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:17:58 DEBUG : both16: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:17:59 DEBUG : both16: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:18:07 DEBUG : only17: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:18:07 DEBUG : only17: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:18:10 DEBUG : both18: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:18:13 DEBUG : only18: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:18:13 DEBUG : only18: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:18:16 DEBUG : both19: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:18:19 DEBUG : only19: Trying to read object after upload: try again in 100ms (1/10) 2026/07/24 04:18:19 DEBUG : only19: Trying to read object after upload: try again in 200ms (2/10) 2026/07/24 04:18:20 DEBUG : only19: Trying to read object after upload: try again in 400ms (3/10) 2026/07/24 04:18:21 DEBUG : only19: Trying to read object after upload: try again in 800ms (4/10) 2026/07/24 04:18:22 DEBUG : both0: size = 6 OK 2026/07/24 04:18:22 DEBUG : both0: Sizes identical 2026/07/24 04:18:22 DEBUG : both10: size = 6 OK 2026/07/24 04:18:22 DEBUG : both10: Sizes identical 2026/07/24 04:18:22 DEBUG : both11: size = 6 OK 2026/07/24 04:18:22 DEBUG : both11: Sizes identical 2026/07/24 04:18:22 DEBUG : both12: size = 6 OK 2026/07/24 04:18:22 DEBUG : both12: Sizes identical 2026/07/24 04:18:22 DEBUG : Linkbox root 'rclone-test-rarepop5hacu': Waiting for checks to finish 2026/07/24 04:18:22 DEBUG : both0: Unchanged skipping 2026/07/24 04:18:22 DEBUG : both13: size = 6 OK 2026/07/24 04:18:22 DEBUG : both13: Sizes identical 2026/07/24 04:18:22 DEBUG : both10: Unchanged skipping 2026/07/24 04:18:22 DEBUG : both14: size = 6 OK 2026/07/24 04:18:22 DEBUG : both14: Sizes identical 2026/07/24 04:18:22 DEBUG : both14: Unchanged skipping 2026/07/24 04:18:22 DEBUG : both15: size = 6 OK 2026/07/24 04:18:22 DEBUG : both15: Sizes identical 2026/07/24 04:18:22 DEBUG : both13: Unchanged skipping 2026/07/24 04:18:22 DEBUG : both16: size = 6 OK 2026/07/24 04:18:22 DEBUG : both16: Sizes identical 2026/07/24 04:18:22 DEBUG : both16: Unchanged skipping 2026/07/24 04:18:22 DEBUG : both17: size = 6 OK 2026/07/24 04:18:22 DEBUG : both17: Sizes identical 2026/07/24 04:18:22 DEBUG : both17: Unchanged skipping 2026/07/24 04:18:22 DEBUG : both18: size = 6 OK 2026/07/24 04:18:22 DEBUG : both18: Sizes identical 2026/07/24 04:18:22 DEBUG : both18: Unchanged skipping 2026/07/24 04:18:22 DEBUG : both19: size = 6 OK 2026/07/24 04:18:22 DEBUG : both19: Sizes identical 2026/07/24 04:18:22 DEBUG : both19: Unchanged skipping 2026/07/24 04:18:22 DEBUG : both1: size = 6 OK 2026/07/24 04:18:22 DEBUG : both1: Sizes identical 2026/07/24 04:18:22 DEBUG : both1: Unchanged skipping 2026/07/24 04:18:22 DEBUG : both2: size = 6 OK 2026/07/24 04:18:22 DEBUG : both2: Sizes identical 2026/07/24 04:18:22 DEBUG : both2: Unchanged skipping 2026/07/24 04:18:22 DEBUG : both3: size = 6 OK 2026/07/24 04:18:22 DEBUG : both3: Sizes identical 2026/07/24 04:18:22 DEBUG : both3: Unchanged skipping 2026/07/24 04:18:22 DEBUG : both4: size = 6 OK 2026/07/24 04:18:22 DEBUG : both4: Sizes identical 2026/07/24 04:18:22 DEBUG : both4: Unchanged skipping 2026/07/24 04:18:22 DEBUG : both5: size = 6 OK 2026/07/24 04:18:22 DEBUG : both5: Sizes identical 2026/07/24 04:18:22 DEBUG : both5: Unchanged skipping 2026/07/24 04:18:22 DEBUG : both6: size = 6 OK 2026/07/24 04:18:22 DEBUG : both6: Sizes identical 2026/07/24 04:18:22 DEBUG : both6: Unchanged skipping 2026/07/24 04:18:22 DEBUG : both7: size = 6 OK 2026/07/24 04:18:22 DEBUG : both7: Sizes identical 2026/07/24 04:18:22 DEBUG : both7: Unchanged skipping 2026/07/24 04:18:22 DEBUG : both8: size = 6 OK 2026/07/24 04:18:22 DEBUG : both8: Sizes identical 2026/07/24 04:18:22 DEBUG : both8: Unchanged skipping 2026/07/24 04:18:22 DEBUG : both9: size = 6 OK 2026/07/24 04:18:22 DEBUG : both9: Sizes identical 2026/07/24 04:18:22 DEBUG : both9: Unchanged skipping 2026/07/24 04:18:22 DEBUG : only0: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only0: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only0: Sizes differ 2026/07/24 04:18:22 DEBUG : only10: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only10: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only10: Sizes differ 2026/07/24 04:18:22 DEBUG : only11: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only11: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only11: Sizes differ 2026/07/24 04:18:22 DEBUG : only12: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only12: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only12: Sizes differ 2026/07/24 04:18:22 DEBUG : only13: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only13: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only13: Sizes differ 2026/07/24 04:18:22 DEBUG : only14: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only14: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only14: Sizes differ 2026/07/24 04:18:22 DEBUG : only15: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only15: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only15: Sizes differ 2026/07/24 04:18:22 DEBUG : only16: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only16: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only16: Sizes differ 2026/07/24 04:18:22 DEBUG : only17: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only17: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only17: Sizes differ 2026/07/24 04:18:22 DEBUG : only18: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only18: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only18: Sizes differ 2026/07/24 04:18:22 DEBUG : only19: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only19: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only19: Sizes differ 2026/07/24 04:18:22 DEBUG : only1: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only1: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only1: Sizes differ 2026/07/24 04:18:22 DEBUG : only2: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only2: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only2: Sizes differ 2026/07/24 04:18:22 DEBUG : only3: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only3: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only3: Sizes differ 2026/07/24 04:18:22 DEBUG : only4: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only4: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only4: Sizes differ 2026/07/24 04:18:22 DEBUG : only5: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only5: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only5: Sizes differ 2026/07/24 04:18:22 DEBUG : only6: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only6: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only6: Sizes differ 2026/07/24 04:18:22 DEBUG : only7: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only7: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only7: Sizes differ 2026/07/24 04:18:22 DEBUG : only8: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only8: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only8: Sizes differ 2026/07/24 04:18:22 DEBUG : both11: Unchanged skipping 2026/07/24 04:18:22 DEBUG : only9: size = 0 (Local file system at /tmp/rclone1388196193) 2026/07/24 04:18:22 DEBUG : only9: size = 6 (Linkbox root 'rclone-test-rarepop5hacu') 2026/07/24 04:18:22 DEBUG : only9: Sizes differ 2026/07/24 04:18:22 DEBUG : both12: Unchanged skipping 2026/07/24 04:18:22 ERROR : only10: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only11: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only12: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 DEBUG : both15: Unchanged skipping 2026/07/24 04:18:22 DEBUG : Linkbox root 'rclone-test-rarepop5hacu': Waiting for transfers to finish 2026/07/24 04:18:22 ERROR : only13: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only14: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only15: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only16: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only17: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only18: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only19: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only1: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only2: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only3: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only4: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only5: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only6: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only7: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only8: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only9: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : only0: Failed to copy: can't upload empty files to this remote 2026/07/24 04:18:22 ERROR : Linkbox root 'rclone-test-rarepop5hacu': not deleting files as there were IO errors 2026/07/24 04:18:22 ERROR : Linkbox root 'rclone-test-rarepop5hacu': not deleting directories as there were IO errors sync_test.go:2781: Skip test because remote cannot upload empty files 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 --- SKIP: TestSyncConcurrentTruncate (179.72s) === RUN TestSyncReplaceDirModTime run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" 2026/07/24 04:19:23 DEBUG : empty_dir: Making directory with metadata 2026/07/24 04:19:23 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/24 04:19:23 DEBUG : empty_on_remote: Making directory with metadata 2026/07/24 04:19:23 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/24 04:19:23 INFO : empty_on_remote: Making directory 2026/07/24 04:19:25 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2026/07/24 04:19:34 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2026/07/24 04:19:34 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2026/07/24 04:19:34 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2026/07/24 04:19:34 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) run.go:186: removing dir "test_dir2/sub_dir" failed - try 1/3: sleeping 1s: directory not empty --- PASS: TestSyncReplaceDirModTime (24.98s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" 2026/07/24 04:19:48 DEBUG : empty_dir: Making directory with metadata 2026/07/24 04:19:48 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/24 04:19:48 DEBUG : empty_on_remote: Making directory with metadata 2026/07/24 04:19:48 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/24 04:19:48 INFO : empty_on_remote: Making directory 2026/07/24 04:19:50 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2026/07/24 04:20:01 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2026/07/24 04:20:01 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2026/07/24 04:20:01 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2026/07/24 04:20:01 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2026/07/24 04:20:01 INFO : empty_dir: Set directory modification time (using DirSetModTime) 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:292: Sleeping for 6s just to make sure --- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (36.57s) === RUN TestNothingToTransferWithEmptyDirs run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" 2026/07/24 04:20:25 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/24 04:20:25 INFO : sub dir: Making directory 2026/07/24 04:20:27 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC --- PASS: TestNothingToTransferWithEmptyDirs (41.83s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:198: Remote "Linkbox root 'rclone-test-rarepop5hacu'", Local "Local file system at /tmp/rclone1388196193", Modify Window "876000h0m0s" 2026/07/24 04:21:07 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/24 04:21:07 INFO : sub dir: Making directory 2026/07/24 04:21:08 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:186: removing dir "sub dir2/very" failed - try 1/3: sleeping 1s: directory not empty run.go:186: removing dir "sub dir2/very" failed - try 2/3: sleeping 1.5s: directory not empty run.go:186: removing dir "sub dir2" failed - try 1/3: sleeping 1s: directory not empty --- PASS: TestNothingToTransferWithoutEmptyDirs (54.98s) FAIL 2026/07/24 04:22:02 DEBUG : Linkbox root 'rclone-test-rarepop5hacu': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestMoveOverSelf|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncCompareDest|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncCopyDest|TestSyncIgnoreCase|TestSyncImmutable|TestSyncMultipleCompareDest|TestSyncReplaceDirModTime|TestSyncReplaceDirModTimeWithEmptyDirs|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncUTFNorm)$|^TestMaxTransfer$/^(Cautious|Hard|Soft)$'" - Finished ERROR in 18m47.048330803s (try 3/5): exit status 1: Failed [TestMoveOverSelf TestServerSideMoveOverSelf TestServerSideMoveWithFilter TestSyncCompareDest]