"./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/02 02:41:27 DEBUG : Creating backend with remote "TestB2:rclone-test-kazadel4kago" 2025/11/02 02:41:27 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 02:41:28 DEBUG : Creating backend with remote "/tmp/rclone324028504" === RUN TestServerSideCopy run.go:185: Remote "B2 bucket rclone-test-kazadel4kago", Local "Local file system at /tmp/rclone324028504", Modify Window "1ms" 2025/11/02 02:41:30 DEBUG : Creating backend with remote "TestB2:rclone-test-lamerum8cafu" sync_test.go:620: Server side copy (if possible) B2 bucket rclone-test-kazadel4kago -> B2 bucket rclone-test-lamerum8cafu 2025/11/02 02:41:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 02:41:31 DEBUG : B2 bucket rclone-test-lamerum8cafu: Waiting for checks to finish 2025/11/02 02:41:31 DEBUG : B2 bucket rclone-test-lamerum8cafu: Waiting for transfers to finish 2025/11/02 02:41:31 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:41:31 DEBUG : B2 bucket rclone-test-lamerum8cafu: Purge remote 2025/11/02 02:41:31 INFO : B2 bucket rclone-test-lamerum8cafu: cleaning bucket "rclone-test-lamerum8cafu" of all files --- FAIL: TestServerSideCopy (4.59s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "B2 bucket rclone-test-kazadel4kago", Local "Local file system at /tmp/rclone324028504", Modify Window "1ms" 2025/11/02 02:41:33 DEBUG : Creating backend with remote "TestB2:rclone-test-jirefoy8koma" sync_test.go:656: Server side copy (if possible) B2 bucket rclone-test-kazadel4kago -> B2 bucket rclone-test-jirefoy8koma 2025/11/02 02:41:34 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 02:41:34 DEBUG : B2 bucket rclone-test-jirefoy8koma: Waiting for checks to finish 2025/11/02 02:41:34 DEBUG : B2 bucket rclone-test-jirefoy8koma: Waiting for transfers to finish 2025/11/02 02:41:35 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:41:35 DEBUG : B2 bucket rclone-test-jirefoy8koma: Purge remote 2025/11/02 02:41:35 INFO : B2 bucket rclone-test-jirefoy8koma: cleaning bucket "rclone-test-jirefoy8koma" of all files --- FAIL: TestServerSideCopyOverSelf (3.30s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "B2 bucket rclone-test-kazadel4kago", Local "Local file system at /tmp/rclone324028504", Modify Window "1ms" 2025/11/02 02:41:36 DEBUG : Creating backend with remote "TestB2:rclone-test-webepaj3jesi" sync_test.go:701: Server side copy (if possible) B2 bucket rclone-test-kazadel4kago -> B2 bucket rclone-test-webepaj3jesi 2025/11/02 02:41:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 02:41:37 DEBUG : B2 bucket rclone-test-webepaj3jesi: Waiting for checks to finish 2025/11/02 02:41:37 DEBUG : B2 bucket rclone-test-webepaj3jesi: Waiting for transfers to finish 2025/11/02 02:41:38 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:41:38 DEBUG : B2 bucket rclone-test-webepaj3jesi: Purge remote 2025/11/02 02:41:38 INFO : B2 bucket rclone-test-webepaj3jesi: cleaning bucket "rclone-test-webepaj3jesi" of all files --- FAIL: TestServerSideMoveOverSelf (3.26s) === RUN TestSyncAfterChangingModtimeOnly run.go:185: Remote "B2 bucket rclone-test-kazadel4kago", Local "Local file system at /tmp/rclone324028504", Modify Window "1ms" 2025/11/02 02:41:40 DEBUG : empty space: size = 1 OK 2025/11/02 02:41:40 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for checks to finish 2025/11/02 02:41:40 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:41:40 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/02 02:41:40 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2025/11/02 02:41:40 DEBUG : empty space: Unchanged skipping 2025/11/02 02:41:40 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for transfers to finish 2025/11/02 02:41:40 DEBUG : Waiting for deletions to finish 2025/11/02 02:41:40 INFO : There was nothing to transfer 2025/11/02 02:41:40 DEBUG : empty space: size = 1 OK 2025/11/02 02:41:40 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:41:40 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for checks to finish 2025/11/02 02:41:40 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/02 02:41:41 ERROR : empty space: Failed to set modification time: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:41 DEBUG : empty space: Unchanged skipping 2025/11/02 02:41:41 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for transfers to finish 2025/11/02 02:41:41 ERROR : B2 bucket rclone-test-kazadel4kago: not deleting files as there were IO errors 2025/11/02 02:41:41 ERROR : B2 bucket rclone-test-kazadel4kago: 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.32s) === RUN TestSyncWithTrackRenames run.go:185: Remote "B2 bucket rclone-test-kazadel4kago", Local "Local file system at /tmp/rclone324028504", Modify Window "1ms" sync_test.go:1568: Can track renames: true 2025/11/02 02:41:42 INFO : B2 bucket rclone-test-kazadel4kago: Making map for --track-renames 2025/11/02 02:41:42 INFO : B2 bucket rclone-test-kazadel4kago: Finished making map for --track-renames 2025/11/02 02:41:42 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for checks to finish 2025/11/02 02:41:42 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for renames to finish 2025/11/02 02:41:42 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 02:41:42 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 02:41:42 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for transfers to finish 2025/11/02 02:41:42 DEBUG : potato: size = 14 OK 2025/11/02 02:41:42 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 02:41:42 INFO : potato: Copied (new) 2025/11/02 02:41:42 DEBUG : yam: size = 11 OK 2025/11/02 02:41:42 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 02:41:42 INFO : yam: Copied (new) 2025/11/02 02:41:42 DEBUG : Waiting for deletions to finish 2025/11/02 02:41:43 DEBUG : potato: size = 14 OK 2025/11/02 02:41:43 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 02:41:43 INFO : B2 bucket rclone-test-kazadel4kago: Making map for --track-renames 2025/11/02 02:41:43 INFO : B2 bucket rclone-test-kazadel4kago: Finished making map for --track-renames 2025/11/02 02:41:43 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for checks to finish 2025/11/02 02:41:43 DEBUG : potato: Unchanged skipping 2025/11/02 02:41:43 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for renames to finish 2025/11/02 02:41:43 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:43 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:43 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:43 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/02 02:41:43 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for transfers to finish 2025/11/02 02:41:43 DEBUG : yaml: size = 11 OK 2025/11/02 02:41:43 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 02:41:43 INFO : yaml: Copied (new) 2025/11/02 02:41:43 ERROR : B2 bucket rclone-test-kazadel4kago: not deleting files as there were IO errors 2025/11/02 02:41:43 ERROR : B2 bucket rclone-test-kazadel4kago: 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 (2.86s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "B2 bucket rclone-test-kazadel4kago", Local "Local file system at /tmp/rclone324028504", Modify Window "1ms" sync_test.go:1640: Can track renames: true 2025/11/02 02:41:45 INFO : B2 bucket rclone-test-kazadel4kago: Making map for --track-renames 2025/11/02 02:41:45 INFO : B2 bucket rclone-test-kazadel4kago: Finished making map for --track-renames 2025/11/02 02:41:45 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for checks to finish 2025/11/02 02:41:45 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 02:41:45 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 02:41:45 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for renames to finish 2025/11/02 02:41:45 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for transfers to finish 2025/11/02 02:41:45 DEBUG : potato: size = 14 OK 2025/11/02 02:41:45 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 02:41:45 INFO : potato: Copied (new) 2025/11/02 02:41:45 DEBUG : yam: size = 11 OK 2025/11/02 02:41:45 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 02:41:45 INFO : yam: Copied (new) 2025/11/02 02:41:45 DEBUG : Waiting for deletions to finish 2025/11/02 02:41:45 DEBUG : potato: size = 14 OK 2025/11/02 02:41:45 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 02:41:45 INFO : B2 bucket rclone-test-kazadel4kago: Making map for --track-renames 2025/11/02 02:41:45 INFO : B2 bucket rclone-test-kazadel4kago: Finished making map for --track-renames 2025/11/02 02:41:45 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for checks to finish 2025/11/02 02:41:45 DEBUG : potato: Unchanged skipping 2025/11/02 02:41:45 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for renames to finish 2025/11/02 02:41:46 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:46 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:46 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:46 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/02 02:41:46 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for transfers to finish 2025/11/02 02:41:46 DEBUG : yaml: size = 11 OK 2025/11/02 02:41:46 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 02:41:46 INFO : yaml: Copied (new) 2025/11/02 02:41:46 ERROR : B2 bucket rclone-test-kazadel4kago: not deleting files as there were IO errors 2025/11/02 02:41:46 ERROR : B2 bucket rclone-test-kazadel4kago: 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.30s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "B2 bucket rclone-test-kazadel4kago", Local "Local file system at /tmp/rclone324028504", Modify Window "1ms" sync_test.go:1679: Can track renames: true 2025/11/02 02:41:47 INFO : B2 bucket rclone-test-kazadel4kago: Making map for --track-renames 2025/11/02 02:41:47 INFO : B2 bucket rclone-test-kazadel4kago: Finished making map for --track-renames 2025/11/02 02:41:47 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for checks to finish 2025/11/02 02:41:47 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/11/02 02:41:47 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 02:41:47 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for renames to finish 2025/11/02 02:41:47 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for transfers to finish 2025/11/02 02:41:47 DEBUG : sub/yam: size = 11 OK 2025/11/02 02:41:47 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 02:41:47 INFO : sub/yam: Copied (new) 2025/11/02 02:41:47 DEBUG : potato: size = 14 OK 2025/11/02 02:41:47 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 02:41:47 INFO : potato: Copied (new) 2025/11/02 02:41:47 DEBUG : Waiting for deletions to finish 2025/11/02 02:41:47 DEBUG : potato: size = 14 OK 2025/11/02 02:41:47 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 02:41:47 DEBUG : potato: Unchanged skipping 2025/11/02 02:41:47 INFO : B2 bucket rclone-test-kazadel4kago: Making map for --track-renames 2025/11/02 02:41:47 INFO : B2 bucket rclone-test-kazadel4kago: Finished making map for --track-renames 2025/11/02 02:41:47 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for checks to finish 2025/11/02 02:41:47 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for renames to finish 2025/11/02 02:41:48 ERROR : sub/yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:48 ERROR : sub/yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:48 DEBUG : yam: Failed to rename to "sub/yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:48 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 02:41:48 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for transfers to finish 2025/11/02 02:41:48 DEBUG : yam: size = 11 OK 2025/11/02 02:41:48 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 02:41:48 INFO : yam: Copied (new) 2025/11/02 02:41:48 ERROR : B2 bucket rclone-test-kazadel4kago: not deleting files as there were IO errors 2025/11/02 02:41:48 ERROR : B2 bucket rclone-test-kazadel4kago: 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.31s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "B2 bucket rclone-test-kazadel4kago", Local "Local file system at /tmp/rclone324028504", Modify Window "1ms" 2025/11/02 02:41:49 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 02:41:49 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/11/02 02:41:49 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for checks to finish 2025/11/02 02:41:49 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for transfers to finish 2025/11/02 02:41:49 DEBUG : sub dir/hello world: size = 11 OK 2025/11/02 02:41:49 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 02:41:49 INFO : sub dir/hello world: Copied (new) 2025/11/02 02:41:49 INFO : sub dir/hello world: Deleted 2025/11/02 02:41:49 DEBUG : nested/sub dir/file: size = 6 OK 2025/11/02 02:41:49 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/11/02 02:41:49 INFO : nested/sub dir/file: Copied (new) 2025/11/02 02:41:49 INFO : nested/sub dir/file: Deleted 2025/11/02 02:41:49 ERROR : Local file system at /tmp/rclone324028504: 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.14s) === RUN TestServerSideMove run.go:185: Remote "B2 bucket rclone-test-kazadel4kago", Local "Local file system at /tmp/rclone324028504", Modify Window "1ms" 2025/11/02 02:41:50 DEBUG : Creating backend with remote "TestB2:rclone-test-zabeyun7yowa" 2025/11/02 02:41:50 DEBUG : Config file has changed externally - reloading sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-kazadel4kago -> B2 bucket rclone-test-zabeyun7yowa 2025/11/02 02:41:54 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 02:41:54 DEBUG : empty space: size = 1 OK 2025/11/02 02:41:54 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/02 02:41:54 DEBUG : empty space: Unchanged skipping 2025/11/02 02:41:54 DEBUG : potato3: size = 68 (B2 bucket rclone-test-kazadel4kago) 2025/11/02 02:41:54 DEBUG : potato3: size = 60 (B2 bucket rclone-test-zabeyun7yowa) 2025/11/02 02:41:54 DEBUG : potato3: Sizes differ 2025/11/02 02:41:54 DEBUG : B2 bucket rclone-test-zabeyun7yowa: Waiting for checks to finish 2025/11/02 02:41:54 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:54 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:54 INFO : empty space: Deleted 2025/11/02 02:41:54 DEBUG : B2 bucket rclone-test-zabeyun7yowa: Waiting for transfers to finish 2025/11/02 02:41:55 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:41:55 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:41:55 DEBUG : B2 bucket rclone-test-zabeyun7yowa: Purge remote 2025/11/02 02:41:55 INFO : B2 bucket rclone-test-zabeyun7yowa: cleaning bucket "rclone-test-zabeyun7yowa" of all files 2025/11/02 02:41:55 DEBUG : empty space: Deleting (id "4_zffe24db3701d4b2b9fa80e1b_f106bb9add9e9780b_d20251102_m024153_c001_v0001090_t0033_u01762051313673") 2025/11/02 02:41:55 DEBUG : potato3: Deleting (id "4_zffe24db3701d4b2b9fa80e1b_f106bb9add9e9780d_d20251102_m024153_c001_v0001090_t0020_u01762051313976") --- FAIL: TestServerSideMove (6.09s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "B2 bucket rclone-test-kazadel4kago", Local "Local file system at /tmp/rclone324028504", Modify Window "1ms" 2025/11/02 02:41:56 DEBUG : Creating backend with remote "TestB2:rclone-test-weqoten8heyo" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-kazadel4kago -> B2 bucket rclone-test-weqoten8heyo 2025/11/02 02:42:00 DEBUG : empty space: Excluded (Size Filter) 2025/11/02 02:42:01 DEBUG : empty space: Excluded (Size Filter) 2025/11/02 02:42:01 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 02:42:01 DEBUG : potato3: size = 68 (B2 bucket rclone-test-kazadel4kago) 2025/11/02 02:42:01 DEBUG : potato3: size = 60 (B2 bucket rclone-test-weqoten8heyo) 2025/11/02 02:42:01 DEBUG : potato3: Sizes differ 2025/11/02 02:42:01 DEBUG : B2 bucket rclone-test-weqoten8heyo: Waiting for checks to finish 2025/11/02 02:42:01 DEBUG : B2 bucket rclone-test-weqoten8heyo: Waiting for transfers to finish 2025/11/02 02:42:01 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:42:01 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:42:01 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:42:01 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:42:01 DEBUG : B2 bucket rclone-test-weqoten8heyo: Purge remote 2025/11/02 02:42:01 INFO : B2 bucket rclone-test-weqoten8heyo: cleaning bucket "rclone-test-weqoten8heyo" of all files 2025/11/02 02:42:01 DEBUG : empty space: Deleting (id "4_z0f526d63701d4b2b9fa80e1b_f106f2b0239a9eb64_d20251102_m024200_c001_v0001180_t0003_u01762051320233") 2025/11/02 02:42:01 DEBUG : potato3: Deleting (id "4_z0f526d63701d4b2b9fa80e1b_f106f2b0239a9eb68_d20251102_m024200_c001_v0001180_t0047_u01762051320509") --- FAIL: TestServerSideMoveWithFilter (6.33s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "B2 bucket rclone-test-kazadel4kago", Local "Local file system at /tmp/rclone324028504", Modify Window "1ms" 2025/11/02 02:42:03 DEBUG : Creating backend with remote "TestB2:rclone-test-sasulan1koga" 2025/11/02 02:42:05 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-kazadel4kago -> B2 bucket rclone-test-sasulan1koga 2025/11/02 02:42:09 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 02:42:09 DEBUG : empty space: size = 1 OK 2025/11/02 02:42:09 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/02 02:42:09 DEBUG : empty space: Unchanged skipping 2025/11/02 02:42:09 DEBUG : potato3: size = 68 (B2 bucket rclone-test-kazadel4kago) 2025/11/02 02:42:09 DEBUG : B2 bucket rclone-test-sasulan1koga: Waiting for checks to finish 2025/11/02 02:42:09 DEBUG : potato3: size = 60 (B2 bucket rclone-test-sasulan1koga) 2025/11/02 02:42:09 DEBUG : potato3: Sizes differ 2025/11/02 02:42:09 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:42:09 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:42:09 INFO : empty space: Deleted 2025/11/02 02:42:09 DEBUG : B2 bucket rclone-test-sasulan1koga: Waiting for transfers to finish 2025/11/02 02:42:09 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 02:42:09 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:42:09 DEBUG : B2 bucket rclone-test-sasulan1koga: Purge remote 2025/11/02 02:42:09 INFO : B2 bucket rclone-test-sasulan1koga: cleaning bucket "rclone-test-sasulan1koga" of all files 2025/11/02 02:42:09 DEBUG : empty space: Deleting (id "4_zefb27de3701d4b2b9fa80e1b_f100f39fd870d46b4_d20251102_m024206_c001_v0001104_t0054_u01762051326682") 2025/11/02 02:42:09 DEBUG : potato3: Deleting (id "4_zefb27de3701d4b2b9fa80e1b_f100f39fd870d46b8_d20251102_m024207_c001_v0001104_t0021_u01762051327189") --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (7.86s) === RUN TestSyncOverlapWithFilter run.go:185: Remote "B2 bucket rclone-test-kazadel4kago", Local "Local file system at /tmp/rclone324028504", Modify Window "1ms" 2025/11/02 02:42:10 DEBUG : Creating backend with remote "TestB2:rclone-test-kazadel4kago/rclone-sync-test" 2025/11/02 02:42:12 DEBUG : Creating backend with remote "TestB2:rclone-test-kazadel4kago/rclone-sync-test-include/layer2" 2025/11/02 02:42:14 DEBUG : Creating backend with remote "TestB2:rclone-test-kazadel4kago/rclone-sync-test-ignore-file" 2025/11/02 02:42:17 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_list_file_names": EOF) 2025/11/02 02:42:17 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/02 02:42:18 DEBUG : pacer: Reducing sleep to 10ms 2025/11/02 02:42:18 DEBUG : B2 bucket rclone-test-kazadel4kago path rclone-sync-test: Waiting for checks to finish 2025/11/02 02:42:18 DEBUG : B2 bucket rclone-test-kazadel4kago path rclone-sync-test: Waiting for transfers to finish 2025/11/02 02:42:18 DEBUG : Waiting for deletions to finish 2025/11/02 02:42:18 INFO : There was nothing to transfer 2025/11/02 02:42:18 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for checks to finish 2025/11/02 02:42:18 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for transfers to finish 2025/11/02 02:42:18 DEBUG : Waiting for deletions to finish 2025/11/02 02:42:18 INFO : There was nothing to transfer 2025/11/02 02:42:19 DEBUG : B2 bucket rclone-test-kazadel4kago path rclone-sync-test-include/layer2: Waiting for checks to finish 2025/11/02 02:42:19 DEBUG : B2 bucket rclone-test-kazadel4kago path rclone-sync-test-include/layer2: Waiting for transfers to finish 2025/11/02 02:42:19 DEBUG : Waiting for deletions to finish 2025/11/02 02:42:19 INFO : There was nothing to transfer 2025/11/02 02:42:19 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for checks to finish 2025/11/02 02:42:19 DEBUG : B2 bucket rclone-test-kazadel4kago: Waiting for transfers to finish 2025/11/02 02:42:19 DEBUG : Waiting for deletions to finish 2025/11/02 02:42:19 INFO : There was nothing to transfer 2025/11/02 02:42:19 DEBUG : B2 bucket rclone-test-kazadel4kago path rclone-sync-test-ignore-file: Waiting for checks to finish 2025/11/02 02:42:19 DEBUG : B2 bucket rclone-test-kazadel4kago path rclone-sync-test-ignore-file: Waiting for transfers to finish 2025/11/02 02:42:19 DEBUG : Waiting for deletions to finish 2025/11/02 02:42:19 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (9.44s) FAIL 2025/11/02 02:42:20 DEBUG : B2 bucket rclone-test-kazadel4kago: Purge remote 2025/11/02 02:42:20 INFO : B2 bucket rclone-test-kazadel4kago: cleaning bucket "rclone-test-kazadel4kago" of all files 2025/11/02 02:42:20 DEBUG : empty space: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5fd_d20251102_m024209_c001_v7007000_t0000_u01762051329125") 2025/11/02 02:42:20 DEBUG : empty space: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f114710e9629587c3_d20251102_m024204_c001_v0001178_t0051_u01762051324705") 2025/11/02 02:42:20 DEBUG : empty space: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5f9_d20251102_m024202_c001_v7007000_t0000_u01762051322414") 2025/11/02 02:42:20 DEBUG : empty space: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f112d629640333c32_d20251102_m024158_c001_v0001183_t0034_u01762051318316") 2025/11/02 02:42:20 DEBUG : empty space: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5e9_d20251102_m024154_c001_v7007000_t0000_u01762051314776") 2025/11/02 02:42:20 DEBUG : empty space: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f114710e9629587b0_d20251102_m024151_c001_v0001178_t0045_u01762051311578") 2025/11/02 02:42:20 DEBUG : empty space: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a54a_d20251102_m024141_c001_v7007000_t0000_u01762051301757") 2025/11/02 02:42:20 DEBUG : empty space: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f114710e962958788_d20251102_m024139_c001_v0001178_t0008_u01762051299808") 2025/11/02 02:42:20 DEBUG : nested/sub dir/file: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5d2_d20251102_m024150_c001_v7007000_t0000_u01762051310182") 2025/11/02 02:42:20 DEBUG : nested/sub dir/file: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f114710e9629587a6_d20251102_m024149_c001_v0001178_t0028_u01762051309787") 2025/11/02 02:42:21 DEBUG : potato: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5c9_d20251102_m024148_c001_v7007000_t0000_u01762051308856") 2025/11/02 02:42:21 DEBUG : potato: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f112d629640333bf3_d20251102_m024147_c001_v0001183_t0004_u01762051307473") 2025/11/02 02:42:21 DEBUG : potato: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5bb_d20251102_m024146_c001_v7007000_t0000_u01762051306553") 2025/11/02 02:42:21 DEBUG : potato: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f112d629640333bed_d20251102_m024145_c001_v0001183_t0020_u01762051305162") 2025/11/02 02:42:21 DEBUG : potato: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a593_d20251102_m024144_c001_v7007000_t0000_u01762051304189") 2025/11/02 02:42:21 DEBUG : potato: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f114710e962958791_d20251102_m024142_c001_v0001178_t0014_u01762051302315") 2025/11/02 02:42:21 DEBUG : potato2: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5fe_d20251102_m024210_c001_v7007000_t0000_u01762051330464") 2025/11/02 02:42:21 DEBUG : potato2: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f112d629640333c42_d20251102_m024204_c001_v0001183_t0000_u01762051324074") 2025/11/02 02:42:21 DEBUG : potato2: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5fa_d20251102_m024202_c001_v7007000_t0000_u01762051322597") 2025/11/02 02:42:21 DEBUG : potato2: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f114710e9629587b5_d20251102_m024157_c001_v0001178_t0001_u01762051317737") 2025/11/02 02:42:21 DEBUG : potato2: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5ea_d20251102_m024156_c001_v7007000_t0000_u01762051316273") 2025/11/02 02:42:21 DEBUG : potato2: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f112d629640333c0c_d20251102_m024151_c001_v0001183_t0002_u01762051311354") 2025/11/02 02:42:21 DEBUG : potato3: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5ff_d20251102_m024210_c001_v7007000_t0000_u01762051330647") 2025/11/02 02:42:21 DEBUG : potato3: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f112d629640333c46_d20251102_m024204_c001_v0001183_t0000_u01762051324907") 2025/11/02 02:42:21 DEBUG : potato3: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5fb_d20251102_m024202_c001_v7007000_t0000_u01762051322780") 2025/11/02 02:42:21 DEBUG : potato3: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f114710e9629587b9_d20251102_m024158_c001_v0001178_t0045_u01762051318522") 2025/11/02 02:42:21 DEBUG : potato3: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5eb_d20251102_m024156_c001_v7007000_t0000_u01762051316456") 2025/11/02 02:42:22 DEBUG : potato3: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f112d629640333c0e_d20251102_m024151_c001_v0001183_t0034_u01762051311787") 2025/11/02 02:42:22 DEBUG : rclone-sync-test-ignore-file/.ignore: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f412fe48847bf7f0c_d20251102_m024220_c001_v7007000_t0000_u01762051340086") 2025/11/02 02:42:22 DEBUG : rclone-sync-test-ignore-file/.ignore: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f114710e9629587df_d20251102_m024216_c001_v0001178_t0058_u01762051336957") 2025/11/02 02:42:22 DEBUG : sub dir/hello world: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5d3_d20251102_m024150_c001_v7007000_t0000_u01762051310365") 2025/11/02 02:42:22 DEBUG : sub dir/hello world: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f112d629640333c01_d20251102_m024149_c001_v0001183_t0022_u01762051309771") 2025/11/02 02:42:22 DEBUG : sub dir/hello world: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a52c_d20251102_m024139_c001_v7007000_t0000_u01762051299437") 2025/11/02 02:42:22 DEBUG : sub dir/hello world: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f114710e962958782_d20251102_m024136_c001_v0001178_t0034_u01762051296544") 2025/11/02 02:42:22 DEBUG : sub dir/hello world: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a508_d20251102_m024136_c001_v7007000_t0000_u01762051296179") 2025/11/02 02:42:22 DEBUG : sub dir/hello world: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f114710e96295877c_d20251102_m024133_c001_v0001178_t0057_u01762051293243") 2025/11/02 02:42:22 DEBUG : sub dir/hello world: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a4b0_d20251102_m024132_c001_v7007000_t0000_u01762051292877") 2025/11/02 02:42:22 DEBUG : sub dir/hello world: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f114710e962958772_d20251102_m024129_c001_v0001178_t0052_u01762051289958") 2025/11/02 02:42:22 DEBUG : sub/yam: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5cb_d20251102_m024149_c001_v7007000_t0000_u01762051309042") 2025/11/02 02:42:22 DEBUG : sub/yam: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f114710e96295879a_d20251102_m024147_c001_v0001178_t0058_u01762051307468") 2025/11/02 02:42:22 DEBUG : yam: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5cc_d20251102_m024149_c001_v7007000_t0000_u01762051309227") 2025/11/02 02:42:22 DEBUG : yam: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f114710e96295879e_d20251102_m024148_c001_v0001178_t0026_u01762051308427") 2025/11/02 02:42:22 DEBUG : yam: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5bd_d20251102_m024146_c001_v7007000_t0000_u01762051306736") 2025/11/02 02:42:22 DEBUG : yam: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f114710e962958797_d20251102_m024145_c001_v0001178_t0047_u01762051305178") 2025/11/02 02:42:22 DEBUG : yam: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a599_d20251102_m024144_c001_v7007000_t0000_u01762051304373") 2025/11/02 02:42:22 DEBUG : yam: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f112d629640333be1_d20251102_m024142_c001_v0001183_t0041_u01762051302843") 2025/11/02 02:42:22 DEBUG : yaml: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5be_d20251102_m024146_c001_v7007000_t0000_u01762051306919") 2025/11/02 02:42:23 DEBUG : yaml: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f112d629640333bef_d20251102_m024146_c001_v0001183_t0002_u01762051306158") 2025/11/02 02:42:23 DEBUG : yaml: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f420343d70678a5a1_d20251102_m024144_c001_v7007000_t0000_u01762051304619") 2025/11/02 02:42:23 DEBUG : yaml: Deleting (id "4_zdfe2edf3700d4b2b9fa80e1b_f114710e962958795_d20251102_m024143_c001_v0001178_t0051_u01762051303788") "./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 55.987564697s (try 3/5): exit status 1: Failed [TestServerSideCopy TestServerSideCopyOverSelf TestServerSideMoveOverSelf TestSyncAfterChangingModtimeOnly TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs]