"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncAfterChangingModtimeOnly|TestSyncOverlapWithFilter|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Starting (try 2/5) 2025/11/01 02:14:18 DEBUG : Creating backend with remote "TestB2:rclone-test-vakapuj0zapi" 2025/11/01 02:14:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/01 02:14:19 DEBUG : Creating backend with remote "/tmp/rclone1801727288" === RUN TestServerSideCopy run.go:185: Remote "B2 bucket rclone-test-vakapuj0zapi", Local "Local file system at /tmp/rclone1801727288", Modify Window "1ms" 2025/11/01 02:14:21 DEBUG : Creating backend with remote "TestB2:rclone-test-lohosad6pohi" sync_test.go:620: Server side copy (if possible) B2 bucket rclone-test-vakapuj0zapi -> B2 bucket rclone-test-lohosad6pohi 2025/11/01 02:14:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/01 02:14:22 DEBUG : B2 bucket rclone-test-lohosad6pohi: Waiting for checks to finish 2025/11/01 02:14:22 DEBUG : B2 bucket rclone-test-lohosad6pohi: Waiting for transfers to finish 2025/11/01 02:14:23 ERROR : sub dir/hello world: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:624: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:624 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideCopy 2025/11/01 02:14:23 DEBUG : B2 bucket rclone-test-lohosad6pohi: Purge remote 2025/11/01 02:14:23 INFO : B2 bucket rclone-test-lohosad6pohi: cleaning bucket "rclone-test-lohosad6pohi" of all files --- FAIL: TestServerSideCopy (5.16s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "B2 bucket rclone-test-vakapuj0zapi", Local "Local file system at /tmp/rclone1801727288", Modify Window "1ms" 2025/11/01 02:14:25 DEBUG : Creating backend with remote "TestB2:rclone-test-fanoqad2geze" sync_test.go:656: Server side copy (if possible) B2 bucket rclone-test-vakapuj0zapi -> B2 bucket rclone-test-fanoqad2geze 2025/11/01 02:14:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/01 02:14:26 DEBUG : B2 bucket rclone-test-fanoqad2geze: Waiting for checks to finish 2025/11/01 02:14:26 DEBUG : B2 bucket rclone-test-fanoqad2geze: Waiting for transfers to finish 2025/11/01 02:14:27 ERROR : sub dir/hello world: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:660: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:660 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideCopyOverSelf 2025/11/01 02:14:27 DEBUG : B2 bucket rclone-test-fanoqad2geze: Purge remote 2025/11/01 02:14:27 INFO : B2 bucket rclone-test-fanoqad2geze: cleaning bucket "rclone-test-fanoqad2geze" of all files --- FAIL: TestServerSideCopyOverSelf (3.71s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "B2 bucket rclone-test-vakapuj0zapi", Local "Local file system at /tmp/rclone1801727288", Modify Window "1ms" 2025/11/01 02:14:28 DEBUG : Creating backend with remote "TestB2:rclone-test-xalofon1dimu" sync_test.go:701: Server side copy (if possible) B2 bucket rclone-test-vakapuj0zapi -> B2 bucket rclone-test-xalofon1dimu 2025/11/01 02:14:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/01 02:14:30 DEBUG : B2 bucket rclone-test-xalofon1dimu: Waiting for checks to finish 2025/11/01 02:14:30 DEBUG : B2 bucket rclone-test-xalofon1dimu: Waiting for transfers to finish 2025/11/01 02:14:30 ERROR : sub dir/hello world: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:705: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:705 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideMoveOverSelf 2025/11/01 02:14:30 DEBUG : B2 bucket rclone-test-xalofon1dimu: Purge remote 2025/11/01 02:14:30 INFO : B2 bucket rclone-test-xalofon1dimu: cleaning bucket "rclone-test-xalofon1dimu" of all files --- FAIL: TestServerSideMoveOverSelf (3.74s) === RUN TestSyncAfterChangingModtimeOnly run.go:185: Remote "B2 bucket rclone-test-vakapuj0zapi", Local "Local file system at /tmp/rclone1801727288", Modify Window "1ms" 2025/11/01 02:14:32 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2025/11/01 02:14:32 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for checks to finish 2025/11/01 02:14:32 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/01 02:14:32 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2025/11/01 02:14:32 DEBUG : empty space: Unchanged skipping 2025/11/01 02:14:32 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for transfers to finish 2025/11/01 02:14:32 DEBUG : Waiting for deletions to finish 2025/11/01 02:14:32 INFO : There was nothing to transfer 2025/11/01 02:14:33 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for checks to finish 2025/11/01 02:14:33 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2025/11/01 02:14:33 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/01 02:14:33 ERROR : empty space: Failed to set modification time: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:14:33 DEBUG : empty space: Unchanged skipping 2025/11/01 02:14:33 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for transfers to finish 2025/11/01 02:14:33 ERROR : B2 bucket rclone-test-vakapuj0zapi: not deleting files as there were IO errors 2025/11/01 02:14:33 ERROR : B2 bucket rclone-test-vakapuj0zapi: 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.31s) === RUN TestSyncWithTrackRenames run.go:185: Remote "B2 bucket rclone-test-vakapuj0zapi", Local "Local file system at /tmp/rclone1801727288", Modify Window "1ms" sync_test.go:1568: Can track renames: true 2025/11/01 02:14:34 INFO : B2 bucket rclone-test-vakapuj0zapi: Making map for --track-renames 2025/11/01 02:14:34 INFO : B2 bucket rclone-test-vakapuj0zapi: Finished making map for --track-renames 2025/11/01 02:14:34 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for checks to finish 2025/11/01 02:14:34 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/01 02:14:34 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for renames to finish 2025/11/01 02:14:34 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/01 02:14:34 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for transfers to finish 2025/11/01 02:14:35 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/01 02:14:35 INFO : potato: Copied (new) 2025/11/01 02:14:35 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 02:14:35 INFO : yam: Copied (new) 2025/11/01 02:14:35 DEBUG : Waiting for deletions to finish 2025/11/01 02:14:36 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/01 02:14:36 INFO : B2 bucket rclone-test-vakapuj0zapi: Making map for --track-renames 2025/11/01 02:14:36 INFO : B2 bucket rclone-test-vakapuj0zapi: Finished making map for --track-renames 2025/11/01 02:14:36 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for checks to finish 2025/11/01 02:14:36 DEBUG : potato: Unchanged skipping 2025/11/01 02:14:36 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for renames to finish 2025/11/01 02:14:36 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:14:36 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:14:36 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:14:36 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/01 02:14:36 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for transfers to finish 2025/11/01 02:14:37 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 02:14:37 INFO : yaml: Copied (new) 2025/11/01 02:14:37 ERROR : B2 bucket rclone-test-vakapuj0zapi: not deleting files as there were IO errors 2025/11/01 02:14:37 ERROR : B2 bucket rclone-test-vakapuj0zapi: 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.53s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "B2 bucket rclone-test-vakapuj0zapi", Local "Local file system at /tmp/rclone1801727288", Modify Window "1ms" sync_test.go:1640: Can track renames: true 2025/11/01 02:14:38 INFO : B2 bucket rclone-test-vakapuj0zapi: Making map for --track-renames 2025/11/01 02:14:38 INFO : B2 bucket rclone-test-vakapuj0zapi: Finished making map for --track-renames 2025/11/01 02:14:38 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for checks to finish 2025/11/01 02:14:38 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/01 02:14:38 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/01 02:14:38 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for renames to finish 2025/11/01 02:14:38 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for transfers to finish 2025/11/01 02:14:38 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 02:14:38 INFO : yam: Copied (new) 2025/11/01 02:14:38 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/01 02:14:38 INFO : potato: Copied (new) 2025/11/01 02:14:38 DEBUG : Waiting for deletions to finish 2025/11/01 02:14:38 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/01 02:14:38 INFO : B2 bucket rclone-test-vakapuj0zapi: Making map for --track-renames 2025/11/01 02:14:38 INFO : B2 bucket rclone-test-vakapuj0zapi: Finished making map for --track-renames 2025/11/01 02:14:38 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for checks to finish 2025/11/01 02:14:38 DEBUG : potato: Unchanged skipping 2025/11/01 02:14:38 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for renames to finish 2025/11/01 02:14:39 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:14:39 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:14:39 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:14:39 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/01 02:14:39 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for transfers to finish 2025/11/01 02:14:39 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 02:14:39 INFO : yaml: Copied (new) 2025/11/01 02:14:39 ERROR : B2 bucket rclone-test-vakapuj0zapi: not deleting files as there were IO errors 2025/11/01 02:14:39 ERROR : B2 bucket rclone-test-vakapuj0zapi: 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.75s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "B2 bucket rclone-test-vakapuj0zapi", Local "Local file system at /tmp/rclone1801727288", Modify Window "1ms" sync_test.go:1679: Can track renames: true 2025/11/01 02:14:40 INFO : B2 bucket rclone-test-vakapuj0zapi: Making map for --track-renames 2025/11/01 02:14:40 INFO : B2 bucket rclone-test-vakapuj0zapi: Finished making map for --track-renames 2025/11/01 02:14:40 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for checks to finish 2025/11/01 02:14:40 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/01 02:14:40 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/11/01 02:14:40 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for renames to finish 2025/11/01 02:14:40 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for transfers to finish 2025/11/01 02:14:41 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/01 02:14:41 INFO : potato: Copied (new) 2025/11/01 02:14:41 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 02:14:41 INFO : sub/yam: Copied (new) 2025/11/01 02:14:41 DEBUG : Waiting for deletions to finish 2025/11/01 02:14:41 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/01 02:14:41 DEBUG : potato: Unchanged skipping 2025/11/01 02:14:41 INFO : B2 bucket rclone-test-vakapuj0zapi: Making map for --track-renames 2025/11/01 02:14:41 INFO : B2 bucket rclone-test-vakapuj0zapi: Finished making map for --track-renames 2025/11/01 02:14:41 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for checks to finish 2025/11/01 02:14:41 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for renames to finish 2025/11/01 02:14:42 ERROR : sub/yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:14:42 ERROR : sub/yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:14:42 DEBUG : yam: Failed to rename to "sub/yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:14:42 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/01 02:14:42 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for transfers to finish 2025/11/01 02:14:42 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/01 02:14:42 INFO : yam: Copied (new) 2025/11/01 02:14:42 ERROR : B2 bucket rclone-test-vakapuj0zapi: not deleting files as there were IO errors 2025/11/01 02:14:42 ERROR : B2 bucket rclone-test-vakapuj0zapi: 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 (3.07s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "B2 bucket rclone-test-vakapuj0zapi", Local "Local file system at /tmp/rclone1801727288", Modify Window "1ms" 2025/11/01 02:14:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/01 02:14:43 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/11/01 02:14:43 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for checks to finish 2025/11/01 02:14:43 DEBUG : B2 bucket rclone-test-vakapuj0zapi: Waiting for transfers to finish 2025/11/01 02:14:44 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/01 02:14:44 INFO : sub dir/hello world: Copied (new) 2025/11/01 02:14:44 INFO : sub dir/hello world: Deleted 2025/11/01 02:14:44 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/11/01 02:14:44 INFO : nested/sub dir/file: Copied (new) 2025/11/01 02:14:44 INFO : nested/sub dir/file: Deleted 2025/11/01 02:14:44 ERROR : Local file system at /tmp/rclone1801727288: 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.46s) === RUN TestServerSideMove run.go:185: Remote "B2 bucket rclone-test-vakapuj0zapi", Local "Local file system at /tmp/rclone1801727288", Modify Window "1ms" 2025/11/01 02:14:45 DEBUG : Creating backend with remote "TestB2:rclone-test-xiyomuc8vogo" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-vakapuj0zapi -> B2 bucket rclone-test-xiyomuc8vogo 2025/11/01 02:14:50 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/01 02:14:50 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/01 02:14:50 DEBUG : empty space: Unchanged skipping 2025/11/01 02:14:50 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/11/01 02:14:50 DEBUG : B2 bucket rclone-test-xiyomuc8vogo: Waiting for checks to finish 2025/11/01 02:14:50 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:14:50 INFO : empty space: Deleted 2025/11/01 02:14:50 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:14:50 DEBUG : B2 bucket rclone-test-xiyomuc8vogo: Waiting for transfers to finish 2025/11/01 02:14:50 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:14:50 ERROR : potato3: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1911 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideMove 2025/11/01 02:14:50 DEBUG : B2 bucket rclone-test-xiyomuc8vogo: Purge remote 2025/11/01 02:14:50 INFO : B2 bucket rclone-test-xiyomuc8vogo: cleaning bucket "rclone-test-xiyomuc8vogo" of all files 2025/11/01 02:14:50 DEBUG : empty space: Deleting (id "4_zdf822d43302d3bdb9fa80e1b_f1124ccdbc0e2ee70_d20251101_m021448_c001_v0001043_t0003_u01761963288808") 2025/11/01 02:14:50 DEBUG : potato3: Deleting (id "4_zdf822d43302d3bdb9fa80e1b_f1124ccdbc0e2ee72_d20251101_m021449_c001_v0001043_t0025_u01761963289286") --- FAIL: TestServerSideMove (6.82s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "B2 bucket rclone-test-vakapuj0zapi", Local "Local file system at /tmp/rclone1801727288", Modify Window "1ms" 2025/11/01 02:14:52 DEBUG : Creating backend with remote "TestB2:rclone-test-jepufev0cude" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-vakapuj0zapi -> B2 bucket rclone-test-jepufev0cude 2025/11/01 02:14:57 DEBUG : empty space: Excluded (Size Filter) 2025/11/01 02:14:57 DEBUG : empty space: Excluded 2025/11/01 02:14:57 DEBUG : empty space: Excluded (Size Filter) 2025/11/01 02:14:57 DEBUG : empty space: Excluded 2025/11/01 02:14:57 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/01 02:14:57 DEBUG : B2 bucket rclone-test-jepufev0cude: Waiting for checks to finish 2025/11/01 02:14:57 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/11/01 02:14:57 DEBUG : B2 bucket rclone-test-jepufev0cude: Waiting for transfers to finish 2025/11/01 02:14:57 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:14:57 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:14:57 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:14:57 ERROR : potato3: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1924 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideMoveWithFilter 2025/11/01 02:14:57 DEBUG : B2 bucket rclone-test-jepufev0cude: Purge remote 2025/11/01 02:14:57 INFO : B2 bucket rclone-test-jepufev0cude: cleaning bucket "rclone-test-jepufev0cude" of all files 2025/11/01 02:14:57 DEBUG : empty space: Deleting (id "4_zef824d23302d3bdb9fa80e1b_f102f6f06adcd8b61_d20251101_m021456_c001_v0001182_t0043_u01761963296409") 2025/11/01 02:14:57 DEBUG : potato3: Deleting (id "4_zef824d23302d3bdb9fa80e1b_f102f6f06adcd8b63_d20251101_m021456_c001_v0001182_t0027_u01761963296734") --- FAIL: TestServerSideMoveWithFilter (7.37s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "B2 bucket rclone-test-vakapuj0zapi", Local "Local file system at /tmp/rclone1801727288", Modify Window "1ms" 2025/11/01 02:14:59 DEBUG : Creating backend with remote "TestB2:rclone-test-mojaral6cobu" 2025/11/01 02:15:01 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-vakapuj0zapi -> B2 bucket rclone-test-mojaral6cobu 2025/11/01 02:15:05 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/01 02:15:05 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/01 02:15:05 DEBUG : empty space: Unchanged skipping 2025/11/01 02:15:05 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/11/01 02:15:05 DEBUG : B2 bucket rclone-test-mojaral6cobu: Waiting for checks to finish 2025/11/01 02:15:05 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:05 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:05 INFO : empty space: Deleted 2025/11/01 02:15:05 DEBUG : B2 bucket rclone-test-mojaral6cobu: Waiting for transfers to finish 2025/11/01 02:15:05 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/01 02:15:05 ERROR : potato3: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1931 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideMoveDeleteEmptySourceDirs 2025/11/01 02:15:05 DEBUG : B2 bucket rclone-test-mojaral6cobu: Purge remote 2025/11/01 02:15:05 INFO : B2 bucket rclone-test-mojaral6cobu: cleaning bucket "rclone-test-mojaral6cobu" of all files 2025/11/01 02:15:05 DEBUG : empty space: Deleting (id "4_zbff25de3302d3bdb9fa80e1b_f1128da00325443da_d20251101_m021503_c001_v0001101_t0043_u01761963303530") 2025/11/01 02:15:05 DEBUG : potato3: Deleting (id "4_zbff25de3302d3bdb9fa80e1b_f1128da00325443e0_d20251101_m021504_c001_v0001101_t0028_u01761963304057") --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (7.71s) === RUN TestSyncOverlapWithFilter run.go:185: Remote "B2 bucket rclone-test-vakapuj0zapi", Local "Local file system at /tmp/rclone1801727288", Modify Window "1ms" 2025/11/01 02:15:07 DEBUG : Creating backend with remote "TestB2:rclone-test-vakapuj0zapi/rclone-sync-test" 2025/11/01 02:15:09 DEBUG : Creating backend with remote "TestB2:rclone-test-vakapuj0zapi/rclone-sync-test-include/layer2" 2025/11/01 02:15:09 DEBUG : pacer: low level retry 1/10 (error incident id 3894aee67876-c2e0efc59b3b9bec (500 internal_error)) 2025/11/01 02:15:09 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/01 02:15:09 DEBUG : pacer: low level retry 2/10 (error incident id 3894aee67876-fcf3c0c6680b54ff (500 internal_error)) 2025/11/01 02:15:09 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/11/01 02:15:10 DEBUG : pacer: low level retry 3/10 (error incident id 3894aee67876-933ef29c2f6b6c92 (500 internal_error)) 2025/11/01 02:15:10 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/11/01 02:15:10 DEBUG : pacer: low level retry 4/10 (error incident id 3894aee67876-3de10f3ae6aa352b (500 internal_error)) 2025/11/01 02:15:10 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/11/01 02:15:10 DEBUG : pacer: low level retry 5/10 (error incident id 3894aee67876-3225cc112232e8c4 (500 internal_error)) 2025/11/01 02:15:10 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/11/01 02:15:10 DEBUG : pacer: low level retry 6/10 (error incident id 3894aee67876-8c8c05a63fc59cdb (500 internal_error)) 2025/11/01 02:15:10 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/11/01 02:15:10 DEBUG : pacer: low level retry 7/10 (error incident id 3894aee67876-0f931467cfe4f2fc (500 internal_error)) 2025/11/01 02:15:10 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/11/01 02:15:11 DEBUG : pacer: low level retry 8/10 (error incident id 3894aee67876-c47a902ecbdf79d7 (500 internal_error)) 2025/11/01 02:15:11 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2025/11/01 02:15:12 DEBUG : pacer: low level retry 9/10 (error incident id 3894aee67876-89956015f4993425 (500 internal_error)) 2025/11/01 02:15:12 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2025/11/01 02:15:15 DEBUG : pacer: low level retry 10/10 (error incident id 3894aee67876-7a6977350e110d38 (500 internal_error)) 2025/11/01 02:15:15 DEBUG : pacer: Rate limited, increasing sleep to 10.24s --- FAIL: TestSyncOverlapWithFilter (9.02s) panic: runtime error: invalid memory address or nil pointer dereference [recovered] panic: runtime error: invalid memory address or nil pointer dereference [signal SIGSEGV: segmentation violation code=0x1 addr=0x30 pc=0x1ff2965] goroutine 1244 [running]: testing.tRunner.func1.2({0x23693a0, 0x40ba020}) /usr/local/go/src/testing/testing.go:1734 +0x21c testing.tRunner.func1() /usr/local/go/src/testing/testing.go:1737 +0x35e panic({0x23693a0?, 0x40ba020?}) /usr/local/go/src/runtime/panic.go:792 +0x132 github.com/rclone/rclone/fs/sync.TestSyncOverlapWithFilter(0xc00086a000) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2016 +0x2a5 testing.tRunner(0xc00086a000, 0x2976500) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1851 +0x413 "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncAfterChangingModtimeOnly|TestSyncOverlapWithFilter|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Finished ERROR in 57.266767716s (try 2/5): exit status 2: Failed [TestServerSideCopy TestServerSideCopyOverSelf TestServerSideMoveOverSelf TestSyncAfterChangingModtimeOnly TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncOverlapWithFilter]