"./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 3/5) 2025/11/01 01:58:30 DEBUG : Creating backend with remote "TestB2:rclone-test-yosiyuz1vaju" 2025/11/01 01:58:30 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/01 01:58:30 DEBUG : Creating backend with remote "/tmp/rclone1475174158" === RUN TestServerSideCopy run.go:185: Remote "B2 bucket rclone-test-yosiyuz1vaju", Local "Local file system at /tmp/rclone1475174158", Modify Window "1ms" 2025/11/01 01:58:32 DEBUG : Creating backend with remote "TestB2:rclone-test-qocuhor5pina" 2025/11/01 01:58:32 DEBUG : Config file has changed externally - reloading sync_test.go:620: Server side copy (if possible) B2 bucket rclone-test-yosiyuz1vaju -> B2 bucket rclone-test-qocuhor5pina 2025/11/01 01:58:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/01 01:58:33 DEBUG : B2 bucket rclone-test-qocuhor5pina: Waiting for checks to finish 2025/11/01 01:58:33 DEBUG : B2 bucket rclone-test-qocuhor5pina: Waiting for transfers to finish 2025/11/01 01:58: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/01 01:58:34 DEBUG : B2 bucket rclone-test-qocuhor5pina: Purge remote 2025/11/01 01:58:34 INFO : B2 bucket rclone-test-qocuhor5pina: cleaning bucket "rclone-test-qocuhor5pina" of all files --- FAIL: TestServerSideCopy (4.69s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "B2 bucket rclone-test-yosiyuz1vaju", Local "Local file system at /tmp/rclone1475174158", Modify Window "1ms" 2025/11/01 01:58:35 DEBUG : Creating backend with remote "TestB2:rclone-test-digaqoh4hiru" 2025/11/01 01:58:35 DEBUG : Config file has changed externally - reloading sync_test.go:656: Server side copy (if possible) B2 bucket rclone-test-yosiyuz1vaju -> B2 bucket rclone-test-digaqoh4hiru 2025/11/01 01:58:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/01 01:58:36 DEBUG : B2 bucket rclone-test-digaqoh4hiru: Waiting for checks to finish 2025/11/01 01:58:36 DEBUG : B2 bucket rclone-test-digaqoh4hiru: Waiting for transfers to finish 2025/11/01 01:58: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/01 01:58:37 DEBUG : B2 bucket rclone-test-digaqoh4hiru: Purge remote 2025/11/01 01:58:37 INFO : B2 bucket rclone-test-digaqoh4hiru: cleaning bucket "rclone-test-digaqoh4hiru" of all files --- FAIL: TestServerSideCopyOverSelf (3.28s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "B2 bucket rclone-test-yosiyuz1vaju", Local "Local file system at /tmp/rclone1475174158", Modify Window "1ms" 2025/11/01 01:58:39 DEBUG : Creating backend with remote "TestB2:rclone-test-qoyutic5behe" 2025/11/01 01:58:39 DEBUG : Config file has changed externally - reloading sync_test.go:701: Server side copy (if possible) B2 bucket rclone-test-yosiyuz1vaju -> B2 bucket rclone-test-qoyutic5behe 2025/11/01 01:58:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/01 01:58:40 DEBUG : B2 bucket rclone-test-qoyutic5behe: Waiting for checks to finish 2025/11/01 01:58:40 DEBUG : B2 bucket rclone-test-qoyutic5behe: Waiting for transfers to finish 2025/11/01 01:58:41 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 01:58:41 DEBUG : B2 bucket rclone-test-qoyutic5behe: Purge remote 2025/11/01 01:58:41 INFO : B2 bucket rclone-test-qoyutic5behe: cleaning bucket "rclone-test-qoyutic5behe" of all files --- FAIL: TestServerSideMoveOverSelf (3.42s) === RUN TestSyncAfterChangingModtimeOnly run.go:185: Remote "B2 bucket rclone-test-yosiyuz1vaju", Local "Local file system at /tmp/rclone1475174158", Modify Window "1ms" 2025/11/01 01:58:42 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for checks to finish 2025/11/01 01:58: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/01 01:58:42 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/01 01:58:42 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2025/11/01 01:58:42 DEBUG : empty space: Unchanged skipping 2025/11/01 01:58:42 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for transfers to finish 2025/11/01 01:58:42 DEBUG : Waiting for deletions to finish 2025/11/01 01:58:42 INFO : There was nothing to transfer 2025/11/01 01:58:43 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for checks to finish 2025/11/01 01:58: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/01 01:58:43 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/01 01:58:43 ERROR : empty space: Failed to set modification time: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:58:43 DEBUG : empty space: Unchanged skipping 2025/11/01 01:58:43 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for transfers to finish 2025/11/01 01:58:43 ERROR : B2 bucket rclone-test-yosiyuz1vaju: not deleting files as there were IO errors 2025/11/01 01:58:43 ERROR : B2 bucket rclone-test-yosiyuz1vaju: 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-yosiyuz1vaju", Local "Local file system at /tmp/rclone1475174158", Modify Window "1ms" sync_test.go:1568: Can track renames: true 2025/11/01 01:58:44 INFO : B2 bucket rclone-test-yosiyuz1vaju: Making map for --track-renames 2025/11/01 01:58:44 INFO : B2 bucket rclone-test-yosiyuz1vaju: Finished making map for --track-renames 2025/11/01 01:58:44 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for checks to finish 2025/11/01 01:58:44 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/01 01:58:44 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for renames to finish 2025/11/01 01:58:44 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/01 01:58:44 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for transfers to finish 2025/11/01 01:58:44 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/01 01:58:44 INFO : potato: Copied (new) 2025/11/01 01:58:45 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 01:58:45 INFO : yam: Copied (new) 2025/11/01 01:58:45 DEBUG : Waiting for deletions to finish 2025/11/01 01:58:45 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/01 01:58:45 INFO : B2 bucket rclone-test-yosiyuz1vaju: Making map for --track-renames 2025/11/01 01:58:45 INFO : B2 bucket rclone-test-yosiyuz1vaju: Finished making map for --track-renames 2025/11/01 01:58:45 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for checks to finish 2025/11/01 01:58:45 DEBUG : potato: Unchanged skipping 2025/11/01 01:58:45 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for renames to finish 2025/11/01 01:58:46 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:58:46 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:58:46 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:58:46 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/01 01:58:46 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for transfers to finish 2025/11/01 01:58:46 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 01:58:46 INFO : yaml: Copied (new) 2025/11/01 01:58:46 ERROR : B2 bucket rclone-test-yosiyuz1vaju: not deleting files as there were IO errors 2025/11/01 01:58:46 ERROR : B2 bucket rclone-test-yosiyuz1vaju: 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.05s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "B2 bucket rclone-test-yosiyuz1vaju", Local "Local file system at /tmp/rclone1475174158", Modify Window "1ms" sync_test.go:1640: Can track renames: true 2025/11/01 01:58:47 INFO : B2 bucket rclone-test-yosiyuz1vaju: Making map for --track-renames 2025/11/01 01:58:47 INFO : B2 bucket rclone-test-yosiyuz1vaju: Finished making map for --track-renames 2025/11/01 01:58:47 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for checks to finish 2025/11/01 01:58:47 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/01 01:58:47 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for renames to finish 2025/11/01 01:58:47 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/01 01:58:47 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for transfers to finish 2025/11/01 01:58:47 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 01:58:47 INFO : yam: Copied (new) 2025/11/01 01:58:48 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/01 01:58:48 INFO : potato: Copied (new) 2025/11/01 01:58:48 DEBUG : Waiting for deletions to finish 2025/11/01 01:58:48 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/01 01:58:48 INFO : B2 bucket rclone-test-yosiyuz1vaju: Making map for --track-renames 2025/11/01 01:58:48 INFO : B2 bucket rclone-test-yosiyuz1vaju: Finished making map for --track-renames 2025/11/01 01:58:48 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for checks to finish 2025/11/01 01:58:48 DEBUG : potato: Unchanged skipping 2025/11/01 01:58:48 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for renames to finish 2025/11/01 01:58:48 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:58:48 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:58:48 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:58:48 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/01 01:58:48 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for transfers to finish 2025/11/01 01:58:49 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 01:58:49 INFO : yaml: Copied (new) 2025/11/01 01:58:49 ERROR : B2 bucket rclone-test-yosiyuz1vaju: not deleting files as there were IO errors 2025/11/01 01:58:49 ERROR : B2 bucket rclone-test-yosiyuz1vaju: 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.52s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "B2 bucket rclone-test-yosiyuz1vaju", Local "Local file system at /tmp/rclone1475174158", Modify Window "1ms" sync_test.go:1679: Can track renames: true 2025/11/01 01:58:50 INFO : B2 bucket rclone-test-yosiyuz1vaju: Making map for --track-renames 2025/11/01 01:58:50 INFO : B2 bucket rclone-test-yosiyuz1vaju: Finished making map for --track-renames 2025/11/01 01:58:50 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for checks to finish 2025/11/01 01:58:50 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for renames to finish 2025/11/01 01:58:50 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/01 01:58:50 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/11/01 01:58:50 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for transfers to finish 2025/11/01 01:58:50 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 01:58:50 INFO : sub/yam: Copied (new) 2025/11/01 01:58:50 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/01 01:58:50 INFO : potato: Copied (new) 2025/11/01 01:58:50 DEBUG : Waiting for deletions to finish 2025/11/01 01:58:50 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/01 01:58:50 DEBUG : potato: Unchanged skipping 2025/11/01 01:58:50 INFO : B2 bucket rclone-test-yosiyuz1vaju: Making map for --track-renames 2025/11/01 01:58:50 INFO : B2 bucket rclone-test-yosiyuz1vaju: Finished making map for --track-renames 2025/11/01 01:58:50 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for checks to finish 2025/11/01 01:58:50 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for renames to finish 2025/11/01 01:58:51 ERROR : sub/yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:58:51 ERROR : sub/yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:58:51 DEBUG : yam: Failed to rename to "sub/yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:58:51 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/01 01:58:51 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for transfers to finish 2025/11/01 01:58:51 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 01:58:51 INFO : yam: Copied (new) 2025/11/01 01:58:51 ERROR : B2 bucket rclone-test-yosiyuz1vaju: not deleting files as there were IO errors 2025/11/01 01:58:51 ERROR : B2 bucket rclone-test-yosiyuz1vaju: 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.42s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "B2 bucket rclone-test-yosiyuz1vaju", Local "Local file system at /tmp/rclone1475174158", Modify Window "1ms" 2025/11/01 01:58:52 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/01 01:58:52 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/11/01 01:58:52 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for checks to finish 2025/11/01 01:58:52 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for transfers to finish 2025/11/01 01:58:52 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/11/01 01:58:52 INFO : nested/sub dir/file: Copied (new) 2025/11/01 01:58:52 INFO : nested/sub dir/file: Deleted 2025/11/01 01:58:53 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/01 01:58:53 INFO : sub dir/hello world: Copied (new) 2025/11/01 01:58:53 INFO : sub dir/hello world: Deleted 2025/11/01 01:58:53 ERROR : Local file system at /tmp/rclone1475174158: 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.65s) === RUN TestServerSideMove run.go:185: Remote "B2 bucket rclone-test-yosiyuz1vaju", Local "Local file system at /tmp/rclone1475174158", Modify Window "1ms" 2025/11/01 01:58:54 DEBUG : Creating backend with remote "TestB2:rclone-test-fibezoj9bapu" 2025/11/01 01:58:54 DEBUG : Config file has changed externally - reloading sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-yosiyuz1vaju -> B2 bucket rclone-test-fibezoj9bapu 2025/11/01 01:58:58 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/01 01:58:58 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/01 01:58:58 DEBUG : empty space: Unchanged skipping 2025/11/01 01:58:58 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/11/01 01:58:58 DEBUG : B2 bucket rclone-test-fibezoj9bapu: Waiting for checks to finish 2025/11/01 01:58:58 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:58:58 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:58:58 INFO : empty space: Deleted 2025/11/01 01:58:58 DEBUG : B2 bucket rclone-test-fibezoj9bapu: Waiting for transfers to finish 2025/11/01 01:58:59 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:58:59 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 01:58:59 DEBUG : B2 bucket rclone-test-fibezoj9bapu: Purge remote 2025/11/01 01:58:59 INFO : B2 bucket rclone-test-fibezoj9bapu: cleaning bucket "rclone-test-fibezoj9bapu" of all files 2025/11/01 01:58:59 DEBUG : empty space: Deleting (id "4_zbf729df3203d3bdb9fa80e1b_f116878b54593a925_d20251101_m015857_c001_v0001144_t0047_u01761962337241") 2025/11/01 01:58:59 DEBUG : potato3: Deleting (id "4_zbf729df3203d3bdb9fa80e1b_f116878b54593a929_d20251101_m015857_c001_v0001144_t0007_u01761962337826") --- FAIL: TestServerSideMove (6.56s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "B2 bucket rclone-test-yosiyuz1vaju", Local "Local file system at /tmp/rclone1475174158", Modify Window "1ms" 2025/11/01 01:59:00 DEBUG : Creating backend with remote "TestB2:rclone-test-celorub8fovo" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-yosiyuz1vaju -> B2 bucket rclone-test-celorub8fovo 2025/11/01 01:59:06 DEBUG : empty space: Excluded (Size Filter) 2025/11/01 01:59:06 DEBUG : empty space: Excluded (Size Filter) 2025/11/01 01:59:06 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/01 01:59:06 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/11/01 01:59:06 DEBUG : B2 bucket rclone-test-celorub8fovo: Waiting for checks to finish 2025/11/01 01:59:06 DEBUG : B2 bucket rclone-test-celorub8fovo: Waiting for transfers to finish 2025/11/01 01:59:06 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:59:06 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:59:06 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:59:06 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/01 01:59:06 DEBUG : B2 bucket rclone-test-celorub8fovo: Purge remote 2025/11/01 01:59:06 INFO : B2 bucket rclone-test-celorub8fovo: cleaning bucket "rclone-test-celorub8fovo" of all files 2025/11/01 01:59:06 DEBUG : empty space: Deleting (id "4_zdf42bdd3203d3bdb9fa80e1b_f10135b9282d4f42e_d20251101_m015904_c001_v0001100_t0053_u01761962344810") 2025/11/01 01:59:06 DEBUG : potato3: Deleting (id "4_zdf42bdd3203d3bdb9fa80e1b_f10135b9282d4f434_d20251101_m015905_c001_v0001100_t0049_u01761962345414") --- FAIL: TestServerSideMoveWithFilter (7.67s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "B2 bucket rclone-test-yosiyuz1vaju", Local "Local file system at /tmp/rclone1475174158", Modify Window "1ms" 2025/11/01 01:59:08 DEBUG : Creating backend with remote "TestB2:rclone-test-vabicim7komi" 2025/11/01 01:59:10 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-yosiyuz1vaju -> B2 bucket rclone-test-vabicim7komi 2025/11/01 01:59:13 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/01 01:59:13 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/01 01:59:13 DEBUG : empty space: Unchanged skipping 2025/11/01 01:59:13 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/11/01 01:59:13 DEBUG : B2 bucket rclone-test-vabicim7komi: Waiting for checks to finish 2025/11/01 01:59:13 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:59:13 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:59:13 INFO : empty space: Deleted 2025/11/01 01:59:13 DEBUG : B2 bucket rclone-test-vabicim7komi: Waiting for transfers to finish 2025/11/01 01:59:13 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 01:59:13 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 01:59:13 DEBUG : B2 bucket rclone-test-vabicim7komi: Purge remote 2025/11/01 01:59:13 INFO : B2 bucket rclone-test-vabicim7komi: cleaning bucket "rclone-test-vabicim7komi" of all files 2025/11/01 01:59:13 DEBUG : empty space: Deleting (id "4_z2fc2dd93203d3bdb9fa80e1b_f118bc6e2e7391bee_d20251101_m015911_c001_v0001136_t0041_u01761962351808") 2025/11/01 01:59:13 DEBUG : potato3: Deleting (id "4_z2fc2dd93203d3bdb9fa80e1b_f118bc6e2e7391bf2_d20251101_m015912_c001_v0001136_t0029_u01761962352235") --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (6.98s) === RUN TestSyncOverlapWithFilter run.go:185: Remote "B2 bucket rclone-test-yosiyuz1vaju", Local "Local file system at /tmp/rclone1475174158", Modify Window "1ms" 2025/11/01 01:59:15 DEBUG : Creating backend with remote "TestB2:rclone-test-yosiyuz1vaju/rclone-sync-test" 2025/11/01 01:59:17 DEBUG : Creating backend with remote "TestB2:rclone-test-yosiyuz1vaju/rclone-sync-test-include/layer2" 2025/11/01 01:59:18 DEBUG : Creating backend with remote "TestB2:rclone-test-yosiyuz1vaju/rclone-sync-test-ignore-file" 2025/11/01 01:59:22 DEBUG : B2 bucket rclone-test-yosiyuz1vaju path rclone-sync-test: Waiting for checks to finish 2025/11/01 01:59:22 DEBUG : B2 bucket rclone-test-yosiyuz1vaju path rclone-sync-test: Waiting for transfers to finish 2025/11/01 01:59:22 DEBUG : Waiting for deletions to finish 2025/11/01 01:59:22 INFO : There was nothing to transfer 2025/11/01 01:59:23 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for checks to finish 2025/11/01 01:59:23 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for transfers to finish 2025/11/01 01:59:23 DEBUG : Waiting for deletions to finish 2025/11/01 01:59:23 INFO : There was nothing to transfer 2025/11/01 01:59:23 DEBUG : B2 bucket rclone-test-yosiyuz1vaju path rclone-sync-test-include/layer2: Waiting for checks to finish 2025/11/01 01:59:23 DEBUG : B2 bucket rclone-test-yosiyuz1vaju path rclone-sync-test-include/layer2: Waiting for transfers to finish 2025/11/01 01:59:23 DEBUG : Waiting for deletions to finish 2025/11/01 01:59:23 INFO : There was nothing to transfer 2025/11/01 01:59:23 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for checks to finish 2025/11/01 01:59:23 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Waiting for transfers to finish 2025/11/01 01:59:23 DEBUG : Waiting for deletions to finish 2025/11/01 01:59:23 INFO : There was nothing to transfer 2025/11/01 01:59:24 DEBUG : B2 bucket rclone-test-yosiyuz1vaju path rclone-sync-test-ignore-file: Waiting for checks to finish 2025/11/01 01:59:24 DEBUG : B2 bucket rclone-test-yosiyuz1vaju path rclone-sync-test-ignore-file: Waiting for transfers to finish 2025/11/01 01:59:24 DEBUG : Waiting for deletions to finish 2025/11/01 01:59:24 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (9.46s) FAIL 2025/11/01 01:59:24 DEBUG : B2 bucket rclone-test-yosiyuz1vaju: Purge remote 2025/11/01 01:59:24 INFO : B2 bucket rclone-test-yosiyuz1vaju: cleaning bucket "rclone-test-yosiyuz1vaju" of all files 2025/11/01 01:59:25 DEBUG : empty space: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c84130_d20251101_m015913_c001_v7007000_t0000_u01761962353231") 2025/11/01 01:59:25 DEBUG : empty space: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f113afd3e37a37af2_d20251101_m015909_c001_v0001099_t0050_u01761962349845") 2025/11/01 01:59:25 DEBUG : empty space: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c8412a_d20251101_m015907_c001_v7007000_t0000_u01761962347633") 2025/11/01 01:59:25 DEBUG : empty space: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f1019555b43aece56_d20251101_m015902_c001_v0001183_t0053_u01761962342400") 2025/11/01 01:59:25 DEBUG : empty space: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c84123_d20251101_m015858_c001_v7007000_t0000_u01761962338766") 2025/11/01 01:59:25 DEBUG : empty space: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f113afd3e37a37adb_d20251101_m015854_c001_v0001099_t0010_u01761962334910") 2025/11/01 01:59:25 DEBUG : empty space: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c8410f_d20251101_m015844_c001_v7007000_t0000_u01761962324121") 2025/11/01 01:59:25 DEBUG : empty space: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f1019555b43aece13_d20251101_m015842_c001_v0001183_t0024_u01761962322269") 2025/11/01 01:59:25 DEBUG : nested/sub dir/file: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c8411f_d20251101_m015853_c001_v7007000_t0000_u01761962333586") 2025/11/01 01:59:25 DEBUG : nested/sub dir/file: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f1019555b43aece3a_d20251101_m015852_c001_v0001183_t0020_u01761962332670") 2025/11/01 01:59:25 DEBUG : potato: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c8411c_d20251101_m015851_c001_v7007000_t0000_u01761962331749") 2025/11/01 01:59:25 DEBUG : potato: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f113afd3e37a37ad2_d20251101_m015850_c001_v0001099_t0048_u01761962330259") 2025/11/01 01:59:25 DEBUG : potato: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c84118_d20251101_m015849_c001_v7007000_t0000_u01761962329330") 2025/11/01 01:59:25 DEBUG : potato: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f113afd3e37a37ace_d20251101_m015847_c001_v0001099_t0017_u01761962327717") 2025/11/01 01:59:25 DEBUG : potato: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c84110_d20251101_m015846_c001_v7007000_t0000_u01761962326799") 2025/11/01 01:59:25 DEBUG : potato: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f1019555b43aece1f_d20251101_m015844_c001_v0001183_t0041_u01761962324665") 2025/11/01 01:59:25 DEBUG : potato2: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c84134_d20251101_m015914_c001_v7007000_t0000_u01761962354796") 2025/11/01 01:59:25 DEBUG : potato2: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f1019555b43aece61_d20251101_m015909_c001_v0001183_t0051_u01761962349282") 2025/11/01 01:59:25 DEBUG : potato2: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c8412b_d20251101_m015907_c001_v7007000_t0000_u01761962347816") 2025/11/01 01:59:25 DEBUG : potato2: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f113afd3e37a37ae3_d20251101_m015901_c001_v0001099_t0038_u01761962341673") 2025/11/01 01:59:26 DEBUG : potato2: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c84124_d20251101_m015900_c001_v7007000_t0000_u01761962340148") 2025/11/01 01:59:26 DEBUG : potato2: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f1019555b43aece40_d20251101_m015854_c001_v0001183_t0038_u01761962334693") 2025/11/01 01:59:26 DEBUG : potato3: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c84135_d20251101_m015914_c001_v7007000_t0000_u01761962354978") 2025/11/01 01:59:26 DEBUG : potato3: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f1019555b43aece65_d20251101_m015910_c001_v0001183_t0023_u01761962350126") 2025/11/01 01:59:26 DEBUG : potato3: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c8412c_d20251101_m015907_c001_v7007000_t0000_u01761962347998") 2025/11/01 01:59:26 DEBUG : potato3: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f113afd3e37a37ae5_d20251101_m015902_c001_v0001099_t0046_u01761962342631") 2025/11/01 01:59:26 DEBUG : potato3: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c84125_d20251101_m015900_c001_v7007000_t0000_u01761962340330") 2025/11/01 01:59:26 DEBUG : potato3: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f1019555b43aece44_d20251101_m015855_c001_v0001183_t0028_u01761962335391") 2025/11/01 01:59:26 DEBUG : rclone-sync-test-ignore-file/.ignore: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f413849d483d0c292_d20251101_m015924_c001_v7007000_t0000_u01761962364443") 2025/11/01 01:59:26 DEBUG : rclone-sync-test-ignore-file/.ignore: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f113afd3e37a37b06_d20251101_m015921_c001_v0001099_t0010_u01761962361313") 2025/11/01 01:59:26 DEBUG : sub dir/hello world: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c84120_d20251101_m015853_c001_v7007000_t0000_u01761962333768") 2025/11/01 01:59:26 DEBUG : sub dir/hello world: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f113afd3e37a37ad9_d20251101_m015852_c001_v0001099_t0027_u01761962332660") 2025/11/01 01:59:26 DEBUG : sub dir/hello world: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c8410e_d20251101_m015841_c001_v7007000_t0000_u01761962321884") 2025/11/01 01:59:26 DEBUG : sub dir/hello world: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f1019555b43aece02_d20251101_m015838_c001_v0001183_t0028_u01761962318824") 2025/11/01 01:59:26 DEBUG : sub dir/hello world: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c8410b_d20251101_m015838_c001_v7007000_t0000_u01761962318462") 2025/11/01 01:59:26 DEBUG : sub dir/hello world: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f1019555b43aecdf0_d20251101_m015835_c001_v0001183_t0008_u01761962315544") 2025/11/01 01:59:26 DEBUG : sub dir/hello world: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c84108_d20251101_m015835_c001_v7007000_t0000_u01761962315125") 2025/11/01 01:59:26 DEBUG : sub dir/hello world: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f1019555b43aecddc_d20251101_m015832_c001_v0001183_t0015_u01761962312302") 2025/11/01 01:59:26 DEBUG : sub/yam: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c8411d_d20251101_m015851_c001_v7007000_t0000_u01761962331932") 2025/11/01 01:59:26 DEBUG : sub/yam: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f1019555b43aece2d_d20251101_m015850_c001_v0001183_t0010_u01761962330271") 2025/11/01 01:59:27 DEBUG : yam: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c8411e_d20251101_m015852_c001_v7007000_t0000_u01761962332115") 2025/11/01 01:59:27 DEBUG : yam: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f1019555b43aece33_d20251101_m015851_c001_v0001183_t0043_u01761962331359") 2025/11/01 01:59:27 DEBUG : yam: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c8411a_d20251101_m015849_c001_v7007000_t0000_u01761962329512") 2025/11/01 01:59:27 DEBUG : yam: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f1019555b43aece25_d20251101_m015847_c001_v0001183_t0024_u01761962327729") 2025/11/01 01:59:27 DEBUG : yam: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c84115_d20251101_m015846_c001_v7007000_t0000_u01761962326981") 2025/11/01 01:59:27 DEBUG : yam: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f113afd3e37a37acb_d20251101_m015845_c001_v0001099_t0057_u01761962325218") 2025/11/01 01:59:27 DEBUG : yaml: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c8411b_d20251101_m015849_c001_v7007000_t0000_u01761962329695") 2025/11/01 01:59:27 DEBUG : yaml: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f1019555b43aece2b_d20251101_m015848_c001_v0001183_t0013_u01761962328937") 2025/11/01 01:59:27 DEBUG : yaml: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f4064ce6046c84116_d20251101_m015847_c001_v7007000_t0000_u01761962327163") 2025/11/01 01:59:27 DEBUG : yaml: Deleting (id "4_z3fa23de3203d3bdb9fa80e1b_f1019555b43aece23_d20251101_m015846_c001_v0001183_t0036_u01761962326402") "./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 58.400445624s (try 3/5): exit status 1: Failed [TestServerSideCopy TestServerSideCopyOverSelf TestServerSideMoveOverSelf TestSyncAfterChangingModtimeOnly TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs]