"./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|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime|TestTransformFile)$'" - Starting (try 4/5) 2025/11/03 02:22:33 DEBUG : Creating backend with remote "TestB2:rclone-test-lalomib5pobo" 2025/11/03 02:22:33 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/03 02:22:33 DEBUG : Creating backend with remote "/tmp/rclone2894148106" === RUN TestServerSideCopy run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:22:36 DEBUG : Creating backend with remote "TestB2:rclone-test-vinoxex7wocu" sync_test.go:620: Server side copy (if possible) B2 bucket rclone-test-lalomib5pobo -> B2 bucket rclone-test-vinoxex7wocu 2025/11/03 02:22:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/03 02:22:37 DEBUG : B2 bucket rclone-test-vinoxex7wocu: Waiting for checks to finish 2025/11/03 02:22:37 DEBUG : B2 bucket rclone-test-vinoxex7wocu: Waiting for transfers to finish 2025/11/03 02:22:38 ERROR : sub dir/hello world: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:624: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:624 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideCopy 2025/11/03 02:22:38 DEBUG : B2 bucket rclone-test-vinoxex7wocu: Purge remote 2025/11/03 02:22:38 INFO : B2 bucket rclone-test-vinoxex7wocu: cleaning bucket "rclone-test-vinoxex7wocu" of all files --- FAIL: TestServerSideCopy (5.59s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:22:39 DEBUG : Creating backend with remote "TestB2:rclone-test-xabusuj2rani" sync_test.go:656: Server side copy (if possible) B2 bucket rclone-test-lalomib5pobo -> B2 bucket rclone-test-xabusuj2rani 2025/11/03 02:22:41 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/03 02:22:41 DEBUG : B2 bucket rclone-test-xabusuj2rani: Waiting for checks to finish 2025/11/03 02:22:41 DEBUG : B2 bucket rclone-test-xabusuj2rani: Waiting for transfers to finish 2025/11/03 02:22:41 ERROR : sub dir/hello world: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:660: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:660 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideCopyOverSelf 2025/11/03 02:22:41 DEBUG : B2 bucket rclone-test-xabusuj2rani: Purge remote 2025/11/03 02:22:41 INFO : B2 bucket rclone-test-xabusuj2rani: cleaning bucket "rclone-test-xabusuj2rani" of all files --- FAIL: TestServerSideCopyOverSelf (3.85s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:22:43 DEBUG : Creating backend with remote "TestB2:rclone-test-kexukow9zevo" sync_test.go:701: Server side copy (if possible) B2 bucket rclone-test-lalomib5pobo -> B2 bucket rclone-test-kexukow9zevo 2025/11/03 02:22:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/03 02:22:45 DEBUG : B2 bucket rclone-test-kexukow9zevo: Waiting for checks to finish 2025/11/03 02:22:45 DEBUG : B2 bucket rclone-test-kexukow9zevo: Waiting for transfers to finish 2025/11/03 02:22:45 ERROR : sub dir/hello world: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:705: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:705 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideMoveOverSelf 2025/11/03 02:22:45 DEBUG : B2 bucket rclone-test-kexukow9zevo: Purge remote 2025/11/03 02:22:45 INFO : B2 bucket rclone-test-kexukow9zevo: cleaning bucket "rclone-test-kexukow9zevo" of all files --- FAIL: TestServerSideMoveOverSelf (4.03s) === RUN TestSyncAfterChangingModtimeOnly run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:22:47 DEBUG : empty space: size = 1 OK 2025/11/03 02:22:47 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2025/11/03 02:22:47 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for checks to finish 2025/11/03 02:22:47 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/03 02:22:47 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2025/11/03 02:22:47 DEBUG : empty space: Unchanged skipping 2025/11/03 02:22:47 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for transfers to finish 2025/11/03 02:22:47 DEBUG : Waiting for deletions to finish 2025/11/03 02:22:47 INFO : There was nothing to transfer 2025/11/03 02:22:48 DEBUG : empty space: size = 1 OK 2025/11/03 02:22:48 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2025/11/03 02:22:48 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for checks to finish 2025/11/03 02:22:48 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/03 02:22:49 ERROR : empty space: Failed to set modification time: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:22:49 DEBUG : empty space: Unchanged skipping 2025/11/03 02:22:49 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for transfers to finish 2025/11/03 02:22:49 ERROR : B2 bucket rclone-test-lalomib5pobo: not deleting files as there were IO errors 2025/11/03 02:22:49 ERROR : B2 bucket rclone-test-lalomib5pobo: 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.42s) === RUN TestSyncWithTrackRenames run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" sync_test.go:1568: Can track renames: true 2025/11/03 02:22:49 INFO : B2 bucket rclone-test-lalomib5pobo: Making map for --track-renames 2025/11/03 02:22:49 INFO : B2 bucket rclone-test-lalomib5pobo: Finished making map for --track-renames 2025/11/03 02:22:49 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for checks to finish 2025/11/03 02:22:49 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/03 02:22:49 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/03 02:22:49 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for renames to finish 2025/11/03 02:22:49 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for transfers to finish 2025/11/03 02:22:50 DEBUG : potato: size = 14 OK 2025/11/03 02:22:50 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/03 02:22:50 INFO : potato: Copied (new) 2025/11/03 02:22:51 DEBUG : yam: size = 11 OK 2025/11/03 02:22:51 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 02:22:51 INFO : yam: Copied (new) 2025/11/03 02:22:51 DEBUG : Waiting for deletions to finish 2025/11/03 02:22:51 DEBUG : potato: size = 14 OK 2025/11/03 02:22:51 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/03 02:22:51 DEBUG : potato: Unchanged skipping 2025/11/03 02:22:51 INFO : B2 bucket rclone-test-lalomib5pobo: Making map for --track-renames 2025/11/03 02:22:51 INFO : B2 bucket rclone-test-lalomib5pobo: Finished making map for --track-renames 2025/11/03 02:22:51 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for checks to finish 2025/11/03 02:22:51 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for renames to finish 2025/11/03 02:22:51 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:22:51 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:22:51 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:22:51 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/03 02:22:51 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for transfers to finish 2025/11/03 02:22:52 DEBUG : yaml: size = 11 OK 2025/11/03 02:22:52 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 02:22:52 INFO : yaml: Copied (new) 2025/11/03 02:22:52 ERROR : B2 bucket rclone-test-lalomib5pobo: not deleting files as there were IO errors 2025/11/03 02:22:52 ERROR : B2 bucket rclone-test-lalomib5pobo: 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.51s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" sync_test.go:1640: Can track renames: true 2025/11/03 02:22:53 INFO : B2 bucket rclone-test-lalomib5pobo: Making map for --track-renames 2025/11/03 02:22:53 INFO : B2 bucket rclone-test-lalomib5pobo: Finished making map for --track-renames 2025/11/03 02:22:53 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for checks to finish 2025/11/03 02:22:53 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/03 02:22:53 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/03 02:22:53 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for renames to finish 2025/11/03 02:22:53 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for transfers to finish 2025/11/03 02:22:53 DEBUG : yam: size = 11 OK 2025/11/03 02:22:53 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 02:22:53 INFO : yam: Copied (new) 2025/11/03 02:22:53 DEBUG : potato: size = 14 OK 2025/11/03 02:22:53 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/03 02:22:53 INFO : potato: Copied (new) 2025/11/03 02:22:53 DEBUG : Waiting for deletions to finish 2025/11/03 02:22:54 DEBUG : potato: size = 14 OK 2025/11/03 02:22:54 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/03 02:22:54 INFO : B2 bucket rclone-test-lalomib5pobo: Making map for --track-renames 2025/11/03 02:22:54 DEBUG : potato: Unchanged skipping 2025/11/03 02:22:54 INFO : B2 bucket rclone-test-lalomib5pobo: Finished making map for --track-renames 2025/11/03 02:22:54 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for checks to finish 2025/11/03 02:22:54 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for renames to finish 2025/11/03 02:22:54 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:22:54 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:22:54 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:22:54 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/03 02:22:54 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for transfers to finish 2025/11/03 02:22:54 DEBUG : yaml: size = 11 OK 2025/11/03 02:22:54 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 02:22:54 INFO : yaml: Copied (new) 2025/11/03 02:22:54 ERROR : B2 bucket rclone-test-lalomib5pobo: not deleting files as there were IO errors 2025/11/03 02:22:54 ERROR : B2 bucket rclone-test-lalomib5pobo: 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.74s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" sync_test.go:1679: Can track renames: true 2025/11/03 02:22:55 INFO : B2 bucket rclone-test-lalomib5pobo: Making map for --track-renames 2025/11/03 02:22:55 INFO : B2 bucket rclone-test-lalomib5pobo: Finished making map for --track-renames 2025/11/03 02:22:55 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for checks to finish 2025/11/03 02:22:55 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/03 02:22:55 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/11/03 02:22:55 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for renames to finish 2025/11/03 02:22:55 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for transfers to finish 2025/11/03 02:22:56 DEBUG : sub/yam: size = 11 OK 2025/11/03 02:22:56 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 02:22:56 INFO : sub/yam: Copied (new) 2025/11/03 02:22:56 DEBUG : potato: size = 14 OK 2025/11/03 02:22:56 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/03 02:22:56 INFO : potato: Copied (new) 2025/11/03 02:22:56 DEBUG : Waiting for deletions to finish 2025/11/03 02:22:56 DEBUG : potato: size = 14 OK 2025/11/03 02:22:56 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/03 02:22:56 DEBUG : potato: Unchanged skipping 2025/11/03 02:22:56 INFO : B2 bucket rclone-test-lalomib5pobo: Making map for --track-renames 2025/11/03 02:22:56 INFO : B2 bucket rclone-test-lalomib5pobo: Finished making map for --track-renames 2025/11/03 02:22:56 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for checks to finish 2025/11/03 02:22:56 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for renames to finish 2025/11/03 02:22:57 ERROR : sub/yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:22:57 ERROR : sub/yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:22:57 DEBUG : yam: Failed to rename to "sub/yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:22:57 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/03 02:22:57 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for transfers to finish 2025/11/03 02:22:57 DEBUG : yam: size = 11 OK 2025/11/03 02:22:57 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 02:22:57 INFO : yam: Copied (new) 2025/11/03 02:22:57 ERROR : B2 bucket rclone-test-lalomib5pobo: not deleting files as there were IO errors 2025/11/03 02:22:57 ERROR : B2 bucket rclone-test-lalomib5pobo: 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.70s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:22:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/03 02:22:58 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/11/03 02:22:58 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for checks to finish 2025/11/03 02:22:58 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for transfers to finish 2025/11/03 02:22:59 DEBUG : nested/sub dir/file: size = 6 OK 2025/11/03 02:22:59 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/11/03 02:22:59 INFO : nested/sub dir/file: Copied (new) 2025/11/03 02:22:59 INFO : nested/sub dir/file: Deleted 2025/11/03 02:22:59 DEBUG : sub dir/hello world: size = 11 OK 2025/11/03 02:22:59 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/03 02:22:59 INFO : sub dir/hello world: Copied (new) 2025/11/03 02:22:59 INFO : sub dir/hello world: Deleted 2025/11/03 02:22:59 ERROR : Local file system at /tmp/rclone2894148106: 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.38s) === RUN TestServerSideMove run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:22:59 DEBUG : Creating backend with remote "TestB2:rclone-test-talumoq3wuku" 2025/11/03 02:22:59 DEBUG : Config file has changed externally - reloading sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-lalomib5pobo -> B2 bucket rclone-test-talumoq3wuku 2025/11/03 02:23:04 DEBUG : empty space: size = 1 OK 2025/11/03 02:23:04 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/03 02:23:04 DEBUG : empty space: Unchanged skipping 2025/11/03 02:23:04 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/03 02:23:04 DEBUG : potato3: size = 68 (B2 bucket rclone-test-lalomib5pobo) 2025/11/03 02:23:04 DEBUG : potato3: size = 60 (B2 bucket rclone-test-talumoq3wuku) 2025/11/03 02:23:04 DEBUG : potato3: Sizes differ 2025/11/03 02:23:04 DEBUG : B2 bucket rclone-test-talumoq3wuku: Waiting for checks to finish 2025/11/03 02:23:04 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:04 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:04 INFO : empty space: Deleted 2025/11/03 02:23:04 DEBUG : B2 bucket rclone-test-talumoq3wuku: Waiting for transfers to finish 2025/11/03 02:23:04 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:04 ERROR : potato3: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1911 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideMove 2025/11/03 02:23:04 DEBUG : B2 bucket rclone-test-talumoq3wuku: Purge remote 2025/11/03 02:23:04 INFO : B2 bucket rclone-test-talumoq3wuku: cleaning bucket "rclone-test-talumoq3wuku" of all files 2025/11/03 02:23:04 DEBUG : empty space: Deleting (id "4_zff126d73806d4b7b9fa80e1b_f114bdaacbdb513ce_d20251103_m022303_c001_v0001171_t0056_u01762136583281") 2025/11/03 02:23:04 DEBUG : potato3: Deleting (id "4_zff126d73806d4b7b9fa80e1b_f114bdaacbdb513d0_d20251103_m022303_c001_v0001171_t0011_u01762136583557") --- FAIL: TestServerSideMove (6.30s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:23:06 DEBUG : Creating backend with remote "TestB2:rclone-test-dobicaj3faji" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-lalomib5pobo -> B2 bucket rclone-test-dobicaj3faji 2025/11/03 02:23:12 DEBUG : empty space: Excluded (Size Filter) 2025/11/03 02:23:12 DEBUG : empty space: Excluded 2025/11/03 02:23:12 DEBUG : empty space: Excluded (Size Filter) 2025/11/03 02:23:12 DEBUG : empty space: Excluded 2025/11/03 02:23:12 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/03 02:23:12 DEBUG : potato3: size = 68 (B2 bucket rclone-test-lalomib5pobo) 2025/11/03 02:23:12 DEBUG : potato3: size = 60 (B2 bucket rclone-test-dobicaj3faji) 2025/11/03 02:23:12 DEBUG : potato3: Sizes differ 2025/11/03 02:23:12 DEBUG : B2 bucket rclone-test-dobicaj3faji: Waiting for checks to finish 2025/11/03 02:23:12 DEBUG : B2 bucket rclone-test-dobicaj3faji: Waiting for transfers to finish 2025/11/03 02:23:12 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:12 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:12 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:12 ERROR : potato3: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1924 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideMoveWithFilter 2025/11/03 02:23:12 DEBUG : B2 bucket rclone-test-dobicaj3faji: Purge remote 2025/11/03 02:23:12 INFO : B2 bucket rclone-test-dobicaj3faji: cleaning bucket "rclone-test-dobicaj3faji" of all files 2025/11/03 02:23:12 DEBUG : empty space: Deleting (id "4_zcf328d53806d4b7b9fa80e1b_f113790be2adab1a5_d20251103_m022310_c001_v0001032_t0004_u01762136590818") 2025/11/03 02:23:12 DEBUG : potato3: Deleting (id "4_zcf328d53806d4b7b9fa80e1b_f113790be2adab1a7_d20251103_m022311_c001_v0001032_t0030_u01762136591296") --- FAIL: TestServerSideMoveWithFilter (7.93s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:23:14 DEBUG : Creating backend with remote "TestB2:rclone-test-radacor1zoze" 2025/11/03 02:23:16 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-lalomib5pobo -> B2 bucket rclone-test-radacor1zoze 2025/11/03 02:23:18 DEBUG : empty space: Clearing upload URL because of error: incident id eda8b059104e-f961f9e214dc3087 (500 internal_error) 2025/11/03 02:23:18 DEBUG : pacer: low level retry 1/1 (error incident id eda8b059104e-f961f9e214dc3087 (500 internal_error)) 2025/11/03 02:23:18 DEBUG : pacer: Rate limited, increasing sleep to 20ms run.go:286: Retry Put of "empty space" to B2 bucket rclone-test-radacor1zoze: 1/10 (incident id eda8b059104e-f961f9e214dc3087 (500 internal_error)) 2025/11/03 02:23:20 DEBUG : pacer: Reducing sleep to 10ms 2025/11/03 02:23:22 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/03 02:23:22 DEBUG : empty space: size = 1 OK 2025/11/03 02:23:22 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/03 02:23:22 DEBUG : empty space: Unchanged skipping 2025/11/03 02:23:22 DEBUG : potato3: size = 68 (B2 bucket rclone-test-lalomib5pobo) 2025/11/03 02:23:22 DEBUG : potato3: size = 60 (B2 bucket rclone-test-radacor1zoze) 2025/11/03 02:23:22 DEBUG : potato3: Sizes differ 2025/11/03 02:23:22 DEBUG : B2 bucket rclone-test-radacor1zoze: Waiting for checks to finish 2025/11/03 02:23:22 INFO : empty space: Deleted 2025/11/03 02:23:22 DEBUG : B2 bucket rclone-test-radacor1zoze: Waiting for transfers to finish 2025/11/03 02:23:22 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:22 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:22 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:22 ERROR : potato3: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1931 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideMoveDeleteEmptySourceDirs 2025/11/03 02:23:22 DEBUG : B2 bucket rclone-test-radacor1zoze: Purge remote 2025/11/03 02:23:22 INFO : B2 bucket rclone-test-radacor1zoze: cleaning bucket "rclone-test-radacor1zoze" of all files 2025/11/03 02:23:22 DEBUG : empty space: Deleting (id "4_z3fc2ad33806d4b7b9fa80e1b_f112afe686949ae32_d20251103_m022321_c001_v0001102_t0004_u01762136601121") 2025/11/03 02:23:22 DEBUG : potato3: Deleting (id "4_z3fc2ad33806d4b7b9fa80e1b_f112afe686949ae34_d20251103_m022321_c001_v0001102_t0059_u01762136601394") --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (10.20s) === RUN TestSyncCopyDest run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:23:24 DEBUG : Creating backend with remote "TestB2:rclone-test-lalomib5pobo/dst" 2025/11/03 02:23:25 DEBUG : Creating backend with remote "TestB2:rclone-test-lalomib5pobo/CopyDest" 2025/11/03 02:23:25 DEBUG : pacer: low level retry 1/10 (error incident id 3894aee67876-38af7069b23b29e3 (500 internal_error)) 2025/11/03 02:23:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/03 02:23:26 DEBUG : pacer: low level retry 2/10 (error incident id 3894aee67876-3d1f4d06964017ec (500 internal_error)) 2025/11/03 02:23:26 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/11/03 02:23:26 DEBUG : pacer: low level retry 3/10 (error incident id 3894aee67876-bfb0b33a80f24d0a (500 internal_error)) 2025/11/03 02:23:26 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/11/03 02:23:26 DEBUG : pacer: low level retry 4/10 (error incident id 3894aee67876-fe6f3befc952880f (500 internal_error)) 2025/11/03 02:23:26 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/11/03 02:23:26 DEBUG : pacer: low level retry 5/10 (error incident id 3894aee67876-94bdbebb169bcad3 (500 internal_error)) 2025/11/03 02:23:26 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/11/03 02:23:26 DEBUG : pacer: low level retry 6/10 (error incident id 3894aee67876-603afb168cb82b20 (500 internal_error)) 2025/11/03 02:23:26 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/11/03 02:23:27 DEBUG : pacer: low level retry 7/10 (error incident id 3894aee67876-7d5fa347fb036e47 (500 internal_error)) 2025/11/03 02:23:27 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/11/03 02:23:27 DEBUG : pacer: low level retry 8/10 (error incident id 3894aee67876-e759417dad980567 (500 internal_error)) 2025/11/03 02:23:27 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2025/11/03 02:23:29 DEBUG : pacer: low level retry 9/10 (error incident id 3894aee67876-1f4d7649afb83542 (500 internal_error)) 2025/11/03 02:23:29 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2025/11/03 02:23:31 DEBUG : pacer: low level retry 10/10 (error incident id 3894aee67876-ed213f0f8d7bb56a (500 internal_error)) 2025/11/03 02:23:31 DEBUG : pacer: Rate limited, increasing sleep to 10.24s sync_test.go:2281: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2281 Error: Received unexpected error: failed to make fs for --copy-dest ["TestB2:rclone-test-lalomib5pobo/CopyDest"]: failed to authorize account: failed to authenticate: incident id 3894aee67876-ed213f0f8d7bb56a (500 internal_error) Test: TestSyncCopyDest --- FAIL: TestSyncCopyDest (8.11s) === RUN TestSyncBackupDir run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:23:34 DEBUG : Creating backend with remote "TestB2:rclone-test-lalomib5pobo/dst" 2025/11/03 02:23:35 DEBUG : Creating backend with remote "TestB2:rclone-test-lalomib5pobo/backup" 2025/11/03 02:23:37 DEBUG : one: size = 4 (Local file system at /tmp/rclone2894148106) 2025/11/03 02:23:37 DEBUG : one: size = 3 (B2 bucket rclone-test-lalomib5pobo path dst) 2025/11/03 02:23:37 DEBUG : one: Sizes differ 2025/11/03 02:23:37 DEBUG : two: size = 3 OK 2025/11/03 02:23:37 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/03 02:23:37 DEBUG : two: Unchanged skipping 2025/11/03 02:23:37 DEBUG : B2 bucket rclone-test-lalomib5pobo path dst: Waiting for checks to finish 2025/11/03 02:23:38 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:38 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:38 DEBUG : B2 bucket rclone-test-lalomib5pobo path dst: Waiting for transfers to finish 2025/11/03 02:23:38 ERROR : B2 bucket rclone-test-lalomib5pobo path dst: not deleting files as there were IO errors 2025/11/03 02:23:38 ERROR : B2 bucket rclone-test-lalomib5pobo 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 (7.38s) === RUN TestSyncBackupDirWithSuffix run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:23:41 DEBUG : Creating backend with remote "TestB2:rclone-test-lalomib5pobo/dst" 2025/11/03 02:23:43 DEBUG : Creating backend with remote "TestB2:rclone-test-lalomib5pobo/backup" 2025/11/03 02:23:44 DEBUG : one: size = 4 (Local file system at /tmp/rclone2894148106) 2025/11/03 02:23:44 DEBUG : one: size = 3 (B2 bucket rclone-test-lalomib5pobo path dst) 2025/11/03 02:23:44 DEBUG : one: Sizes differ 2025/11/03 02:23:44 DEBUG : B2 bucket rclone-test-lalomib5pobo path dst: Waiting for checks to finish 2025/11/03 02:23:44 DEBUG : two: size = 3 OK 2025/11/03 02:23:44 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/03 02:23:44 DEBUG : two: Unchanged skipping 2025/11/03 02:23:45 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:45 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:45 DEBUG : B2 bucket rclone-test-lalomib5pobo path dst: Waiting for transfers to finish 2025/11/03 02:23:45 ERROR : B2 bucket rclone-test-lalomib5pobo path dst: not deleting files as there were IO errors 2025/11/03 02:23:45 ERROR : B2 bucket rclone-test-lalomib5pobo 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 (7.04s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:23:49 DEBUG : Creating backend with remote "TestB2:rclone-test-lalomib5pobo/dst" 2025/11/03 02:23:50 DEBUG : Creating backend with remote "TestB2:rclone-test-lalomib5pobo/backup" 2025/11/03 02:23:52 DEBUG : B2 bucket rclone-test-lalomib5pobo path dst: Waiting for checks to finish 2025/11/03 02:23:52 DEBUG : one: size = 4 (Local file system at /tmp/rclone2894148106) 2025/11/03 02:23:52 DEBUG : two: size = 3 OK 2025/11/03 02:23:52 DEBUG : one: size = 3 (B2 bucket rclone-test-lalomib5pobo path dst) 2025/11/03 02:23:52 DEBUG : one: Sizes differ 2025/11/03 02:23:52 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/03 02:23:52 DEBUG : two: Unchanged skipping 2025/11/03 02:23:53 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:53 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:53 DEBUG : B2 bucket rclone-test-lalomib5pobo path dst: Waiting for transfers to finish 2025/11/03 02:23:53 ERROR : B2 bucket rclone-test-lalomib5pobo path dst: not deleting files as there were IO errors 2025/11/03 02:23:53 ERROR : B2 bucket rclone-test-lalomib5pobo 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 (7.19s) === RUN TestSyncBackupDirSuffixOnly run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:23:56 DEBUG : Creating backend with remote "TestB2:rclone-test-lalomib5pobo/dst" 2025/11/03 02:23:57 DEBUG : one: size = 4 (Local file system at /tmp/rclone2894148106) 2025/11/03 02:23:57 DEBUG : one: size = 3 (B2 bucket rclone-test-lalomib5pobo path dst) 2025/11/03 02:23:57 DEBUG : one: Sizes differ 2025/11/03 02:23:57 DEBUG : two: size = 3 OK 2025/11/03 02:23:57 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/03 02:23:57 DEBUG : two: Unchanged skipping 2025/11/03 02:23:57 DEBUG : B2 bucket rclone-test-lalomib5pobo path dst: Waiting for checks to finish 2025/11/03 02:23:58 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:58 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:23:58 DEBUG : B2 bucket rclone-test-lalomib5pobo path dst: Waiting for transfers to finish 2025/11/03 02:23:58 ERROR : B2 bucket rclone-test-lalomib5pobo path dst: not deleting files as there were IO errors 2025/11/03 02:23:58 ERROR : B2 bucket rclone-test-lalomib5pobo 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.23s) === RUN TestSyncSuffix run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:24:00 DEBUG : Creating backend with remote "TestB2:rclone-test-lalomib5pobo/dst" 2025/11/03 02:24:01 DEBUG : one: size = 4 (Local file system at /tmp/rclone2894148106) 2025/11/03 02:24:01 DEBUG : one: size = 3 (B2 bucket rclone-test-lalomib5pobo path dst) 2025/11/03 02:24:01 DEBUG : one: Sizes differ 2025/11/03 02:24:03 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:24:03 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.77s) === RUN TestSyncSuffixKeepExtension run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:24:05 DEBUG : Creating backend with remote "TestB2:rclone-test-lalomib5pobo/dst" 2025/11/03 02:24:06 DEBUG : one: size = 4 (Local file system at /tmp/rclone2894148106) 2025/11/03 02:24:06 DEBUG : one: size = 3 (B2 bucket rclone-test-lalomib5pobo path dst) 2025/11/03 02:24:06 DEBUG : one: Sizes differ 2025/11/03 02:24:08 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:24:08 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.95s) === RUN TestTransformFile run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:24:08 DEBUG : empty_dir: Making directory with metadata 2025/11/03 02:24:08 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/11/03 02:24:08 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:24:08 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/03 02:24:08 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/03 02:24:09 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/03 02:24:09 INFO : tictacempty_dir: Making directory 2025/11/03 02:24:09 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/03 02:24:09 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:24:09 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:24:09 INFO : tictactoe: Making directory 2025/11/03 02:24:09 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:24:09 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:24:09 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/03 02:24:09 INFO : tictactoe/tictactoe: Making directory 2025/11/03 02:24:09 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/03 02:24:09 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/11/03 02:24:09 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/03 02:24:09 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/03 02:24:09 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/03 02:24:09 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/03 02:24:09 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for checks to finish 2025/11/03 02:24:09 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for transfers to finish 2025/11/03 02:24:09 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2025/11/03 02:24:09 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/03 02:24:09 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/11/03 02:24:09 INFO : toe/toe/toe.txt: Deleted 2025/11/03 02:24:09 ERROR : Local file system at /tmp/rclone2894148106: 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.35s) === RUN TestManualTransformFile run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:24:10 DEBUG : Reset feature "Copy" 2025/11/03 02:24:10 DEBUG : Reset feature "Move" 2025/11/03 02:24:10 DEBUG : Reset feature "Copy" 2025/11/03 02:24:10 DEBUG : Reset feature "Move" 2025/11/03 02:24:10 DEBUG : empty_dir: Making directory with metadata 2025/11/03 02:24:10 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/11/03 02:24:10 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:24:10 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/03 02:24:10 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/03 02:24:10 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/03 02:24:10 INFO : tictacempty_dir: Making directory 2025/11/03 02:24:10 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/03 02:24:10 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:24:10 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:24:10 INFO : tictactoe: Making directory 2025/11/03 02:24:10 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:24:10 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:24:10 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/03 02:24:10 INFO : tictactoe/tictactoe: Making directory 2025/11/03 02:24:10 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/03 02:24:10 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/11/03 02:24:10 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/03 02:24:10 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/03 02:24:10 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/03 02:24:10 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/03 02:24:10 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for checks to finish 2025/11/03 02:24:10 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for transfers to finish 2025/11/03 02:24:11 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2025/11/03 02:24:11 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/03 02:24:11 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/11/03 02:24:11 INFO : toe/toe/toe.txt: Deleted 2025/11/03 02:24:11 ERROR : Local file system at /tmp/rclone2894148106: 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.87s) === RUN TestBase64 run.go:185: Remote "B2 bucket rclone-test-lalomib5pobo", Local "Local file system at /tmp/rclone2894148106", Modify Window "1ms" 2025/11/03 02:24:12 DEBUG : toe: transformed to: dG9l 2025/11/03 02:24:12 DEBUG : toe: transformed to: dG9l 2025/11/03 02:24:12 INFO : dG9l: Making directory 2025/11/03 02:24:12 DEBUG : toe: transformed to: dG9l 2025/11/03 02:24:12 DEBUG : toe: transformed to: dG9l 2025/11/03 02:24:12 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/11/03 02:24:12 INFO : dG9l/dG9l: Making directory 2025/11/03 02:24:12 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/11/03 02:24:12 DEBUG : toe.txt: transformed to: dG9lLnR4dA== 2025/11/03 02:24:12 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/11/03 02:24:12 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/03 02:24:12 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/11/03 02:24:12 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for checks to finish 2025/11/03 02:24:12 DEBUG : B2 bucket rclone-test-lalomib5pobo: Waiting for transfers to finish 2025/11/03 02:24:12 DEBUG : dG9l/dG9l/dG9lLnR4dA==: size = 11 OK 2025/11/03 02:24:12 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/03 02:24:12 INFO : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA== 2025/11/03 02:24:12 ERROR : B2 bucket rclone-test-lalomib5pobo: not deleting files as there were IO errors 2025/11/03 02:24:12 ERROR : B2 bucket rclone-test-lalomib5pobo: 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.06s) FAIL 2025/11/03 02:24:13 DEBUG : B2 bucket rclone-test-lalomib5pobo: Purge remote 2025/11/03 02:24:13 INFO : B2 bucket rclone-test-lalomib5pobo: cleaning bucket "rclone-test-lalomib5pobo" of all files 2025/11/03 02:24:13 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558bce_d20251103_m022413_c001_v7007000_t0000_u01762136653001") 2025/11/03 02:24:13 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc494e0_d20251103_m022412_c001_v0001136_t0047_u01762136652504") 2025/11/03 02:24:13 DEBUG : dst/one: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558bc3_d20251103_m022408_c001_v7007000_t0000_u01762136648338") 2025/11/03 02:24:13 DEBUG : dst/one: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce597f0_d20251103_m022404_c001_v0001120_t0054_u01762136644131") 2025/11/03 02:24:13 DEBUG : dst/one: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558ba9_d20251103_m022403_c001_v7007000_t0000_u01762136643389") 2025/11/03 02:24:13 DEBUG : dst/one: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc494ab_d20251103_m022359_c001_v0001136_t0012_u01762136639362") 2025/11/03 02:24:13 DEBUG : dst/one: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558b96_d20251103_m022358_c001_v7007000_t0000_u01762136638625") 2025/11/03 02:24:13 DEBUG : dst/one: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce597dc_d20251103_m022354_c001_v0001120_t0052_u01762136634490") 2025/11/03 02:24:13 DEBUG : dst/one: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558b74_d20251103_m022353_c001_v7007000_t0000_u01762136633385") 2025/11/03 02:24:13 DEBUG : dst/one: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc49490_d20251103_m022347_c001_v0001136_t0036_u01762136627303") 2025/11/03 02:24:14 DEBUG : dst/one: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558b68_d20251103_m022346_c001_v7007000_t0000_u01762136626206") 2025/11/03 02:24:14 DEBUG : dst/one: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce597b8_d20251103_m022340_c001_v0001120_t0014_u01762136620268") 2025/11/03 02:24:14 DEBUG : dst/one: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558b5c_d20251103_m022339_c001_v7007000_t0000_u01762136619162") 2025/11/03 02:24:14 DEBUG : dst/one: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc49466_d20251103_m022332_c001_v0001136_t0030_u01762136612898") 2025/11/03 02:24:14 DEBUG : dst/three.txt: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558bc4_d20251103_m022408_c001_v7007000_t0000_u01762136648523") 2025/11/03 02:24:14 DEBUG : dst/three.txt: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce597f2_d20251103_m022405_c001_v0001120_t0001_u01762136645044") 2025/11/03 02:24:14 DEBUG : dst/three.txt: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558bab_d20251103_m022403_c001_v7007000_t0000_u01762136643574") 2025/11/03 02:24:14 DEBUG : dst/three.txt: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc494b1_d20251103_m022400_c001_v0001136_t0030_u01762136640155") 2025/11/03 02:24:14 DEBUG : dst/three.txt: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558b97_d20251103_m022358_c001_v7007000_t0000_u01762136638809") 2025/11/03 02:24:14 DEBUG : dst/three.txt: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce597e0_d20251103_m022355_c001_v0001120_t0042_u01762136635486") 2025/11/03 02:24:14 DEBUG : dst/three.txt: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558b75_d20251103_m022353_c001_v7007000_t0000_u01762136633570") 2025/11/03 02:24:14 DEBUG : dst/three.txt: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc49494_d20251103_m022348_c001_v0001136_t0033_u01762136628464") 2025/11/03 02:24:14 DEBUG : dst/three.txt: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558b69_d20251103_m022346_c001_v7007000_t0000_u01762136626392") 2025/11/03 02:24:14 DEBUG : dst/three.txt: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce597ba_d20251103_m022341_c001_v0001120_t0027_u01762136621299") 2025/11/03 02:24:14 DEBUG : dst/three.txt: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558b5d_d20251103_m022339_c001_v7007000_t0000_u01762136619346") 2025/11/03 02:24:14 DEBUG : dst/three.txt: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc4946c_d20251103_m022334_c001_v0001136_t0005_u01762136614229") 2025/11/03 02:24:14 DEBUG : dst/two: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558bc6_d20251103_m022408_c001_v7007000_t0000_u01762136648708") 2025/11/03 02:24:15 DEBUG : dst/two: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc494bf_d20251103_m022404_c001_v0001136_t0051_u01762136644427") 2025/11/03 02:24:15 DEBUG : dst/two: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558bad_d20251103_m022403_c001_v7007000_t0000_u01762136643760") 2025/11/03 02:24:15 DEBUG : dst/two: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce597ec_d20251103_m022359_c001_v0001120_t0026_u01762136639669") 2025/11/03 02:24:15 DEBUG : dst/two: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558b99_d20251103_m022358_c001_v7007000_t0000_u01762136638993") 2025/11/03 02:24:15 DEBUG : dst/two: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc494a1_d20251103_m022355_c001_v0001136_t0035_u01762136635161") 2025/11/03 02:24:15 DEBUG : dst/two: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558b77_d20251103_m022353_c001_v7007000_t0000_u01762136633762") 2025/11/03 02:24:15 DEBUG : dst/two: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce597c8_d20251103_m022347_c001_v0001120_t0029_u01762136627984") 2025/11/03 02:24:15 DEBUG : dst/two: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558b6a_d20251103_m022346_c001_v7007000_t0000_u01762136626575") 2025/11/03 02:24:15 DEBUG : dst/two: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc4947e_d20251103_m022340_c001_v0001136_t0005_u01762136620934") 2025/11/03 02:24:15 DEBUG : dst/two: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558b5e_d20251103_m022339_c001_v7007000_t0000_u01762136619531") 2025/11/03 02:24:15 DEBUG : dst/two: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce597ad_d20251103_m022333_c001_v0001120_t0012_u01762136613851") 2025/11/03 02:24:15 DEBUG : empty space: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f423e001ce83174a1_d20251103_m022322_c001_v7007000_t0000_u01762136602445") 2025/11/03 02:24:15 DEBUG : empty space: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc49433_d20251103_m022315_c001_v0001136_t0012_u01762136595818") 2025/11/03 02:24:15 DEBUG : empty space: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3eb0_d20251103_m022313_c001_v7007000_t0000_u01762136593473") 2025/11/03 02:24:15 DEBUG : empty space: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce59781_d20251103_m022308_c001_v0001120_t0000_u01762136588028") 2025/11/03 02:24:15 DEBUG : empty space: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e86_d20251103_m022304_c001_v7007000_t0000_u01762136584321") 2025/11/03 02:24:15 DEBUG : empty space: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc4940f_d20251103_m022300_c001_v0001136_t0008_u01762136580919") 2025/11/03 02:24:15 DEBUG : empty space: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e45_d20251103_m022249_c001_v7007000_t0000_u01762136569280") 2025/11/03 02:24:15 DEBUG : empty space: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce5974a_d20251103_m022247_c001_v0001120_t0000_u01762136567224") 2025/11/03 02:24:15 DEBUG : nested/sub dir/file: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e6b_d20251103_m022259_c001_v7007000_t0000_u01762136579424") 2025/11/03 02:24:15 DEBUG : nested/sub dir/file: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce59769_d20251103_m022258_c001_v0001120_t0053_u01762136578791") 2025/11/03 02:24:16 DEBUG : potato: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e62_d20251103_m022257_c001_v7007000_t0000_u01762136577866") 2025/11/03 02:24:16 DEBUG : potato: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc493fd_d20251103_m022256_c001_v0001136_t0050_u01762136576084") 2025/11/03 02:24:16 DEBUG : potato: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e50_d20251103_m022255_c001_v7007000_t0000_u01762136575162") 2025/11/03 02:24:16 DEBUG : potato: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc493f7_d20251103_m022253_c001_v0001136_t0004_u01762136573338") 2025/11/03 02:24:16 DEBUG : potato: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e49_d20251103_m022252_c001_v7007000_t0000_u01762136572424") 2025/11/03 02:24:16 DEBUG : potato: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce59755_d20251103_m022249_c001_v0001120_t0023_u01762136569827") 2025/11/03 02:24:16 DEBUG : potato2: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f423e001ce83174a6_d20251103_m022323_c001_v7007000_t0000_u01762136603858") 2025/11/03 02:24:16 DEBUG : potato2: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce5978d_d20251103_m022315_c001_v0001120_t0036_u01762136595112") 2025/11/03 02:24:16 DEBUG : potato2: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3eb1_d20251103_m022313_c001_v7007000_t0000_u01762136593656") 2025/11/03 02:24:16 DEBUG : potato2: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc4941b_d20251103_m022307_c001_v0001136_t0020_u01762136587210") 2025/11/03 02:24:16 DEBUG : potato2: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e94_d20251103_m022305_c001_v7007000_t0000_u01762136585720") 2025/11/03 02:24:16 DEBUG : potato2: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce5976d_d20251103_m022300_c001_v0001120_t0052_u01762136580542") 2025/11/03 02:24:16 DEBUG : potato3: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f423e001ce83174a7_d20251103_m022324_c001_v7007000_t0000_u01762136604042") 2025/11/03 02:24:16 DEBUG : potato3: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce59791_d20251103_m022316_c001_v0001120_t0008_u01762136596330") 2025/11/03 02:24:16 DEBUG : potato3: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3eb3_d20251103_m022313_c001_v7007000_t0000_u01762136593839") 2025/11/03 02:24:16 DEBUG : potato3: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc4941f_d20251103_m022308_c001_v0001136_t0042_u01762136588463") 2025/11/03 02:24:16 DEBUG : potato3: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e97_d20251103_m022305_c001_v7007000_t0000_u01762136585904") 2025/11/03 02:24:16 DEBUG : potato3: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce59771_d20251103_m022301_c001_v0001120_t0049_u01762136581245") 2025/11/03 02:24:16 DEBUG : sub dir/hello world: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e6c_d20251103_m022259_c001_v7007000_t0000_u01762136579608") 2025/11/03 02:24:16 DEBUG : sub dir/hello world: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc49409_d20251103_m022258_c001_v0001136_t0026_u01762136578780") 2025/11/03 02:24:16 DEBUG : sub dir/hello world: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e43_d20251103_m022246_c001_v7007000_t0000_u01762136566856") 2025/11/03 02:24:16 DEBUG : sub dir/hello world: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce59740_d20251103_m022243_c001_v0001120_t0019_u01762136563196") 2025/11/03 02:24:17 DEBUG : sub dir/hello world: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e3e_d20251103_m022242_c001_v7007000_t0000_u01762136562828") 2025/11/03 02:24:17 DEBUG : sub dir/hello world: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce5972a_d20251103_m022239_c001_v0001120_t0000_u01762136559345") 2025/11/03 02:24:17 DEBUG : sub dir/hello world: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e38_d20251103_m022238_c001_v7007000_t0000_u01762136558980") 2025/11/03 02:24:17 DEBUG : sub dir/hello world: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce59724_d20251103_m022235_c001_v0001120_t0026_u01762136555204") 2025/11/03 02:24:17 DEBUG : sub/yam: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e64_d20251103_m022258_c001_v7007000_t0000_u01762136578049") 2025/11/03 02:24:17 DEBUG : sub/yam: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce5975f_d20251103_m022256_c001_v0001120_t0045_u01762136576096") 2025/11/03 02:24:17 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558bcd_d20251103_m022411_c001_v7007000_t0000_u01762136651937") 2025/11/03 02:24:17 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce597f8_d20251103_m022410_c001_v0001120_t0039_u01762136650981") 2025/11/03 02:24:17 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f408b8c205e558bcc_d20251103_m022410_c001_v7007000_t0000_u01762136650064") 2025/11/03 02:24:17 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc494cf_d20251103_m022409_c001_v0001136_t0053_u01762136649298") 2025/11/03 02:24:17 DEBUG : yam: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e66_d20251103_m022258_c001_v7007000_t0000_u01762136578232") 2025/11/03 02:24:17 DEBUG : yam: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce59763_d20251103_m022257_c001_v0001120_t0038_u01762136577343") 2025/11/03 02:24:17 DEBUG : yam: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e52_d20251103_m022255_c001_v7007000_t0000_u01762136575351") 2025/11/03 02:24:17 DEBUG : yam: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce59759_d20251103_m022253_c001_v0001120_t0050_u01762136573350") 2025/11/03 02:24:17 DEBUG : yam: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e4a_d20251103_m022252_c001_v7007000_t0000_u01762136572607") 2025/11/03 02:24:17 DEBUG : yam: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f1003a2c65cc493f1_d20251103_m022250_c001_v0001136_t0029_u01762136570380") 2025/11/03 02:24:17 DEBUG : yaml: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e53_d20251103_m022255_c001_v7007000_t0000_u01762136575537") 2025/11/03 02:24:17 DEBUG : yaml: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce5975b_d20251103_m022254_c001_v0001120_t0007_u01762136574659") 2025/11/03 02:24:17 DEBUG : yaml: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f404df7c3921c3e4b_d20251103_m022252_c001_v7007000_t0000_u01762136572790") 2025/11/03 02:24:17 DEBUG : yaml: Deleting (id "4_zaf42fda3805d4b7b9fa80e1b_f108b17b7fce59757_d20251103_m022251_c001_v0001120_t0051_u01762136571875") "./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|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime|TestTransformFile)$'" - Finished ERROR in 1m45.800702933s (try 4/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]