"./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/03 02:20:54 DEBUG : Creating backend with remote "TestB2:rclone-test-yuyirey8waqi" 2025/11/03 02:20:54 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/03 02:20:55 DEBUG : Creating backend with remote "/tmp/rclone1540899823" === RUN TestServerSideCopy run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:20:57 DEBUG : Creating backend with remote "TestB2:rclone-test-qojilev2niha" sync_test.go:620: Server side copy (if possible) B2 bucket rclone-test-yuyirey8waqi -> B2 bucket rclone-test-qojilev2niha 2025/11/03 02:20:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/03 02:20:58 DEBUG : B2 bucket rclone-test-qojilev2niha: Waiting for checks to finish 2025/11/03 02:20:58 DEBUG : B2 bucket rclone-test-qojilev2niha: Waiting for transfers to finish 2025/11/03 02:20:59 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:20:59 DEBUG : B2 bucket rclone-test-qojilev2niha: Purge remote 2025/11/03 02:20:59 INFO : B2 bucket rclone-test-qojilev2niha: cleaning bucket "rclone-test-qojilev2niha" of all files --- FAIL: TestServerSideCopy (5.14s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:21:00 DEBUG : Creating backend with remote "TestB2:rclone-test-soparuk2leze" sync_test.go:656: Server side copy (if possible) B2 bucket rclone-test-yuyirey8waqi -> B2 bucket rclone-test-soparuk2leze 2025/11/03 02:21:02 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/03 02:21:02 DEBUG : B2 bucket rclone-test-soparuk2leze: Waiting for checks to finish 2025/11/03 02:21:02 DEBUG : B2 bucket rclone-test-soparuk2leze: Waiting for transfers to finish 2025/11/03 02:21:02 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:21:02 DEBUG : B2 bucket rclone-test-soparuk2leze: Purge remote 2025/11/03 02:21:02 INFO : B2 bucket rclone-test-soparuk2leze: cleaning bucket "rclone-test-soparuk2leze" of all files --- FAIL: TestServerSideCopyOverSelf (3.73s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:21:04 DEBUG : Creating backend with remote "TestB2:rclone-test-zokigav9zoxo" sync_test.go:701: Server side copy (if possible) B2 bucket rclone-test-yuyirey8waqi -> B2 bucket rclone-test-zokigav9zoxo 2025/11/03 02:21:05 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/03 02:21:05 DEBUG : B2 bucket rclone-test-zokigav9zoxo: Waiting for checks to finish 2025/11/03 02:21:05 DEBUG : B2 bucket rclone-test-zokigav9zoxo: Waiting for transfers to finish 2025/11/03 02:21:06 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:21:06 DEBUG : B2 bucket rclone-test-zokigav9zoxo: Purge remote 2025/11/03 02:21:06 INFO : B2 bucket rclone-test-zokigav9zoxo: cleaning bucket "rclone-test-zokigav9zoxo" of all files --- FAIL: TestServerSideMoveOverSelf (3.63s) === RUN TestSyncAfterChangingModtimeOnly run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:21:08 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for checks to finish 2025/11/03 02:21:08 DEBUG : empty space: size = 1 OK 2025/11/03 02:21:08 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:21:08 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/03 02:21:08 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2025/11/03 02:21:08 DEBUG : empty space: Unchanged skipping 2025/11/03 02:21:08 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for transfers to finish 2025/11/03 02:21:08 DEBUG : Waiting for deletions to finish 2025/11/03 02:21:08 INFO : There was nothing to transfer 2025/11/03 02:21:08 DEBUG : empty space: size = 1 OK 2025/11/03 02:21:08 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:21:08 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for checks to finish 2025/11/03 02:21:08 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/03 02:21:09 ERROR : empty space: Failed to set modification time: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:09 DEBUG : empty space: Unchanged skipping 2025/11/03 02:21:09 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for transfers to finish 2025/11/03 02:21:09 ERROR : B2 bucket rclone-test-yuyirey8waqi: not deleting files as there were IO errors 2025/11/03 02:21:09 ERROR : B2 bucket rclone-test-yuyirey8waqi: 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.34s) === RUN TestSyncWithTrackRenames run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" sync_test.go:1568: Can track renames: true 2025/11/03 02:21:10 INFO : B2 bucket rclone-test-yuyirey8waqi: Making map for --track-renames 2025/11/03 02:21:10 INFO : B2 bucket rclone-test-yuyirey8waqi: Finished making map for --track-renames 2025/11/03 02:21:10 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for checks to finish 2025/11/03 02:21:10 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/03 02:21:10 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for renames to finish 2025/11/03 02:21:10 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/03 02:21:10 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for transfers to finish 2025/11/03 02:21:10 DEBUG : potato: size = 14 OK 2025/11/03 02:21:10 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/03 02:21:10 INFO : potato: Copied (new) 2025/11/03 02:21:11 DEBUG : yam: size = 11 OK 2025/11/03 02:21:11 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 02:21:11 INFO : yam: Copied (new) 2025/11/03 02:21:11 DEBUG : Waiting for deletions to finish 2025/11/03 02:21:11 DEBUG : potato: size = 14 OK 2025/11/03 02:21:11 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/03 02:21:11 INFO : B2 bucket rclone-test-yuyirey8waqi: Making map for --track-renames 2025/11/03 02:21:11 INFO : B2 bucket rclone-test-yuyirey8waqi: Finished making map for --track-renames 2025/11/03 02:21:11 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for checks to finish 2025/11/03 02:21:11 DEBUG : potato: Unchanged skipping 2025/11/03 02:21:11 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for renames to finish 2025/11/03 02:21:11 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:11 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:11 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:11 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/03 02:21:11 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for transfers to finish 2025/11/03 02:21:12 DEBUG : yaml: size = 11 OK 2025/11/03 02:21:12 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 02:21:12 INFO : yaml: Copied (new) 2025/11/03 02:21:12 ERROR : B2 bucket rclone-test-yuyirey8waqi: not deleting files as there were IO errors 2025/11/03 02:21:12 ERROR : B2 bucket rclone-test-yuyirey8waqi: 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.93s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" sync_test.go:1640: Can track renames: true 2025/11/03 02:21:13 INFO : B2 bucket rclone-test-yuyirey8waqi: Making map for --track-renames 2025/11/03 02:21:13 INFO : B2 bucket rclone-test-yuyirey8waqi: Finished making map for --track-renames 2025/11/03 02:21:13 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for checks to finish 2025/11/03 02:21:13 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/03 02:21:13 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/03 02:21:13 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for renames to finish 2025/11/03 02:21:13 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for transfers to finish 2025/11/03 02:21:13 DEBUG : yam: size = 11 OK 2025/11/03 02:21:13 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 02:21:13 INFO : yam: Copied (new) 2025/11/03 02:21:13 DEBUG : potato: size = 14 OK 2025/11/03 02:21:13 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/03 02:21:13 INFO : potato: Copied (new) 2025/11/03 02:21:13 DEBUG : Waiting for deletions to finish 2025/11/03 02:21:13 DEBUG : potato: size = 14 OK 2025/11/03 02:21:13 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/03 02:21:13 INFO : B2 bucket rclone-test-yuyirey8waqi: Making map for --track-renames 2025/11/03 02:21:13 INFO : B2 bucket rclone-test-yuyirey8waqi: Finished making map for --track-renames 2025/11/03 02:21:13 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for checks to finish 2025/11/03 02:21:13 DEBUG : potato: Unchanged skipping 2025/11/03 02:21:13 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for renames to finish 2025/11/03 02:21:14 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:14 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:14 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:14 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/03 02:21:14 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for transfers to finish 2025/11/03 02:21:14 DEBUG : yaml: size = 11 OK 2025/11/03 02:21:14 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 02:21:14 INFO : yaml: Copied (new) 2025/11/03 02:21:14 ERROR : B2 bucket rclone-test-yuyirey8waqi: not deleting files as there were IO errors 2025/11/03 02:21:14 ERROR : B2 bucket rclone-test-yuyirey8waqi: 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.28s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" sync_test.go:1679: Can track renames: true 2025/11/03 02:21:15 INFO : B2 bucket rclone-test-yuyirey8waqi: Making map for --track-renames 2025/11/03 02:21:15 INFO : B2 bucket rclone-test-yuyirey8waqi: Finished making map for --track-renames 2025/11/03 02:21:15 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for checks to finish 2025/11/03 02:21:15 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/03 02:21:15 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/11/03 02:21:15 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for renames to finish 2025/11/03 02:21:15 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for transfers to finish 2025/11/03 02:21:15 DEBUG : potato: size = 14 OK 2025/11/03 02:21:15 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/03 02:21:15 INFO : potato: Copied (new) 2025/11/03 02:21:15 DEBUG : sub/yam: size = 11 OK 2025/11/03 02:21:15 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 02:21:15 INFO : sub/yam: Copied (new) 2025/11/03 02:21:15 DEBUG : Waiting for deletions to finish 2025/11/03 02:21:16 DEBUG : potato: size = 14 OK 2025/11/03 02:21:16 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/03 02:21:16 DEBUG : potato: Unchanged skipping 2025/11/03 02:21:16 INFO : B2 bucket rclone-test-yuyirey8waqi: Making map for --track-renames 2025/11/03 02:21:16 INFO : B2 bucket rclone-test-yuyirey8waqi: Finished making map for --track-renames 2025/11/03 02:21:16 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for checks to finish 2025/11/03 02:21:16 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for renames to finish 2025/11/03 02:21:16 ERROR : sub/yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:16 ERROR : sub/yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:16 DEBUG : yam: Failed to rename to "sub/yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:16 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/03 02:21:16 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for transfers to finish 2025/11/03 02:21:16 DEBUG : yam: size = 11 OK 2025/11/03 02:21:16 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/03 02:21:16 INFO : yam: Copied (new) 2025/11/03 02:21:16 ERROR : B2 bucket rclone-test-yuyirey8waqi: not deleting files as there were IO errors 2025/11/03 02:21:16 ERROR : B2 bucket rclone-test-yuyirey8waqi: 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.58s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:21:18 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/03 02:21:18 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/11/03 02:21:18 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for checks to finish 2025/11/03 02:21:18 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for transfers to finish 2025/11/03 02:21:18 DEBUG : nested/sub dir/file: size = 6 OK 2025/11/03 02:21:18 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/11/03 02:21:18 INFO : nested/sub dir/file: Copied (new) 2025/11/03 02:21:18 INFO : nested/sub dir/file: Deleted 2025/11/03 02:21:18 DEBUG : sub dir/hello world: size = 11 OK 2025/11/03 02:21:18 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/03 02:21:18 INFO : sub dir/hello world: Copied (new) 2025/11/03 02:21:18 INFO : sub dir/hello world: Deleted 2025/11/03 02:21:18 ERROR : Local file system at /tmp/rclone1540899823: 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.13s) === RUN TestServerSideMove run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:21:18 DEBUG : Creating backend with remote "TestB2:rclone-test-lenubit0xoko" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-yuyirey8waqi -> B2 bucket rclone-test-lenubit0xoko 2025/11/03 02:21:23 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/03 02:21:23 DEBUG : empty space: size = 1 OK 2025/11/03 02:21:23 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/03 02:21:23 DEBUG : empty space: Unchanged skipping 2025/11/03 02:21:23 DEBUG : potato3: size = 68 (B2 bucket rclone-test-yuyirey8waqi) 2025/11/03 02:21:23 DEBUG : potato3: size = 60 (B2 bucket rclone-test-lenubit0xoko) 2025/11/03 02:21:23 DEBUG : potato3: Sizes differ 2025/11/03 02:21:23 DEBUG : B2 bucket rclone-test-lenubit0xoko: Waiting for checks to finish 2025/11/03 02:21:23 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:23 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:23 INFO : empty space: Deleted 2025/11/03 02:21:23 DEBUG : B2 bucket rclone-test-lenubit0xoko: Waiting for transfers to finish 2025/11/03 02:21:24 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:24 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:21:24 DEBUG : B2 bucket rclone-test-lenubit0xoko: Purge remote 2025/11/03 02:21:24 INFO : B2 bucket rclone-test-lenubit0xoko: cleaning bucket "rclone-test-lenubit0xoko" of all files 2025/11/03 02:21:24 DEBUG : empty space: Deleting (id "4_z6f22ddc3804d4b7b9fa80e1b_f1060df8d3687330d_d20251103_m022122_c001_v0001165_t0033_u01762136482019") 2025/11/03 02:21:24 DEBUG : potato3: Deleting (id "4_z6f22ddc3804d4b7b9fa80e1b_f1060df8d3687330f_d20251103_m022123_c001_v0001165_t0052_u01762136483181") --- FAIL: TestServerSideMove (6.62s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:21:25 DEBUG : Creating backend with remote "TestB2:rclone-test-vohiveq7wahu" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-yuyirey8waqi -> B2 bucket rclone-test-vohiveq7wahu 2025/11/03 02:21:30 DEBUG : empty space: Excluded (Size Filter) 2025/11/03 02:21:30 DEBUG : empty space: Excluded 2025/11/03 02:21:30 DEBUG : empty space: Excluded (Size Filter) 2025/11/03 02:21:30 DEBUG : empty space: Excluded 2025/11/03 02:21:30 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/03 02:21:30 DEBUG : B2 bucket rclone-test-vohiveq7wahu: Waiting for checks to finish 2025/11/03 02:21:30 DEBUG : potato3: size = 68 (B2 bucket rclone-test-yuyirey8waqi) 2025/11/03 02:21:30 DEBUG : potato3: size = 60 (B2 bucket rclone-test-vohiveq7wahu) 2025/11/03 02:21:30 DEBUG : potato3: Sizes differ 2025/11/03 02:21:30 DEBUG : B2 bucket rclone-test-vohiveq7wahu: Waiting for transfers to finish 2025/11/03 02:21:30 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:30 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:30 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:30 ERROR : potato3: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1924 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideMoveWithFilter 2025/11/03 02:21:30 DEBUG : B2 bucket rclone-test-vohiveq7wahu: Purge remote 2025/11/03 02:21:30 INFO : B2 bucket rclone-test-vohiveq7wahu: cleaning bucket "rclone-test-vohiveq7wahu" of all files 2025/11/03 02:21:30 DEBUG : empty space: Deleting (id "4_zdfc2fd83804d4b7b9fa80e1b_f100c79b3df70b2f8_d20251103_m022129_c001_v0001100_t0049_u01762136489207") 2025/11/03 02:21:30 DEBUG : potato3: Deleting (id "4_zdfc2fd83804d4b7b9fa80e1b_f100c79b3df70b2fa_d20251103_m022129_c001_v0001100_t0009_u01762136489665") --- FAIL: TestServerSideMoveWithFilter (6.72s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:21:32 DEBUG : Creating backend with remote "TestB2:rclone-test-rujavat8kiwu" 2025/11/03 02:21:34 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-yuyirey8waqi -> B2 bucket rclone-test-rujavat8kiwu 2025/11/03 02:21:37 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/03 02:21:37 DEBUG : empty space: size = 1 OK 2025/11/03 02:21:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/03 02:21:37 DEBUG : empty space: Unchanged skipping 2025/11/03 02:21:37 DEBUG : B2 bucket rclone-test-rujavat8kiwu: Waiting for checks to finish 2025/11/03 02:21:37 DEBUG : potato3: size = 68 (B2 bucket rclone-test-yuyirey8waqi) 2025/11/03 02:21:37 DEBUG : potato3: size = 60 (B2 bucket rclone-test-rujavat8kiwu) 2025/11/03 02:21:37 DEBUG : potato3: Sizes differ 2025/11/03 02:21:37 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:37 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:37 INFO : empty space: Deleted 2025/11/03 02:21:37 DEBUG : B2 bucket rclone-test-rujavat8kiwu: Waiting for transfers to finish 2025/11/03 02:21:37 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:37 ERROR : potato3: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:1746: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1746 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1931 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideMoveDeleteEmptySourceDirs 2025/11/03 02:21:37 DEBUG : B2 bucket rclone-test-rujavat8kiwu: Purge remote 2025/11/03 02:21:37 INFO : B2 bucket rclone-test-rujavat8kiwu: cleaning bucket "rclone-test-rujavat8kiwu" of all files 2025/11/03 02:21:37 DEBUG : empty space: Deleting (id "4_z2ff21d33805d4b7b9fa80e1b_f10778e87fb3d6317_d20251103_m022135_c001_v0001157_t0051_u01762136495969") 2025/11/03 02:21:37 DEBUG : potato3: Deleting (id "4_z2ff21d33805d4b7b9fa80e1b_f10778e87fb3d631b_d20251103_m022136_c001_v0001157_t0057_u01762136496441") --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (6.71s) === RUN TestSyncCopyDest run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:21:39 DEBUG : Creating backend with remote "TestB2:rclone-test-yuyirey8waqi/dst" 2025/11/03 02:21:40 DEBUG : Creating backend with remote "TestB2:rclone-test-yuyirey8waqi/CopyDest" 2025/11/03 02:21:42 DEBUG : one: Need to transfer - File not found at Destination 2025/11/03 02:21:42 DEBUG : B2 bucket rclone-test-yuyirey8waqi path dst: Waiting for checks to finish 2025/11/03 02:21:42 DEBUG : B2 bucket rclone-test-yuyirey8waqi path dst: Waiting for transfers to finish 2025/11/03 02:21:43 DEBUG : one: size = 3 OK 2025/11/03 02:21:43 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/03 02:21:43 INFO : one: Copied (new) 2025/11/03 02:21:43 DEBUG : Waiting for deletions to finish 2025/11/03 02:21:43 DEBUG : one: size = 5 (Local file system at /tmp/rclone1540899823) 2025/11/03 02:21:43 DEBUG : one: size = 3 (B2 bucket rclone-test-yuyirey8waqi path dst) 2025/11/03 02:21:43 DEBUG : one: Sizes differ 2025/11/03 02:21:43 DEBUG : B2 bucket rclone-test-yuyirey8waqi path dst: Waiting for checks to finish 2025/11/03 02:21:43 DEBUG : B2 bucket rclone-test-yuyirey8waqi path dst: Waiting for transfers to finish 2025/11/03 02:21:44 DEBUG : one: size = 5 OK 2025/11/03 02:21:44 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/11/03 02:21:44 INFO : one: Copied (replaced existing) 2025/11/03 02:21:44 DEBUG : Waiting for deletions to finish 2025/11/03 02:21:45 DEBUG : Creating backend with remote "TestB2:rclone-test-yuyirey8waqi/BackupDir" 2025/11/03 02:21:47 DEBUG : one: size = 5 (Local file system at /tmp/rclone1540899823) 2025/11/03 02:21:47 DEBUG : B2 bucket rclone-test-yuyirey8waqi path dst: Waiting for checks to finish 2025/11/03 02:21:47 DEBUG : one: size = 3 (B2 bucket rclone-test-yuyirey8waqi path dst) 2025/11/03 02:21:47 DEBUG : one: Sizes differ 2025/11/03 02:21:47 DEBUG : one: size = 5 OK 2025/11/03 02:21:47 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2025/11/03 02:21:47 DEBUG : one: size = 5 (Local file system at /tmp/rclone1540899823) 2025/11/03 02:21:47 DEBUG : one: size = 3 (B2 bucket rclone-test-yuyirey8waqi path dst) 2025/11/03 02:21:47 DEBUG : one: Sizes differ 2025/11/03 02:21:48 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:48 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:48 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:48 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:48 DEBUG : B2 bucket rclone-test-yuyirey8waqi path dst: Waiting for transfers to finish 2025/11/03 02:21:48 ERROR : B2 bucket rclone-test-yuyirey8waqi path dst: not deleting files as there were IO errors 2025/11/03 02:21:48 ERROR : B2 bucket rclone-test-yuyirey8waqi 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.44s) === RUN TestSyncBackupDir run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:21:50 DEBUG : Creating backend with remote "TestB2:rclone-test-yuyirey8waqi/dst" 2025/11/03 02:21:51 DEBUG : Creating backend with remote "TestB2:rclone-test-yuyirey8waqi/backup" 2025/11/03 02:21:53 DEBUG : one: size = 4 (Local file system at /tmp/rclone1540899823) 2025/11/03 02:21:53 DEBUG : one: size = 3 (B2 bucket rclone-test-yuyirey8waqi path dst) 2025/11/03 02:21:53 DEBUG : B2 bucket rclone-test-yuyirey8waqi path dst: Waiting for checks to finish 2025/11/03 02:21:53 DEBUG : one: Sizes differ 2025/11/03 02:21:53 DEBUG : two: size = 3 OK 2025/11/03 02:21:53 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/03 02:21:53 DEBUG : two: Unchanged skipping 2025/11/03 02:21:54 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:54 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:21:54 DEBUG : B2 bucket rclone-test-yuyirey8waqi path dst: Waiting for transfers to finish 2025/11/03 02:21:54 ERROR : B2 bucket rclone-test-yuyirey8waqi path dst: not deleting files as there were IO errors 2025/11/03 02:21:54 ERROR : B2 bucket rclone-test-yuyirey8waqi 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 (5.72s) === RUN TestSyncBackupDirWithSuffix run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:21:56 DEBUG : Creating backend with remote "TestB2:rclone-test-yuyirey8waqi/dst" 2025/11/03 02:21:57 DEBUG : Creating backend with remote "TestB2:rclone-test-yuyirey8waqi/backup" 2025/11/03 02:21:59 DEBUG : B2 bucket rclone-test-yuyirey8waqi path dst: Waiting for checks to finish 2025/11/03 02:21:59 DEBUG : two: size = 3 OK 2025/11/03 02:21:59 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/03 02:21:59 DEBUG : two: Unchanged skipping 2025/11/03 02:21:59 DEBUG : one: size = 4 (Local file system at /tmp/rclone1540899823) 2025/11/03 02:21:59 DEBUG : one: size = 3 (B2 bucket rclone-test-yuyirey8waqi path dst) 2025/11/03 02:21:59 DEBUG : one: Sizes differ 2025/11/03 02:22:00 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:22:00 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:22:00 DEBUG : B2 bucket rclone-test-yuyirey8waqi path dst: Waiting for transfers to finish 2025/11/03 02:22:00 ERROR : B2 bucket rclone-test-yuyirey8waqi path dst: not deleting files as there were IO errors 2025/11/03 02:22:00 ERROR : B2 bucket rclone-test-yuyirey8waqi path dst: not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2461 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (6.83s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:22:03 DEBUG : Creating backend with remote "TestB2:rclone-test-yuyirey8waqi/dst" 2025/11/03 02:22:04 DEBUG : pacer: low level retry 1/10 (error incident id 3894aee67876-6a3e3baf230a0a8c (500 internal_error)) 2025/11/03 02:22:04 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/03 02:22:04 DEBUG : pacer: low level retry 2/10 (error incident id 3894aee67876-a4aed7331d779572 (500 internal_error)) 2025/11/03 02:22:04 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/11/03 02:22:04 DEBUG : pacer: low level retry 3/10 (error incident id 3894aee67876-af5643e776935ca7 (500 internal_error)) 2025/11/03 02:22:04 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/11/03 02:22:04 DEBUG : pacer: low level retry 4/10 (error incident id 3894aee67876-7a71a5a6a41dccd7 (500 internal_error)) 2025/11/03 02:22:04 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/11/03 02:22:04 DEBUG : pacer: low level retry 5/10 (error incident id 3894aee67876-2b5ac8d631c9d3b6 (500 internal_error)) 2025/11/03 02:22:04 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/11/03 02:22:05 DEBUG : pacer: low level retry 6/10 (error incident id 3894aee67876-18b6178d08946486 (500 internal_error)) 2025/11/03 02:22:05 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/11/03 02:22:05 DEBUG : pacer: low level retry 7/10 (error incident id 3894aee67876-a02cc6d13085326f (500 internal_error)) 2025/11/03 02:22:05 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/11/03 02:22:06 DEBUG : pacer: low level retry 8/10 (error incident id 3894aee67876-c1435d08ad824f16 (500 internal_error)) 2025/11/03 02:22:06 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2025/11/03 02:22:07 DEBUG : pacer: low level retry 9/10 (error incident id 3894aee67876-95eebf158cbe2abb (500 internal_error)) 2025/11/03 02:22:07 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2025/11/03 02:22:09 DEBUG : pacer: low level retry 10/10 (error incident id 3894aee67876-1d2817fe5c090b71 (500 internal_error)) 2025/11/03 02:22:09 DEBUG : pacer: Rate limited, increasing sleep to 10.24s sync_test.go:2411: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2411 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2465 Error: Received unexpected error: failed to authorize account: failed to authenticate: incident id 3894aee67876-1d2817fe5c090b71 (500 internal_error) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (9.12s) === RUN TestSyncBackupDirSuffixOnly run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:22:12 DEBUG : Creating backend with remote "TestB2:rclone-test-yuyirey8waqi/dst" 2025/11/03 02:22:14 DEBUG : B2 bucket rclone-test-yuyirey8waqi path dst: Waiting for checks to finish 2025/11/03 02:22:14 DEBUG : two: size = 3 OK 2025/11/03 02:22:14 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/03 02:22:14 DEBUG : two: Unchanged skipping 2025/11/03 02:22:14 DEBUG : one: size = 4 (Local file system at /tmp/rclone1540899823) 2025/11/03 02:22:14 DEBUG : one: size = 3 (B2 bucket rclone-test-yuyirey8waqi path dst) 2025/11/03 02:22:14 DEBUG : one: Sizes differ 2025/11/03 02:22:15 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:22:15 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:22:15 DEBUG : B2 bucket rclone-test-yuyirey8waqi path dst: Waiting for transfers to finish 2025/11/03 02:22:15 ERROR : B2 bucket rclone-test-yuyirey8waqi path dst: not deleting files as there were IO errors 2025/11/03 02:22:15 ERROR : B2 bucket rclone-test-yuyirey8waqi 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.00s) === RUN TestSyncSuffix run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:22:17 DEBUG : Creating backend with remote "TestB2:rclone-test-yuyirey8waqi/dst" 2025/11/03 02:22:18 DEBUG : one: size = 4 (Local file system at /tmp/rclone1540899823) 2025/11/03 02:22:18 DEBUG : one: size = 3 (B2 bucket rclone-test-yuyirey8waqi path dst) 2025/11/03 02:22:18 DEBUG : one: Sizes differ 2025/11/03 02:22:19 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:22:19 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.13s) === RUN TestSyncSuffixKeepExtension run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:22:21 DEBUG : Creating backend with remote "TestB2:rclone-test-yuyirey8waqi/dst" 2025/11/03 02:22:22 DEBUG : one: size = 4 (Local file system at /tmp/rclone1540899823) 2025/11/03 02:22:22 DEBUG : one: size = 3 (B2 bucket rclone-test-yuyirey8waqi path dst) 2025/11/03 02:22:22 DEBUG : one: Sizes differ 2025/11/03 02:22:23 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/03 02:22:23 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.18s) === RUN TestTransformFile run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:22:24 DEBUG : empty_dir: Making directory with metadata 2025/11/03 02:22:24 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/11/03 02:22:24 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:22:24 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/03 02:22:24 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/03 02:22:24 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/03 02:22:24 INFO : tictacempty_dir: Making directory 2025/11/03 02:22:24 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/03 02:22:24 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:22:24 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:22:24 INFO : tictactoe: Making directory 2025/11/03 02:22:24 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:22:24 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:22:24 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/03 02:22:24 INFO : tictactoe/tictactoe: Making directory 2025/11/03 02:22:24 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/03 02:22:24 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/11/03 02:22:24 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/03 02:22:24 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/03 02:22:24 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/03 02:22:24 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/03 02:22:24 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for checks to finish 2025/11/03 02:22:24 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for transfers to finish 2025/11/03 02:22:24 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2025/11/03 02:22:24 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/03 02:22:24 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/11/03 02:22:24 INFO : toe/toe/toe.txt: Deleted 2025/11/03 02:22:24 ERROR : Local file system at /tmp/rclone1540899823: 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 (0.93s) === RUN TestManualTransformFile run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:22:25 DEBUG : Reset feature "Copy" 2025/11/03 02:22:25 DEBUG : Reset feature "Move" 2025/11/03 02:22:25 DEBUG : Reset feature "Copy" 2025/11/03 02:22:25 DEBUG : Reset feature "Move" 2025/11/03 02:22:25 DEBUG : empty_dir: Making directory with metadata 2025/11/03 02:22:25 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/11/03 02:22:25 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:22:25 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/03 02:22:25 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/03 02:22:25 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/03 02:22:25 INFO : tictacempty_dir: Making directory 2025/11/03 02:22:25 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/03 02:22:25 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:22:25 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:22:25 INFO : tictactoe: Making directory 2025/11/03 02:22:25 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:22:25 DEBUG : toe: transformed to: tictactoe 2025/11/03 02:22:25 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/03 02:22:25 INFO : tictactoe/tictactoe: Making directory 2025/11/03 02:22:25 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/03 02:22:25 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/11/03 02:22:25 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/03 02:22:25 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/03 02:22:25 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for checks to finish 2025/11/03 02:22:25 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for transfers to finish 2025/11/03 02:22:25 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/03 02:22:25 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/03 02:22:25 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2025/11/03 02:22:25 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/03 02:22:25 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/11/03 02:22:25 INFO : toe/toe/toe.txt: Deleted 2025/11/03 02:22:25 ERROR : Local file system at /tmp/rclone1540899823: 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.95s) === RUN TestBase64 run.go:185: Remote "B2 bucket rclone-test-yuyirey8waqi", Local "Local file system at /tmp/rclone1540899823", Modify Window "1ms" 2025/11/03 02:22:26 DEBUG : toe: transformed to: dG9l 2025/11/03 02:22:26 DEBUG : toe: transformed to: dG9l 2025/11/03 02:22:26 INFO : dG9l: Making directory 2025/11/03 02:22:26 DEBUG : toe: transformed to: dG9l 2025/11/03 02:22:26 DEBUG : toe: transformed to: dG9l 2025/11/03 02:22:26 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/11/03 02:22:26 INFO : dG9l/dG9l: Making directory 2025/11/03 02:22:26 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/11/03 02:22:26 DEBUG : toe.txt: transformed to: dG9lLnR4dA== 2025/11/03 02:22:26 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/11/03 02:22:26 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/03 02:22:26 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/11/03 02:22:26 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for checks to finish 2025/11/03 02:22:26 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Waiting for transfers to finish 2025/11/03 02:22:26 DEBUG : dG9l/dG9l/dG9lLnR4dA==: size = 11 OK 2025/11/03 02:22:26 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/03 02:22:26 INFO : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA== 2025/11/03 02:22:26 ERROR : B2 bucket rclone-test-yuyirey8waqi: not deleting files as there were IO errors 2025/11/03 02:22:26 ERROR : B2 bucket rclone-test-yuyirey8waqi: 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 (0.95s) FAIL 2025/11/03 02:22:27 DEBUG : B2 bucket rclone-test-yuyirey8waqi: Purge remote 2025/11/03 02:22:27 INFO : B2 bucket rclone-test-yuyirey8waqi: cleaning bucket "rclone-test-yuyirey8waqi" of all files 2025/11/03 02:22:27 DEBUG : CopyDest/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7e6f_d20251103_m022149_c001_v7007000_t0000_u01762136509027") 2025/11/03 02:22:27 DEBUG : CopyDest/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f5a7_d20251103_m022145_c001_v0001177_t0015_u01762136505451") 2025/11/03 02:22:27 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f41816a065a5398db_d20251103_m022227_c001_v7007000_t0000_u01762136547024") 2025/11/03 02:22:27 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe333d6_d20251103_m022226_c001_v0001183_t0026_u01762136546630") 2025/11/03 02:22:27 DEBUG : dst/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f41816a065a5398ce_d20251103_m022223_c001_v7007000_t0000_u01762136543814") 2025/11/03 02:22:27 DEBUG : dst/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f639_d20251103_m022220_c001_v0001177_t0048_u01762136540383") 2025/11/03 02:22:27 DEBUG : dst/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f41816a065a5398c6_d20251103_m022219_c001_v7007000_t0000_u01762136539651") 2025/11/03 02:22:27 DEBUG : dst/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe333a6_d20251103_m022216_c001_v0001183_t0008_u01762136536252") 2025/11/03 02:22:27 DEBUG : dst/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f41816a065a5398b6_d20251103_m022215_c001_v7007000_t0000_u01762136535456") 2025/11/03 02:22:27 DEBUG : dst/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f613_d20251103_m022211_c001_v0001177_t0026_u01762136531608") 2025/11/03 02:22:28 DEBUG : dst/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f41816a065a5398a0_d20251103_m022210_c001_v7007000_t0000_u01762136530510") 2025/11/03 02:22:28 DEBUG : dst/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe3335c_d20251103_m022202_c001_v0001183_t0039_u01762136522486") 2025/11/03 02:22:28 DEBUG : dst/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7e9f_d20251103_m022201_c001_v7007000_t0000_u01762136521028") 2025/11/03 02:22:28 DEBUG : dst/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f5dd_d20251103_m022155_c001_v0001177_t0018_u01762136515655") 2025/11/03 02:22:28 DEBUG : dst/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7e84_d20251103_m022154_c001_v7007000_t0000_u01762136514568") 2025/11/03 02:22:28 DEBUG : dst/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe33316_d20251103_m022149_c001_v0001183_t0042_u01762136509571") 2025/11/03 02:22:28 DEBUG : dst/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7e71_d20251103_m022149_c001_v7007000_t0000_u01762136509209") 2025/11/03 02:22:28 DEBUG : dst/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe332fe_d20251103_m022144_c001_v0001183_t0028_u01762136504886") 2025/11/03 02:22:28 DEBUG : dst/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f101fa87d29139646_d20251103_m022143_c001_v0001182_t0034_u01762136503989") 2025/11/03 02:22:28 DEBUG : dst/one: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f101fa87d29139634_d20251103_m022142_c001_v0001182_t0034_u01762136502787") 2025/11/03 02:22:28 DEBUG : dst/three.txt: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f41816a065a5398cf_d20251103_m022224_c001_v7007000_t0000_u01762136544006") 2025/11/03 02:22:28 DEBUG : dst/three.txt: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f63b_d20251103_m022220_c001_v0001177_t0055_u01762136540806") 2025/11/03 02:22:28 DEBUG : dst/three.txt: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f41816a065a5398c7_d20251103_m022219_c001_v7007000_t0000_u01762136539833") 2025/11/03 02:22:28 DEBUG : dst/three.txt: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe333a8_d20251103_m022216_c001_v0001183_t0003_u01762136536667") 2025/11/03 02:22:28 DEBUG : dst/three.txt: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f41816a065a5398b8_d20251103_m022215_c001_v7007000_t0000_u01762136535638") 2025/11/03 02:22:28 DEBUG : dst/three.txt: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f619_d20251103_m022212_c001_v0001177_t0042_u01762136532413") 2025/11/03 02:22:28 DEBUG : dst/three.txt: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f41816a065a5398a3_d20251103_m022210_c001_v7007000_t0000_u01762136530699") 2025/11/03 02:22:29 DEBUG : dst/three.txt: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe3335e_d20251103_m022203_c001_v0001183_t0048_u01762136523265") 2025/11/03 02:22:29 DEBUG : dst/three.txt: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f4164ff5c8cc46068_d20251103_m022201_c001_v7007000_t0000_u01762136521577") 2025/11/03 02:22:29 DEBUG : dst/three.txt: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f5df_d20251103_m022156_c001_v0001177_t0056_u01762136516452") 2025/11/03 02:22:29 DEBUG : dst/three.txt: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7e85_d20251103_m022154_c001_v7007000_t0000_u01762136514749") 2025/11/03 02:22:29 DEBUG : dst/three.txt: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe3331a_d20251103_m022149_c001_v0001183_t0015_u01762136509984") 2025/11/03 02:22:29 DEBUG : dst/two: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f41816a065a5398d2_d20251103_m022224_c001_v7007000_t0000_u01762136544193") 2025/11/03 02:22:29 DEBUG : dst/two: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe333b8_d20251103_m022220_c001_v0001183_t0048_u01762136540596") 2025/11/03 02:22:29 DEBUG : dst/two: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f41816a065a5398c8_d20251103_m022220_c001_v7007000_t0000_u01762136540014") 2025/11/03 02:22:29 DEBUG : dst/two: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f62f_d20251103_m022216_c001_v0001177_t0022_u01762136536453") 2025/11/03 02:22:29 DEBUG : dst/two: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f41816a065a5398b9_d20251103_m022215_c001_v7007000_t0000_u01762136535820") 2025/11/03 02:22:29 DEBUG : dst/two: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe33393_d20251103_m022212_c001_v0001183_t0052_u01762136532203") 2025/11/03 02:22:29 DEBUG : dst/two: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f41816a065a5398a6_d20251103_m022210_c001_v7007000_t0000_u01762136530881") 2025/11/03 02:22:29 DEBUG : dst/two: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f5f5_d20251103_m022203_c001_v0001177_t0006_u01762136523051") 2025/11/03 02:22:29 DEBUG : dst/two: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f4164ff5c8cc46069_d20251103_m022201_c001_v7007000_t0000_u01762136521761") 2025/11/03 02:22:29 DEBUG : dst/two: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe3333c_d20251103_m022156_c001_v0001183_t0038_u01762136516248") 2025/11/03 02:22:29 DEBUG : dst/two: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7e88_d20251103_m022154_c001_v7007000_t0000_u01762136514930") 2025/11/03 02:22:29 DEBUG : dst/two: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f5b7_d20251103_m022149_c001_v0001177_t0026_u01762136509771") 2025/11/03 02:22:29 DEBUG : empty space: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7e2f_d20251103_m022137_c001_v7007000_t0000_u01762136497222") 2025/11/03 02:22:29 DEBUG : empty space: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe332d5_d20251103_m022133_c001_v0001183_t0033_u01762136493901") 2025/11/03 02:22:29 DEBUG : empty space: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7e10_d20251103_m022131_c001_v7007000_t0000_u01762136491691") 2025/11/03 02:22:29 DEBUG : empty space: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f577_d20251103_m022127_c001_v0001177_t0059_u01762136487170") 2025/11/03 02:22:29 DEBUG : empty space: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7de4_d20251103_m022123_c001_v7007000_t0000_u01762136483775") 2025/11/03 02:22:29 DEBUG : empty space: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe332aa_d20251103_m022119_c001_v0001183_t0056_u01762136479859") 2025/11/03 02:22:30 DEBUG : empty space: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7daf_d20251103_m022109_c001_v7007000_t0000_u01762136469764") 2025/11/03 02:22:30 DEBUG : empty space: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe3326b_d20251103_m022107_c001_v0001183_t0040_u01762136467825") 2025/11/03 02:22:30 DEBUG : nested/sub dir/file: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7dcc_d20251103_m022118_c001_v7007000_t0000_u01762136478539") 2025/11/03 02:22:30 DEBUG : nested/sub dir/file: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f55d_d20251103_m022118_c001_v0001177_t0042_u01762136478146") 2025/11/03 02:22:30 DEBUG : potato: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7dc9_d20251103_m022117_c001_v7007000_t0000_u01762136477230") 2025/11/03 02:22:30 DEBUG : potato: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f54d_d20251103_m022115_c001_v0001177_t0037_u01762136475555") 2025/11/03 02:22:30 DEBUG : potato: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7dc0_d20251103_m022114_c001_v7007000_t0000_u01762136474648") 2025/11/03 02:22:30 DEBUG : potato: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f545_d20251103_m022113_c001_v0001177_t0011_u01762136473270") 2025/11/03 02:22:30 DEBUG : potato: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7db3_d20251103_m022112_c001_v7007000_t0000_u01762136472364") 2025/11/03 02:22:30 DEBUG : potato: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe33278_d20251103_m022110_c001_v0001183_t0025_u01762136470343") 2025/11/03 02:22:30 DEBUG : potato2: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7e35_d20251103_m022138_c001_v7007000_t0000_u01762136498581") 2025/11/03 02:22:30 DEBUG : potato2: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f583_d20251103_m022133_c001_v0001177_t0015_u01762136493329") 2025/11/03 02:22:30 DEBUG : potato2: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7e11_d20251103_m022131_c001_v7007000_t0000_u01762136491873") 2025/11/03 02:22:30 DEBUG : potato2: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe332be_d20251103_m022126_c001_v0001183_t0017_u01762136486598") 2025/11/03 02:22:30 DEBUG : potato2: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7deb_d20251103_m022125_c001_v7007000_t0000_u01762136485155") 2025/11/03 02:22:30 DEBUG : potato2: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f567_d20251103_m022119_c001_v0001177_t0000_u01762136479654") 2025/11/03 02:22:30 DEBUG : potato3: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7e37_d20251103_m022138_c001_v7007000_t0000_u01762136498769") 2025/11/03 02:22:30 DEBUG : potato3: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f585_d20251103_m022134_c001_v0001177_t0017_u01762136494110") 2025/11/03 02:22:30 DEBUG : potato3: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7e13_d20251103_m022132_c001_v7007000_t0000_u01762136492055") 2025/11/03 02:22:30 DEBUG : potato3: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe332c2_d20251103_m022127_c001_v0001183_t0012_u01762136487391") 2025/11/03 02:22:30 DEBUG : potato3: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7ded_d20251103_m022125_c001_v7007000_t0000_u01762136485337") 2025/11/03 02:22:31 DEBUG : potato3: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f56d_d20251103_m022120_c001_v0001177_t0043_u01762136480055") 2025/11/03 02:22:31 DEBUG : sub dir/hello world: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7dcd_d20251103_m022118_c001_v7007000_t0000_u01762136478721") 2025/11/03 02:22:31 DEBUG : sub dir/hello world: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe332a4_d20251103_m022118_c001_v0001183_t0021_u01762136478136") 2025/11/03 02:22:31 DEBUG : sub dir/hello world: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7dad_d20251103_m022107_c001_v7007000_t0000_u01762136467464") 2025/11/03 02:22:31 DEBUG : sub dir/hello world: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe33261_d20251103_m022104_c001_v0001183_t0012_u01762136464191") 2025/11/03 02:22:31 DEBUG : sub dir/hello world: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7d9a_d20251103_m022103_c001_v7007000_t0000_u01762136463830") 2025/11/03 02:22:31 DEBUG : sub dir/hello world: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe3324f_d20251103_m022100_c001_v0001183_t0045_u01762136460455") 2025/11/03 02:22:31 DEBUG : sub dir/hello world: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7d98_d20251103_m022100_c001_v7007000_t0000_u01762136460084") 2025/11/03 02:22:31 DEBUG : sub dir/hello world: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe33240_d20251103_m022056_c001_v0001183_t0056_u01762136456834") 2025/11/03 02:22:31 DEBUG : sub/yam: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7dca_d20251103_m022117_c001_v7007000_t0000_u01762136477411") 2025/11/03 02:22:31 DEBUG : sub/yam: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe3329c_d20251103_m022115_c001_v0001183_t0003_u01762136475567") 2025/11/03 02:22:31 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f41816a065a5398d8_d20251103_m022226_c001_v7007000_t0000_u01762136546075") 2025/11/03 02:22:31 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f64b_d20251103_m022225_c001_v0001177_t0045_u01762136545671") 2025/11/03 02:22:31 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f41816a065a5398d4_d20251103_m022225_c001_v7007000_t0000_u01762136545118") 2025/11/03 02:22:31 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe333c8_d20251103_m022224_c001_v0001183_t0000_u01762136544743") 2025/11/03 02:22:31 DEBUG : yam: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7dcb_d20251103_m022117_c001_v7007000_t0000_u01762136477592") 2025/11/03 02:22:31 DEBUG : yam: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f54f_d20251103_m022116_c001_v0001177_t0035_u01762136476747") 2025/11/03 02:22:31 DEBUG : yam: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7dc3_d20251103_m022114_c001_v7007000_t0000_u01762136474830") 2025/11/03 02:22:31 DEBUG : yam: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe3328e_d20251103_m022113_c001_v0001183_t0025_u01762136473282") 2025/11/03 02:22:31 DEBUG : yam: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7db4_d20251103_m022112_c001_v7007000_t0000_u01762136472545") 2025/11/03 02:22:31 DEBUG : yam: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f100203667d65f53d_d20251103_m022110_c001_v0001177_t0055_u01762136470976") 2025/11/03 02:22:32 DEBUG : yaml: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7dc5_d20251103_m022115_c001_v7007000_t0000_u01762136475011") 2025/11/03 02:22:32 DEBUG : yaml: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe33298_d20251103_m022114_c001_v0001183_t0021_u01762136474267") 2025/11/03 02:22:32 DEBUG : yaml: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f400b1d5622ec7db6_d20251103_m022112_c001_v7007000_t0000_u01762136472728") 2025/11/03 02:22:32 DEBUG : yaml: Deleting (id "4_z2f227da3804d4b7b9fa80e1b_f1076a333dfe33282_d20251103_m022111_c001_v0001183_t0018_u01762136471967") "./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 1m38.57125255s (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]