"./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|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Starting (try 3/5) 2025/11/03 03:46:50 DEBUG : Creating backend with remote "TestB2:rclone-test-wipodol0cuga" 2025/11/03 03:46:50 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/03 03:46:51 DEBUG : Creating backend with remote "/tmp/rclone2639611808" === RUN TestServerSideCopy run.go:185: Remote "B2 bucket rclone-test-wipodol0cuga", Local "Local file system at /tmp/rclone2639611808", Modify Window "1ms" 2025/11/03 03:46:53 DEBUG : Creating backend with remote "TestB2:rclone-test-luhakax3toho" sync_test.go:620: Server side copy (if possible) B2 bucket rclone-test-wipodol0cuga -> B2 bucket rclone-test-luhakax3toho 2025/11/03 03:46:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/03 03:46:54 DEBUG : B2 bucket rclone-test-luhakax3toho: Waiting for checks to finish 2025/11/03 03:46:54 DEBUG : B2 bucket rclone-test-luhakax3toho: Waiting for transfers to finish 2025/11/03 03:46:55 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/03 03:46:55 DEBUG : B2 bucket rclone-test-luhakax3toho: Purge remote 2025/11/03 03:46:55 INFO : B2 bucket rclone-test-luhakax3toho: cleaning bucket "rclone-test-luhakax3toho" of all files --- FAIL: TestServerSideCopy (5.40s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "B2 bucket rclone-test-wipodol0cuga", Local "Local file system at /tmp/rclone2639611808", Modify Window "1ms" 2025/11/03 03:46:57 DEBUG : Creating backend with remote "TestB2:rclone-test-racaxix8yaso" sync_test.go:656: Server side copy (if possible) B2 bucket rclone-test-wipodol0cuga -> B2 bucket rclone-test-racaxix8yaso 2025/11/03 03:46:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/03 03:46:58 DEBUG : B2 bucket rclone-test-racaxix8yaso: Waiting for checks to finish 2025/11/03 03:46:58 DEBUG : B2 bucket rclone-test-racaxix8yaso: Waiting for transfers to finish 2025/11/03 03:46:59 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/03 03:46:59 DEBUG : B2 bucket rclone-test-racaxix8yaso: Purge remote 2025/11/03 03:46:59 INFO : B2 bucket rclone-test-racaxix8yaso: cleaning bucket "rclone-test-racaxix8yaso" of all files --- FAIL: TestServerSideCopyOverSelf (3.77s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "B2 bucket rclone-test-wipodol0cuga", Local "Local file system at /tmp/rclone2639611808", Modify Window "1ms" 2025/11/03 03:47:01 DEBUG : Creating backend with remote "TestB2:rclone-test-coheruh4zeto" sync_test.go:701: Server side copy (if possible) B2 bucket rclone-test-wipodol0cuga -> B2 bucket rclone-test-coheruh4zeto 2025/11/03 03:47:02 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/03 03:47:02 DEBUG : B2 bucket rclone-test-coheruh4zeto: Waiting for checks to finish 2025/11/03 03:47:02 DEBUG : B2 bucket rclone-test-coheruh4zeto: Waiting for transfers to finish 2025/11/03 03:47:03 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/03 03:47:03 DEBUG : B2 bucket rclone-test-coheruh4zeto: Purge remote 2025/11/03 03:47:03 INFO : B2 bucket rclone-test-coheruh4zeto: cleaning bucket "rclone-test-coheruh4zeto" of all files --- FAIL: TestServerSideMoveOverSelf (3.87s) === RUN TestSyncAfterChangingModtimeOnly run.go:185: Remote "B2 bucket rclone-test-wipodol0cuga", Local "Local file system at /tmp/rclone2639611808", Modify Window "1ms" 2025/11/03 03:47:04 DEBUG : empty space: size = 1 OK 2025/11/03 03:47:04 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/03 03:47:04 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for checks to finish 2025/11/03 03:47:04 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/03 03:47:04 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2025/11/03 03:47:04 DEBUG : empty space: Unchanged skipping 2025/11/03 03:47:04 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for transfers to finish 2025/11/03 03:47:04 DEBUG : Waiting for deletions to finish 2025/11/03 03:47:04 INFO : There was nothing to transfer 2025/11/03 03:47:05 DEBUG : empty space: size = 1 OK 2025/11/03 03:47:05 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/03 03:47:05 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for checks to finish 2025/11/03 03:47:05 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/03 03:47:06 ERROR : empty space: Failed to set modification time: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:06 DEBUG : empty space: Unchanged skipping 2025/11/03 03:47:06 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for transfers to finish 2025/11/03 03:47:06 ERROR : B2 bucket rclone-test-wipodol0cuga: not deleting files as there were IO errors 2025/11/03 03:47:06 ERROR : B2 bucket rclone-test-wipodol0cuga: 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.40s) === RUN TestSyncWithTrackRenames run.go:185: Remote "B2 bucket rclone-test-wipodol0cuga", Local "Local file system at /tmp/rclone2639611808", Modify Window "1ms" sync_test.go:1568: Can track renames: true 2025/11/03 03:47:06 INFO : B2 bucket rclone-test-wipodol0cuga: Making map for --track-renames 2025/11/03 03:47:06 INFO : B2 bucket rclone-test-wipodol0cuga: Finished making map for --track-renames 2025/11/03 03:47:06 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for checks to finish 2025/11/03 03:47:06 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/03 03:47:06 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/03 03:47:06 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for renames to finish 2025/11/03 03:47:06 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for transfers to finish 2025/11/03 03:47:07 DEBUG : potato: size = 14 OK 2025/11/03 03:47:07 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/03 03:47:07 INFO : potato: Copied (new) 2025/11/03 03:47:07 DEBUG : yam: size = 11 OK 2025/11/03 03:47:07 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 03:47:07 INFO : yam: Copied (new) 2025/11/03 03:47:07 DEBUG : Waiting for deletions to finish 2025/11/03 03:47:08 DEBUG : potato: size = 14 OK 2025/11/03 03:47:08 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/03 03:47:08 INFO : B2 bucket rclone-test-wipodol0cuga: Making map for --track-renames 2025/11/03 03:47:08 INFO : B2 bucket rclone-test-wipodol0cuga: Finished making map for --track-renames 2025/11/03 03:47:08 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for checks to finish 2025/11/03 03:47:08 DEBUG : potato: Unchanged skipping 2025/11/03 03:47:08 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for renames to finish 2025/11/03 03:47:08 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:08 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:08 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:08 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/03 03:47:08 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for transfers to finish 2025/11/03 03:47:08 DEBUG : yaml: size = 11 OK 2025/11/03 03:47:08 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 03:47:08 INFO : yaml: Copied (new) 2025/11/03 03:47:08 ERROR : B2 bucket rclone-test-wipodol0cuga: not deleting files as there were IO errors 2025/11/03 03:47:08 ERROR : B2 bucket rclone-test-wipodol0cuga: 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.14s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "B2 bucket rclone-test-wipodol0cuga", Local "Local file system at /tmp/rclone2639611808", Modify Window "1ms" sync_test.go:1640: Can track renames: true 2025/11/03 03:47:09 INFO : B2 bucket rclone-test-wipodol0cuga: Making map for --track-renames 2025/11/03 03:47:09 INFO : B2 bucket rclone-test-wipodol0cuga: Finished making map for --track-renames 2025/11/03 03:47:09 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for checks to finish 2025/11/03 03:47:09 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/03 03:47:09 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/03 03:47:09 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for renames to finish 2025/11/03 03:47:09 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for transfers to finish 2025/11/03 03:47:10 DEBUG : potato: size = 14 OK 2025/11/03 03:47:10 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/03 03:47:10 INFO : potato: Copied (new) 2025/11/03 03:47:10 DEBUG : yam: size = 11 OK 2025/11/03 03:47:10 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 03:47:10 INFO : yam: Copied (new) 2025/11/03 03:47:10 DEBUG : Waiting for deletions to finish 2025/11/03 03:47:10 DEBUG : potato: size = 14 OK 2025/11/03 03:47:10 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/03 03:47:10 INFO : B2 bucket rclone-test-wipodol0cuga: Making map for --track-renames 2025/11/03 03:47:10 INFO : B2 bucket rclone-test-wipodol0cuga: Finished making map for --track-renames 2025/11/03 03:47:10 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for checks to finish 2025/11/03 03:47:10 DEBUG : potato: Unchanged skipping 2025/11/03 03:47:10 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for renames to finish 2025/11/03 03:47:11 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:11 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:11 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:11 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/03 03:47:11 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for transfers to finish 2025/11/03 03:47:11 DEBUG : yaml: size = 11 OK 2025/11/03 03:47:11 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 03:47:11 INFO : yaml: Copied (new) 2025/11/03 03:47:11 ERROR : B2 bucket rclone-test-wipodol0cuga: not deleting files as there were IO errors 2025/11/03 03:47:11 ERROR : B2 bucket rclone-test-wipodol0cuga: 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.80s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "B2 bucket rclone-test-wipodol0cuga", Local "Local file system at /tmp/rclone2639611808", Modify Window "1ms" sync_test.go:1679: Can track renames: true 2025/11/03 03:47:12 INFO : B2 bucket rclone-test-wipodol0cuga: Making map for --track-renames 2025/11/03 03:47:12 INFO : B2 bucket rclone-test-wipodol0cuga: Finished making map for --track-renames 2025/11/03 03:47:12 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for checks to finish 2025/11/03 03:47:12 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/03 03:47:12 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/11/03 03:47:12 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for renames to finish 2025/11/03 03:47:12 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for transfers to finish 2025/11/03 03:47:13 DEBUG : sub/yam: size = 11 OK 2025/11/03 03:47:13 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 03:47:13 INFO : sub/yam: Copied (new) 2025/11/03 03:47:13 DEBUG : potato: size = 14 OK 2025/11/03 03:47:13 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/03 03:47:13 INFO : potato: Copied (new) 2025/11/03 03:47:13 DEBUG : Waiting for deletions to finish 2025/11/03 03:47:13 DEBUG : potato: size = 14 OK 2025/11/03 03:47:13 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/03 03:47:13 DEBUG : potato: Unchanged skipping 2025/11/03 03:47:13 INFO : B2 bucket rclone-test-wipodol0cuga: Making map for --track-renames 2025/11/03 03:47:13 INFO : B2 bucket rclone-test-wipodol0cuga: Finished making map for --track-renames 2025/11/03 03:47:13 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for checks to finish 2025/11/03 03:47:13 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for renames to finish 2025/11/03 03:47:14 ERROR : sub/yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:14 ERROR : sub/yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:14 DEBUG : yam: Failed to rename to "sub/yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:14 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/03 03:47:14 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for transfers to finish 2025/11/03 03:47:14 DEBUG : yam: size = 11 OK 2025/11/03 03:47:14 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 03:47:14 INFO : yam: Copied (new) 2025/11/03 03:47:14 ERROR : B2 bucket rclone-test-wipodol0cuga: not deleting files as there were IO errors 2025/11/03 03:47:14 ERROR : B2 bucket rclone-test-wipodol0cuga: 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.96s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "B2 bucket rclone-test-wipodol0cuga", Local "Local file system at /tmp/rclone2639611808", Modify Window "1ms" 2025/11/03 03:47:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/03 03:47:15 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/11/03 03:47:15 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for checks to finish 2025/11/03 03:47:15 DEBUG : B2 bucket rclone-test-wipodol0cuga: Waiting for transfers to finish 2025/11/03 03:47:16 DEBUG : sub dir/hello world: size = 11 OK 2025/11/03 03:47:16 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/03 03:47:16 INFO : sub dir/hello world: Copied (new) 2025/11/03 03:47:16 INFO : sub dir/hello world: Deleted 2025/11/03 03:47:16 DEBUG : nested/sub dir/file: size = 6 OK 2025/11/03 03:47:16 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/11/03 03:47:16 INFO : nested/sub dir/file: Copied (new) 2025/11/03 03:47:16 INFO : nested/sub dir/file: Deleted 2025/11/03 03:47:16 ERROR : Local file system at /tmp/rclone2639611808: 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 (2.11s) === RUN TestServerSideMove run.go:185: Remote "B2 bucket rclone-test-wipodol0cuga", Local "Local file system at /tmp/rclone2639611808", Modify Window "1ms" 2025/11/03 03:47:17 DEBUG : Creating backend with remote "TestB2:rclone-test-sisanad7taco" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-wipodol0cuga -> B2 bucket rclone-test-sisanad7taco 2025/11/03 03:47:22 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/03 03:47:22 DEBUG : empty space: size = 1 OK 2025/11/03 03:47:22 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/03 03:47:22 DEBUG : empty space: Unchanged skipping 2025/11/03 03:47:22 DEBUG : potato3: size = 68 (B2 bucket rclone-test-wipodol0cuga) 2025/11/03 03:47:22 DEBUG : potato3: size = 60 (B2 bucket rclone-test-sisanad7taco) 2025/11/03 03:47:22 DEBUG : potato3: Sizes differ 2025/11/03 03:47:22 DEBUG : B2 bucket rclone-test-sisanad7taco: Waiting for checks to finish 2025/11/03 03:47:22 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:22 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:22 INFO : empty space: Deleted 2025/11/03 03:47:22 DEBUG : B2 bucket rclone-test-sisanad7taco: Waiting for transfers to finish 2025/11/03 03:47:22 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:22 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/03 03:47:22 DEBUG : B2 bucket rclone-test-sisanad7taco: Purge remote 2025/11/03 03:47:22 INFO : B2 bucket rclone-test-sisanad7taco: cleaning bucket "rclone-test-sisanad7taco" of all files 2025/11/03 03:47:22 DEBUG : empty space: Deleting (id "4_zafd29d63d03d4b7b9fa80e1b_f109a39cc5eb3986c_d20251103_m034721_c001_v0001102_t0002_u01762141641502") 2025/11/03 03:47:22 DEBUG : potato3: Deleting (id "4_zafd29d63d03d4b7b9fa80e1b_f109a39cc5eb3986e_d20251103_m034721_c001_v0001102_t0049_u01762141641811") --- FAIL: TestServerSideMove (6.65s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "B2 bucket rclone-test-wipodol0cuga", Local "Local file system at /tmp/rclone2639611808", Modify Window "1ms" 2025/11/03 03:47:24 DEBUG : Creating backend with remote "TestB2:rclone-test-cukijoc2pipe" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-wipodol0cuga -> B2 bucket rclone-test-cukijoc2pipe 2025/11/03 03:47:29 DEBUG : empty space: Excluded (Size Filter) 2025/11/03 03:47:29 DEBUG : empty space: Excluded (Size Filter) 2025/11/03 03:47:29 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/03 03:47:29 DEBUG : potato3: size = 68 (B2 bucket rclone-test-wipodol0cuga) 2025/11/03 03:47:29 DEBUG : potato3: size = 60 (B2 bucket rclone-test-cukijoc2pipe) 2025/11/03 03:47:29 DEBUG : potato3: Sizes differ 2025/11/03 03:47:29 DEBUG : B2 bucket rclone-test-cukijoc2pipe: Waiting for checks to finish 2025/11/03 03:47:29 DEBUG : B2 bucket rclone-test-cukijoc2pipe: Waiting for transfers to finish 2025/11/03 03:47:30 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:30 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:30 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:30 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/03 03:47:30 DEBUG : B2 bucket rclone-test-cukijoc2pipe: Purge remote 2025/11/03 03:47:30 INFO : B2 bucket rclone-test-cukijoc2pipe: cleaning bucket "rclone-test-cukijoc2pipe" of all files 2025/11/03 03:47:30 DEBUG : empty space: Deleting (id "4_z7f52bd23d03d4b7b9fa80e1b_f1118e30faf72553b_d20251103_m034728_c001_v0001113_t0048_u01762141648573") 2025/11/03 03:47:30 DEBUG : potato3: Deleting (id "4_z7f52bd23d03d4b7b9fa80e1b_f1118e30faf72553d_d20251103_m034728_c001_v0001113_t0008_u01762141648883") --- FAIL: TestServerSideMoveWithFilter (7.78s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "B2 bucket rclone-test-wipodol0cuga", Local "Local file system at /tmp/rclone2639611808", Modify Window "1ms" 2025/11/03 03:47:32 DEBUG : Creating backend with remote "TestB2:rclone-test-dewonog4qaha" 2025/11/03 03:47:34 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-wipodol0cuga -> B2 bucket rclone-test-dewonog4qaha 2025/11/03 03:47:37 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/03 03:47:37 DEBUG : empty space: size = 1 OK 2025/11/03 03:47:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/03 03:47:37 DEBUG : empty space: Unchanged skipping 2025/11/03 03:47:37 DEBUG : potato3: size = 68 (B2 bucket rclone-test-wipodol0cuga) 2025/11/03 03:47:37 DEBUG : potato3: size = 60 (B2 bucket rclone-test-dewonog4qaha) 2025/11/03 03:47:37 DEBUG : potato3: Sizes differ 2025/11/03 03:47:37 DEBUG : B2 bucket rclone-test-dewonog4qaha: Waiting for checks to finish 2025/11/03 03:47:37 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:37 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:37 INFO : empty space: Deleted 2025/11/03 03:47:37 DEBUG : B2 bucket rclone-test-dewonog4qaha: Waiting for transfers to finish 2025/11/03 03:47:37 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 03:47:37 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/03 03:47:37 DEBUG : B2 bucket rclone-test-dewonog4qaha: Purge remote 2025/11/03 03:47:37 INFO : B2 bucket rclone-test-dewonog4qaha: cleaning bucket "rclone-test-dewonog4qaha" of all files 2025/11/03 03:47:37 DEBUG : empty space: Deleting (id "4_z7fc2dd03d03d4b7b9fa80e1b_f10969c669c812bf1_d20251103_m034736_c001_v0001036_t0049_u01762141656237") 2025/11/03 03:47:37 DEBUG : potato3: Deleting (id "4_z7fc2dd03d03d4b7b9fa80e1b_f10969c669c812bf3_d20251103_m034736_c001_v0001036_t0044_u01762141656588") --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (7.06s) FAIL 2025/11/03 03:47:39 DEBUG : B2 bucket rclone-test-wipodol0cuga: Purge remote 2025/11/03 03:47:39 INFO : B2 bucket rclone-test-wipodol0cuga: cleaning bucket "rclone-test-wipodol0cuga" of all files 2025/11/03 03:47:39 DEBUG : empty space: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d35_d20251103_m034737_c001_v7007000_t0000_u01762141657360") 2025/11/03 03:47:39 DEBUG : empty space: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f107d94b5d0671402_d20251103_m034733_c001_v0001137_t0010_u01762141653842") 2025/11/03 03:47:39 DEBUG : empty space: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d2e_d20251103_m034731_c001_v7007000_t0000_u01762141651436") 2025/11/03 03:47:39 DEBUG : empty space: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f112aeb46c4d8aea1_d20251103_m034725_c001_v0001136_t0006_u01762141645958") 2025/11/03 03:47:39 DEBUG : empty space: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d17_d20251103_m034722_c001_v7007000_t0000_u01762141642460") 2025/11/03 03:47:39 DEBUG : empty space: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f107d94b5d06713cc_d20251103_m034718_c001_v0001137_t0033_u01762141638676") 2025/11/03 03:47:39 DEBUG : empty space: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863cf3_d20251103_m034706_c001_v7007000_t0000_u01762141626366") 2025/11/03 03:47:39 DEBUG : empty space: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f112aeb46c4d8ae5e_d20251103_m034704_c001_v0001136_t0044_u01762141624336") 2025/11/03 03:47:39 DEBUG : nested/sub dir/file: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d10_d20251103_m034717_c001_v7007000_t0000_u01762141637175") 2025/11/03 03:47:39 DEBUG : nested/sub dir/file: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f107d94b5d06713c0_d20251103_m034715_c001_v0001137_t0050_u01762141635821") 2025/11/03 03:47:39 DEBUG : potato: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d0a_d20251103_m034714_c001_v7007000_t0000_u01762141634893") 2025/11/03 03:47:39 DEBUG : potato: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f112aeb46c4d8ae78_d20251103_m034712_c001_v0001136_t0023_u01762141632854") 2025/11/03 03:47:40 DEBUG : potato: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d02_d20251103_m034711_c001_v7007000_t0000_u01762141631936") 2025/11/03 03:47:40 DEBUG : potato: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f107d94b5d06713ac_d20251103_m034710_c001_v0001137_t0032_u01762141630054") 2025/11/03 03:47:40 DEBUG : potato: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863cf5_d20251103_m034709_c001_v7007000_t0000_u01762141629135") 2025/11/03 03:47:40 DEBUG : potato: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f112aeb46c4d8ae64_d20251103_m034706_c001_v0001136_t0036_u01762141626913") 2025/11/03 03:47:40 DEBUG : potato2: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d3a_d20251103_m034738_c001_v7007000_t0000_u01762141658678") 2025/11/03 03:47:40 DEBUG : potato2: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f112aeb46c4d8aeb3_d20251103_m034733_c001_v0001136_t0027_u01762141653103") 2025/11/03 03:47:40 DEBUG : potato2: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d2f_d20251103_m034731_c001_v7007000_t0000_u01762141651622") 2025/11/03 03:47:40 DEBUG : potato2: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f107d94b5d06713e7_d20251103_m034725_c001_v0001137_t0049_u01762141645309") 2025/11/03 03:47:40 DEBUG : potato2: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d1b_d20251103_m034723_c001_v7007000_t0000_u01762141643836") 2025/11/03 03:47:40 DEBUG : potato2: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f112aeb46c4d8ae8e_d20251103_m034718_c001_v0001136_t0057_u01762141638292") 2025/11/03 03:47:40 DEBUG : potato3: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d3b_d20251103_m034738_c001_v7007000_t0000_u01762141658864") 2025/11/03 03:47:40 DEBUG : potato3: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f112aeb46c4d8aebb_d20251103_m034734_c001_v0001136_t0004_u01762141654208") 2025/11/03 03:47:40 DEBUG : potato3: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d31_d20251103_m034731_c001_v7007000_t0000_u01762141651806") 2025/11/03 03:47:40 DEBUG : potato3: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f107d94b5d06713ed_d20251103_m034726_c001_v0001137_t0014_u01762141646507") 2025/11/03 03:47:40 DEBUG : potato3: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d1c_d20251103_m034724_c001_v7007000_t0000_u01762141644020") 2025/11/03 03:47:40 DEBUG : potato3: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f112aeb46c4d8ae90_d20251103_m034719_c001_v0001136_t0053_u01762141639090") 2025/11/03 03:47:40 DEBUG : sub dir/hello world: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d11_d20251103_m034717_c001_v7007000_t0000_u01762141637367") 2025/11/03 03:47:40 DEBUG : sub dir/hello world: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f112aeb46c4d8ae7e_d20251103_m034715_c001_v0001136_t0059_u01762141635809") 2025/11/03 03:47:40 DEBUG : sub dir/hello world: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863ced_d20251103_m034703_c001_v7007000_t0000_u01762141623968") 2025/11/03 03:47:40 DEBUG : sub dir/hello world: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f112aeb46c4d8ae55_d20251103_m034700_c001_v0001136_t0034_u01762141620460") 2025/11/03 03:47:40 DEBUG : sub dir/hello world: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863cdd_d20251103_m034700_c001_v7007000_t0000_u01762141620094") 2025/11/03 03:47:40 DEBUG : sub dir/hello world: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f112aeb46c4d8ae47_d20251103_m034656_c001_v0001136_t0024_u01762141616693") 2025/11/03 03:47:40 DEBUG : sub dir/hello world: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863ccb_d20251103_m034656_c001_v7007000_t0000_u01762141616325") 2025/11/03 03:47:41 DEBUG : sub dir/hello world: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f112aeb46c4d8ae35_d20251103_m034652_c001_v0001136_t0006_u01762141612767") 2025/11/03 03:47:41 DEBUG : sub/yam: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d0b_d20251103_m034715_c001_v7007000_t0000_u01762141635077") 2025/11/03 03:47:41 DEBUG : sub/yam: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f107d94b5d06713b6_d20251103_m034712_c001_v0001137_t0036_u01762141632864") 2025/11/03 03:47:41 DEBUG : yam: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d0c_d20251103_m034715_c001_v7007000_t0000_u01762141635261") 2025/11/03 03:47:41 DEBUG : yam: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f107d94b5d06713ba_d20251103_m034714_c001_v0001137_t0034_u01762141634243") 2025/11/03 03:47:41 DEBUG : yam: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d04_d20251103_m034712_c001_v7007000_t0000_u01762141632119") 2025/11/03 03:47:41 DEBUG : yam: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f112aeb46c4d8ae70_d20251103_m034710_c001_v0001136_t0025_u01762141630065") 2025/11/03 03:47:41 DEBUG : yam: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863cf6_d20251103_m034709_c001_v7007000_t0000_u01762141629320") 2025/11/03 03:47:41 DEBUG : yam: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f107d94b5d06713a4_d20251103_m034707_c001_v0001137_t0018_u01762141627464") 2025/11/03 03:47:41 DEBUG : yaml: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863d05_d20251103_m034712_c001_v7007000_t0000_u01762141632303") 2025/11/03 03:47:41 DEBUG : yaml: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f107d94b5d06713b2_d20251103_m034711_c001_v0001137_t0043_u01762141631198") 2025/11/03 03:47:41 DEBUG : yaml: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f420343d706863cfa_d20251103_m034709_c001_v7007000_t0000_u01762141629504") 2025/11/03 03:47:41 DEBUG : yaml: Deleting (id "4_zafb22d63d03d4b7b9fa80e1b_f112aeb46c4d8ae68_d20251103_m034708_c001_v0001136_t0001_u01762141628573") "./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|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Finished ERROR in 52.008986658s (try 3/5): exit status 1: Failed [TestServerSideCopy TestServerSideCopyOverSelf TestServerSideMoveOverSelf TestSyncAfterChangingModtimeOnly TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs]