"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncAfterChangingModtimeOnly|TestSyncOverlapWithFilter|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Starting (try 2/5) 2025/11/02 02:40:29 DEBUG : Creating backend with remote "TestB2:rclone-test-cusudac3xadi" 2025/11/02 02:40:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 02:40:30 DEBUG : Creating backend with remote "/tmp/rclone3065632865" === RUN TestServerSideCopy run.go:185: Remote "B2 bucket rclone-test-cusudac3xadi", Local "Local file system at /tmp/rclone3065632865", Modify Window "1ms" 2025/11/02 02:40:32 DEBUG : Creating backend with remote "TestB2:rclone-test-ziyigew1vacu" sync_test.go:620: Server side copy (if possible) B2 bucket rclone-test-cusudac3xadi -> B2 bucket rclone-test-ziyigew1vacu 2025/11/02 02:40:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 02:40:33 DEBUG : B2 bucket rclone-test-ziyigew1vacu: Waiting for checks to finish 2025/11/02 02:40:33 DEBUG : B2 bucket rclone-test-ziyigew1vacu: Waiting for transfers to finish 2025/11/02 02:40:34 ERROR : sub dir/hello world: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:624: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:624 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideCopy 2025/11/02 02:40:34 DEBUG : B2 bucket rclone-test-ziyigew1vacu: Purge remote 2025/11/02 02:40:34 INFO : B2 bucket rclone-test-ziyigew1vacu: cleaning bucket "rclone-test-ziyigew1vacu" of all files --- FAIL: TestServerSideCopy (4.88s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "B2 bucket rclone-test-cusudac3xadi", Local "Local file system at /tmp/rclone3065632865", Modify Window "1ms" 2025/11/02 02:40:35 DEBUG : Creating backend with remote "TestB2:rclone-test-xudebos1koko" sync_test.go:656: Server side copy (if possible) B2 bucket rclone-test-cusudac3xadi -> B2 bucket rclone-test-xudebos1koko 2025/11/02 02:40:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 02:40:36 DEBUG : B2 bucket rclone-test-xudebos1koko: Waiting for checks to finish 2025/11/02 02:40:36 DEBUG : B2 bucket rclone-test-xudebos1koko: Waiting for transfers to finish 2025/11/02 02:40:37 ERROR : sub dir/hello world: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:660: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:660 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideCopyOverSelf 2025/11/02 02:40:37 DEBUG : B2 bucket rclone-test-xudebos1koko: Purge remote 2025/11/02 02:40:37 INFO : B2 bucket rclone-test-xudebos1koko: cleaning bucket "rclone-test-xudebos1koko" of all files --- FAIL: TestServerSideCopyOverSelf (3.42s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "B2 bucket rclone-test-cusudac3xadi", Local "Local file system at /tmp/rclone3065632865", Modify Window "1ms" 2025/11/02 02:40:39 DEBUG : Creating backend with remote "TestB2:rclone-test-hukalaw2wera" sync_test.go:701: Server side copy (if possible) B2 bucket rclone-test-cusudac3xadi -> B2 bucket rclone-test-hukalaw2wera 2025/11/02 02:40:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 02:40:40 DEBUG : B2 bucket rclone-test-hukalaw2wera: Waiting for checks to finish 2025/11/02 02:40:40 DEBUG : B2 bucket rclone-test-hukalaw2wera: Waiting for transfers to finish 2025/11/02 02:40:40 ERROR : sub dir/hello world: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:705: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:705 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideMoveOverSelf 2025/11/02 02:40:40 DEBUG : B2 bucket rclone-test-hukalaw2wera: Purge remote 2025/11/02 02:40:40 INFO : B2 bucket rclone-test-hukalaw2wera: cleaning bucket "rclone-test-hukalaw2wera" of all files --- FAIL: TestServerSideMoveOverSelf (3.39s) === RUN TestSyncAfterChangingModtimeOnly run.go:185: Remote "B2 bucket rclone-test-cusudac3xadi", Local "Local file system at /tmp/rclone3065632865", Modify Window "1ms" 2025/11/02 02:40:42 DEBUG : empty space: size = 1 OK 2025/11/02 02:40:42 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2025/11/02 02:40:42 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for checks to finish 2025/11/02 02:40:42 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/02 02:40:42 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2025/11/02 02:40:42 DEBUG : empty space: Unchanged skipping 2025/11/02 02:40:42 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for transfers to finish 2025/11/02 02:40:42 DEBUG : Waiting for deletions to finish 2025/11/02 02:40:42 INFO : There was nothing to transfer 2025/11/02 02:40:43 DEBUG : empty space: size = 1 OK 2025/11/02 02:40:43 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2025/11/02 02:40:43 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for checks to finish 2025/11/02 02:40:43 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/02 02:40:43 ERROR : empty space: Failed to set modification time: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:40:43 DEBUG : empty space: Unchanged skipping 2025/11/02 02:40:43 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for transfers to finish 2025/11/02 02:40:43 ERROR : B2 bucket rclone-test-cusudac3xadi: not deleting files as there were IO errors 2025/11/02 02:40:43 ERROR : B2 bucket rclone-test-cusudac3xadi: not deleting directories as there were IO errors sync_test.go:1033: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1033 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncAfterChangingModtimeOnly --- FAIL: TestSyncAfterChangingModtimeOnly (2.41s) === RUN TestSyncWithTrackRenames run.go:185: Remote "B2 bucket rclone-test-cusudac3xadi", Local "Local file system at /tmp/rclone3065632865", Modify Window "1ms" sync_test.go:1568: Can track renames: true 2025/11/02 02:40:44 INFO : B2 bucket rclone-test-cusudac3xadi: Making map for --track-renames 2025/11/02 02:40:44 INFO : B2 bucket rclone-test-cusudac3xadi: Finished making map for --track-renames 2025/11/02 02:40:44 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for checks to finish 2025/11/02 02:40:44 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for renames to finish 2025/11/02 02:40:44 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 02:40:44 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 02:40:44 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for transfers to finish 2025/11/02 02:40:45 DEBUG : potato: size = 14 OK 2025/11/02 02:40:45 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 02:40:45 INFO : potato: Copied (new) 2025/11/02 02:40:45 DEBUG : yam: size = 11 OK 2025/11/02 02:40:45 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 02:40:45 INFO : yam: Copied (new) 2025/11/02 02:40:45 DEBUG : Waiting for deletions to finish 2025/11/02 02:40:45 DEBUG : potato: size = 14 OK 2025/11/02 02:40:45 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 02:40:45 INFO : B2 bucket rclone-test-cusudac3xadi: Making map for --track-renames 2025/11/02 02:40:45 INFO : B2 bucket rclone-test-cusudac3xadi: Finished making map for --track-renames 2025/11/02 02:40:45 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for checks to finish 2025/11/02 02:40:45 DEBUG : potato: Unchanged skipping 2025/11/02 02:40:45 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for renames to finish 2025/11/02 02:40:46 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:40:46 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:40:46 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:40:46 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/02 02:40:46 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for transfers to finish 2025/11/02 02:40:46 DEBUG : yaml: size = 11 OK 2025/11/02 02:40:46 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 02:40:46 INFO : yaml: Copied (new) 2025/11/02 02:40:46 ERROR : B2 bucket rclone-test-cusudac3xadi: not deleting files as there were IO errors 2025/11/02 02:40:46 ERROR : B2 bucket rclone-test-cusudac3xadi: not deleting directories as there were IO errors sync_test.go:1586: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1586 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenames --- FAIL: TestSyncWithTrackRenames (3.13s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "B2 bucket rclone-test-cusudac3xadi", Local "Local file system at /tmp/rclone3065632865", Modify Window "1ms" sync_test.go:1640: Can track renames: true 2025/11/02 02:40:47 INFO : B2 bucket rclone-test-cusudac3xadi: Making map for --track-renames 2025/11/02 02:40:47 INFO : B2 bucket rclone-test-cusudac3xadi: Finished making map for --track-renames 2025/11/02 02:40:47 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for checks to finish 2025/11/02 02:40:47 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 02:40:47 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 02:40:47 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for renames to finish 2025/11/02 02:40:47 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for transfers to finish 2025/11/02 02:40:48 DEBUG : potato: size = 14 OK 2025/11/02 02:40:48 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 02:40:48 INFO : potato: Copied (new) 2025/11/02 02:40:48 DEBUG : yam: size = 11 OK 2025/11/02 02:40:48 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 02:40:48 INFO : yam: Copied (new) 2025/11/02 02:40:48 DEBUG : Waiting for deletions to finish 2025/11/02 02:40:48 DEBUG : potato: size = 14 OK 2025/11/02 02:40:48 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 02:40:48 INFO : B2 bucket rclone-test-cusudac3xadi: Making map for --track-renames 2025/11/02 02:40:48 INFO : B2 bucket rclone-test-cusudac3xadi: Finished making map for --track-renames 2025/11/02 02:40:48 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for checks to finish 2025/11/02 02:40:48 DEBUG : potato: Unchanged skipping 2025/11/02 02:40:48 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for renames to finish 2025/11/02 02:40:48 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:40:48 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:40:48 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:40:48 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/02 02:40:48 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for transfers to finish 2025/11/02 02:40:49 DEBUG : yaml: size = 11 OK 2025/11/02 02:40:49 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 02:40:49 INFO : yaml: Copied (new) 2025/11/02 02:40:49 ERROR : B2 bucket rclone-test-cusudac3xadi: not deleting files as there were IO errors 2025/11/02 02:40:49 ERROR : B2 bucket rclone-test-cusudac3xadi: not deleting directories as there were IO errors sync_test.go:1658: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1658 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyModtime --- FAIL: TestSyncWithTrackRenamesStrategyModtime (2.41s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "B2 bucket rclone-test-cusudac3xadi", Local "Local file system at /tmp/rclone3065632865", Modify Window "1ms" sync_test.go:1679: Can track renames: true 2025/11/02 02:40:50 INFO : B2 bucket rclone-test-cusudac3xadi: Making map for --track-renames 2025/11/02 02:40:50 INFO : B2 bucket rclone-test-cusudac3xadi: Finished making map for --track-renames 2025/11/02 02:40:50 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for checks to finish 2025/11/02 02:40:50 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 02:40:50 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/11/02 02:40:50 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for renames to finish 2025/11/02 02:40:50 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for transfers to finish 2025/11/02 02:40:50 DEBUG : sub/yam: size = 11 OK 2025/11/02 02:40:50 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 02:40:50 INFO : sub/yam: Copied (new) 2025/11/02 02:40:50 DEBUG : potato: size = 14 OK 2025/11/02 02:40:50 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 02:40:50 INFO : potato: Copied (new) 2025/11/02 02:40:50 DEBUG : Waiting for deletions to finish 2025/11/02 02:40:50 DEBUG : potato: size = 14 OK 2025/11/02 02:40:50 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 02:40:50 DEBUG : potato: Unchanged skipping 2025/11/02 02:40:50 INFO : B2 bucket rclone-test-cusudac3xadi: Making map for --track-renames 2025/11/02 02:40:50 INFO : B2 bucket rclone-test-cusudac3xadi: Finished making map for --track-renames 2025/11/02 02:40:50 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for checks to finish 2025/11/02 02:40:50 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for renames to finish 2025/11/02 02:40:51 ERROR : sub/yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:40:51 ERROR : sub/yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:40:51 DEBUG : yam: Failed to rename to "sub/yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:40:51 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 02:40:51 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for transfers to finish 2025/11/02 02:40:51 DEBUG : yam: size = 11 OK 2025/11/02 02:40:51 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 02:40:51 INFO : yam: Copied (new) 2025/11/02 02:40:51 ERROR : B2 bucket rclone-test-cusudac3xadi: not deleting files as there were IO errors 2025/11/02 02:40:51 ERROR : B2 bucket rclone-test-cusudac3xadi: not deleting directories as there were IO errors sync_test.go:1697: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1697 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyLeaf --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (2.37s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "B2 bucket rclone-test-cusudac3xadi", Local "Local file system at /tmp/rclone3065632865", Modify Window "1ms" 2025/11/02 02:40:52 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 02:40:52 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/11/02 02:40:52 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for checks to finish 2025/11/02 02:40:52 DEBUG : B2 bucket rclone-test-cusudac3xadi: Waiting for transfers to finish 2025/11/02 02:40:52 DEBUG : nested/sub dir/file: size = 6 OK 2025/11/02 02:40:52 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/11/02 02:40:52 INFO : nested/sub dir/file: Copied (new) 2025/11/02 02:40:52 INFO : nested/sub dir/file: Deleted 2025/11/02 02:40:52 DEBUG : sub dir/hello world: size = 11 OK 2025/11/02 02:40:52 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 02:40:52 INFO : sub dir/hello world: Copied (new) 2025/11/02 02:40:52 INFO : sub dir/hello world: Deleted 2025/11/02 02:40:52 ERROR : Local file system at /tmp/rclone3065632865: not deleting directories as there were IO errors sync_test.go:1818: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1818 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestMoveWithDeleteEmptySrcDirs --- FAIL: TestMoveWithDeleteEmptySrcDirs (1.26s) === RUN TestServerSideMove run.go:185: Remote "B2 bucket rclone-test-cusudac3xadi", Local "Local file system at /tmp/rclone3065632865", Modify Window "1ms" 2025/11/02 02:40:53 DEBUG : Creating backend with remote "TestB2:rclone-test-tuticar7woje" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-cusudac3xadi -> B2 bucket rclone-test-tuticar7woje 2025/11/02 02:40:57 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 02:40:57 DEBUG : empty space: size = 1 OK 2025/11/02 02:40:57 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/02 02:40:57 DEBUG : empty space: Unchanged skipping 2025/11/02 02:40:57 DEBUG : potato3: size = 68 (B2 bucket rclone-test-cusudac3xadi) 2025/11/02 02:40:57 DEBUG : potato3: size = 60 (B2 bucket rclone-test-tuticar7woje) 2025/11/02 02:40:57 DEBUG : potato3: Sizes differ 2025/11/02 02:40:57 DEBUG : B2 bucket rclone-test-tuticar7woje: Waiting for checks to finish 2025/11/02 02:40:57 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:40:57 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:40:57 INFO : empty space: Deleted 2025/11/02 02:40:57 DEBUG : B2 bucket rclone-test-tuticar7woje: Waiting for transfers to finish 2025/11/02 02:40:57 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:40:57 ERROR : potato3: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1911 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideMove 2025/11/02 02:40:57 DEBUG : B2 bucket rclone-test-tuticar7woje: Purge remote 2025/11/02 02:40:57 INFO : B2 bucket rclone-test-tuticar7woje: cleaning bucket "rclone-test-tuticar7woje" of all files 2025/11/02 02:40:58 DEBUG : empty space: Deleting (id "4_zefc26dd3700d4b2b9fa80e1b_f11796889210ead55_d20251102_m024056_c001_v0001182_t0028_u01762051256750") 2025/11/02 02:40:58 DEBUG : potato3: Deleting (id "4_zefc26dd3700d4b2b9fa80e1b_f11796889210ead59_d20251102_m024056_c001_v0001182_t0002_u01762051256951") --- FAIL: TestServerSideMove (5.73s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "B2 bucket rclone-test-cusudac3xadi", Local "Local file system at /tmp/rclone3065632865", Modify Window "1ms" 2025/11/02 02:40:59 DEBUG : Creating backend with remote "TestB2:rclone-test-vanoxil7gipu" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-cusudac3xadi -> B2 bucket rclone-test-vanoxil7gipu 2025/11/02 02:41:04 DEBUG : empty space: Excluded (Size Filter) 2025/11/02 02:41:04 DEBUG : empty space: Excluded (Size Filter) 2025/11/02 02:41:04 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 02:41:04 DEBUG : potato3: size = 68 (B2 bucket rclone-test-cusudac3xadi) 2025/11/02 02:41:04 DEBUG : potato3: size = 60 (B2 bucket rclone-test-vanoxil7gipu) 2025/11/02 02:41:04 DEBUG : potato3: Sizes differ 2025/11/02 02:41:04 DEBUG : B2 bucket rclone-test-vanoxil7gipu: Waiting for checks to finish 2025/11/02 02:41:04 DEBUG : B2 bucket rclone-test-vanoxil7gipu: Waiting for transfers to finish 2025/11/02 02:41:04 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:04 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:04 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:04 ERROR : potato3: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1924 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideMoveWithFilter 2025/11/02 02:41:04 DEBUG : B2 bucket rclone-test-vanoxil7gipu: Purge remote 2025/11/02 02:41:04 INFO : B2 bucket rclone-test-vanoxil7gipu: cleaning bucket "rclone-test-vanoxil7gipu" of all files 2025/11/02 02:41:04 DEBUG : empty space: Deleting (id "4_z4f328d73700d4b2b9fa80e1b_f116ad1bfa0d32693_d20251102_m024103_c001_v0001157_t0008_u01762051263254") 2025/11/02 02:41:04 DEBUG : potato3: Deleting (id "4_z4f328d73700d4b2b9fa80e1b_f116ad1bfa0d32695_d20251102_m024103_c001_v0001157_t0046_u01762051263542") --- FAIL: TestServerSideMoveWithFilter (6.70s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "B2 bucket rclone-test-cusudac3xadi", Local "Local file system at /tmp/rclone3065632865", Modify Window "1ms" 2025/11/02 02:41:06 DEBUG : Creating backend with remote "TestB2:rclone-test-durodux8beya" 2025/11/02 02:41:08 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-cusudac3xadi -> B2 bucket rclone-test-durodux8beya 2025/11/02 02:41:10 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 02:41:10 DEBUG : empty space: size = 1 OK 2025/11/02 02:41:10 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/02 02:41:10 DEBUG : empty space: Unchanged skipping 2025/11/02 02:41:10 DEBUG : potato3: size = 68 (B2 bucket rclone-test-cusudac3xadi) 2025/11/02 02:41:10 DEBUG : potato3: size = 60 (B2 bucket rclone-test-durodux8beya) 2025/11/02 02:41:10 DEBUG : potato3: Sizes differ 2025/11/02 02:41:10 DEBUG : B2 bucket rclone-test-durodux8beya: Waiting for checks to finish 2025/11/02 02:41:10 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:10 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:10 INFO : empty space: Deleted 2025/11/02 02:41:10 DEBUG : B2 bucket rclone-test-durodux8beya: Waiting for transfers to finish 2025/11/02 02:41:10 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:10 ERROR : potato3: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1931 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideMoveDeleteEmptySourceDirs 2025/11/02 02:41:10 DEBUG : B2 bucket rclone-test-durodux8beya: Purge remote 2025/11/02 02:41:10 INFO : B2 bucket rclone-test-durodux8beya: cleaning bucket "rclone-test-durodux8beya" of all files 2025/11/02 02:41:11 DEBUG : empty space: Deleting (id "4_z1fc2ad13700d4b2b9fa80e1b_f11428d7d99415ae9_d20251102_m024109_c001_v0001181_t0030_u01762051269854") 2025/11/02 02:41:11 DEBUG : potato3: Deleting (id "4_z1fc2ad13700d4b2b9fa80e1b_f11428d7d99415aeb_d20251102_m024110_c001_v0001181_t0059_u01762051270076") --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (6.34s) === RUN TestSyncOverlapWithFilter run.go:185: Remote "B2 bucket rclone-test-cusudac3xadi", Local "Local file system at /tmp/rclone3065632865", Modify Window "1ms" 2025/11/02 02:41:12 DEBUG : Creating backend with remote "TestB2:rclone-test-cusudac3xadi/rclone-sync-test" 2025/11/02 02:41:13 DEBUG : pacer: low level retry 1/10 (error incident id 3894aee67876-1318a1b5907ab182 (500 internal_error)) 2025/11/02 02:41:13 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/02 02:41:13 DEBUG : pacer: low level retry 2/10 (error incident id 3894aee67876-43d4ab01be6ba4e6 (500 internal_error)) 2025/11/02 02:41:13 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/11/02 02:41:13 DEBUG : pacer: low level retry 3/10 (error incident id 3894aee67876-1d66cca91e840ca5 (500 internal_error)) 2025/11/02 02:41:13 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/11/02 02:41:13 DEBUG : pacer: low level retry 4/10 (error incident id 3894aee67876-724f3beec950d58b (500 internal_error)) 2025/11/02 02:41:13 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/11/02 02:41:13 DEBUG : pacer: low level retry 5/10 (error incident id 3894aee67876-be6425b23ab574a5 (500 internal_error)) 2025/11/02 02:41:13 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/11/02 02:41:13 DEBUG : pacer: low level retry 6/10 (error incident id 3894aee67876-8b0996a0cc246be5 (500 internal_error)) 2025/11/02 02:41:13 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/11/02 02:41:14 DEBUG : pacer: low level retry 7/10 (error incident id 3894aee67876-9e7bf6abe61b89d0 (500 internal_error)) 2025/11/02 02:41:14 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/11/02 02:41:14 DEBUG : pacer: low level retry 8/10 (error incident id 3894aee67876-bfcca0d32cbf8ec3 (500 internal_error)) 2025/11/02 02:41:14 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2025/11/02 02:41:16 DEBUG : pacer: low level retry 9/10 (error incident id 3894aee67876-be6a809d56550ed5 (500 internal_error)) 2025/11/02 02:41:16 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2025/11/02 02:41:18 DEBUG : pacer: low level retry 10/10 (error incident id 3894aee67876-ac042f99afa340f7 (500 internal_error)) 2025/11/02 02:41:18 DEBUG : pacer: Rate limited, increasing sleep to 10.24s --- FAIL: TestSyncOverlapWithFilter (7.04s) panic: runtime error: invalid memory address or nil pointer dereference [recovered] panic: runtime error: invalid memory address or nil pointer dereference [signal SIGSEGV: segmentation violation code=0x1 addr=0x30 pc=0x1ff303e] goroutine 1235 [running]: testing.tRunner.func1.2({0x236a420, 0x40bc020}) /usr/local/go/src/testing/testing.go:1734 +0x21c testing.tRunner.func1() /usr/local/go/src/testing/testing.go:1737 +0x35e panic({0x236a420?, 0x40bc020?}) /usr/local/go/src/runtime/panic.go:792 +0x132 github.com/rclone/rclone/fs/sync.TestSyncOverlapWithFilter(0xc00093ac40) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2011 +0x1de testing.tRunner(0xc00093ac40, 0x2977760) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1851 +0x413 "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -fast-list -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncAfterChangingModtimeOnly|TestSyncOverlapWithFilter|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Finished ERROR in 49.764622353s (try 2/5): exit status 2: Failed [TestServerSideCopy TestServerSideCopyOverSelf TestServerSideMoveOverSelf TestSyncAfterChangingModtimeOnly TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncOverlapWithFilter]