"./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 3/5) 2025/11/02 04:03:11 DEBUG : Creating backend with remote "TestB2:rclone-test-xubeseb3wewo" 2025/11/02 04:03:11 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 04:03:12 DEBUG : Creating backend with remote "/tmp/rclone2536651817" === RUN TestServerSideCopy run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:03:14 DEBUG : Creating backend with remote "TestB2:rclone-test-zivojol9zana" sync_test.go:620: Server side copy (if possible) B2 bucket rclone-test-xubeseb3wewo -> B2 bucket rclone-test-zivojol9zana 2025/11/02 04:03:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 04:03:15 DEBUG : B2 bucket rclone-test-zivojol9zana: Waiting for checks to finish 2025/11/02 04:03:15 DEBUG : B2 bucket rclone-test-zivojol9zana: Waiting for transfers to finish 2025/11/02 04:03:16 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:03:16 DEBUG : B2 bucket rclone-test-zivojol9zana: Purge remote 2025/11/02 04:03:16 INFO : B2 bucket rclone-test-zivojol9zana: cleaning bucket "rclone-test-zivojol9zana" of all files --- FAIL: TestServerSideCopy (4.75s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:03:17 DEBUG : Creating backend with remote "TestB2:rclone-test-manutaj8piza" sync_test.go:656: Server side copy (if possible) B2 bucket rclone-test-xubeseb3wewo -> B2 bucket rclone-test-manutaj8piza 2025/11/02 04:03:19 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 04:03:19 DEBUG : B2 bucket rclone-test-manutaj8piza: Waiting for checks to finish 2025/11/02 04:03:19 DEBUG : B2 bucket rclone-test-manutaj8piza: Waiting for transfers to finish 2025/11/02 04:03:19 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:03:19 DEBUG : B2 bucket rclone-test-manutaj8piza: Purge remote 2025/11/02 04:03:19 INFO : B2 bucket rclone-test-manutaj8piza: cleaning bucket "rclone-test-manutaj8piza" of all files --- FAIL: TestServerSideCopyOverSelf (3.38s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:03:21 DEBUG : Creating backend with remote "TestB2:rclone-test-yizokiw1gufu" sync_test.go:701: Server side copy (if possible) B2 bucket rclone-test-xubeseb3wewo -> B2 bucket rclone-test-yizokiw1gufu 2025/11/02 04:03:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 04:03:22 DEBUG : B2 bucket rclone-test-yizokiw1gufu: Waiting for checks to finish 2025/11/02 04:03:22 DEBUG : B2 bucket rclone-test-yizokiw1gufu: Waiting for transfers to finish 2025/11/02 04:03:23 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:03:23 DEBUG : B2 bucket rclone-test-yizokiw1gufu: Purge remote 2025/11/02 04:03:23 INFO : B2 bucket rclone-test-yizokiw1gufu: cleaning bucket "rclone-test-yizokiw1gufu" of all files --- FAIL: TestServerSideMoveOverSelf (3.67s) === RUN TestSyncAfterChangingModtimeOnly run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:03:24 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for checks to finish 2025/11/02 04:03:24 DEBUG : empty space: size = 1 OK 2025/11/02 04:03:24 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:03:24 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/02 04:03:24 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2025/11/02 04:03:24 DEBUG : empty space: Unchanged skipping 2025/11/02 04:03:24 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for transfers to finish 2025/11/02 04:03:24 DEBUG : Waiting for deletions to finish 2025/11/02 04:03:24 INFO : There was nothing to transfer 2025/11/02 04:03:25 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for checks to finish 2025/11/02 04:03:25 DEBUG : empty space: size = 1 OK 2025/11/02 04:03:25 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:03:25 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/02 04:03:26 ERROR : empty space: Failed to set modification time: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:26 DEBUG : empty space: Unchanged skipping 2025/11/02 04:03:26 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for transfers to finish 2025/11/02 04:03:26 ERROR : B2 bucket rclone-test-xubeseb3wewo: not deleting files as there were IO errors 2025/11/02 04:03:26 ERROR : B2 bucket rclone-test-xubeseb3wewo: 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.21s) === RUN TestSyncWithTrackRenames run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" sync_test.go:1568: Can track renames: true 2025/11/02 04:03:26 INFO : B2 bucket rclone-test-xubeseb3wewo: Making map for --track-renames 2025/11/02 04:03:26 INFO : B2 bucket rclone-test-xubeseb3wewo: Finished making map for --track-renames 2025/11/02 04:03:26 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for checks to finish 2025/11/02 04:03:26 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 04:03:26 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for renames to finish 2025/11/02 04:03:26 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 04:03:26 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for transfers to finish 2025/11/02 04:03:26 DEBUG : potato: size = 14 OK 2025/11/02 04:03:26 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 04:03:26 INFO : potato: Copied (new) 2025/11/02 04:03:27 DEBUG : yam: size = 11 OK 2025/11/02 04:03:27 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:03:27 INFO : yam: Copied (new) 2025/11/02 04:03:27 DEBUG : Waiting for deletions to finish 2025/11/02 04:03:28 DEBUG : potato: size = 14 OK 2025/11/02 04:03:28 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:03:28 INFO : B2 bucket rclone-test-xubeseb3wewo: Making map for --track-renames 2025/11/02 04:03:28 INFO : B2 bucket rclone-test-xubeseb3wewo: Finished making map for --track-renames 2025/11/02 04:03:28 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for checks to finish 2025/11/02 04:03:28 DEBUG : potato: Unchanged skipping 2025/11/02 04:03:28 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for renames to finish 2025/11/02 04:03:28 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:28 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:28 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:28 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/02 04:03:28 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for transfers to finish 2025/11/02 04:03:28 DEBUG : yaml: size = 11 OK 2025/11/02 04:03:28 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:03:28 INFO : yaml: Copied (new) 2025/11/02 04:03:28 ERROR : B2 bucket rclone-test-xubeseb3wewo: not deleting files as there were IO errors 2025/11/02 04:03:28 ERROR : B2 bucket rclone-test-xubeseb3wewo: not deleting directories as there were IO errors sync_test.go:1586: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1586 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenames --- FAIL: TestSyncWithTrackRenames (2.97s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" sync_test.go:1640: Can track renames: true 2025/11/02 04:03:29 INFO : B2 bucket rclone-test-xubeseb3wewo: Making map for --track-renames 2025/11/02 04:03:29 INFO : B2 bucket rclone-test-xubeseb3wewo: Finished making map for --track-renames 2025/11/02 04:03:29 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for checks to finish 2025/11/02 04:03:29 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 04:03:29 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 04:03:29 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for renames to finish 2025/11/02 04:03:29 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for transfers to finish 2025/11/02 04:03:29 DEBUG : potato: size = 14 OK 2025/11/02 04:03:29 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 04:03:29 INFO : potato: Copied (new) 2025/11/02 04:03:30 DEBUG : yam: size = 11 OK 2025/11/02 04:03:30 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:03:30 INFO : yam: Copied (new) 2025/11/02 04:03:30 DEBUG : Waiting for deletions to finish 2025/11/02 04:03:30 DEBUG : potato: size = 14 OK 2025/11/02 04:03:30 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:03:30 INFO : B2 bucket rclone-test-xubeseb3wewo: Making map for --track-renames 2025/11/02 04:03:30 INFO : B2 bucket rclone-test-xubeseb3wewo: Finished making map for --track-renames 2025/11/02 04:03:30 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for checks to finish 2025/11/02 04:03:30 DEBUG : potato: Unchanged skipping 2025/11/02 04:03:30 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for renames to finish 2025/11/02 04:03:30 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:30 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:30 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:30 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/02 04:03:30 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for transfers to finish 2025/11/02 04:03:31 DEBUG : yaml: size = 11 OK 2025/11/02 04:03:31 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:03:31 INFO : yaml: Copied (new) 2025/11/02 04:03:31 ERROR : B2 bucket rclone-test-xubeseb3wewo: not deleting files as there were IO errors 2025/11/02 04:03:31 ERROR : B2 bucket rclone-test-xubeseb3wewo: 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.48s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" sync_test.go:1679: Can track renames: true 2025/11/02 04:03:32 INFO : B2 bucket rclone-test-xubeseb3wewo: Making map for --track-renames 2025/11/02 04:03:32 INFO : B2 bucket rclone-test-xubeseb3wewo: Finished making map for --track-renames 2025/11/02 04:03:32 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for checks to finish 2025/11/02 04:03:32 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 04:03:32 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/11/02 04:03:32 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for renames to finish 2025/11/02 04:03:32 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for transfers to finish 2025/11/02 04:03:32 DEBUG : sub/yam: size = 11 OK 2025/11/02 04:03:32 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:03:32 INFO : sub/yam: Copied (new) 2025/11/02 04:03:32 DEBUG : potato: size = 14 OK 2025/11/02 04:03:32 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 04:03:32 INFO : potato: Copied (new) 2025/11/02 04:03:32 DEBUG : Waiting for deletions to finish 2025/11/02 04:03:32 DEBUG : potato: size = 14 OK 2025/11/02 04:03:32 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:03:32 DEBUG : potato: Unchanged skipping 2025/11/02 04:03:32 INFO : B2 bucket rclone-test-xubeseb3wewo: Making map for --track-renames 2025/11/02 04:03:32 INFO : B2 bucket rclone-test-xubeseb3wewo: Finished making map for --track-renames 2025/11/02 04:03:32 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for checks to finish 2025/11/02 04:03:32 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for renames to finish 2025/11/02 04:03:33 ERROR : sub/yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:33 ERROR : sub/yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:33 DEBUG : yam: Failed to rename to "sub/yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:33 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 04:03:33 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for transfers to finish 2025/11/02 04:03:33 DEBUG : yam: size = 11 OK 2025/11/02 04:03:33 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:03:33 INFO : yam: Copied (new) 2025/11/02 04:03:33 ERROR : B2 bucket rclone-test-xubeseb3wewo: not deleting files as there were IO errors 2025/11/02 04:03:33 ERROR : B2 bucket rclone-test-xubeseb3wewo: 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.45s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:03:34 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 04:03:34 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/11/02 04:03:34 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for checks to finish 2025/11/02 04:03:34 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for transfers to finish 2025/11/02 04:03:34 DEBUG : nested/sub dir/file: size = 6 OK 2025/11/02 04:03:34 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/11/02 04:03:34 INFO : nested/sub dir/file: Copied (new) 2025/11/02 04:03:34 INFO : nested/sub dir/file: Deleted 2025/11/02 04:03:35 DEBUG : sub dir/hello world: size = 11 OK 2025/11/02 04:03:35 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 04:03:35 INFO : sub dir/hello world: Copied (new) 2025/11/02 04:03:35 INFO : sub dir/hello world: Deleted 2025/11/02 04:03:35 ERROR : Local file system at /tmp/rclone2536651817: 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.34s) === RUN TestServerSideMove run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:03:35 DEBUG : Creating backend with remote "TestB2:rclone-test-yugacav9tuso" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-xubeseb3wewo -> B2 bucket rclone-test-yugacav9tuso 2025/11/02 04:03:39 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 04:03:39 DEBUG : empty space: size = 1 OK 2025/11/02 04:03:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/02 04:03:39 DEBUG : empty space: Unchanged skipping 2025/11/02 04:03:39 DEBUG : B2 bucket rclone-test-yugacav9tuso: Waiting for checks to finish 2025/11/02 04:03:39 DEBUG : potato3: size = 68 (B2 bucket rclone-test-xubeseb3wewo) 2025/11/02 04:03:39 DEBUG : potato3: size = 60 (B2 bucket rclone-test-yugacav9tuso) 2025/11/02 04:03:39 DEBUG : potato3: Sizes differ 2025/11/02 04:03:39 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:39 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:39 INFO : empty space: Deleted 2025/11/02 04:03:39 DEBUG : B2 bucket rclone-test-yugacav9tuso: Waiting for transfers to finish 2025/11/02 04:03:40 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:40 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:03:40 DEBUG : B2 bucket rclone-test-yugacav9tuso: Purge remote 2025/11/02 04:03:40 INFO : B2 bucket rclone-test-yugacav9tuso: cleaning bucket "rclone-test-yugacav9tuso" of all files 2025/11/02 04:03:40 DEBUG : empty space: Deleting (id "4_z1f722d63b0cd4b2b9fa80e1b_f1020a7bd1462857d_d20251102_m040339_c001_v0001176_t0048_u01762056219063") 2025/11/02 04:03:40 DEBUG : potato3: Deleting (id "4_z1f722d63b0cd4b2b9fa80e1b_f1020a7bd1462857f_d20251102_m040339_c001_v0001176_t0047_u01762056219285") --- FAIL: TestServerSideMove (5.94s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:03:41 DEBUG : Creating backend with remote "TestB2:rclone-test-watarob6made" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-xubeseb3wewo -> B2 bucket rclone-test-watarob6made 2025/11/02 04:03:46 DEBUG : empty space: Excluded (Size Filter) 2025/11/02 04:03:46 DEBUG : empty space: Excluded 2025/11/02 04:03:46 DEBUG : empty space: Excluded (Size Filter) 2025/11/02 04:03:46 DEBUG : empty space: Excluded 2025/11/02 04:03:46 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 04:03:46 DEBUG : potato3: size = 68 (B2 bucket rclone-test-xubeseb3wewo) 2025/11/02 04:03:46 DEBUG : potato3: size = 60 (B2 bucket rclone-test-watarob6made) 2025/11/02 04:03:46 DEBUG : potato3: Sizes differ 2025/11/02 04:03:46 DEBUG : B2 bucket rclone-test-watarob6made: Waiting for checks to finish 2025/11/02 04:03:46 DEBUG : B2 bucket rclone-test-watarob6made: Waiting for transfers to finish 2025/11/02 04:03:46 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:46 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:46 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:46 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:03:46 DEBUG : B2 bucket rclone-test-watarob6made: Purge remote 2025/11/02 04:03:46 INFO : B2 bucket rclone-test-watarob6made: cleaning bucket "rclone-test-watarob6made" of all files 2025/11/02 04:03:47 DEBUG : empty space: Deleting (id "4_z8f523df3b0cd4b2b9fa80e1b_f1021b6e8ebb7584d_d20251102_m040345_c001_v0001157_t0029_u01762056225591") 2025/11/02 04:03:47 DEBUG : potato3: Deleting (id "4_z8f523df3b0cd4b2b9fa80e1b_f1021b6e8ebb75851_d20251102_m040345_c001_v0001157_t0052_u01762056225932") --- FAIL: TestServerSideMoveWithFilter (6.88s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:03:48 DEBUG : Creating backend with remote "TestB2:rclone-test-fodujel5tuju" 2025/11/02 04:03:50 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-xubeseb3wewo -> B2 bucket rclone-test-fodujel5tuju 2025/11/02 04:03:53 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 04:03:53 DEBUG : empty space: size = 1 OK 2025/11/02 04:03:53 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/02 04:03:53 DEBUG : empty space: Unchanged skipping 2025/11/02 04:03:53 DEBUG : B2 bucket rclone-test-fodujel5tuju: Waiting for checks to finish 2025/11/02 04:03:53 DEBUG : potato3: size = 68 (B2 bucket rclone-test-xubeseb3wewo) 2025/11/02 04:03:53 DEBUG : potato3: size = 60 (B2 bucket rclone-test-fodujel5tuju) 2025/11/02 04:03:53 DEBUG : potato3: Sizes differ 2025/11/02 04:03:54 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:54 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:54 INFO : empty space: Deleted 2025/11/02 04:03:54 DEBUG : B2 bucket rclone-test-fodujel5tuju: Waiting for transfers to finish 2025/11/02 04:03:54 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:03:54 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:03:54 DEBUG : B2 bucket rclone-test-fodujel5tuju: Purge remote 2025/11/02 04:03:54 INFO : B2 bucket rclone-test-fodujel5tuju: cleaning bucket "rclone-test-fodujel5tuju" of all files 2025/11/02 04:03:54 DEBUG : empty space: Deleting (id "4_z0f325da3b0cd4b2b9fa80e1b_f1143a78740a349e4_d20251102_m040352_c001_v0001043_t0012_u01762056232441") 2025/11/02 04:03:54 DEBUG : potato3: Deleting (id "4_z0f325da3b0cd4b2b9fa80e1b_f1143a78740a349e8_d20251102_m040352_c001_v0001043_t0024_u01762056232920") --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (7.12s) === RUN TestSyncCopyDest run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:03:55 DEBUG : Creating backend with remote "TestB2:rclone-test-xubeseb3wewo/dst" 2025/11/02 04:03:56 DEBUG : Creating backend with remote "TestB2:rclone-test-xubeseb3wewo/CopyDest" 2025/11/02 04:03:58 DEBUG : one: Need to transfer - File not found at Destination 2025/11/02 04:03:58 DEBUG : B2 bucket rclone-test-xubeseb3wewo path dst: Waiting for checks to finish 2025/11/02 04:03:58 DEBUG : B2 bucket rclone-test-xubeseb3wewo path dst: Waiting for transfers to finish 2025/11/02 04:03:59 DEBUG : one: size = 3 OK 2025/11/02 04:03:59 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/02 04:03:59 INFO : one: Copied (new) 2025/11/02 04:03:59 DEBUG : Waiting for deletions to finish 2025/11/02 04:04:00 DEBUG : B2 bucket rclone-test-xubeseb3wewo path dst: Waiting for checks to finish 2025/11/02 04:04:00 DEBUG : one: size = 5 (Local file system at /tmp/rclone2536651817) 2025/11/02 04:04:00 DEBUG : one: size = 3 (B2 bucket rclone-test-xubeseb3wewo path dst) 2025/11/02 04:04:00 DEBUG : one: Sizes differ 2025/11/02 04:04:00 DEBUG : B2 bucket rclone-test-xubeseb3wewo path dst: Waiting for transfers to finish 2025/11/02 04:04:01 DEBUG : one: size = 5 OK 2025/11/02 04:04:01 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/11/02 04:04:01 INFO : one: Copied (replaced existing) 2025/11/02 04:04:01 DEBUG : Waiting for deletions to finish 2025/11/02 04:04:02 DEBUG : Creating backend with remote "TestB2:rclone-test-xubeseb3wewo/BackupDir" 2025/11/02 04:04:04 DEBUG : B2 bucket rclone-test-xubeseb3wewo path dst: Waiting for checks to finish 2025/11/02 04:04:04 DEBUG : one: size = 5 (Local file system at /tmp/rclone2536651817) 2025/11/02 04:04:04 DEBUG : one: size = 3 (B2 bucket rclone-test-xubeseb3wewo path dst) 2025/11/02 04:04:04 DEBUG : one: Sizes differ 2025/11/02 04:04:04 DEBUG : one: size = 5 OK 2025/11/02 04:04:04 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/02 04:04:04 DEBUG : one: size = 5 (Local file system at /tmp/rclone2536651817) 2025/11/02 04:04:04 DEBUG : one: size = 3 (B2 bucket rclone-test-xubeseb3wewo path dst) 2025/11/02 04:04:04 DEBUG : one: Sizes differ 2025/11/02 04:04:05 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:04:05 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:04:05 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:04:05 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:04:05 DEBUG : B2 bucket rclone-test-xubeseb3wewo path dst: Waiting for transfers to finish 2025/11/02 04:04:05 ERROR : B2 bucket rclone-test-xubeseb3wewo path dst: not deleting files as there were IO errors 2025/11/02 04:04:05 ERROR : B2 bucket rclone-test-xubeseb3wewo 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.82s) === RUN TestSyncBackupDir run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:04:07 DEBUG : Creating backend with remote "TestB2:rclone-test-xubeseb3wewo/dst" 2025/11/02 04:04:09 DEBUG : Creating backend with remote "TestB2:rclone-test-xubeseb3wewo/backup" 2025/11/02 04:04:10 DEBUG : one: size = 4 (Local file system at /tmp/rclone2536651817) 2025/11/02 04:04:10 DEBUG : B2 bucket rclone-test-xubeseb3wewo path dst: Waiting for checks to finish 2025/11/02 04:04:10 DEBUG : one: size = 3 (B2 bucket rclone-test-xubeseb3wewo path dst) 2025/11/02 04:04:10 DEBUG : one: Sizes differ 2025/11/02 04:04:10 DEBUG : two: size = 3 OK 2025/11/02 04:04:10 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:04:10 DEBUG : two: Unchanged skipping 2025/11/02 04:04:11 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:04:11 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:04:11 DEBUG : B2 bucket rclone-test-xubeseb3wewo path dst: Waiting for transfers to finish 2025/11/02 04:04:11 ERROR : B2 bucket rclone-test-xubeseb3wewo path dst: not deleting files as there were IO errors 2025/11/02 04:04:11 ERROR : B2 bucket rclone-test-xubeseb3wewo 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.37s) === RUN TestSyncBackupDirWithSuffix run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:04:14 DEBUG : Creating backend with remote "TestB2:rclone-test-xubeseb3wewo/dst" 2025/11/02 04:04:15 DEBUG : Creating backend with remote "TestB2:rclone-test-xubeseb3wewo/backup" 2025/11/02 04:04:17 DEBUG : two: size = 3 OK 2025/11/02 04:04:17 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:04:17 DEBUG : one: size = 4 (Local file system at /tmp/rclone2536651817) 2025/11/02 04:04:17 DEBUG : one: size = 3 (B2 bucket rclone-test-xubeseb3wewo path dst) 2025/11/02 04:04:17 DEBUG : one: Sizes differ 2025/11/02 04:04:17 DEBUG : two: Unchanged skipping 2025/11/02 04:04:17 DEBUG : B2 bucket rclone-test-xubeseb3wewo path dst: Waiting for checks to finish 2025/11/02 04:04:18 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:04:18 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:04:18 DEBUG : B2 bucket rclone-test-xubeseb3wewo path dst: Waiting for transfers to finish 2025/11/02 04:04:18 ERROR : B2 bucket rclone-test-xubeseb3wewo path dst: not deleting files as there were IO errors 2025/11/02 04:04:18 ERROR : B2 bucket rclone-test-xubeseb3wewo 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.14s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:04:21 DEBUG : Creating backend with remote "TestB2:rclone-test-xubeseb3wewo/dst" 2025/11/02 04:04:22 DEBUG : Creating backend with remote "TestB2:rclone-test-xubeseb3wewo/backup" 2025/11/02 04:04:24 DEBUG : one: size = 4 (Local file system at /tmp/rclone2536651817) 2025/11/02 04:04:24 DEBUG : one: size = 3 (B2 bucket rclone-test-xubeseb3wewo path dst) 2025/11/02 04:04:24 DEBUG : B2 bucket rclone-test-xubeseb3wewo path dst: Waiting for checks to finish 2025/11/02 04:04:24 DEBUG : one: Sizes differ 2025/11/02 04:04:24 DEBUG : two: size = 3 OK 2025/11/02 04:04:24 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:04:24 DEBUG : two: Unchanged skipping 2025/11/02 04:04:25 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:04:25 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:04:25 DEBUG : B2 bucket rclone-test-xubeseb3wewo path dst: Waiting for transfers to finish 2025/11/02 04:04:25 ERROR : B2 bucket rclone-test-xubeseb3wewo path dst: not deleting files as there were IO errors 2025/11/02 04:04:25 ERROR : B2 bucket rclone-test-xubeseb3wewo 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.68s) === RUN TestSyncBackupDirSuffixOnly run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:04:28 DEBUG : Creating backend with remote "TestB2:rclone-test-xubeseb3wewo/dst" 2025/11/02 04:04:30 DEBUG : one: size = 4 (Local file system at /tmp/rclone2536651817) 2025/11/02 04:04:30 DEBUG : two: size = 3 OK 2025/11/02 04:04:30 DEBUG : one: size = 3 (B2 bucket rclone-test-xubeseb3wewo path dst) 2025/11/02 04:04:30 DEBUG : B2 bucket rclone-test-xubeseb3wewo path dst: Waiting for checks to finish 2025/11/02 04:04:30 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:04:30 DEBUG : two: Unchanged skipping 2025/11/02 04:04:30 DEBUG : one: Sizes differ 2025/11/02 04:04:30 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:04:30 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:04:30 DEBUG : B2 bucket rclone-test-xubeseb3wewo path dst: Waiting for transfers to finish 2025/11/02 04:04:30 ERROR : B2 bucket rclone-test-xubeseb3wewo path dst: not deleting files as there were IO errors 2025/11/02 04:04:30 ERROR : B2 bucket rclone-test-xubeseb3wewo 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 (4.81s) === RUN TestSyncSuffix run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:04:32 DEBUG : Creating backend with remote "TestB2:rclone-test-xubeseb3wewo/dst" 2025/11/02 04:04:33 DEBUG : pacer: low level retry 1/10 (error incident id 3894aee67876-5db29e23f4120f14 (500 internal_error)) 2025/11/02 04:04:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/02 04:04:33 DEBUG : pacer: low level retry 2/10 (error incident id 3894aee67876-5e4e09c6a55c8b61 (500 internal_error)) 2025/11/02 04:04:33 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/11/02 04:04:33 DEBUG : pacer: low level retry 3/10 (error incident id 3894aee67876-66d2d95729c4ee10 (500 internal_error)) 2025/11/02 04:04:33 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/11/02 04:04:33 DEBUG : pacer: low level retry 4/10 (error incident id 3894aee67876-355fd4100554932f (500 internal_error)) 2025/11/02 04:04:33 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/11/02 04:04:34 DEBUG : pacer: low level retry 5/10 (error incident id 3894aee67876-320edb471a633033 (500 internal_error)) 2025/11/02 04:04:34 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/11/02 04:04:34 DEBUG : pacer: low level retry 6/10 (error incident id 3894aee67876-9ccb6ce561b5ff87 (500 internal_error)) 2025/11/02 04:04:34 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/11/02 04:04:34 DEBUG : pacer: low level retry 7/10 (error incident id 3894aee67876-5b5630e304d97098 (500 internal_error)) 2025/11/02 04:04:34 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/11/02 04:04:35 DEBUG : pacer: low level retry 8/10 (error incident id 3894aee67876-56701c1af9872604 (500 internal_error)) 2025/11/02 04:04:35 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2025/11/02 04:04:36 DEBUG : pacer: low level retry 9/10 (error incident id 3894aee67876-75969e95302039d7 (500 internal_error)) 2025/11/02 04:04:36 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2025/11/02 04:04:39 DEBUG : pacer: low level retry 10/10 (error incident id 3894aee67876-5b1fb67b915a22fc (500 internal_error)) 2025/11/02 04:04:39 DEBUG : pacer: Rate limited, increasing sleep to 10.24s sync_test.go:2499: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2499 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2553 Error: Received unexpected error: failed to authorize account: failed to authenticate: incident id 3894aee67876-5b1fb67b915a22fc (500 internal_error) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (8.90s) === RUN TestSyncSuffixKeepExtension run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:04:42 DEBUG : Creating backend with remote "TestB2:rclone-test-xubeseb3wewo/dst" 2025/11/02 04:04:43 DEBUG : one: size = 4 (Local file system at /tmp/rclone2536651817) 2025/11/02 04:04:43 DEBUG : one: size = 3 (B2 bucket rclone-test-xubeseb3wewo path dst) 2025/11/02 04:04:43 DEBUG : one: Sizes differ 2025/11/02 04:04:44 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:04:44 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 (5.29s) === RUN TestTransformFile run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:04:45 DEBUG : empty_dir: Making directory with metadata 2025/11/02 04:04:45 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/11/02 04:04:45 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:04:45 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:04:45 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:04:45 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:04:45 INFO : tictacempty_dir: Making directory 2025/11/02 04:04:45 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:04:45 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:04:45 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:04:45 INFO : tictactoe: Making directory 2025/11/02 04:04:45 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:04:45 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:04:45 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/02 04:04:45 INFO : tictactoe/tictactoe: Making directory 2025/11/02 04:04:45 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/02 04:04:45 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/11/02 04:04:45 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:04:45 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/02 04:04:45 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:04:45 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:04:45 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for checks to finish 2025/11/02 04:04:45 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for transfers to finish 2025/11/02 04:04:46 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2025/11/02 04:04:46 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 04:04:46 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:04:46 INFO : toe/toe/toe.txt: Deleted 2025/11/02 04:04:46 ERROR : Local file system at /tmp/rclone2536651817: 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.27s) === RUN TestManualTransformFile run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:04:47 DEBUG : Reset feature "Copy" 2025/11/02 04:04:47 DEBUG : Reset feature "Move" 2025/11/02 04:04:47 DEBUG : Reset feature "Copy" 2025/11/02 04:04:47 DEBUG : Reset feature "Move" 2025/11/02 04:04:47 DEBUG : empty_dir: Making directory with metadata 2025/11/02 04:04:47 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/11/02 04:04:47 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:04:47 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:04:47 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:04:47 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:04:47 INFO : tictacempty_dir: Making directory 2025/11/02 04:04:47 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:04:47 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:04:47 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:04:47 INFO : tictactoe: Making directory 2025/11/02 04:04:47 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:04:47 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:04:47 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/02 04:04:47 INFO : tictactoe/tictactoe: Making directory 2025/11/02 04:04:47 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/02 04:04:47 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/11/02 04:04:47 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:04:47 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/02 04:04:47 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:04:47 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:04:47 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for checks to finish 2025/11/02 04:04:47 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for transfers to finish 2025/11/02 04:04:47 DEBUG : tictactoe/tictactoe/tictactoe.txt: Clearing upload URL because of error: Post "https://pod-000-1182-04.backblaze.com/b2api/v1/b2_upload_file/1f82cd43b0bd4b2b9fa80e1b/c001_v0001182_t0052": EOF 2025/11/02 04:04:47 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1182-04.backblaze.com/b2api/v1/b2_upload_file/1f82cd43b0bd4b2b9fa80e1b/c001_v0001182_t0052": EOF) 2025/11/02 04:04:47 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/02 04:04:47 DEBUG : toe/toe/toe.txt: Received error: Post "https://pod-000-1182-04.backblaze.com/b2api/v1/b2_upload_file/1f82cd43b0bd4b2b9fa80e1b/c001_v0001182_t0052": EOF - low level retry 0/10 2025/11/02 04:04:47 DEBUG : pacer: Reducing sleep to 10ms 2025/11/02 04:04:47 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2025/11/02 04:04:47 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 04:04:47 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:04:47 INFO : toe/toe/toe.txt: Deleted 2025/11/02 04:04:47 ERROR : Local file system at /tmp/rclone2536651817: 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 (0.99s) === RUN TestBase64 run.go:185: Remote "B2 bucket rclone-test-xubeseb3wewo", Local "Local file system at /tmp/rclone2536651817", Modify Window "1ms" 2025/11/02 04:04:48 DEBUG : toe: transformed to: dG9l 2025/11/02 04:04:48 DEBUG : toe: transformed to: dG9l 2025/11/02 04:04:48 INFO : dG9l: Making directory 2025/11/02 04:04:48 DEBUG : toe: transformed to: dG9l 2025/11/02 04:04:48 DEBUG : toe: transformed to: dG9l 2025/11/02 04:04:48 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/11/02 04:04:48 INFO : dG9l/dG9l: Making directory 2025/11/02 04:04:48 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/11/02 04:04:48 DEBUG : toe.txt: transformed to: dG9lLnR4dA== 2025/11/02 04:04:48 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/11/02 04:04:48 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/02 04:04:48 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/11/02 04:04:48 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for checks to finish 2025/11/02 04:04:48 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Waiting for transfers to finish 2025/11/02 04:04:48 DEBUG : dG9l/dG9l/dG9lLnR4dA==: size = 11 OK 2025/11/02 04:04:48 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 04:04:48 INFO : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA== 2025/11/02 04:04:48 ERROR : B2 bucket rclone-test-xubeseb3wewo: not deleting files as there were IO errors 2025/11/02 04:04:48 ERROR : B2 bucket rclone-test-xubeseb3wewo: 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.18s) FAIL 2025/11/02 04:04:49 DEBUG : B2 bucket rclone-test-xubeseb3wewo: Purge remote 2025/11/02 04:04:49 INFO : B2 bucket rclone-test-xubeseb3wewo: cleaning bucket "rclone-test-xubeseb3wewo" of all files 2025/11/02 04:04:49 DEBUG : CopyDest/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47bdf_d20251102_m040406_c001_v7007000_t0000_u01762056246108") 2025/11/02 04:04:49 DEBUG : CopyDest/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d775d_d20251102_m040402_c001_v0001182_t0049_u01762056242560") 2025/11/02 04:04:49 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f4222faa0dd4b9f05_d20251102_m040448_c001_v7007000_t0000_u01762056288920") 2025/11/02 04:04:49 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118dc5_d20251102_m040448_c001_v0001145_t0008_u01762056288289") 2025/11/02 04:04:49 DEBUG : dst/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f4222faa0dd4b9ef5_d20251102_m040445_c001_v7007000_t0000_u01762056285105") 2025/11/02 04:04:49 DEBUG : dst/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d7837_d20251102_m040440_c001_v0001182_t0046_u01762056280918") 2025/11/02 04:04:49 DEBUG : dst/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f4222faa0dd4b9ec6_d20251102_m040439_c001_v7007000_t0000_u01762056279807") 2025/11/02 04:04:49 DEBUG : dst/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118d74_d20251102_m040431_c001_v0001145_t0006_u01762056271647") 2025/11/02 04:04:49 DEBUG : dst/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f408b8c205e49dfef_d20251102_m040430_c001_v7007000_t0000_u01762056270916") 2025/11/02 04:04:49 DEBUG : dst/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d77f2_d20251102_m040427_c001_v0001182_t0015_u01762056267203") 2025/11/02 04:04:50 DEBUG : dst/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f408b8c205e49dfcb_d20251102_m040426_c001_v7007000_t0000_u01762056266100") 2025/11/02 04:04:50 DEBUG : dst/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118d50_d20251102_m040420_c001_v0001145_t0038_u01762056260522") 2025/11/02 04:04:50 DEBUG : dst/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47c41_d20251102_m040419_c001_v7007000_t0000_u01762056259052") 2025/11/02 04:04:50 DEBUG : dst/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d77ad_d20251102_m040413_c001_v0001182_t0005_u01762056253379") 2025/11/02 04:04:50 DEBUG : dst/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47bf1_d20251102_m040412_c001_v7007000_t0000_u01762056252259") 2025/11/02 04:04:50 DEBUG : dst/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118d12_d20251102_m040406_c001_v0001145_t0049_u01762056246649") 2025/11/02 04:04:50 DEBUG : dst/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47be0_d20251102_m040406_c001_v7007000_t0000_u01762056246289") 2025/11/02 04:04:50 DEBUG : dst/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118cfb_d20251102_m040401_c001_v0001145_t0013_u01762056241785") 2025/11/02 04:04:50 DEBUG : dst/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f1130088996fd87f1_d20251102_m040400_c001_v0001100_t0008_u01762056240809") 2025/11/02 04:04:50 DEBUG : dst/one: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f1130088996fd87ed_d20251102_m040359_c001_v0001100_t0004_u01762056239536") 2025/11/02 04:04:50 DEBUG : dst/three.txt: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f4222faa0dd4b9ef7_d20251102_m040445_c001_v7007000_t0000_u01762056285293") 2025/11/02 04:04:50 DEBUG : dst/three.txt: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d783f_d20251102_m040442_c001_v0001182_t0006_u01762056282098") 2025/11/02 04:04:50 DEBUG : dst/three.txt: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f4222faa0dd4b9eca_d20251102_m040439_c001_v7007000_t0000_u01762056279990") 2025/11/02 04:04:50 DEBUG : dst/three.txt: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118d79_d20251102_m040432_c001_v0001145_t0020_u01762056272322") 2025/11/02 04:04:50 DEBUG : dst/three.txt: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f408b8c205e49dff0_d20251102_m040431_c001_v7007000_t0000_u01762056271098") 2025/11/02 04:04:50 DEBUG : dst/three.txt: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d77fc_d20251102_m040428_c001_v0001182_t0017_u01762056268107") 2025/11/02 04:04:50 DEBUG : dst/three.txt: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f408b8c205e49dfd0_d20251102_m040426_c001_v7007000_t0000_u01762056266282") 2025/11/02 04:04:50 DEBUG : dst/three.txt: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118d56_d20251102_m040421_c001_v0001145_t0029_u01762056261361") 2025/11/02 04:04:50 DEBUG : dst/three.txt: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f408b8c205e49dfa4_d20251102_m040419_c001_v7007000_t0000_u01762056259612") 2025/11/02 04:04:50 DEBUG : dst/three.txt: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d77b3_d20251102_m040414_c001_v0001182_t0007_u01762056254247") 2025/11/02 04:04:50 DEBUG : dst/three.txt: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47bf2_d20251102_m040412_c001_v7007000_t0000_u01762056252439") 2025/11/02 04:04:51 DEBUG : dst/three.txt: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118d18_d20251102_m040407_c001_v0001145_t0018_u01762056247147") 2025/11/02 04:04:51 DEBUG : dst/two: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f4222faa0dd4b9ef8_d20251102_m040445_c001_v7007000_t0000_u01762056285475") 2025/11/02 04:04:51 DEBUG : dst/two: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118da5_d20251102_m040441_c001_v0001145_t0017_u01762056281572") 2025/11/02 04:04:51 DEBUG : dst/two: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f4222faa0dd4b9ecc_d20251102_m040440_c001_v7007000_t0000_u01762056280184") 2025/11/02 04:04:51 DEBUG : dst/two: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d7814_d20251102_m040432_c001_v0001182_t0020_u01762056272077") 2025/11/02 04:04:51 DEBUG : dst/two: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f408b8c205e49dff1_d20251102_m040431_c001_v7007000_t0000_u01762056271283") 2025/11/02 04:04:51 DEBUG : dst/two: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118d68_d20251102_m040427_c001_v0001145_t0030_u01762056267762") 2025/11/02 04:04:51 DEBUG : dst/two: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f408b8c205e49dfd2_d20251102_m040426_c001_v7007000_t0000_u01762056266466") 2025/11/02 04:04:51 DEBUG : dst/two: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d77d6_d20251102_m040421_c001_v0001182_t0053_u01762056261140") 2025/11/02 04:04:51 DEBUG : dst/two: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f408b8c205e49dfa9_d20251102_m040419_c001_v7007000_t0000_u01762056259794") 2025/11/02 04:04:51 DEBUG : dst/two: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118d30_d20251102_m040413_c001_v0001145_t0056_u01762056253950") 2025/11/02 04:04:51 DEBUG : dst/two: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47bf6_d20251102_m040412_c001_v7007000_t0000_u01762056252657") 2025/11/02 04:04:51 DEBUG : dst/two: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d7780_d20251102_m040406_c001_v0001182_t0021_u01762056246934") 2025/11/02 04:04:51 DEBUG : empty space: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47bb5_d20251102_m040353_c001_v7007000_t0000_u01762056233960") 2025/11/02 04:04:51 DEBUG : empty space: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118cd7_d20251102_m040350_c001_v0001145_t0001_u01762056230346") 2025/11/02 04:04:51 DEBUG : empty space: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47b8f_d20251102_m040347_c001_v7007000_t0000_u01762056227977") 2025/11/02 04:04:51 DEBUG : empty space: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d76eb_d20251102_m040343_c001_v0001182_t0017_u01762056223521") 2025/11/02 04:04:51 DEBUG : empty space: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47b3c_d20251102_m040339_c001_v7007000_t0000_u01762056219871") 2025/11/02 04:04:51 DEBUG : empty space: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118cab_d20251102_m040336_c001_v0001145_t0015_u01762056216665") 2025/11/02 04:04:51 DEBUG : empty space: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47add_d20251102_m040326_c001_v7007000_t0000_u01762056206294") 2025/11/02 04:04:52 DEBUG : empty space: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d763f_d20251102_m040324_c001_v0001182_t0033_u01762056204453") 2025/11/02 04:04:52 DEBUG : nested/sub dir/file: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47b19_d20251102_m040335_c001_v7007000_t0000_u01762056215352") 2025/11/02 04:04:52 DEBUG : nested/sub dir/file: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d76a2_d20251102_m040334_c001_v0001182_t0044_u01762056214747") 2025/11/02 04:04:52 DEBUG : potato: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47b0f_d20251102_m040333_c001_v7007000_t0000_u01762056213827") 2025/11/02 04:04:52 DEBUG : potato: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118c99_d20251102_m040332_c001_v0001145_t0054_u01762056212289") 2025/11/02 04:04:52 DEBUG : potato: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47afe_d20251102_m040331_c001_v7007000_t0000_u01762056211387") 2025/11/02 04:04:52 DEBUG : potato: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d7678_d20251102_m040329_c001_v0001182_t0023_u01762056209820") 2025/11/02 04:04:52 DEBUG : potato: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47aed_d20251102_m040328_c001_v7007000_t0000_u01762056208907") 2025/11/02 04:04:52 DEBUG : potato: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d7651_d20251102_m040326_c001_v0001182_t0054_u01762056206838") 2025/11/02 04:04:52 DEBUG : potato2: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47bbd_d20251102_m040355_c001_v7007000_t0000_u01762056235288") 2025/11/02 04:04:52 DEBUG : potato2: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d76f9_d20251102_m040349_c001_v0001182_t0002_u01762056229645") 2025/11/02 04:04:52 DEBUG : potato2: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47b91_d20251102_m040348_c001_v7007000_t0000_u01762056228159") 2025/11/02 04:04:52 DEBUG : potato2: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118cc5_d20251102_m040342_c001_v0001145_t0027_u01762056222734") 2025/11/02 04:04:52 DEBUG : potato2: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47b4f_d20251102_m040341_c001_v7007000_t0000_u01762056221289") 2025/11/02 04:04:52 DEBUG : potato2: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d76ae_d20251102_m040336_c001_v0001182_t0005_u01762056216450") 2025/11/02 04:04:52 DEBUG : potato3: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47bbe_d20251102_m040355_c001_v7007000_t0000_u01762056235470") 2025/11/02 04:04:52 DEBUG : potato3: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d7701_d20251102_m040350_c001_v0001182_t0040_u01762056230648") 2025/11/02 04:04:52 DEBUG : potato3: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47b9c_d20251102_m040348_c001_v7007000_t0000_u01762056228340") 2025/11/02 04:04:52 DEBUG : potato3: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118cc9_d20251102_m040343_c001_v0001145_t0002_u01762056223730") 2025/11/02 04:04:52 DEBUG : potato3: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47b51_d20251102_m040341_c001_v7007000_t0000_u01762056221472") 2025/11/02 04:04:52 DEBUG : potato3: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d76b2_d20251102_m040337_c001_v0001182_t0046_u01762056217341") 2025/11/02 04:04:53 DEBUG : sub dir/hello world: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47b1c_d20251102_m040335_c001_v7007000_t0000_u01762056215533") 2025/11/02 04:04:53 DEBUG : sub dir/hello world: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118c9f_d20251102_m040334_c001_v0001145_t0015_u01762056214734") 2025/11/02 04:04:53 DEBUG : sub dir/hello world: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47ad6_d20251102_m040324_c001_v7007000_t0000_u01762056204092") 2025/11/02 04:04:53 DEBUG : sub dir/hello world: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d762b_d20251102_m040320_c001_v0001182_t0027_u01762056200781") 2025/11/02 04:04:53 DEBUG : sub dir/hello world: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47abf_d20251102_m040320_c001_v7007000_t0000_u01762056200424") 2025/11/02 04:04:53 DEBUG : sub dir/hello world: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d7615_d20251102_m040317_c001_v0001182_t0040_u01762056197402") 2025/11/02 04:04:53 DEBUG : sub dir/hello world: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47a95_d20251102_m040317_c001_v7007000_t0000_u01762056197026") 2025/11/02 04:04:53 DEBUG : sub dir/hello world: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d75f3_d20251102_m040314_c001_v0001182_t0044_u01762056194021") 2025/11/02 04:04:53 DEBUG : sub/yam: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47b12_d20251102_m040334_c001_v7007000_t0000_u01762056214012") 2025/11/02 04:04:53 DEBUG : sub/yam: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d7692_d20251102_m040332_c001_v0001182_t0034_u01762056212301") 2025/11/02 04:04:53 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f4222faa0dd4b9eff_d20251102_m040447_c001_v7007000_t0000_u01762056287737") 2025/11/02 04:04:53 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118dc1_d20251102_m040447_c001_v0001145_t0031_u01762056287307") 2025/11/02 04:04:53 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f4222faa0dd4b9efb_d20251102_m040446_c001_v7007000_t0000_u01762056286747") 2025/11/02 04:04:53 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118db9_d20251102_m040446_c001_v0001145_t0041_u01762056286026") 2025/11/02 04:04:53 DEBUG : yam: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47b13_d20251102_m040334_c001_v7007000_t0000_u01762056214194") 2025/11/02 04:04:53 DEBUG : yam: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d769c_d20251102_m040333_c001_v0001182_t0056_u01762056213438") 2025/11/02 04:04:53 DEBUG : yam: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47b00_d20251102_m040331_c001_v7007000_t0000_u01762056211569") 2025/11/02 04:04:53 DEBUG : yam: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118c8d_d20251102_m040329_c001_v0001145_t0042_u01762056209808") 2025/11/02 04:04:53 DEBUG : yam: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47aee_d20251102_m040329_c001_v7007000_t0000_u01762056209089") 2025/11/02 04:04:53 DEBUG : yam: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f110295efa7118c81_d20251102_m040327_c001_v0001145_t0027_u01762056207392") 2025/11/02 04:04:54 DEBUG : yaml: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47b01_d20251102_m040331_c001_v7007000_t0000_u01762056211751") 2025/11/02 04:04:54 DEBUG : yaml: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d768a_d20251102_m040330_c001_v0001182_t0013_u01762056210972") 2025/11/02 04:04:54 DEBUG : yaml: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f40243b349ee47af0_d20251102_m040329_c001_v7007000_t0000_u01762056209270") 2025/11/02 04:04:54 DEBUG : yaml: Deleting (id "4_z1f82cd43b0bd4b2b9fa80e1b_f104476ab727d766b_d20251102_m040328_c001_v0001182_t0027_u01762056208504") "./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 1m43.092215378s (try 3/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]