"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^(TestBase64|TestManualTransformFile|TestMoveWithDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncAfterChangingModtimeOnly|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncCopyDest|TestSyncMultipleCompareDest|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime|TestTransformFile)$'" - Starting (try 2/5) 2025/11/02 04:00:47 DEBUG : Creating backend with remote "TestB2:rclone-test-losokay9bide" 2025/11/02 04:00:47 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 04:00:47 DEBUG : Creating backend with remote "/tmp/rclone3134491823" === RUN TestServerSideCopy run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:00:49 DEBUG : Creating backend with remote "TestB2:rclone-test-fejikox9weti" sync_test.go:620: Server side copy (if possible) B2 bucket rclone-test-losokay9bide -> B2 bucket rclone-test-fejikox9weti 2025/11/02 04:00:51 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 04:00:51 DEBUG : B2 bucket rclone-test-fejikox9weti: Waiting for checks to finish 2025/11/02 04:00:51 DEBUG : B2 bucket rclone-test-fejikox9weti: Waiting for transfers to finish 2025/11/02 04:00:51 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 04:00:51 DEBUG : B2 bucket rclone-test-fejikox9weti: Purge remote 2025/11/02 04:00:51 INFO : B2 bucket rclone-test-fejikox9weti: cleaning bucket "rclone-test-fejikox9weti" of all files --- FAIL: TestServerSideCopy (5.14s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:00:53 DEBUG : Creating backend with remote "TestB2:rclone-test-pufavab8tito" sync_test.go:656: Server side copy (if possible) B2 bucket rclone-test-losokay9bide -> B2 bucket rclone-test-pufavab8tito 2025/11/02 04:00:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 04:00:54 DEBUG : B2 bucket rclone-test-pufavab8tito: Waiting for checks to finish 2025/11/02 04:00:54 DEBUG : B2 bucket rclone-test-pufavab8tito: Waiting for transfers to finish 2025/11/02 04:00:55 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 04:00:55 DEBUG : B2 bucket rclone-test-pufavab8tito: Purge remote 2025/11/02 04:00:55 INFO : B2 bucket rclone-test-pufavab8tito: cleaning bucket "rclone-test-pufavab8tito" of all files --- FAIL: TestServerSideCopyOverSelf (3.89s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:00:57 DEBUG : Creating backend with remote "TestB2:rclone-test-qaqugiw3kobi" sync_test.go:701: Server side copy (if possible) B2 bucket rclone-test-losokay9bide -> B2 bucket rclone-test-qaqugiw3kobi 2025/11/02 04:00:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 04:00:59 DEBUG : B2 bucket rclone-test-qaqugiw3kobi: Waiting for checks to finish 2025/11/02 04:00:59 DEBUG : B2 bucket rclone-test-qaqugiw3kobi: Waiting for transfers to finish 2025/11/02 04:00:59 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 04:00:59 DEBUG : B2 bucket rclone-test-qaqugiw3kobi: Purge remote 2025/11/02 04:00:59 INFO : B2 bucket rclone-test-qaqugiw3kobi: cleaning bucket "rclone-test-qaqugiw3kobi" of all files --- FAIL: TestServerSideMoveOverSelf (4.06s) === RUN TestSyncAfterChangingModtimeOnly run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:01:01 DEBUG : empty space: size = 1 OK 2025/11/02 04:01:01 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 04:01:01 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for checks to finish 2025/11/02 04:01:01 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/02 04:01:01 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2025/11/02 04:01:01 DEBUG : empty space: Unchanged skipping 2025/11/02 04:01:01 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for transfers to finish 2025/11/02 04:01:01 DEBUG : Waiting for deletions to finish 2025/11/02 04:01:01 INFO : There was nothing to transfer 2025/11/02 04:01:02 DEBUG : empty space: size = 1 OK 2025/11/02 04:01:02 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 04:01:02 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for checks to finish 2025/11/02 04:01:02 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/02 04:01:02 ERROR : empty space: Failed to set modification time: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:02 DEBUG : empty space: Unchanged skipping 2025/11/02 04:01:02 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for transfers to finish 2025/11/02 04:01:02 ERROR : B2 bucket rclone-test-losokay9bide: not deleting files as there were IO errors 2025/11/02 04:01:02 ERROR : B2 bucket rclone-test-losokay9bide: 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.57s) === RUN TestSyncWithTrackRenames run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" sync_test.go:1568: Can track renames: true 2025/11/02 04:01:03 INFO : B2 bucket rclone-test-losokay9bide: Making map for --track-renames 2025/11/02 04:01:03 INFO : B2 bucket rclone-test-losokay9bide: Finished making map for --track-renames 2025/11/02 04:01:03 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for checks to finish 2025/11/02 04:01:03 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 04:01:03 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 04:01:03 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for renames to finish 2025/11/02 04:01:03 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for transfers to finish 2025/11/02 04:01:04 DEBUG : potato: size = 14 OK 2025/11/02 04:01:04 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 04:01:04 INFO : potato: Copied (new) 2025/11/02 04:01:04 DEBUG : yam: size = 11 OK 2025/11/02 04:01:04 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:01:04 INFO : yam: Copied (new) 2025/11/02 04:01:04 DEBUG : Waiting for deletions to finish 2025/11/02 04:01:04 DEBUG : potato: size = 14 OK 2025/11/02 04:01:04 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:01:04 INFO : B2 bucket rclone-test-losokay9bide: Making map for --track-renames 2025/11/02 04:01:04 INFO : B2 bucket rclone-test-losokay9bide: Finished making map for --track-renames 2025/11/02 04:01:04 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for checks to finish 2025/11/02 04:01:04 DEBUG : potato: Unchanged skipping 2025/11/02 04:01:04 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for renames to finish 2025/11/02 04:01:05 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:05 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:05 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:05 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/02 04:01:05 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for transfers to finish 2025/11/02 04:01:05 DEBUG : yaml: size = 11 OK 2025/11/02 04:01:05 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:01:05 INFO : yaml: Copied (new) 2025/11/02 04:01:05 ERROR : B2 bucket rclone-test-losokay9bide: not deleting files as there were IO errors 2025/11/02 04:01:05 ERROR : B2 bucket rclone-test-losokay9bide: 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.30s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" sync_test.go:1640: Can track renames: true 2025/11/02 04:01:06 INFO : B2 bucket rclone-test-losokay9bide: Making map for --track-renames 2025/11/02 04:01:06 INFO : B2 bucket rclone-test-losokay9bide: Finished making map for --track-renames 2025/11/02 04:01:06 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for checks to finish 2025/11/02 04:01:06 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 04:01:06 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 04:01:06 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for renames to finish 2025/11/02 04:01:06 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for transfers to finish 2025/11/02 04:01:07 DEBUG : yam: size = 11 OK 2025/11/02 04:01:07 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:01:07 INFO : yam: Copied (new) 2025/11/02 04:01:07 DEBUG : potato: size = 14 OK 2025/11/02 04:01:07 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 04:01:07 INFO : potato: Copied (new) 2025/11/02 04:01:07 DEBUG : Waiting for deletions to finish 2025/11/02 04:01:07 DEBUG : potato: size = 14 OK 2025/11/02 04:01:07 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:01:07 INFO : B2 bucket rclone-test-losokay9bide: Making map for --track-renames 2025/11/02 04:01:07 INFO : B2 bucket rclone-test-losokay9bide: Finished making map for --track-renames 2025/11/02 04:01:07 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for checks to finish 2025/11/02 04:01:07 DEBUG : potato: Unchanged skipping 2025/11/02 04:01:07 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for renames to finish 2025/11/02 04:01:08 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:08 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:08 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:08 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/02 04:01:08 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for transfers to finish 2025/11/02 04:01:08 DEBUG : yaml: size = 11 OK 2025/11/02 04:01:08 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:01:08 INFO : yaml: Copied (new) 2025/11/02 04:01:08 ERROR : B2 bucket rclone-test-losokay9bide: not deleting files as there were IO errors 2025/11/02 04:01:08 ERROR : B2 bucket rclone-test-losokay9bide: 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.94s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" sync_test.go:1679: Can track renames: true 2025/11/02 04:01:09 INFO : B2 bucket rclone-test-losokay9bide: Making map for --track-renames 2025/11/02 04:01:09 INFO : B2 bucket rclone-test-losokay9bide: Finished making map for --track-renames 2025/11/02 04:01:09 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for checks to finish 2025/11/02 04:01:09 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 04:01:09 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/11/02 04:01:09 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for renames to finish 2025/11/02 04:01:09 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for transfers to finish 2025/11/02 04:01:10 DEBUG : sub/yam: size = 11 OK 2025/11/02 04:01:10 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:01:10 INFO : sub/yam: Copied (new) 2025/11/02 04:01:10 DEBUG : potato: size = 14 OK 2025/11/02 04:01:10 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 04:01:10 INFO : potato: Copied (new) 2025/11/02 04:01:10 DEBUG : Waiting for deletions to finish 2025/11/02 04:01:10 DEBUG : potato: size = 14 OK 2025/11/02 04:01:10 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:01:10 DEBUG : potato: Unchanged skipping 2025/11/02 04:01:10 INFO : B2 bucket rclone-test-losokay9bide: Making map for --track-renames 2025/11/02 04:01:10 INFO : B2 bucket rclone-test-losokay9bide: Finished making map for --track-renames 2025/11/02 04:01:10 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for checks to finish 2025/11/02 04:01:10 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for renames to finish 2025/11/02 04:01:11 ERROR : sub/yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:11 ERROR : sub/yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:11 DEBUG : yam: Failed to rename to "sub/yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:11 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 04:01:11 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for transfers to finish 2025/11/02 04:01:11 DEBUG : yam: size = 11 OK 2025/11/02 04:01:11 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:01:11 INFO : yam: Copied (new) 2025/11/02 04:01:11 ERROR : B2 bucket rclone-test-losokay9bide: not deleting files as there were IO errors 2025/11/02 04:01:11 ERROR : B2 bucket rclone-test-losokay9bide: 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.97s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:01:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 04:01:12 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/11/02 04:01:12 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for checks to finish 2025/11/02 04:01:12 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for transfers to finish 2025/11/02 04:01:13 DEBUG : sub dir/hello world: size = 11 OK 2025/11/02 04:01:13 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 04:01:13 INFO : sub dir/hello world: Copied (new) 2025/11/02 04:01:13 INFO : sub dir/hello world: Deleted 2025/11/02 04:01:13 DEBUG : nested/sub dir/file: size = 6 OK 2025/11/02 04:01:13 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/11/02 04:01:13 INFO : nested/sub dir/file: Copied (new) 2025/11/02 04:01:13 INFO : nested/sub dir/file: Deleted 2025/11/02 04:01:13 ERROR : Local file system at /tmp/rclone3134491823: 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.98s) === RUN TestServerSideMove run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:01:14 DEBUG : Creating backend with remote "TestB2:rclone-test-cerihes4hofa" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-losokay9bide -> B2 bucket rclone-test-cerihes4hofa 2025/11/02 04:01:19 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 04:01:19 DEBUG : empty space: size = 1 OK 2025/11/02 04:01:19 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/02 04:01:19 DEBUG : empty space: Unchanged skipping 2025/11/02 04:01:19 DEBUG : potato3: size = 68 (B2 bucket rclone-test-losokay9bide) 2025/11/02 04:01:19 DEBUG : potato3: size = 60 (B2 bucket rclone-test-cerihes4hofa) 2025/11/02 04:01:19 DEBUG : potato3: Sizes differ 2025/11/02 04:01:19 DEBUG : B2 bucket rclone-test-cerihes4hofa: Waiting for checks to finish 2025/11/02 04:01:19 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:19 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:19 INFO : empty space: Deleted 2025/11/02 04:01:19 DEBUG : B2 bucket rclone-test-cerihes4hofa: Waiting for transfers to finish 2025/11/02 04:01:19 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:19 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 04:01:19 DEBUG : B2 bucket rclone-test-cerihes4hofa: Purge remote 2025/11/02 04:01:19 INFO : B2 bucket rclone-test-cerihes4hofa: cleaning bucket "rclone-test-cerihes4hofa" of all files 2025/11/02 04:01:19 DEBUG : empty space: Deleting (id "4_z3ff2fdf3b09d4b2b9fa80e1b_f1006facd485c2ac7_d20251102_m040118_c001_v0001131_t0017_u01762056078227") 2025/11/02 04:01:19 DEBUG : potato3: Deleting (id "4_z3ff2fdf3b09d4b2b9fa80e1b_f1006facd485c2acb_d20251102_m040118_c001_v0001131_t0053_u01762056078652") --- FAIL: TestServerSideMove (6.68s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:01:21 DEBUG : Creating backend with remote "TestB2:rclone-test-pinidib6cigi" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-losokay9bide -> B2 bucket rclone-test-pinidib6cigi 2025/11/02 04:01:27 DEBUG : empty space: Excluded (Size Filter) 2025/11/02 04:01:27 DEBUG : empty space: Excluded 2025/11/02 04:01:27 DEBUG : empty space: Excluded (Size Filter) 2025/11/02 04:01:27 DEBUG : empty space: Excluded 2025/11/02 04:01:27 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 04:01:27 DEBUG : potato3: size = 68 (B2 bucket rclone-test-losokay9bide) 2025/11/02 04:01:27 DEBUG : potato3: size = 60 (B2 bucket rclone-test-pinidib6cigi) 2025/11/02 04:01:27 DEBUG : potato3: Sizes differ 2025/11/02 04:01:27 DEBUG : B2 bucket rclone-test-pinidib6cigi: Waiting for checks to finish 2025/11/02 04:01:27 DEBUG : B2 bucket rclone-test-pinidib6cigi: Waiting for transfers to finish 2025/11/02 04:01:27 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:27 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:27 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:27 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 04:01:27 DEBUG : B2 bucket rclone-test-pinidib6cigi: Purge remote 2025/11/02 04:01:27 INFO : B2 bucket rclone-test-pinidib6cigi: cleaning bucket "rclone-test-pinidib6cigi" of all files 2025/11/02 04:01:27 DEBUG : empty space: Deleting (id "4_zdf221dd3b0ad4b2b9fa80e1b_f115136e2432c660d_d20251102_m040125_c001_v0001091_t0012_u01762056085863") 2025/11/02 04:01:27 DEBUG : potato3: Deleting (id "4_zdf221dd3b0ad4b2b9fa80e1b_f115136e2432c6611_d20251102_m040126_c001_v0001091_t0028_u01762056086383") --- FAIL: TestServerSideMoveWithFilter (7.97s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:01:29 DEBUG : Creating backend with remote "TestB2:rclone-test-fopaliv3tici" 2025/11/02 04:01:31 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-losokay9bide -> B2 bucket rclone-test-fopaliv3tici 2025/11/02 04:01:34 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 04:01:34 DEBUG : empty space: size = 1 OK 2025/11/02 04:01:34 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/02 04:01:34 DEBUG : empty space: Unchanged skipping 2025/11/02 04:01:34 DEBUG : potato3: size = 68 (B2 bucket rclone-test-losokay9bide) 2025/11/02 04:01:34 DEBUG : potato3: size = 60 (B2 bucket rclone-test-fopaliv3tici) 2025/11/02 04:01:34 DEBUG : potato3: Sizes differ 2025/11/02 04:01:34 DEBUG : B2 bucket rclone-test-fopaliv3tici: Waiting for checks to finish 2025/11/02 04:01:34 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:34 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:34 INFO : empty space: Deleted 2025/11/02 04:01:34 DEBUG : B2 bucket rclone-test-fopaliv3tici: Waiting for transfers to finish 2025/11/02 04:01:34 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:34 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 04:01:34 DEBUG : B2 bucket rclone-test-fopaliv3tici: Purge remote 2025/11/02 04:01:34 INFO : B2 bucket rclone-test-fopaliv3tici: cleaning bucket "rclone-test-fopaliv3tici" of all files 2025/11/02 04:01:35 DEBUG : empty space: Deleting (id "4_z1fb23db3b0ad4b2b9fa80e1b_f119f706d54b26f1c_d20251102_m040133_c001_v0001134_t0016_u01762056093383") 2025/11/02 04:01:35 DEBUG : potato3: Deleting (id "4_z1fb23db3b0ad4b2b9fa80e1b_f119f706d54b26f20_d20251102_m040133_c001_v0001134_t0020_u01762056093897") --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (7.20s) === RUN TestSyncMultipleCompareDest run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:01:36 DEBUG : pre-dest1/1: Clearing upload URL because of error: Post "https://pod-000-1117-18.backblaze.com/b2api/v1/b2_upload_file/2fa28de3b09d4b2b9fa80e1b/c001_v0001117_t0015": EOF 2025/11/02 04:01:36 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1117-18.backblaze.com/b2api/v1/b2_upload_file/2fa28de3b09d4b2b9fa80e1b/c001_v0001117_t0015": EOF) 2025/11/02 04:01:36 DEBUG : pacer: Rate limited, increasing sleep to 20ms run.go:286: Retry Put of "pre-dest1/1" to B2 bucket rclone-test-losokay9bide: 1/10 (Post "https://pod-000-1117-18.backblaze.com/b2api/v1/b2_upload_file/2fa28de3b09d4b2b9fa80e1b/c001_v0001117_t0015": EOF) 2025/11/02 04:01:39 DEBUG : pacer: Reducing sleep to 10ms 2025/11/02 04:01:39 DEBUG : Creating backend with remote "TestB2:rclone-test-losokay9bide/dest" 2025/11/02 04:01:39 DEBUG : Config file has changed externally - reloading 2025/11/02 04:01:40 DEBUG : Creating backend with remote "TestB2:rclone-test-losokay9bide/pre-dest1" 2025/11/02 04:01:41 DEBUG : Creating backend with remote "TestB2:rclone-test-losokay9bide/pre-dest2" 2025/11/02 04:01:44 DEBUG : 1: size = 1 OK 2025/11/02 04:01:44 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:01:44 DEBUG : 1: Destination found in --compare-dest, skipping 2025/11/02 04:01:44 DEBUG : 2: size = 1 OK 2025/11/02 04:01:44 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:01:44 DEBUG : 2: Destination found in --compare-dest, skipping 2025/11/02 04:01:44 DEBUG : 3: Need to transfer - File not found at Destination 2025/11/02 04:01:44 DEBUG : B2 bucket rclone-test-losokay9bide path dest: Waiting for checks to finish 2025/11/02 04:01:44 DEBUG : B2 bucket rclone-test-losokay9bide path dest: Waiting for transfers to finish 2025/11/02 04:01:45 DEBUG : 3: size = 1 OK 2025/11/02 04:01:45 DEBUG : 3: sha1 = 77de68daecd823babbb58edb1c8e14d7106e83bb OK 2025/11/02 04:01:45 INFO : 3: Copied (new) 2025/11/02 04:01:45 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (10.75s) === RUN TestSyncCopyDest run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:01:47 DEBUG : Creating backend with remote "TestB2:rclone-test-losokay9bide/dst" 2025/11/02 04:01:48 DEBUG : Creating backend with remote "TestB2:rclone-test-losokay9bide/CopyDest" 2025/11/02 04:01:50 DEBUG : one: Need to transfer - File not found at Destination 2025/11/02 04:01:50 DEBUG : B2 bucket rclone-test-losokay9bide path dst: Waiting for checks to finish 2025/11/02 04:01:50 DEBUG : B2 bucket rclone-test-losokay9bide path dst: Waiting for transfers to finish 2025/11/02 04:01:51 DEBUG : one: size = 3 OK 2025/11/02 04:01:51 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/02 04:01:51 INFO : one: Copied (new) 2025/11/02 04:01:51 DEBUG : Waiting for deletions to finish 2025/11/02 04:01:51 DEBUG : one: size = 5 (Local file system at /tmp/rclone3134491823) 2025/11/02 04:01:51 DEBUG : one: size = 3 (B2 bucket rclone-test-losokay9bide path dst) 2025/11/02 04:01:51 DEBUG : one: Sizes differ 2025/11/02 04:01:51 DEBUG : B2 bucket rclone-test-losokay9bide path dst: Waiting for checks to finish 2025/11/02 04:01:52 DEBUG : B2 bucket rclone-test-losokay9bide path dst: Waiting for transfers to finish 2025/11/02 04:01:52 DEBUG : one: size = 5 OK 2025/11/02 04:01:52 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/11/02 04:01:52 INFO : one: Copied (replaced existing) 2025/11/02 04:01:52 DEBUG : Waiting for deletions to finish 2025/11/02 04:01:53 DEBUG : Creating backend with remote "TestB2:rclone-test-losokay9bide/BackupDir" 2025/11/02 04:01:55 DEBUG : one: size = 5 (Local file system at /tmp/rclone3134491823) 2025/11/02 04:01:55 DEBUG : one: size = 3 (B2 bucket rclone-test-losokay9bide path dst) 2025/11/02 04:01:55 DEBUG : one: Sizes differ 2025/11/02 04:01:55 DEBUG : B2 bucket rclone-test-losokay9bide path dst: Waiting for checks to finish 2025/11/02 04:01:55 DEBUG : one: size = 5 OK 2025/11/02 04:01:55 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/02 04:01:55 DEBUG : one: size = 5 (Local file system at /tmp/rclone3134491823) 2025/11/02 04:01:55 DEBUG : one: size = 3 (B2 bucket rclone-test-losokay9bide path dst) 2025/11/02 04:01:55 DEBUG : one: Sizes differ 2025/11/02 04:01:56 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:56 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:56 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:56 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:01:56 DEBUG : B2 bucket rclone-test-losokay9bide path dst: Waiting for transfers to finish 2025/11/02 04:01:56 ERROR : B2 bucket rclone-test-losokay9bide path dst: not deleting files as there were IO errors 2025/11/02 04:01:56 ERROR : B2 bucket rclone-test-losokay9bide path dst: not deleting directories as there were IO errors sync_test.go:2318: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2318 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestSyncCopyDest --- FAIL: TestSyncCopyDest (10.47s) === RUN TestSyncBackupDir run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:01:59 DEBUG : Creating backend with remote "TestB2:rclone-test-losokay9bide/dst" 2025/11/02 04:02:00 DEBUG : Creating backend with remote "TestB2:rclone-test-losokay9bide/backup" 2025/11/02 04:02:01 DEBUG : one: size = 4 (Local file system at /tmp/rclone3134491823) 2025/11/02 04:02:01 DEBUG : one: size = 3 (B2 bucket rclone-test-losokay9bide path dst) 2025/11/02 04:02:01 DEBUG : one: Sizes differ 2025/11/02 04:02:01 DEBUG : two: size = 3 OK 2025/11/02 04:02:01 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:02:01 DEBUG : two: Unchanged skipping 2025/11/02 04:02:01 DEBUG : B2 bucket rclone-test-losokay9bide path dst: Waiting for checks to finish 2025/11/02 04:02:03 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:02:03 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:02:03 DEBUG : B2 bucket rclone-test-losokay9bide path dst: Waiting for transfers to finish 2025/11/02 04:02:03 ERROR : B2 bucket rclone-test-losokay9bide path dst: not deleting files as there were IO errors 2025/11/02 04:02:03 ERROR : B2 bucket rclone-test-losokay9bide path dst: not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2457 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (6.32s) === RUN TestSyncBackupDirWithSuffix run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:02:05 DEBUG : Creating backend with remote "TestB2:rclone-test-losokay9bide/dst" 2025/11/02 04:02:06 DEBUG : Creating backend with remote "TestB2:rclone-test-losokay9bide/backup" 2025/11/02 04:02:08 DEBUG : one: size = 4 (Local file system at /tmp/rclone3134491823) 2025/11/02 04:02:08 DEBUG : one: size = 3 (B2 bucket rclone-test-losokay9bide path dst) 2025/11/02 04:02:08 DEBUG : one: Sizes differ 2025/11/02 04:02:08 DEBUG : two: size = 3 OK 2025/11/02 04:02:08 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:02:08 DEBUG : two: Unchanged skipping 2025/11/02 04:02:08 DEBUG : B2 bucket rclone-test-losokay9bide path dst: Waiting for checks to finish 2025/11/02 04:02:09 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:02:09 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:02:09 DEBUG : B2 bucket rclone-test-losokay9bide path dst: Waiting for transfers to finish 2025/11/02 04:02:09 ERROR : B2 bucket rclone-test-losokay9bide path dst: not deleting files as there were IO errors 2025/11/02 04:02:09 ERROR : B2 bucket rclone-test-losokay9bide path dst: not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2461 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (6.63s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:02:12 DEBUG : Creating backend with remote "TestB2:rclone-test-losokay9bide/dst" 2025/11/02 04:02:13 DEBUG : Creating backend with remote "TestB2:rclone-test-losokay9bide/backup" 2025/11/02 04:02:15 DEBUG : two: size = 3 OK 2025/11/02 04:02:15 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:02:15 DEBUG : two: Unchanged skipping 2025/11/02 04:02:15 DEBUG : one: size = 4 (Local file system at /tmp/rclone3134491823) 2025/11/02 04:02:15 DEBUG : one: size = 3 (B2 bucket rclone-test-losokay9bide path dst) 2025/11/02 04:02:15 DEBUG : one: Sizes differ 2025/11/02 04:02:15 DEBUG : B2 bucket rclone-test-losokay9bide path dst: Waiting for checks to finish 2025/11/02 04:02:16 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:02:16 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:02:16 DEBUG : B2 bucket rclone-test-losokay9bide path dst: Waiting for transfers to finish 2025/11/02 04:02:16 ERROR : B2 bucket rclone-test-losokay9bide path dst: not deleting files as there were IO errors 2025/11/02 04:02:16 ERROR : B2 bucket rclone-test-losokay9bide path dst: not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2465 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (6.84s) === RUN TestSyncBackupDirSuffixOnly run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:02:19 DEBUG : Creating backend with remote "TestB2:rclone-test-losokay9bide/dst" 2025/11/02 04:02:21 DEBUG : one: size = 4 (Local file system at /tmp/rclone3134491823) 2025/11/02 04:02:21 DEBUG : one: size = 3 (B2 bucket rclone-test-losokay9bide path dst) 2025/11/02 04:02:21 DEBUG : one: Sizes differ 2025/11/02 04:02:21 DEBUG : two: size = 3 OK 2025/11/02 04:02:21 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:02:21 DEBUG : two: Unchanged skipping 2025/11/02 04:02:21 DEBUG : B2 bucket rclone-test-losokay9bide path dst: Waiting for checks to finish 2025/11/02 04:02:21 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:02:21 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:02:21 DEBUG : B2 bucket rclone-test-losokay9bide path dst: Waiting for transfers to finish 2025/11/02 04:02:21 ERROR : B2 bucket rclone-test-losokay9bide path dst: not deleting files as there were IO errors 2025/11/02 04:02:21 ERROR : B2 bucket rclone-test-losokay9bide path dst: not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2469 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (5.02s) === RUN TestSyncSuffix run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:02:23 DEBUG : Creating backend with remote "TestB2:rclone-test-losokay9bide/dst" 2025/11/02 04:02:25 DEBUG : one: size = 4 (Local file system at /tmp/rclone3134491823) 2025/11/02 04:02:25 DEBUG : one: size = 3 (B2 bucket rclone-test-losokay9bide path dst) 2025/11/02 04:02:25 DEBUG : one: Sizes differ 2025/11/02 04:02:26 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:02:26 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:2503: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2503 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2553 Error: Received unexpected error: moving to --backup-dir failed: expected a FileSseMode but found: '' (400 bad_request) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (4.71s) === RUN TestSyncSuffixKeepExtension run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:02:28 DEBUG : Creating backend with remote "TestB2:rclone-test-losokay9bide/dst" 2025/11/02 04:02:29 DEBUG : one: size = 4 (Local file system at /tmp/rclone3134491823) 2025/11/02 04:02:29 DEBUG : one: size = 3 (B2 bucket rclone-test-losokay9bide path dst) 2025/11/02 04:02:29 DEBUG : one: Sizes differ 2025/11/02 04:02:31 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:02:31 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:2503: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2503 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Received unexpected error: moving to --backup-dir failed: expected a FileSseMode but found: '' (400 bad_request) Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (4.76s) === RUN TestTransformFile run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:02:31 DEBUG : empty_dir: Making directory with metadata 2025/11/02 04:02:31 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/11/02 04:02:31 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:02:31 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:02:31 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:02:32 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:02:32 INFO : tictacempty_dir: Making directory 2025/11/02 04:02:32 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:02:32 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:02:32 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:02:32 INFO : tictactoe: Making directory 2025/11/02 04:02:32 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:02:32 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:02:32 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/02 04:02:32 INFO : tictactoe/tictactoe: Making directory 2025/11/02 04:02:32 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/02 04:02:32 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/11/02 04:02:32 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:02:32 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/02 04:02:32 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:02:32 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for checks to finish 2025/11/02 04:02:32 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for transfers to finish 2025/11/02 04:02:32 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:02:32 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2025/11/02 04:02:32 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 04:02:32 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:02:32 INFO : toe/toe/toe.txt: Deleted 2025/11/02 04:02:32 ERROR : Local file system at /tmp/rclone3134491823: not deleting directories as there were IO errors sync_transform_test.go:427: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:427 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestTransformFile --- FAIL: TestTransformFile (1.07s) === RUN TestManualTransformFile run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:02:32 DEBUG : Reset feature "Copy" 2025/11/02 04:02:32 DEBUG : Reset feature "Move" 2025/11/02 04:02:32 DEBUG : Reset feature "Copy" 2025/11/02 04:02:32 DEBUG : Reset feature "Move" 2025/11/02 04:02:32 DEBUG : empty_dir: Making directory with metadata 2025/11/02 04:02:32 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/11/02 04:02:32 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:02:32 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:02:32 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:02:33 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:02:33 INFO : tictacempty_dir: Making directory 2025/11/02 04:02:33 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:02:33 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:02:33 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:02:33 INFO : tictactoe: Making directory 2025/11/02 04:02:33 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:02:33 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:02:33 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/02 04:02:33 INFO : tictactoe/tictactoe: Making directory 2025/11/02 04:02:33 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/02 04:02:33 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/11/02 04:02:33 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:02:33 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/02 04:02:33 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:02:33 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:02:33 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for checks to finish 2025/11/02 04:02:33 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for transfers to finish 2025/11/02 04:02:33 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2025/11/02 04:02:33 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 04:02:33 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:02:33 INFO : toe/toe/toe.txt: Deleted 2025/11/02 04:02:33 ERROR : Local file system at /tmp/rclone3134491823: not deleting directories as there were IO errors sync_transform_test.go:457: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:457 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestManualTransformFile --- FAIL: TestManualTransformFile (1.28s) === RUN TestBase64 run.go:185: Remote "B2 bucket rclone-test-losokay9bide", Local "Local file system at /tmp/rclone3134491823", Modify Window "1ms" 2025/11/02 04:02:34 DEBUG : toe: transformed to: dG9l 2025/11/02 04:02:34 DEBUG : toe: transformed to: dG9l 2025/11/02 04:02:34 INFO : dG9l: Making directory 2025/11/02 04:02:34 DEBUG : toe: transformed to: dG9l 2025/11/02 04:02:34 DEBUG : toe: transformed to: dG9l 2025/11/02 04:02:34 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/11/02 04:02:34 INFO : dG9l/dG9l: Making directory 2025/11/02 04:02:34 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/11/02 04:02:34 DEBUG : toe.txt: transformed to: dG9lLnR4dA== 2025/11/02 04:02:34 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/11/02 04:02:34 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/02 04:02:34 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/11/02 04:02:34 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for checks to finish 2025/11/02 04:02:34 DEBUG : B2 bucket rclone-test-losokay9bide: Waiting for transfers to finish 2025/11/02 04:02:34 DEBUG : dG9l/dG9l/dG9lLnR4dA==: size = 11 OK 2025/11/02 04:02:34 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 04:02:34 INFO : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA== 2025/11/02 04:02:34 ERROR : B2 bucket rclone-test-losokay9bide: not deleting files as there were IO errors 2025/11/02 04:02:34 ERROR : B2 bucket rclone-test-losokay9bide: not deleting directories as there were IO errors sync_transform_test.go:481: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:481 Error: Received unexpected error: not deleting files as there were IO errors Test: TestBase64 --- FAIL: TestBase64 (1.12s) FAIL 2025/11/02 04:02:35 DEBUG : B2 bucket rclone-test-losokay9bide: Purge remote 2025/11/02 04:02:35 INFO : B2 bucket rclone-test-losokay9bide: cleaning bucket "rclone-test-losokay9bide" of all files 2025/11/02 04:02:35 DEBUG : CopyDest/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e2515ff_d20251102_m040157_c001_v7007000_t0000_u01762056117194") 2025/11/02 04:02:35 DEBUG : CopyDest/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f77473ab_d20251102_m040153_c001_v0001172_t0048_u01762056113534") 2025/11/02 04:02:35 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251824_d20251102_m040235_c001_v7007000_t0000_u01762056155128") 2025/11/02 04:02:35 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f774744c_d20251102_m040234_c001_v0001172_t0015_u01762056154556") 2025/11/02 04:02:35 DEBUG : dest/3: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e2515e7_d20251102_m040146_c001_v7007000_t0000_u01762056106539") 2025/11/02 04:02:35 DEBUG : dest/3: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f414158ae5bb59fc3_d20251102_m040145_c001_v0001137_t0042_u01762056105449") 2025/11/02 04:02:35 DEBUG : dst/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251813_d20251102_m040231_c001_v7007000_t0000_u01762056151298") 2025/11/02 04:02:35 DEBUG : dst/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747434_d20251102_m040227_c001_v0001172_t0010_u01762056147314") 2025/11/02 04:02:35 DEBUG : dst/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251687_d20251102_m040226_c001_v7007000_t0000_u01762056146450") 2025/11/02 04:02:36 DEBUG : dst/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747422_d20251102_m040222_c001_v0001172_t0051_u01762056142551") 2025/11/02 04:02:36 DEBUG : dst/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e25167d_d20251102_m040221_c001_v7007000_t0000_u01762056141822") 2025/11/02 04:02:36 DEBUG : dst/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747412_d20251102_m040217_c001_v0001172_t0010_u01762056137901") 2025/11/02 04:02:36 DEBUG : dst/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251664_d20251102_m040216_c001_v7007000_t0000_u01762056136803") 2025/11/02 04:02:36 DEBUG : dst/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f77473e6_d20251102_m040211_c001_v0001172_t0013_u01762056131048") 2025/11/02 04:02:36 DEBUG : dst/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251656_d20251102_m040209_c001_v7007000_t0000_u01762056129942") 2025/11/02 04:02:36 DEBUG : dst/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f77473cc_d20251102_m040204_c001_v0001172_t0003_u01762056124425") 2025/11/02 04:02:36 DEBUG : dst/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251630_d20251102_m040203_c001_v7007000_t0000_u01762056123335") 2025/11/02 04:02:36 DEBUG : dst/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f77473b7_d20251102_m040157_c001_v0001172_t0002_u01762056117743") 2025/11/02 04:02:36 DEBUG : dst/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251602_d20251102_m040157_c001_v7007000_t0000_u01762056117374") 2025/11/02 04:02:36 DEBUG : dst/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f77473a9_d20251102_m040153_c001_v0001172_t0007_u01762056113240") 2025/11/02 04:02:36 DEBUG : dst/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f111a36fd0202f954_d20251102_m040152_c001_v0001120_t0007_u01762056112262") 2025/11/02 04:02:36 DEBUG : dst/one: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f111a36fd0202f94e_d20251102_m040150_c001_v0001120_t0003_u01762056110921") 2025/11/02 04:02:36 DEBUG : dst/three.txt: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251816_d20251102_m040231_c001_v7007000_t0000_u01762056151480") 2025/11/02 04:02:36 DEBUG : dst/three.txt: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747438_d20251102_m040228_c001_v0001172_t0056_u01762056148126") 2025/11/02 04:02:36 DEBUG : dst/three.txt: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251689_d20251102_m040226_c001_v7007000_t0000_u01762056146631") 2025/11/02 04:02:36 DEBUG : dst/three.txt: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747426_d20251102_m040223_c001_v0001172_t0017_u01762056143270") 2025/11/02 04:02:37 DEBUG : dst/three.txt: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e25167e_d20251102_m040222_c001_v7007000_t0000_u01762056142004") 2025/11/02 04:02:37 DEBUG : dst/three.txt: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747418_d20251102_m040218_c001_v0001172_t0058_u01762056138883") 2025/11/02 04:02:37 DEBUG : dst/three.txt: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251665_d20251102_m040216_c001_v7007000_t0000_u01762056136984") 2025/11/02 04:02:37 DEBUG : dst/three.txt: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f77473ee_d20251102_m040212_c001_v0001172_t0004_u01762056132001") 2025/11/02 04:02:37 DEBUG : dst/three.txt: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251657_d20251102_m040210_c001_v7007000_t0000_u01762056130126") 2025/11/02 04:02:37 DEBUG : dst/three.txt: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f77473d4_d20251102_m040205_c001_v0001172_t0021_u01762056125124") 2025/11/02 04:02:37 DEBUG : dst/three.txt: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251631_d20251102_m040203_c001_v7007000_t0000_u01762056123515") 2025/11/02 04:02:37 DEBUG : dst/three.txt: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f77473be_d20251102_m040158_c001_v0001172_t0018_u01762056118384") 2025/11/02 04:02:37 DEBUG : dst/two: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251817_d20251102_m040231_c001_v7007000_t0000_u01762056151661") 2025/11/02 04:02:37 DEBUG : dst/two: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747436_d20251102_m040227_c001_v0001172_t0046_u01762056147672") 2025/11/02 04:02:37 DEBUG : dst/two: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e25168b_d20251102_m040226_c001_v7007000_t0000_u01762056146813") 2025/11/02 04:02:37 DEBUG : dst/two: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747424_d20251102_m040222_c001_v0001172_t0004_u01762056142908") 2025/11/02 04:02:37 DEBUG : dst/two: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251680_d20251102_m040222_c001_v7007000_t0000_u01762056142185") 2025/11/02 04:02:37 DEBUG : dst/two: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747416_d20251102_m040218_c001_v0001172_t0017_u01762056138427") 2025/11/02 04:02:37 DEBUG : dst/two: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251667_d20251102_m040217_c001_v7007000_t0000_u01762056137166") 2025/11/02 04:02:37 DEBUG : dst/two: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f77473ea_d20251102_m040211_c001_v0001172_t0000_u01762056131650") 2025/11/02 04:02:37 DEBUG : dst/two: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251658_d20251102_m040210_c001_v7007000_t0000_u01762056130322") 2025/11/02 04:02:37 DEBUG : dst/two: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f77473d0_d20251102_m040204_c001_v0001172_t0046_u01762056124724") 2025/11/02 04:02:37 DEBUG : dst/two: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251638_d20251102_m040203_c001_v7007000_t0000_u01762056123695") 2025/11/02 04:02:37 DEBUG : dst/two: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f77473bc_d20251102_m040158_c001_v0001172_t0051_u01762056118096") 2025/11/02 04:02:38 DEBUG : empty space: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee47345_d20251102_m040134_c001_v7007000_t0000_u01762056094654") 2025/11/02 04:02:38 DEBUG : empty space: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f118f55f43e60a135_d20251102_m040131_c001_v0001117_t0045_u01762056091081") 2025/11/02 04:02:38 DEBUG : empty space: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee4731e_d20251102_m040128_c001_v7007000_t0000_u01762056088583") 2025/11/02 04:02:38 DEBUG : empty space: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f774733c_d20251102_m040123_c001_v0001172_t0057_u01762056083313") 2025/11/02 04:02:38 DEBUG : empty space: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee47307_d20251102_m040119_c001_v7007000_t0000_u01762056079418") 2025/11/02 04:02:38 DEBUG : empty space: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f118f55f43e60a0fa_d20251102_m040115_c001_v0001117_t0058_u01762056075600") 2025/11/02 04:02:38 DEBUG : empty space: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee472c6_d20251102_m040103_c001_v7007000_t0000_u01762056063094") 2025/11/02 04:02:38 DEBUG : empty space: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f118f55f43e60a0bc_d20251102_m040100_c001_v0001117_t0043_u01762056060884") 2025/11/02 04:02:38 DEBUG : nested/sub dir/file: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee472f6_d20251102_m040114_c001_v7007000_t0000_u01762056074070") 2025/11/02 04:02:38 DEBUG : nested/sub dir/file: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f118f55f43e60a0f2_d20251102_m040112_c001_v0001117_t0034_u01762056072854") 2025/11/02 04:02:38 DEBUG : potato: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee472e0_d20251102_m040111_c001_v7007000_t0000_u01762056071946") 2025/11/02 04:02:38 DEBUG : potato: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f118f55f43e60a0e8_d20251102_m040109_c001_v0001117_t0035_u01762056069876") 2025/11/02 04:02:38 DEBUG : potato: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee472d6_d20251102_m040108_c001_v7007000_t0000_u01762056068974") 2025/11/02 04:02:38 DEBUG : potato: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f118f55f43e60a0dc_d20251102_m040106_c001_v0001117_t0000_u01762056066941") 2025/11/02 04:02:38 DEBUG : potato: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee472cb_d20251102_m040106_c001_v7007000_t0000_u01762056066022") 2025/11/02 04:02:38 DEBUG : potato: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f118f55f43e60a0ca_d20251102_m040103_c001_v0001117_t0053_u01762056063631") 2025/11/02 04:02:38 DEBUG : potato2: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee4734e_d20251102_m040135_c001_v7007000_t0000_u01762056095967") 2025/11/02 04:02:38 DEBUG : potato2: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747351_d20251102_m040130_c001_v0001172_t0004_u01762056090216") 2025/11/02 04:02:39 DEBUG : potato2: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee47320_d20251102_m040128_c001_v7007000_t0000_u01762056088766") 2025/11/02 04:02:39 DEBUG : potato2: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f118f55f43e60a10f_d20251102_m040122_c001_v0001117_t0020_u01762056082269") 2025/11/02 04:02:39 DEBUG : potato2: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee4730b_d20251102_m040120_c001_v7007000_t0000_u01762056080796") 2025/11/02 04:02:39 DEBUG : potato2: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f774731a_d20251102_m040115_c001_v0001172_t0043_u01762056075251") 2025/11/02 04:02:39 DEBUG : potato3: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee47350_d20251102_m040136_c001_v7007000_t0000_u01762056096148") 2025/11/02 04:02:39 DEBUG : potato3: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747357_d20251102_m040131_c001_v0001172_t0003_u01762056091576") 2025/11/02 04:02:39 DEBUG : potato3: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee47323_d20251102_m040128_c001_v7007000_t0000_u01762056088950") 2025/11/02 04:02:39 DEBUG : potato3: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f118f55f43e60a115_d20251102_m040123_c001_v0001117_t0054_u01762056083647") 2025/11/02 04:02:39 DEBUG : potato3: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee4730c_d20251102_m040120_c001_v7007000_t0000_u01762056080977") 2025/11/02 04:02:39 DEBUG : potato3: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747322_d20251102_m040116_c001_v0001172_t0033_u01762056076185") 2025/11/02 04:02:39 DEBUG : pre-dest1/1: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e2515e9_d20251102_m040146_c001_v7007000_t0000_u01762056106720") 2025/11/02 04:02:39 DEBUG : pre-dest1/1: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f774736f_d20251102_m040138_c001_v0001172_t0059_u01762056098937") 2025/11/02 04:02:39 DEBUG : pre-dest2/2: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e2515ea_d20251102_m040146_c001_v7007000_t0000_u01762056106901") 2025/11/02 04:02:39 DEBUG : pre-dest2/2: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747371_d20251102_m040139_c001_v0001172_t0018_u01762056099218") 2025/11/02 04:02:39 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee472f7_d20251102_m040114_c001_v7007000_t0000_u01762056074293") 2025/11/02 04:02:39 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747310_d20251102_m040112_c001_v0001172_t0022_u01762056072870") 2025/11/02 04:02:39 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee472c0_d20251102_m040100_c001_v7007000_t0000_u01762056060525") 2025/11/02 04:02:39 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f118f55f43e60a0aa_d20251102_m040056_c001_v0001117_t0022_u01762056056825") 2025/11/02 04:02:39 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee472ba_d20251102_m040056_c001_v7007000_t0000_u01762056056461") 2025/11/02 04:02:39 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f118f55f43e60a08a_d20251102_m040052_c001_v0001117_t0022_u01762056052930") 2025/11/02 04:02:39 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee4721d_d20251102_m040052_c001_v7007000_t0000_u01762056052572") 2025/11/02 04:02:39 DEBUG : sub dir/hello world: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f118f55f43e60a077_d20251102_m040049_c001_v0001117_t0005_u01762056049161") 2025/11/02 04:02:40 DEBUG : sub/yam: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee472e1_d20251102_m040112_c001_v7007000_t0000_u01762056072129") 2025/11/02 04:02:40 DEBUG : sub/yam: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747300_d20251102_m040109_c001_v0001172_t0027_u01762056069891") 2025/11/02 04:02:40 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e251821_d20251102_m040234_c001_v7007000_t0000_u01762056154008") 2025/11/02 04:02:40 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747448_d20251102_m040233_c001_v0001172_t0035_u01762056153275") 2025/11/02 04:02:40 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f41400e9a7e25181b_d20251102_m040232_c001_v7007000_t0000_u01762056152728") 2025/11/02 04:02:40 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747442_d20251102_m040232_c001_v0001172_t0047_u01762056152210") 2025/11/02 04:02:40 DEBUG : yam: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee472e7_d20251102_m040112_c001_v7007000_t0000_u01762056072309") 2025/11/02 04:02:40 DEBUG : yam: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f7747308_d20251102_m040111_c001_v0001172_t0020_u01762056071457") 2025/11/02 04:02:40 DEBUG : yam: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee472d7_d20251102_m040109_c001_v7007000_t0000_u01762056069155") 2025/11/02 04:02:40 DEBUG : yam: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f77472ec_d20251102_m040106_c001_v0001172_t0027_u01762056066936") 2025/11/02 04:02:40 DEBUG : yam: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee472cd_d20251102_m040106_c001_v7007000_t0000_u01762056066205") 2025/11/02 04:02:40 DEBUG : yam: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f77472da_d20251102_m040104_c001_v0001172_t0007_u01762056064189") 2025/11/02 04:02:40 DEBUG : yaml: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee472d9_d20251102_m040109_c001_v7007000_t0000_u01762056069336") 2025/11/02 04:02:40 DEBUG : yaml: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f1032ed34f77472fa_d20251102_m040108_c001_v0001172_t0038_u01762056068423") 2025/11/02 04:02:40 DEBUG : yaml: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f40243b349ee472cf_d20251102_m040106_c001_v7007000_t0000_u01762056066392") 2025/11/02 04:02:40 DEBUG : yaml: Deleting (id "4_z2fa28de3b09d4b2b9fa80e1b_f118f55f43e60a0d2_d20251102_m040105_c001_v0001117_t0053_u01762056065206") "./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^(TestBase64|TestManualTransformFile|TestMoveWithDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncAfterChangingModtimeOnly|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncCopyDest|TestSyncMultipleCompareDest|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime|TestTransformFile)$'" - Finished ERROR in 1m54.597416469s (try 2/5): exit status 1: Failed [TestServerSideCopy TestServerSideCopyOverSelf TestServerSideMoveOverSelf TestSyncAfterChangingModtimeOnly TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncCopyDest TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension TestTransformFile TestManualTransformFile TestBase64]