"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncAfterChangingModtimeOnly|TestSyncOverlapWithFilter|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Starting (try 3/5) 2025/11/01 02:15:16 DEBUG : Creating backend with remote "TestB2:rclone-test-givuciy5wazu" 2025/11/01 02:15:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/01 02:15:16 DEBUG : Creating backend with remote "/tmp/rclone4257862914" === RUN TestServerSideCopy run.go:185: Remote "B2 bucket rclone-test-givuciy5wazu", Local "Local file system at /tmp/rclone4257862914", Modify Window "1ms" 2025/11/01 02:15:18 DEBUG : Creating backend with remote "TestB2:rclone-test-zosukaf1suda" sync_test.go:620: Server side copy (if possible) B2 bucket rclone-test-givuciy5wazu -> B2 bucket rclone-test-zosukaf1suda 2025/11/01 02:15:19 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/01 02:15:19 DEBUG : B2 bucket rclone-test-zosukaf1suda: Waiting for checks to finish 2025/11/01 02:15:19 DEBUG : B2 bucket rclone-test-zosukaf1suda: Waiting for transfers to finish 2025/11/01 02:15:20 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/01 02:15:20 DEBUG : B2 bucket rclone-test-zosukaf1suda: Purge remote 2025/11/01 02:15:20 INFO : B2 bucket rclone-test-zosukaf1suda: cleaning bucket "rclone-test-zosukaf1suda" of all files --- FAIL: TestServerSideCopy (5.02s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "B2 bucket rclone-test-givuciy5wazu", Local "Local file system at /tmp/rclone4257862914", Modify Window "1ms" 2025/11/01 02:15:22 DEBUG : Creating backend with remote "TestB2:rclone-test-fofudoc8leqa" sync_test.go:656: Server side copy (if possible) B2 bucket rclone-test-givuciy5wazu -> B2 bucket rclone-test-fofudoc8leqa 2025/11/01 02:15:23 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/01 02:15:23 DEBUG : B2 bucket rclone-test-fofudoc8leqa: Waiting for checks to finish 2025/11/01 02:15:23 DEBUG : B2 bucket rclone-test-fofudoc8leqa: Waiting for transfers to finish 2025/11/01 02:15:24 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/01 02:15:24 DEBUG : B2 bucket rclone-test-fofudoc8leqa: Purge remote 2025/11/01 02:15:24 INFO : B2 bucket rclone-test-fofudoc8leqa: cleaning bucket "rclone-test-fofudoc8leqa" of all files --- FAIL: TestServerSideCopyOverSelf (3.58s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "B2 bucket rclone-test-givuciy5wazu", Local "Local file system at /tmp/rclone4257862914", Modify Window "1ms" 2025/11/01 02:15:25 DEBUG : Creating backend with remote "TestB2:rclone-test-necezav8codo" sync_test.go:701: Server side copy (if possible) B2 bucket rclone-test-givuciy5wazu -> B2 bucket rclone-test-necezav8codo 2025/11/01 02:15:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/01 02:15:27 DEBUG : B2 bucket rclone-test-necezav8codo: Waiting for checks to finish 2025/11/01 02:15:27 DEBUG : B2 bucket rclone-test-necezav8codo: Waiting for transfers to finish 2025/11/01 02:15:27 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/01 02:15:27 DEBUG : B2 bucket rclone-test-necezav8codo: Purge remote 2025/11/01 02:15:27 INFO : B2 bucket rclone-test-necezav8codo: cleaning bucket "rclone-test-necezav8codo" of all files --- FAIL: TestServerSideMoveOverSelf (3.54s) === RUN TestSyncAfterChangingModtimeOnly run.go:185: Remote "B2 bucket rclone-test-givuciy5wazu", Local "Local file system at /tmp/rclone4257862914", Modify Window "1ms" 2025/11/01 02:15:29 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for checks to finish 2025/11/01 02:15:29 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/01 02:15:29 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/01 02:15:29 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2025/11/01 02:15:29 DEBUG : empty space: Unchanged skipping 2025/11/01 02:15:29 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for transfers to finish 2025/11/01 02:15:29 DEBUG : Waiting for deletions to finish 2025/11/01 02:15:29 INFO : There was nothing to transfer 2025/11/01 02:15:29 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for checks to finish 2025/11/01 02:15:29 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/01 02:15:29 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/01 02:15:30 ERROR : empty space: Failed to set modification time: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:30 DEBUG : empty space: Unchanged skipping 2025/11/01 02:15:30 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for transfers to finish 2025/11/01 02:15:30 ERROR : B2 bucket rclone-test-givuciy5wazu: not deleting files as there were IO errors 2025/11/01 02:15:30 ERROR : B2 bucket rclone-test-givuciy5wazu: 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.24s) === RUN TestSyncWithTrackRenames run.go:185: Remote "B2 bucket rclone-test-givuciy5wazu", Local "Local file system at /tmp/rclone4257862914", Modify Window "1ms" sync_test.go:1568: Can track renames: true 2025/11/01 02:15:31 INFO : B2 bucket rclone-test-givuciy5wazu: Making map for --track-renames 2025/11/01 02:15:31 INFO : B2 bucket rclone-test-givuciy5wazu: Finished making map for --track-renames 2025/11/01 02:15:31 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for checks to finish 2025/11/01 02:15:31 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for renames to finish 2025/11/01 02:15:31 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/01 02:15:31 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/01 02:15:31 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for transfers to finish 2025/11/01 02:15:31 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/01 02:15:31 INFO : potato: Copied (new) 2025/11/01 02:15:32 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 02:15:32 INFO : yam: Copied (new) 2025/11/01 02:15:32 DEBUG : Waiting for deletions to finish 2025/11/01 02:15:32 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/01 02:15:32 INFO : B2 bucket rclone-test-givuciy5wazu: Making map for --track-renames 2025/11/01 02:15:32 INFO : B2 bucket rclone-test-givuciy5wazu: Finished making map for --track-renames 2025/11/01 02:15:32 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for checks to finish 2025/11/01 02:15:32 DEBUG : potato: Unchanged skipping 2025/11/01 02:15:32 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for renames to finish 2025/11/01 02:15:33 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:33 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:33 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:33 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/01 02:15:33 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for transfers to finish 2025/11/01 02:15:33 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 02:15:33 INFO : yaml: Copied (new) 2025/11/01 02:15:33 ERROR : B2 bucket rclone-test-givuciy5wazu: not deleting files as there were IO errors 2025/11/01 02:15:33 ERROR : B2 bucket rclone-test-givuciy5wazu: 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-givuciy5wazu", Local "Local file system at /tmp/rclone4257862914", Modify Window "1ms" sync_test.go:1640: Can track renames: true 2025/11/01 02:15:34 INFO : B2 bucket rclone-test-givuciy5wazu: Making map for --track-renames 2025/11/01 02:15:34 INFO : B2 bucket rclone-test-givuciy5wazu: Finished making map for --track-renames 2025/11/01 02:15:34 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for checks to finish 2025/11/01 02:15:34 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/01 02:15:34 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/01 02:15:34 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for renames to finish 2025/11/01 02:15:34 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for transfers to finish 2025/11/01 02:15:34 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 02:15:34 INFO : yam: Copied (new) 2025/11/01 02:15:34 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/01 02:15:34 INFO : potato: Copied (new) 2025/11/01 02:15:34 DEBUG : Waiting for deletions to finish 2025/11/01 02:15:35 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/01 02:15:35 INFO : B2 bucket rclone-test-givuciy5wazu: Making map for --track-renames 2025/11/01 02:15:35 INFO : B2 bucket rclone-test-givuciy5wazu: Finished making map for --track-renames 2025/11/01 02:15:35 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for checks to finish 2025/11/01 02:15:35 DEBUG : potato: Unchanged skipping 2025/11/01 02:15:35 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for renames to finish 2025/11/01 02:15:35 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:35 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:35 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:35 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/01 02:15:35 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for transfers to finish 2025/11/01 02:15:36 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 02:15:36 INFO : yaml: Copied (new) 2025/11/01 02:15:36 ERROR : B2 bucket rclone-test-givuciy5wazu: not deleting files as there were IO errors 2025/11/01 02:15:36 ERROR : B2 bucket rclone-test-givuciy5wazu: 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.60s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "B2 bucket rclone-test-givuciy5wazu", Local "Local file system at /tmp/rclone4257862914", Modify Window "1ms" sync_test.go:1679: Can track renames: true 2025/11/01 02:15:37 INFO : B2 bucket rclone-test-givuciy5wazu: Making map for --track-renames 2025/11/01 02:15:37 INFO : B2 bucket rclone-test-givuciy5wazu: Finished making map for --track-renames 2025/11/01 02:15:37 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for checks to finish 2025/11/01 02:15:37 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/01 02:15:37 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/11/01 02:15:37 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for renames to finish 2025/11/01 02:15:37 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for transfers to finish 2025/11/01 02:15:37 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 02:15:37 INFO : sub/yam: Copied (new) 2025/11/01 02:15:37 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/01 02:15:37 INFO : potato: Copied (new) 2025/11/01 02:15:37 DEBUG : Waiting for deletions to finish 2025/11/01 02:15:38 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/01 02:15:38 DEBUG : potato: Unchanged skipping 2025/11/01 02:15:38 INFO : B2 bucket rclone-test-givuciy5wazu: Making map for --track-renames 2025/11/01 02:15:38 INFO : B2 bucket rclone-test-givuciy5wazu: Finished making map for --track-renames 2025/11/01 02:15:38 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for checks to finish 2025/11/01 02:15:38 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for renames to finish 2025/11/01 02:15:38 ERROR : sub/yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:38 ERROR : sub/yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:38 DEBUG : yam: Failed to rename to "sub/yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:38 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/01 02:15:38 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for transfers to finish 2025/11/01 02:15:38 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 02:15:38 INFO : yam: Copied (new) 2025/11/01 02:15:38 ERROR : B2 bucket rclone-test-givuciy5wazu: not deleting files as there were IO errors 2025/11/01 02:15:38 ERROR : B2 bucket rclone-test-givuciy5wazu: 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.95s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "B2 bucket rclone-test-givuciy5wazu", Local "Local file system at /tmp/rclone4257862914", Modify Window "1ms" 2025/11/01 02:15:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/01 02:15:40 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/11/01 02:15:40 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for checks to finish 2025/11/01 02:15:40 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for transfers to finish 2025/11/01 02:15:40 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/11/01 02:15:40 INFO : nested/sub dir/file: Copied (new) 2025/11/01 02:15:40 INFO : nested/sub dir/file: Deleted 2025/11/01 02:15:40 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/01 02:15:40 INFO : sub dir/hello world: Copied (new) 2025/11/01 02:15:40 INFO : sub dir/hello world: Deleted 2025/11/01 02:15:40 ERROR : Local file system at /tmp/rclone4257862914: 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.41s) === RUN TestServerSideMove run.go:185: Remote "B2 bucket rclone-test-givuciy5wazu", Local "Local file system at /tmp/rclone4257862914", Modify Window "1ms" 2025/11/01 02:15:41 DEBUG : Creating backend with remote "TestB2:rclone-test-ceyifab3dije" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-givuciy5wazu -> B2 bucket rclone-test-ceyifab3dije 2025/11/01 02:15:46 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/01 02:15:46 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/01 02:15:46 DEBUG : empty space: Unchanged skipping 2025/11/01 02:15:46 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/11/01 02:15:46 DEBUG : B2 bucket rclone-test-ceyifab3dije: Waiting for checks to finish 2025/11/01 02:15:46 INFO : empty space: Deleted 2025/11/01 02:15:46 DEBUG : B2 bucket rclone-test-ceyifab3dije: Waiting for transfers to finish 2025/11/01 02:15:46 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:46 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:46 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:46 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/01 02:15:46 DEBUG : B2 bucket rclone-test-ceyifab3dije: Purge remote 2025/11/01 02:15:46 INFO : B2 bucket rclone-test-ceyifab3dije: cleaning bucket "rclone-test-ceyifab3dije" of all files 2025/11/01 02:15:46 DEBUG : empty space: Deleting (id "4_zdf020d13303d3bdb9fa80e1b_f11223bc4d5a7309c_d20251101_m021545_c001_v0001131_t0020_u01761963345367") 2025/11/01 02:15:46 DEBUG : potato3: Deleting (id "4_zdf020d13303d3bdb9fa80e1b_f11223bc4d5a7309e_d20251101_m021545_c001_v0001131_t0033_u01761963345669") --- FAIL: TestServerSideMove (7.18s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "B2 bucket rclone-test-givuciy5wazu", Local "Local file system at /tmp/rclone4257862914", Modify Window "1ms" 2025/11/01 02:15:48 DEBUG : Creating backend with remote "TestB2:rclone-test-kekever2naco" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-givuciy5wazu -> B2 bucket rclone-test-kekever2naco 2025/11/01 02:15:53 DEBUG : empty space: Excluded (Size Filter) 2025/11/01 02:15:53 DEBUG : empty space: Excluded 2025/11/01 02:15:53 DEBUG : empty space: Excluded (Size Filter) 2025/11/01 02:15:53 DEBUG : empty space: Excluded 2025/11/01 02:15:53 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/01 02:15:53 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/11/01 02:15:53 DEBUG : B2 bucket rclone-test-kekever2naco: Waiting for checks to finish 2025/11/01 02:15:53 DEBUG : B2 bucket rclone-test-kekever2naco: Waiting for transfers to finish 2025/11/01 02:15:53 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:53 ERROR : potato3: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:54 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:54 ERROR : potato2: 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/01 02:15:54 DEBUG : B2 bucket rclone-test-kekever2naco: Purge remote 2025/11/01 02:15:54 INFO : B2 bucket rclone-test-kekever2naco: cleaning bucket "rclone-test-kekever2naco" of all files 2025/11/01 02:15:54 DEBUG : empty space: Deleting (id "4_zaf821de3303d3bdb9fa80e1b_f102f664618df0afc_d20251101_m021552_c001_v0001134_t0013_u01761963352695") 2025/11/01 02:15:54 DEBUG : potato3: Deleting (id "4_zaf821de3303d3bdb9fa80e1b_f102f664618df0afe_d20251101_m021553_c001_v0001134_t0016_u01761963353106") --- FAIL: TestServerSideMoveWithFilter (7.50s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "B2 bucket rclone-test-givuciy5wazu", Local "Local file system at /tmp/rclone4257862914", Modify Window "1ms" 2025/11/01 02:15:55 DEBUG : Creating backend with remote "TestB2:rclone-test-yiwevaw6lone" 2025/11/01 02:15:58 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-givuciy5wazu -> B2 bucket rclone-test-yiwevaw6lone 2025/11/01 02:16:02 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/01 02:16:02 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/01 02:16:02 DEBUG : empty space: Unchanged skipping 2025/11/01 02:16:02 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/11/01 02:16:02 DEBUG : B2 bucket rclone-test-yiwevaw6lone: Waiting for checks to finish 2025/11/01 02:16:02 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:16:02 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:16:02 INFO : empty space: Deleted 2025/11/01 02:16:02 DEBUG : B2 bucket rclone-test-yiwevaw6lone: Waiting for transfers to finish 2025/11/01 02:16:02 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:16:02 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/01 02:16:02 DEBUG : B2 bucket rclone-test-yiwevaw6lone: Purge remote 2025/11/01 02:16:02 INFO : B2 bucket rclone-test-yiwevaw6lone: cleaning bucket "rclone-test-yiwevaw6lone" of all files 2025/11/01 02:16:02 DEBUG : empty space: Deleting (id "4_z5f623dd3303d3bdb9fa80e1b_f1074f4829e1d84f5_d20251101_m021600_c001_v0001150_t0018_u01761963360490") 2025/11/01 02:16:02 DEBUG : potato3: Deleting (id "4_z5f623dd3303d3bdb9fa80e1b_f1074f4829e1d84f7_d20251101_m021600_c001_v0001150_t0008_u01761963360880") --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (8.12s) === RUN TestSyncOverlapWithFilter run.go:185: Remote "B2 bucket rclone-test-givuciy5wazu", Local "Local file system at /tmp/rclone4257862914", Modify Window "1ms" 2025/11/01 02:16:04 DEBUG : Creating backend with remote "TestB2:rclone-test-givuciy5wazu/rclone-sync-test" 2025/11/01 02:16:05 DEBUG : Creating backend with remote "TestB2:rclone-test-givuciy5wazu/rclone-sync-test-include/layer2" 2025/11/01 02:16:07 DEBUG : Creating backend with remote "TestB2:rclone-test-givuciy5wazu/rclone-sync-test-ignore-file" 2025/11/01 02:16:11 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/11/01 02:16:11 DEBUG : B2 bucket rclone-test-givuciy5wazu path rclone-sync-test: Waiting for checks to finish 2025/11/01 02:16:11 DEBUG : B2 bucket rclone-test-givuciy5wazu path rclone-sync-test: Waiting for transfers to finish 2025/11/01 02:16:11 DEBUG : Waiting for deletions to finish 2025/11/01 02:16:11 INFO : There was nothing to transfer 2025/11/01 02:16:12 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/11/01 02:16:12 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for checks to finish 2025/11/01 02:16:12 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for transfers to finish 2025/11/01 02:16:12 DEBUG : Waiting for deletions to finish 2025/11/01 02:16:12 INFO : There was nothing to transfer 2025/11/01 02:16:12 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2025/11/01 02:16:12 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/01 02:16:13 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/11/01 02:16:13 DEBUG : pacer: Reducing sleep to 10ms 2025/11/01 02:16:13 DEBUG : B2 bucket rclone-test-givuciy5wazu path rclone-sync-test-include/layer2: Waiting for checks to finish 2025/11/01 02:16:13 DEBUG : B2 bucket rclone-test-givuciy5wazu path rclone-sync-test-include/layer2: Waiting for transfers to finish 2025/11/01 02:16:13 DEBUG : Waiting for deletions to finish 2025/11/01 02:16:13 INFO : There was nothing to transfer 2025/11/01 02:16:13 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/11/01 02:16:13 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for checks to finish 2025/11/01 02:16:13 DEBUG : B2 bucket rclone-test-givuciy5wazu: Waiting for transfers to finish 2025/11/01 02:16:13 DEBUG : Waiting for deletions to finish 2025/11/01 02:16:13 INFO : There was nothing to transfer 2025/11/01 02:16:14 DEBUG : Excluded 2025/11/01 02:16:14 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/11/01 02:16:14 DEBUG : B2 bucket rclone-test-givuciy5wazu path rclone-sync-test-ignore-file: Waiting for checks to finish 2025/11/01 02:16:14 DEBUG : B2 bucket rclone-test-givuciy5wazu path rclone-sync-test-ignore-file: Waiting for transfers to finish 2025/11/01 02:16:14 DEBUG : Waiting for deletions to finish 2025/11/01 02:16:14 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (10.96s) FAIL 2025/11/01 02:16:15 DEBUG : B2 bucket rclone-test-givuciy5wazu: Purge remote 2025/11/01 02:16:15 INFO : B2 bucket rclone-test-givuciy5wazu: cleaning bucket "rclone-test-givuciy5wazu" of all files 2025/11/01 02:16:15 DEBUG : empty space: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e8a0c_d20251101_m021602_c001_v7007000_t0000_u01761963362281") 2025/11/01 02:16:15 DEBUG : empty space: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f1186dab83973ab9f_d20251101_m021557_c001_v0001036_t0015_u01761963357802") 2025/11/01 02:16:15 DEBUG : empty space: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e8a07_d20251101_m021555_c001_v7007000_t0000_u01761963355317") 2025/11/01 02:16:15 DEBUG : empty space: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f106bae9c217872ce_d20251101_m021550_c001_v0001171_t0028_u01761963350371") 2025/11/01 02:16:15 DEBUG : empty space: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e8a01_d20251101_m021546_c001_v7007000_t0000_u01761963346357") 2025/11/01 02:16:15 DEBUG : empty space: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f1186dab83973ab81_d20251101_m021542_c001_v0001036_t0030_u01761963342507") 2025/11/01 02:16:15 DEBUG : empty space: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e89ed_d20251101_m021530_c001_v7007000_t0000_u01761963330910") 2025/11/01 02:16:15 DEBUG : empty space: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f106bae9c2178723f_d20251101_m021529_c001_v0001171_t0033_u01761963329027") 2025/11/01 02:16:15 DEBUG : nested/sub dir/file: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e89fd_d20251101_m021540_c001_v7007000_t0000_u01761963340824") 2025/11/01 02:16:15 DEBUG : nested/sub dir/file: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f106bae9c21787291_d20251101_m021540_c001_v0001171_t0029_u01761963340145") 2025/11/01 02:16:15 DEBUG : potato: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e89f9_d20251101_m021539_c001_v7007000_t0000_u01761963339231") 2025/11/01 02:16:15 DEBUG : potato: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f1186dab83973ab6d_d20251101_m021537_c001_v0001036_t0019_u01761963337182") 2025/11/01 02:16:15 DEBUG : potato: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e89f4_d20251101_m021536_c001_v7007000_t0000_u01761963336274") 2025/11/01 02:16:16 DEBUG : potato: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f1186dab83973ab65_d20251101_m021534_c001_v0001036_t0009_u01761963334580") 2025/11/01 02:16:16 DEBUG : potato: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e89f0_d20251101_m021533_c001_v7007000_t0000_u01761963333674") 2025/11/01 02:16:16 DEBUG : potato: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f106bae9c21787251_d20251101_m021531_c001_v0001171_t0001_u01761963331450") 2025/11/01 02:16:16 DEBUG : potato2: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e8a0d_d20251101_m021603_c001_v7007000_t0000_u01761963363607") 2025/11/01 02:16:16 DEBUG : potato2: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f106bae9c217872e6_d20251101_m021556_c001_v0001171_t0009_u01761963356964") 2025/11/01 02:16:16 DEBUG : potato2: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e8a08_d20251101_m021555_c001_v7007000_t0000_u01761963355505") 2025/11/01 02:16:16 DEBUG : potato2: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f1186dab83973ab8d_d20251101_m021549_c001_v0001036_t0026_u01761963349463") 2025/11/01 02:16:16 DEBUG : potato2: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e8a02_d20251101_m021547_c001_v7007000_t0000_u01761963347992") 2025/11/01 02:16:16 DEBUG : potato2: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f106bae9c2178729d_d20251101_m021541_c001_v0001171_t0050_u01761963341958") 2025/11/01 02:16:16 DEBUG : potato3: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e8a0e_d20251101_m021603_c001_v7007000_t0000_u01761963363806") 2025/11/01 02:16:16 DEBUG : potato3: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f106bae9c217872f2_d20251101_m021558_c001_v0001171_t0043_u01761963358436") 2025/11/01 02:16:16 DEBUG : potato3: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e8a09_d20251101_m021555_c001_v7007000_t0000_u01761963355686") 2025/11/01 02:16:16 DEBUG : potato3: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f1186dab83973ab8f_d20251101_m021550_c001_v0001036_t0018_u01761963350714") 2025/11/01 02:16:16 DEBUG : potato3: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e8a03_d20251101_m021548_c001_v7007000_t0000_u01761963348175") 2025/11/01 02:16:16 DEBUG : potato3: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f106bae9c217872a7_d20251101_m021543_c001_v0001171_t0028_u01761963343488") 2025/11/01 02:16:16 DEBUG : rclone-sync-test-ignore-file/.ignore: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4179f6057dea18e0_d20251101_m021614_c001_v7007000_t0000_u01761963374755") 2025/11/01 02:16:16 DEBUG : rclone-sync-test-ignore-file/.ignore: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f1186dab83973abb7_d20251101_m021610_c001_v0001036_t0003_u01761963370126") 2025/11/01 02:16:16 DEBUG : sub dir/hello world: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e89fe_d20251101_m021541_c001_v7007000_t0000_u01761963341005") 2025/11/01 02:16:16 DEBUG : sub dir/hello world: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f1186dab83973ab7b_d20251101_m021540_c001_v0001036_t0041_u01761963340133") 2025/11/01 02:16:16 DEBUG : sub dir/hello world: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e89eb_d20251101_m021528_c001_v7007000_t0000_u01761963328665") 2025/11/01 02:16:16 DEBUG : sub dir/hello world: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f106bae9c21787227_d20251101_m021525_c001_v0001171_t0013_u01761963325489") 2025/11/01 02:16:17 DEBUG : sub dir/hello world: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e89e8_d20251101_m021525_c001_v7007000_t0000_u01761963325127") 2025/11/01 02:16:17 DEBUG : sub dir/hello world: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f106bae9c21787221_d20251101_m021521_c001_v0001171_t0046_u01761963321905") 2025/11/01 02:16:17 DEBUG : sub dir/hello world: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e89e7_d20251101_m021521_c001_v7007000_t0000_u01761963321544") 2025/11/01 02:16:17 DEBUG : sub dir/hello world: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f106bae9c2178720b_d20251101_m021518_c001_v0001171_t0001_u01761963318259") 2025/11/01 02:16:17 DEBUG : sub/yam: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e89fa_d20251101_m021539_c001_v7007000_t0000_u01761963339414") 2025/11/01 02:16:17 DEBUG : sub/yam: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f106bae9c2178727f_d20251101_m021537_c001_v0001171_t0055_u01761963337192") 2025/11/01 02:16:17 DEBUG : yam: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e89fb_d20251101_m021539_c001_v7007000_t0000_u01761963339594") 2025/11/01 02:16:17 DEBUG : yam: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f106bae9c2178728b_d20251101_m021538_c001_v0001171_t0014_u01761963338720") 2025/11/01 02:16:17 DEBUG : yam: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e89f5_d20251101_m021536_c001_v7007000_t0000_u01761963336459") 2025/11/01 02:16:17 DEBUG : yam: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f106bae9c21787265_d20251101_m021534_c001_v0001171_t0010_u01761963334590") 2025/11/01 02:16:17 DEBUG : yam: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e89f1_d20251101_m021533_c001_v7007000_t0000_u01761963333855") 2025/11/01 02:16:17 DEBUG : yam: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f1186dab83973ab61_d20251101_m021532_c001_v0001036_t0006_u01761963332001") 2025/11/01 02:16:17 DEBUG : yaml: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e89f7_d20251101_m021536_c001_v7007000_t0000_u01761963336639") 2025/11/01 02:16:17 DEBUG : yaml: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f106bae9c2178726d_d20251101_m021535_c001_v0001171_t0029_u01761963335790") 2025/11/01 02:16:17 DEBUG : yaml: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f4116d128e05e89f2_d20251101_m021534_c001_v7007000_t0000_u01761963334038") 2025/11/01 02:16:17 DEBUG : yaml: Deleting (id "4_z0fa29d83302d3bdb9fa80e1b_f106bae9c21787257_d20251101_m021533_c001_v0001171_t0058_u01761963333232") "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncAfterChangingModtimeOnly|TestSyncOverlapWithFilter|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Finished ERROR in 1m2.540105736s (try 3/5): exit status 1: Failed [TestServerSideCopy TestServerSideCopyOverSelf TestServerSideMoveOverSelf TestSyncAfterChangingModtimeOnly TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs]