"./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 5/5) 2025/11/02 04:09:38 DEBUG : Creating backend with remote "TestB2:rclone-test-gadiyet4duho" 2025/11/02 04:09:38 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 04:09:39 DEBUG : Creating backend with remote "/tmp/rclone4267789368" === RUN TestServerSideCopy run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:09:41 DEBUG : Creating backend with remote "TestB2:rclone-test-bosebis2ripo" sync_test.go:620: Server side copy (if possible) B2 bucket rclone-test-gadiyet4duho -> B2 bucket rclone-test-bosebis2ripo 2025/11/02 04:09:42 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 04:09:42 DEBUG : B2 bucket rclone-test-bosebis2ripo: Waiting for checks to finish 2025/11/02 04:09:42 DEBUG : B2 bucket rclone-test-bosebis2ripo: Waiting for transfers to finish 2025/11/02 04:09:43 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:09:43 DEBUG : B2 bucket rclone-test-bosebis2ripo: Purge remote 2025/11/02 04:09:43 INFO : B2 bucket rclone-test-bosebis2ripo: cleaning bucket "rclone-test-bosebis2ripo" of all files --- FAIL: TestServerSideCopy (5.08s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:09:44 DEBUG : Creating backend with remote "TestB2:rclone-test-rufekab0bodo" sync_test.go:656: Server side copy (if possible) B2 bucket rclone-test-gadiyet4duho -> B2 bucket rclone-test-rufekab0bodo 2025/11/02 04:09:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 04:09:45 DEBUG : B2 bucket rclone-test-rufekab0bodo: Waiting for checks to finish 2025/11/02 04:09:45 DEBUG : B2 bucket rclone-test-rufekab0bodo: Waiting for transfers to finish 2025/11/02 04:09:46 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:09:46 DEBUG : B2 bucket rclone-test-rufekab0bodo: Purge remote 2025/11/02 04:09:46 INFO : B2 bucket rclone-test-rufekab0bodo: cleaning bucket "rclone-test-rufekab0bodo" of all files --- FAIL: TestServerSideCopyOverSelf (3.60s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:09:48 DEBUG : Creating backend with remote "TestB2:rclone-test-noliqof3yaji" sync_test.go:701: Server side copy (if possible) B2 bucket rclone-test-gadiyet4duho -> B2 bucket rclone-test-noliqof3yaji 2025/11/02 04:09:49 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 04:09:49 DEBUG : B2 bucket rclone-test-noliqof3yaji: Waiting for checks to finish 2025/11/02 04:09:49 DEBUG : B2 bucket rclone-test-noliqof3yaji: Waiting for transfers to finish 2025/11/02 04:09:50 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:09:50 DEBUG : B2 bucket rclone-test-noliqof3yaji: Purge remote 2025/11/02 04:09:50 INFO : B2 bucket rclone-test-noliqof3yaji: cleaning bucket "rclone-test-noliqof3yaji" of all files --- FAIL: TestServerSideMoveOverSelf (3.50s) === RUN TestSyncAfterChangingModtimeOnly run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:09:51 DEBUG : empty space: size = 1 OK 2025/11/02 04:09:51 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:09:51 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for checks to finish 2025/11/02 04:09:51 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/02 04:09:51 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2025/11/02 04:09:51 DEBUG : empty space: Unchanged skipping 2025/11/02 04:09:51 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for transfers to finish 2025/11/02 04:09:51 DEBUG : Waiting for deletions to finish 2025/11/02 04:09:51 INFO : There was nothing to transfer 2025/11/02 04:09:52 DEBUG : empty space: size = 1 OK 2025/11/02 04:09:52 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:09:52 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for checks to finish 2025/11/02 04:09:52 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/02 04:09:52 ERROR : empty space: Failed to set modification time: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:09:52 DEBUG : empty space: Unchanged skipping 2025/11/02 04:09:52 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for transfers to finish 2025/11/02 04:09:52 ERROR : B2 bucket rclone-test-gadiyet4duho: not deleting files as there were IO errors 2025/11/02 04:09:52 ERROR : B2 bucket rclone-test-gadiyet4duho: 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.29s) === RUN TestSyncWithTrackRenames run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" sync_test.go:1568: Can track renames: true 2025/11/02 04:09:53 INFO : B2 bucket rclone-test-gadiyet4duho: Making map for --track-renames 2025/11/02 04:09:53 INFO : B2 bucket rclone-test-gadiyet4duho: Finished making map for --track-renames 2025/11/02 04:09:53 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for checks to finish 2025/11/02 04:09:53 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 04:09:53 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 04:09:53 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for renames to finish 2025/11/02 04:09:53 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for transfers to finish 2025/11/02 04:09:54 DEBUG : potato: size = 14 OK 2025/11/02 04:09:54 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 04:09:54 INFO : potato: Copied (new) 2025/11/02 04:09:54 DEBUG : yam: size = 11 OK 2025/11/02 04:09:54 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:09:54 INFO : yam: Copied (new) 2025/11/02 04:09:54 DEBUG : Waiting for deletions to finish 2025/11/02 04:09:55 DEBUG : potato: size = 14 OK 2025/11/02 04:09:55 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:09:55 INFO : B2 bucket rclone-test-gadiyet4duho: Making map for --track-renames 2025/11/02 04:09:55 INFO : B2 bucket rclone-test-gadiyet4duho: Finished making map for --track-renames 2025/11/02 04:09:55 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for checks to finish 2025/11/02 04:09:55 DEBUG : potato: Unchanged skipping 2025/11/02 04:09:55 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for renames to finish 2025/11/02 04:09:55 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:09:55 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:09:55 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:09:55 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/02 04:09:55 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for transfers to finish 2025/11/02 04:09:55 DEBUG : yaml: size = 11 OK 2025/11/02 04:09:55 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:09:55 INFO : yaml: Copied (new) 2025/11/02 04:09:55 ERROR : B2 bucket rclone-test-gadiyet4duho: not deleting files as there were IO errors 2025/11/02 04:09:55 ERROR : B2 bucket rclone-test-gadiyet4duho: not deleting directories as there were IO errors sync_test.go:1586: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1586 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenames --- FAIL: TestSyncWithTrackRenames (3.26s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" sync_test.go:1640: Can track renames: true 2025/11/02 04:09:56 INFO : B2 bucket rclone-test-gadiyet4duho: Making map for --track-renames 2025/11/02 04:09:56 INFO : B2 bucket rclone-test-gadiyet4duho: Finished making map for --track-renames 2025/11/02 04:09:56 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for checks to finish 2025/11/02 04:09:56 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 04:09:56 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 04:09:56 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for renames to finish 2025/11/02 04:09:56 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for transfers to finish 2025/11/02 04:09:57 DEBUG : yam: size = 11 OK 2025/11/02 04:09:57 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:09:57 INFO : yam: Copied (new) 2025/11/02 04:09:57 DEBUG : potato: size = 14 OK 2025/11/02 04:09:57 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 04:09:57 INFO : potato: Copied (new) 2025/11/02 04:09:57 DEBUG : Waiting for deletions to finish 2025/11/02 04:09:57 DEBUG : potato: size = 14 OK 2025/11/02 04:09:57 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:09:57 INFO : B2 bucket rclone-test-gadiyet4duho: Making map for --track-renames 2025/11/02 04:09:57 INFO : B2 bucket rclone-test-gadiyet4duho: Finished making map for --track-renames 2025/11/02 04:09:57 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for checks to finish 2025/11/02 04:09:57 DEBUG : potato: Unchanged skipping 2025/11/02 04:09:57 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for renames to finish 2025/11/02 04:09:58 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:09:58 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:09:58 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:09:58 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/02 04:09:58 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for transfers to finish 2025/11/02 04:09:58 DEBUG : yaml: size = 11 OK 2025/11/02 04:09:58 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:09:58 INFO : yaml: Copied (new) 2025/11/02 04:09:58 ERROR : B2 bucket rclone-test-gadiyet4duho: not deleting files as there were IO errors 2025/11/02 04:09:58 ERROR : B2 bucket rclone-test-gadiyet4duho: 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 (3.03s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" sync_test.go:1679: Can track renames: true 2025/11/02 04:09:59 INFO : B2 bucket rclone-test-gadiyet4duho: Making map for --track-renames 2025/11/02 04:09:59 INFO : B2 bucket rclone-test-gadiyet4duho: Finished making map for --track-renames 2025/11/02 04:09:59 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for checks to finish 2025/11/02 04:09:59 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 04:09:59 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/11/02 04:09:59 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for renames to finish 2025/11/02 04:09:59 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for transfers to finish 2025/11/02 04:10:00 DEBUG : potato: size = 14 OK 2025/11/02 04:10:00 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 04:10:00 INFO : potato: Copied (new) 2025/11/02 04:10:00 DEBUG : sub/yam: size = 11 OK 2025/11/02 04:10:00 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:10:00 INFO : sub/yam: Copied (new) 2025/11/02 04:10:00 DEBUG : Waiting for deletions to finish 2025/11/02 04:10:01 DEBUG : potato: size = 14 OK 2025/11/02 04:10:01 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:10:01 DEBUG : potato: Unchanged skipping 2025/11/02 04:10:01 INFO : B2 bucket rclone-test-gadiyet4duho: Making map for --track-renames 2025/11/02 04:10:01 INFO : B2 bucket rclone-test-gadiyet4duho: Finished making map for --track-renames 2025/11/02 04:10:01 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for checks to finish 2025/11/02 04:10:01 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for renames to finish 2025/11/02 04:10:01 ERROR : sub/yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:10:01 ERROR : sub/yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:10:01 DEBUG : yam: Failed to rename to "sub/yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:10:01 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 04:10:01 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for transfers to finish 2025/11/02 04:10:02 DEBUG : yam: size = 11 OK 2025/11/02 04:10:02 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:10:02 INFO : yam: Copied (new) 2025/11/02 04:10:02 ERROR : B2 bucket rclone-test-gadiyet4duho: not deleting files as there were IO errors 2025/11/02 04:10:02 ERROR : B2 bucket rclone-test-gadiyet4duho: not deleting directories as there were IO errors sync_test.go:1697: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1697 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyLeaf --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (3.46s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:10:03 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 04:10:03 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/11/02 04:10:03 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for checks to finish 2025/11/02 04:10:03 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for transfers to finish 2025/11/02 04:10:03 DEBUG : nested/sub dir/file: size = 6 OK 2025/11/02 04:10:03 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/11/02 04:10:03 INFO : nested/sub dir/file: Copied (new) 2025/11/02 04:10:03 INFO : nested/sub dir/file: Deleted 2025/11/02 04:10:03 DEBUG : sub dir/hello world: size = 11 OK 2025/11/02 04:10:03 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 04:10:03 INFO : sub dir/hello world: Copied (new) 2025/11/02 04:10:03 INFO : sub dir/hello world: Deleted 2025/11/02 04:10:03 ERROR : Local file system at /tmp/rclone4267789368: 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.37s) === RUN TestServerSideMove run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:10:04 DEBUG : Creating backend with remote "TestB2:rclone-test-rarupev7xoxo" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-gadiyet4duho -> B2 bucket rclone-test-rarupev7xoxo 2025/11/02 04:10:09 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 04:10:09 DEBUG : empty space: size = 1 OK 2025/11/02 04:10:09 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/02 04:10:09 DEBUG : empty space: Unchanged skipping 2025/11/02 04:10:09 DEBUG : potato3: size = 68 (B2 bucket rclone-test-gadiyet4duho) 2025/11/02 04:10:09 DEBUG : potato3: size = 60 (B2 bucket rclone-test-rarupev7xoxo) 2025/11/02 04:10:09 DEBUG : potato3: Sizes differ 2025/11/02 04:10:09 DEBUG : B2 bucket rclone-test-rarupev7xoxo: Waiting for checks to finish 2025/11/02 04:10:09 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:10:09 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:10:09 INFO : empty space: Deleted 2025/11/02 04:10:09 DEBUG : B2 bucket rclone-test-rarupev7xoxo: Waiting for transfers to finish 2025/11/02 04:10:09 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:10:09 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:10:09 DEBUG : B2 bucket rclone-test-rarupev7xoxo: Purge remote 2025/11/02 04:10:09 INFO : B2 bucket rclone-test-rarupev7xoxo: cleaning bucket "rclone-test-rarupev7xoxo" of all files 2025/11/02 04:10:09 DEBUG : empty space: Deleting (id "4_z8fe21d53c02d4b2b9fa80e1b_f1024b8aa3e5c7b30_d20251102_m041008_c001_v0001105_t0049_u01762056608206") 2025/11/02 04:10:09 DEBUG : potato3: Deleting (id "4_z8fe21d53c02d4b2b9fa80e1b_f1024b8aa3e5c7b34_d20251102_m041008_c001_v0001105_t0003_u01762056608508") --- FAIL: TestServerSideMove (6.58s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:10:11 DEBUG : Creating backend with remote "TestB2:rclone-test-zanejuq7suze" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-gadiyet4duho -> B2 bucket rclone-test-zanejuq7suze 2025/11/02 04:10:16 DEBUG : empty space: Excluded (Size Filter) 2025/11/02 04:10:16 DEBUG : empty space: Excluded 2025/11/02 04:10:16 DEBUG : empty space: Excluded (Size Filter) 2025/11/02 04:10:16 DEBUG : empty space: Excluded 2025/11/02 04:10:16 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 04:10:16 DEBUG : potato3: size = 68 (B2 bucket rclone-test-gadiyet4duho) 2025/11/02 04:10:16 DEBUG : potato3: size = 60 (B2 bucket rclone-test-zanejuq7suze) 2025/11/02 04:10:16 DEBUG : potato3: Sizes differ 2025/11/02 04:10:16 DEBUG : B2 bucket rclone-test-zanejuq7suze: Waiting for checks to finish 2025/11/02 04:10:16 DEBUG : B2 bucket rclone-test-zanejuq7suze: Waiting for transfers to finish 2025/11/02 04:10:17 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:10:17 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:10:17 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:10:17 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:10:17 DEBUG : B2 bucket rclone-test-zanejuq7suze: Purge remote 2025/11/02 04:10:17 INFO : B2 bucket rclone-test-zanejuq7suze: cleaning bucket "rclone-test-zanejuq7suze" of all files 2025/11/02 04:10:17 DEBUG : empty space: Deleting (id "4_z1f623d53c02d4b2b9fa80e1b_f100203667d62d1ea_d20251102_m041016_c001_v0001177_t0015_u01762056616118") 2025/11/02 04:10:17 DEBUG : potato3: Deleting (id "4_z1f623d53c02d4b2b9fa80e1b_f100203667d62d1ec_d20251102_m041016_c001_v0001177_t0011_u01762056616335") --- FAIL: TestServerSideMoveWithFilter (7.65s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:10:18 DEBUG : Creating backend with remote "TestB2:rclone-test-gejelav5peki" 2025/11/02 04:10:21 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-gadiyet4duho -> B2 bucket rclone-test-gejelav5peki 2025/11/02 04:10:24 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 04:10:24 DEBUG : empty space: size = 1 OK 2025/11/02 04:10:24 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/02 04:10:24 DEBUG : empty space: Unchanged skipping 2025/11/02 04:10:24 DEBUG : B2 bucket rclone-test-gejelav5peki: Waiting for checks to finish 2025/11/02 04:10:24 DEBUG : potato3: size = 68 (B2 bucket rclone-test-gadiyet4duho) 2025/11/02 04:10:24 DEBUG : potato3: size = 60 (B2 bucket rclone-test-gejelav5peki) 2025/11/02 04:10:24 DEBUG : potato3: Sizes differ 2025/11/02 04:10:24 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:10:24 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:10:24 INFO : empty space: Deleted 2025/11/02 04:10:24 DEBUG : B2 bucket rclone-test-gejelav5peki: Waiting for transfers to finish 2025/11/02 04:10:24 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:10: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:1931 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestServerSideMoveDeleteEmptySourceDirs 2025/11/02 04:10:24 DEBUG : B2 bucket rclone-test-gejelav5peki: Purge remote 2025/11/02 04:10:24 INFO : B2 bucket rclone-test-gejelav5peki: cleaning bucket "rclone-test-gejelav5peki" of all files 2025/11/02 04:10:24 DEBUG : empty space: Deleting (id "4_zffd25d03c02d4b2b9fa80e1b_f1085d27f8ca33a3d_d20251102_m041023_c001_v0001100_t0042_u01762056623268") 2025/11/02 04:10:24 DEBUG : potato3: Deleting (id "4_zffd25d03c02d4b2b9fa80e1b_f1085d27f8ca33a3f_d20251102_m041023_c001_v0001100_t0032_u01762056623752") --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (7.38s) === RUN TestSyncCopyDest run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:10:26 DEBUG : Creating backend with remote "TestB2:rclone-test-gadiyet4duho/dst" 2025/11/02 04:10:27 DEBUG : Creating backend with remote "TestB2:rclone-test-gadiyet4duho/CopyDest" 2025/11/02 04:10:29 DEBUG : one: Need to transfer - File not found at Destination 2025/11/02 04:10:29 DEBUG : B2 bucket rclone-test-gadiyet4duho path dst: Waiting for checks to finish 2025/11/02 04:10:29 DEBUG : B2 bucket rclone-test-gadiyet4duho path dst: Waiting for transfers to finish 2025/11/02 04:10:30 DEBUG : one: Clearing upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/11/02 04:10:30 DEBUG : pacer: low level retry 1/1 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/11/02 04:10:30 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/02 04:10:30 DEBUG : one: Received error: CPU too busy (503 service_unavailable): trying again in 1s - low level retry 0/10 2025/11/02 04:10:30 DEBUG : pacer: Reducing sleep to 500ms 2025/11/02 04:10:31 DEBUG : pacer: Reducing sleep to 250ms 2025/11/02 04:10:31 DEBUG : one: size = 3 OK 2025/11/02 04:10:31 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/02 04:10:31 INFO : one: Copied (new) 2025/11/02 04:10:31 DEBUG : Waiting for deletions to finish 2025/11/02 04:10:32 DEBUG : pacer: Reducing sleep to 125ms 2025/11/02 04:10:32 DEBUG : one: size = 5 (Local file system at /tmp/rclone4267789368) 2025/11/02 04:10:32 DEBUG : one: size = 3 (B2 bucket rclone-test-gadiyet4duho path dst) 2025/11/02 04:10:32 DEBUG : one: Sizes differ 2025/11/02 04:10:32 DEBUG : B2 bucket rclone-test-gadiyet4duho path dst: Waiting for checks to finish 2025/11/02 04:10:32 DEBUG : B2 bucket rclone-test-gadiyet4duho path dst: Waiting for transfers to finish 2025/11/02 04:10:32 DEBUG : pacer: Reducing sleep to 62.5ms 2025/11/02 04:10:32 DEBUG : one: size = 5 OK 2025/11/02 04:10:32 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/11/02 04:10:32 INFO : one: Copied (replaced existing) 2025/11/02 04:10:32 DEBUG : Waiting for deletions to finish 2025/11/02 04:10:34 DEBUG : Creating backend with remote "TestB2:rclone-test-gadiyet4duho/BackupDir" 2025/11/02 04:10:35 DEBUG : pacer: low level retry 1/10 (error incident id 3894aee67876-3824bcdc7244ad9a (500 internal_error)) 2025/11/02 04:10:35 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/02 04:10:35 DEBUG : pacer: low level retry 2/10 (error incident id 3894aee67876-d0d7507291e6df40 (500 internal_error)) 2025/11/02 04:10:35 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/11/02 04:10:35 DEBUG : pacer: low level retry 3/10 (error incident id 3894aee67876-e0f33ff4b835f4f9 (500 internal_error)) 2025/11/02 04:10:35 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/11/02 04:10:35 DEBUG : pacer: low level retry 4/10 (error incident id 3894aee67876-f1a6eb746688de47 (500 internal_error)) 2025/11/02 04:10:35 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/11/02 04:10:36 DEBUG : pacer: low level retry 5/10 (error incident id 3894aee67876-3b9dae3777c75cb0 (500 internal_error)) 2025/11/02 04:10:36 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/11/02 04:10:36 DEBUG : pacer: low level retry 6/10 (error incident id 3894aee67876-b3e0e8fe7457f485 (500 internal_error)) 2025/11/02 04:10:36 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/11/02 04:10:36 DEBUG : pacer: low level retry 7/10 (error incident id 3894aee67876-71494061580d54fc (500 internal_error)) 2025/11/02 04:10:36 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/11/02 04:10:37 DEBUG : pacer: low level retry 8/10 (error incident id 3894aee67876-21758d146ca1e1bd (500 internal_error)) 2025/11/02 04:10:37 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2025/11/02 04:10:38 DEBUG : pacer: low level retry 9/10 (error incident id 3894aee67876-7b1ed10b073663de (500 internal_error)) 2025/11/02 04:10:38 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2025/11/02 04:10:41 DEBUG : pacer: low level retry 10/10 (error incident id 3894aee67876-34f8f2c2e40585f6 (500 internal_error)) 2025/11/02 04:10:41 DEBUG : pacer: Rate limited, increasing sleep to 10.24s sync_test.go:2318: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2318 Error: Received unexpected error: failed to make fs for --backup-dir "TestB2:rclone-test-gadiyet4duho/BackupDir": failed to authorize account: failed to authenticate: incident id 3894aee67876-34f8f2c2e40585f6 (500 internal_error) Test: TestSyncCopyDest --- FAIL: TestSyncCopyDest (15.93s) === RUN TestSyncBackupDir run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:10:44 DEBUG : Creating backend with remote "TestB2:rclone-test-gadiyet4duho/dst" 2025/11/02 04:10:45 DEBUG : Creating backend with remote "TestB2:rclone-test-gadiyet4duho/backup" 2025/11/02 04:10:47 DEBUG : one: size = 4 (Local file system at /tmp/rclone4267789368) 2025/11/02 04:10:47 DEBUG : one: size = 3 (B2 bucket rclone-test-gadiyet4duho path dst) 2025/11/02 04:10:47 DEBUG : one: Sizes differ 2025/11/02 04:10:47 DEBUG : two: size = 3 OK 2025/11/02 04:10:47 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:10:47 DEBUG : two: Unchanged skipping 2025/11/02 04:10:47 DEBUG : B2 bucket rclone-test-gadiyet4duho path dst: Waiting for checks to finish 2025/11/02 04:10:48 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:10:48 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:10:48 DEBUG : B2 bucket rclone-test-gadiyet4duho path dst: Waiting for transfers to finish 2025/11/02 04:10:48 ERROR : B2 bucket rclone-test-gadiyet4duho path dst: not deleting files as there were IO errors 2025/11/02 04:10:48 ERROR : B2 bucket rclone-test-gadiyet4duho 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.90s) === RUN TestSyncBackupDirWithSuffix run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:10:51 DEBUG : Creating backend with remote "TestB2:rclone-test-gadiyet4duho/dst" 2025/11/02 04:10:51 DEBUG : pacer: low level retry 1/10 (error incident id 3894aee67876-f4bc27ba7c90e35b (500 internal_error)) 2025/11/02 04:10:51 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/02 04:10:51 DEBUG : pacer: low level retry 2/10 (error incident id 3894aee67876-d8a919722be4ebca (500 internal_error)) 2025/11/02 04:10:51 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/11/02 04:10:51 DEBUG : pacer: low level retry 3/10 (error incident id 3894aee67876-c5ec1a9bd622c05c (500 internal_error)) 2025/11/02 04:10:51 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/11/02 04:10:52 DEBUG : pacer: low level retry 4/10 (error incident id 3894aee67876-dbd16692e6cd2e08 (500 internal_error)) 2025/11/02 04:10:52 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/11/02 04:10:52 DEBUG : pacer: low level retry 5/10 (error incident id 3894aee67876-f1b5660f7bc2115d (500 internal_error)) 2025/11/02 04:10:52 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/11/02 04:10:52 DEBUG : pacer: low level retry 6/10 (error incident id 3894aee67876-b6d90ad275fd4c3f (500 internal_error)) 2025/11/02 04:10:52 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/11/02 04:10:52 DEBUG : pacer: low level retry 7/10 (error incident id 3894aee67876-345af2af4f5f9efd (500 internal_error)) 2025/11/02 04:10:52 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/11/02 04:10:53 DEBUG : pacer: low level retry 8/10 (error incident id 3894aee67876-a61406b243ab5d0c (500 internal_error)) 2025/11/02 04:10:53 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2025/11/02 04:10:54 DEBUG : pacer: low level retry 9/10 (error incident id 3894aee67876-b39ab51b9a1d2c72 (500 internal_error)) 2025/11/02 04:10:54 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2025/11/02 04:10:57 DEBUG : pacer: low level retry 10/10 (error incident id 3894aee67876-708fe01589c598be (500 internal_error)) 2025/11/02 04:10:57 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:2461 Error: Received unexpected error: failed to authorize account: failed to authenticate: incident id 3894aee67876-708fe01589c598be (500 internal_error) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (9.57s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:11:00 DEBUG : Creating backend with remote "TestB2:rclone-test-gadiyet4duho/dst" 2025/11/02 04:11:01 DEBUG : Creating backend with remote "TestB2:rclone-test-gadiyet4duho/backup" 2025/11/02 04:11:03 DEBUG : one: size = 4 (Local file system at /tmp/rclone4267789368) 2025/11/02 04:11:03 DEBUG : one: size = 3 (B2 bucket rclone-test-gadiyet4duho path dst) 2025/11/02 04:11:03 DEBUG : one: Sizes differ 2025/11/02 04:11:03 DEBUG : two: size = 3 OK 2025/11/02 04:11:03 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:11:03 DEBUG : two: Unchanged skipping 2025/11/02 04:11:03 DEBUG : B2 bucket rclone-test-gadiyet4duho path dst: Waiting for checks to finish 2025/11/02 04:11:04 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:11:04 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:11:04 DEBUG : B2 bucket rclone-test-gadiyet4duho path dst: Waiting for transfers to finish 2025/11/02 04:11:04 ERROR : B2 bucket rclone-test-gadiyet4duho path dst: not deleting files as there were IO errors 2025/11/02 04:11:04 ERROR : B2 bucket rclone-test-gadiyet4duho 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.89s) === RUN TestSyncBackupDirSuffixOnly run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:11:08 DEBUG : Creating backend with remote "TestB2:rclone-test-gadiyet4duho/dst" 2025/11/02 04:11:09 DEBUG : two: size = 3 OK 2025/11/02 04:11:09 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:11:09 DEBUG : B2 bucket rclone-test-gadiyet4duho path dst: Waiting for checks to finish 2025/11/02 04:11:09 DEBUG : two: Unchanged skipping 2025/11/02 04:11:09 DEBUG : one: size = 4 (Local file system at /tmp/rclone4267789368) 2025/11/02 04:11:09 DEBUG : one: size = 3 (B2 bucket rclone-test-gadiyet4duho path dst) 2025/11/02 04:11:09 DEBUG : one: Sizes differ 2025/11/02 04:11:10 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:11:10 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:11:10 DEBUG : B2 bucket rclone-test-gadiyet4duho path dst: Waiting for transfers to finish 2025/11/02 04:11:10 ERROR : B2 bucket rclone-test-gadiyet4duho path dst: not deleting files as there were IO errors 2025/11/02 04:11:10 ERROR : B2 bucket rclone-test-gadiyet4duho 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.63s) === RUN TestSyncSuffix run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:11:12 DEBUG : Creating backend with remote "TestB2:rclone-test-gadiyet4duho/dst" 2025/11/02 04:11:14 DEBUG : one: size = 4 (Local file system at /tmp/rclone4267789368) 2025/11/02 04:11:14 DEBUG : one: size = 3 (B2 bucket rclone-test-gadiyet4duho path dst) 2025/11/02 04:11:14 DEBUG : one: Sizes differ 2025/11/02 04:11:15 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:11:15 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.91s) === RUN TestSyncSuffixKeepExtension run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:11:17 DEBUG : Creating backend with remote "TestB2:rclone-test-gadiyet4duho/dst" 2025/11/02 04:11:18 DEBUG : one: size = 4 (Local file system at /tmp/rclone4267789368) 2025/11/02 04:11:18 DEBUG : one: size = 3 (B2 bucket rclone-test-gadiyet4duho path dst) 2025/11/02 04:11:18 DEBUG : one: Sizes differ 2025/11/02 04:11:20 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:11:20 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) sync_test.go:2503: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2503 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Received unexpected error: moving to --backup-dir failed: expected a FileSseMode but found: '' (400 bad_request) Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (4.95s) === RUN TestTransformFile run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:11:21 DEBUG : empty_dir: Making directory with metadata 2025/11/02 04:11:21 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/11/02 04:11:21 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:11:21 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:11:21 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:11:21 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:11:21 INFO : tictacempty_dir: Making directory 2025/11/02 04:11:21 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:11:21 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:11:21 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:11:21 INFO : tictactoe: Making directory 2025/11/02 04:11:21 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:11:21 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:11:21 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/02 04:11:21 INFO : tictactoe/tictactoe: Making directory 2025/11/02 04:11:21 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/02 04:11:21 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/11/02 04:11:21 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:11:21 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/02 04:11:21 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:11:21 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:11:21 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for checks to finish 2025/11/02 04:11:21 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for transfers to finish 2025/11/02 04:11:21 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2025/11/02 04:11:21 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 04:11:21 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:11:21 INFO : toe/toe/toe.txt: Deleted 2025/11/02 04:11:21 ERROR : Local file system at /tmp/rclone4267789368: 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.10s) === RUN TestManualTransformFile run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:11:22 DEBUG : Reset feature "Copy" 2025/11/02 04:11:22 DEBUG : Reset feature "Move" 2025/11/02 04:11:22 DEBUG : Reset feature "Copy" 2025/11/02 04:11:22 DEBUG : Reset feature "Move" 2025/11/02 04:11:22 DEBUG : empty_dir: Making directory with metadata 2025/11/02 04:11:22 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/11/02 04:11:22 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:11:22 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:11:22 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:11:22 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:11:22 INFO : tictacempty_dir: Making directory 2025/11/02 04:11:22 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:11:22 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:11:22 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:11:22 INFO : tictactoe: Making directory 2025/11/02 04:11:22 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:11:22 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:11:22 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/02 04:11:22 INFO : tictactoe/tictactoe: Making directory 2025/11/02 04:11:22 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/02 04:11:22 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/11/02 04:11:22 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:11:22 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/02 04:11:22 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:11:22 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:11:22 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for checks to finish 2025/11/02 04:11:22 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for transfers to finish 2025/11/02 04:11:22 DEBUG : tictactoe/tictactoe/tictactoe.txt: Clearing upload URL because of error: Post "https://pod-000-1134-14.backblaze.com/b2api/v1/b2_upload_file/cf12ad93c01d4b2b9fa80e1b/c001_v0001134_t0054": EOF 2025/11/02 04:11:22 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1134-14.backblaze.com/b2api/v1/b2_upload_file/cf12ad93c01d4b2b9fa80e1b/c001_v0001134_t0054": EOF) 2025/11/02 04:11:22 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/02 04:11:22 DEBUG : toe/toe/toe.txt: Received error: Post "https://pod-000-1134-14.backblaze.com/b2api/v1/b2_upload_file/cf12ad93c01d4b2b9fa80e1b/c001_v0001134_t0054": EOF - low level retry 0/10 2025/11/02 04:11:22 DEBUG : pacer: Reducing sleep to 10ms 2025/11/02 04:11:22 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2025/11/02 04:11:22 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 04:11:22 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:11:22 INFO : toe/toe/toe.txt: Deleted 2025/11/02 04:11:22 ERROR : Local file system at /tmp/rclone4267789368: not deleting directories as there were IO errors sync_transform_test.go:457: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:457 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestManualTransformFile --- FAIL: TestManualTransformFile (1.27s) === RUN TestBase64 run.go:185: Remote "B2 bucket rclone-test-gadiyet4duho", Local "Local file system at /tmp/rclone4267789368", Modify Window "1ms" 2025/11/02 04:11:23 DEBUG : toe: transformed to: dG9l 2025/11/02 04:11:23 DEBUG : toe: transformed to: dG9l 2025/11/02 04:11:23 INFO : dG9l: Making directory 2025/11/02 04:11:23 DEBUG : toe: transformed to: dG9l 2025/11/02 04:11:23 DEBUG : toe: transformed to: dG9l 2025/11/02 04:11:23 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/11/02 04:11:23 INFO : dG9l/dG9l: Making directory 2025/11/02 04:11:23 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/11/02 04:11:23 DEBUG : toe.txt: transformed to: dG9lLnR4dA== 2025/11/02 04:11:23 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/11/02 04:11:23 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/02 04:11:23 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/11/02 04:11:23 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for checks to finish 2025/11/02 04:11:23 DEBUG : B2 bucket rclone-test-gadiyet4duho: Waiting for transfers to finish 2025/11/02 04:11:23 DEBUG : dG9l/dG9l/dG9lLnR4dA==: size = 11 OK 2025/11/02 04:11:23 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 04:11:23 INFO : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA== 2025/11/02 04:11:23 ERROR : B2 bucket rclone-test-gadiyet4duho: not deleting files as there were IO errors 2025/11/02 04:11:23 ERROR : B2 bucket rclone-test-gadiyet4duho: 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.16s) FAIL 2025/11/02 04:11:24 DEBUG : B2 bucket rclone-test-gadiyet4duho: Purge remote 2025/11/02 04:11:24 INFO : B2 bucket rclone-test-gadiyet4duho: cleaning bucket "rclone-test-gadiyet4duho" of all files 2025/11/02 04:11:24 DEBUG : CopyDest/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f412fe48847c05f5a_d20251102_m041041_c001_v7007000_t0000_u01762056641716") 2025/11/02 04:11:24 DEBUG : CopyDest/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d023d8_d20251102_m041034_c001_v0001134_t0044_u01762056634288") 2025/11/02 04:11:24 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee48800_d20251102_m041124_c001_v7007000_t0000_u01762056684268") 2025/11/02 04:11:24 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab491_d20251102_m041123_c001_v0001157_t0040_u01762056683652") 2025/11/02 04:11:24 DEBUG : dst/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487da_d20251102_m041120_c001_v7007000_t0000_u01762056680356") 2025/11/02 04:11:24 DEBUG : dst/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d02477_d20251102_m041116_c001_v0001134_t0012_u01762056676151") 2025/11/02 04:11:24 DEBUG : dst/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487c8_d20251102_m041115_c001_v7007000_t0000_u01762056675413") 2025/11/02 04:11:24 DEBUG : dst/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab457_d20251102_m041111_c001_v0001157_t0033_u01762056671243") 2025/11/02 04:11:24 DEBUG : dst/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487c4_d20251102_m041110_c001_v7007000_t0000_u01762056670505") 2025/11/02 04:11:25 DEBUG : dst/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d02447_d20251102_m041105_c001_v0001134_t0011_u01762056665986") 2025/11/02 04:11:25 DEBUG : dst/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487bd_d20251102_m041104_c001_v7007000_t0000_u01762056664882") 2025/11/02 04:11:25 DEBUG : dst/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab414_d20251102_m041059_c001_v0001157_t0027_u01762056659087") 2025/11/02 04:11:25 DEBUG : dst/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487b2_d20251102_m041057_c001_v7007000_t0000_u01762056657993") 2025/11/02 04:11:25 DEBUG : dst/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d02408_d20251102_m041049_c001_v0001134_t0048_u01762056649517") 2025/11/02 04:11:25 DEBUG : dst/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f412fe48847c05f61_d20251102_m041048_c001_v7007000_t0000_u01762056648429") 2025/11/02 04:11:25 DEBUG : dst/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab3d8_d20251102_m041042_c001_v0001157_t0008_u01762056642621") 2025/11/02 04:11:25 DEBUG : dst/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f412fe48847c05f5b_d20251102_m041041_c001_v7007000_t0000_u01762056641897") 2025/11/02 04:11:25 DEBUG : dst/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab3a6_d20251102_m041033_c001_v0001157_t0008_u01762056633596") 2025/11/02 04:11:25 DEBUG : dst/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f10837f65b935b64d_d20251102_m041032_c001_v0001183_t0007_u01762056632844") 2025/11/02 04:11:25 DEBUG : dst/one: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f10837f65b935b649_d20251102_m041031_c001_v0001183_t0006_u01762056631545") 2025/11/02 04:11:25 DEBUG : dst/three.txt: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487db_d20251102_m041120_c001_v7007000_t0000_u01762056680545") 2025/11/02 04:11:25 DEBUG : dst/three.txt: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d0247b_d20251102_m041117_c001_v0001134_t0022_u01762056677137") 2025/11/02 04:11:25 DEBUG : dst/three.txt: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487c9_d20251102_m041115_c001_v7007000_t0000_u01762056675599") 2025/11/02 04:11:26 DEBUG : dst/three.txt: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab45b_d20251102_m041112_c001_v0001157_t0033_u01762056672191") 2025/11/02 04:11:26 DEBUG : dst/three.txt: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487c5_d20251102_m041110_c001_v7007000_t0000_u01762056670693") 2025/11/02 04:11:26 DEBUG : dst/three.txt: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d0244d_d20251102_m041107_c001_v0001134_t0038_u01762056667433") 2025/11/02 04:11:26 DEBUG : dst/three.txt: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487be_d20251102_m041105_c001_v7007000_t0000_u01762056665069") 2025/11/02 04:11:26 DEBUG : dst/three.txt: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab416_d20251102_m041100_c001_v0001157_t0057_u01762056660099") 2025/11/02 04:11:26 DEBUG : dst/three.txt: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487b5_d20251102_m041058_c001_v7007000_t0000_u01762056658180") 2025/11/02 04:11:26 DEBUG : dst/three.txt: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d0240a_d20251102_m041050_c001_v0001134_t0049_u01762056650676") 2025/11/02 04:11:26 DEBUG : dst/three.txt: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f412fe48847c05f62_d20251102_m041048_c001_v7007000_t0000_u01762056648610") 2025/11/02 04:11:26 DEBUG : dst/three.txt: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab3e4_d20251102_m041043_c001_v0001157_t0015_u01762056643648") 2025/11/02 04:11:26 DEBUG : dst/two: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487df_d20251102_m041120_c001_v7007000_t0000_u01762056680730") 2025/11/02 04:11:26 DEBUG : dst/two: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab471_d20251102_m041116_c001_v0001157_t0037_u01762056676669") 2025/11/02 04:11:26 DEBUG : dst/two: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487ca_d20251102_m041115_c001_v7007000_t0000_u01762056675785") 2025/11/02 04:11:26 DEBUG : dst/two: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d0245f_d20251102_m041111_c001_v0001134_t0054_u01762056671569") 2025/11/02 04:11:26 DEBUG : dst/two: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487c6_d20251102_m041110_c001_v7007000_t0000_u01762056670877") 2025/11/02 04:11:26 DEBUG : dst/two: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab445_d20251102_m041107_c001_v0001157_t0000_u01762056667091") 2025/11/02 04:11:26 DEBUG : dst/two: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487bf_d20251102_m041105_c001_v7007000_t0000_u01762056665254") 2025/11/02 04:11:26 DEBUG : dst/two: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d02430_d20251102_m041059_c001_v0001134_t0005_u01762056659821") 2025/11/02 04:11:26 DEBUG : dst/two: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487b7_d20251102_m041058_c001_v7007000_t0000_u01762056658364") 2025/11/02 04:11:26 DEBUG : dst/two: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab3f2_d20251102_m041050_c001_v0001157_t0039_u01762056650163") 2025/11/02 04:11:26 DEBUG : dst/two: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f412fe48847c05f64_d20251102_m041048_c001_v7007000_t0000_u01762056648791") 2025/11/02 04:11:27 DEBUG : dst/two: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d023ee_d20251102_m041043_c001_v0001134_t0022_u01762056643290") 2025/11/02 04:11:27 DEBUG : empty space: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b57725f_d20251102_m041024_c001_v7007000_t0000_u01762056624499") 2025/11/02 04:11:27 DEBUG : empty space: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab37c_d20251102_m041020_c001_v0001157_t0005_u01762056620874") 2025/11/02 04:11:27 DEBUG : empty space: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b577254_d20251102_m041018_c001_v7007000_t0000_u01762056618219") 2025/11/02 04:11:27 DEBUG : empty space: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d0238c_d20251102_m041013_c001_v0001134_t0011_u01762056613263") 2025/11/02 04:11:27 DEBUG : empty space: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b577224_d20251102_m041009_c001_v7007000_t0000_u01762056609394") 2025/11/02 04:11:27 DEBUG : empty space: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab331_d20251102_m041005_c001_v0001157_t0024_u01762056605901") 2025/11/02 04:11:27 DEBUG : empty space: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b576849_d20251102_m040953_c001_v7007000_t0000_u01762056593237") 2025/11/02 04:11:27 DEBUG : empty space: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d02352_d20251102_m040951_c001_v0001134_t0030_u01762056591310") 2025/11/02 04:11:27 DEBUG : nested/sub dir/file: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b57721a_d20251102_m041004_c001_v7007000_t0000_u01762056604173") 2025/11/02 04:11:27 DEBUG : nested/sub dir/file: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d02371_d20251102_m041003_c001_v0001134_t0025_u01762056603540") 2025/11/02 04:11:27 DEBUG : potato: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b577216_d20251102_m041002_c001_v7007000_t0000_u01762056602624") 2025/11/02 04:11:27 DEBUG : potato: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d0236b_d20251102_m041000_c001_v0001134_t0008_u01762056600068") 2025/11/02 04:11:27 DEBUG : potato: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b576854_d20251102_m040959_c001_v7007000_t0000_u01762056599165") 2025/11/02 04:11:27 DEBUG : potato: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d02362_d20251102_m040957_c001_v0001134_t0028_u01762056597050") 2025/11/02 04:11:27 DEBUG : potato: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b57684e_d20251102_m040956_c001_v7007000_t0000_u01762056596133") 2025/11/02 04:11:27 DEBUG : potato: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d02356_d20251102_m040953_c001_v0001134_t0034_u01762056593776") 2025/11/02 04:11:27 DEBUG : potato2: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b577262_d20251102_m041025_c001_v7007000_t0000_u01762056625786") 2025/11/02 04:11:27 DEBUG : potato2: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d0239e_d20251102_m041019_c001_v0001134_t0050_u01762056619870") 2025/11/02 04:11:27 DEBUG : potato2: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b577255_d20251102_m041018_c001_v7007000_t0000_u01762056618401") 2025/11/02 04:11:27 DEBUG : potato2: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab353_d20251102_m041012_c001_v0001157_t0021_u01762056612224") 2025/11/02 04:11:27 DEBUG : potato2: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b577229_d20251102_m041010_c001_v7007000_t0000_u01762056610746") 2025/11/02 04:11:28 DEBUG : potato2: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d02377_d20251102_m041005_c001_v0001134_t0019_u01762056605546") 2025/11/02 04:11:28 DEBUG : potato3: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b577263_d20251102_m041025_c001_v7007000_t0000_u01762056625970") 2025/11/02 04:11:28 DEBUG : potato3: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d023a4_d20251102_m041021_c001_v0001134_t0036_u01762056621373") 2025/11/02 04:11:28 DEBUG : potato3: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b577256_d20251102_m041018_c001_v7007000_t0000_u01762056618583") 2025/11/02 04:11:28 DEBUG : potato3: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab359_d20251102_m041014_c001_v0001157_t0046_u01762056614021") 2025/11/02 04:11:28 DEBUG : potato3: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b57722a_d20251102_m041010_c001_v7007000_t0000_u01762056610936") 2025/11/02 04:11:28 DEBUG : potato3: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d02379_d20251102_m041006_c001_v0001134_t0045_u01762056606270") 2025/11/02 04:11:28 DEBUG : sub dir/hello world: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b57721b_d20251102_m041004_c001_v7007000_t0000_u01762056604354") 2025/11/02 04:11:28 DEBUG : sub dir/hello world: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab31f_d20251102_m041003_c001_v0001157_t0057_u01762056603530") 2025/11/02 04:11:28 DEBUG : sub dir/hello world: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b576844_d20251102_m040950_c001_v7007000_t0000_u01762056590939") 2025/11/02 04:11:28 DEBUG : sub dir/hello world: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d02342_d20251102_m040947_c001_v0001134_t0039_u01762056587806") 2025/11/02 04:11:28 DEBUG : sub dir/hello world: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b57683a_d20251102_m040947_c001_v7007000_t0000_u01762056587442") 2025/11/02 04:11:28 DEBUG : sub dir/hello world: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d02338_d20251102_m040944_c001_v0001134_t0046_u01762056584205") 2025/11/02 04:11:28 DEBUG : sub dir/hello world: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b576832_d20251102_m040943_c001_v7007000_t0000_u01762056583844") 2025/11/02 04:11:28 DEBUG : sub dir/hello world: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d0232e_d20251102_m040940_c001_v0001134_t0014_u01762056580429") 2025/11/02 04:11:28 DEBUG : sub/yam: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b577218_d20251102_m041002_c001_v7007000_t0000_u01762056602806") 2025/11/02 04:11:28 DEBUG : sub/yam: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab315_d20251102_m041000_c001_v0001157_t0000_u01762056600079") 2025/11/02 04:11:28 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487f6_d20251102_m041123_c001_v7007000_t0000_u01762056683102") 2025/11/02 04:11:28 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab48b_d20251102_m041122_c001_v0001157_t0049_u01762056682536") 2025/11/02 04:11:28 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f40243b349ee487e5_d20251102_m041121_c001_v7007000_t0000_u01762056681818") 2025/11/02 04:11:28 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab489_d20251102_m041121_c001_v0001157_t0000_u01762056681284") 2025/11/02 04:11:28 DEBUG : yam: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b577219_d20251102_m041002_c001_v7007000_t0000_u01762056602988") 2025/11/02 04:11:29 DEBUG : yam: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d0236f_d20251102_m041001_c001_v0001134_t0052_u01762056601807") 2025/11/02 04:11:29 DEBUG : yam: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b576855_d20251102_m040959_c001_v7007000_t0000_u01762056599346") 2025/11/02 04:11:29 DEBUG : yam: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab305_d20251102_m040957_c001_v0001157_t0021_u01762056597040") 2025/11/02 04:11:29 DEBUG : yam: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b57684f_d20251102_m040956_c001_v7007000_t0000_u01762056596318") 2025/11/02 04:11:29 DEBUG : yam: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab2fb_d20251102_m040954_c001_v0001157_t0043_u01762056594334") 2025/11/02 04:11:29 DEBUG : yaml: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b576856_d20251102_m040959_c001_v7007000_t0000_u01762056599528") 2025/11/02 04:11:29 DEBUG : yaml: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f112c4757ed7ab30d_d20251102_m040958_c001_v0001157_t0012_u01762056598437") 2025/11/02 04:11:29 DEBUG : yaml: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f407c18167b576850_d20251102_m040956_c001_v7007000_t0000_u01762056596500") 2025/11/02 04:11:29 DEBUG : yaml: Deleting (id "4_zcf12ad93c01d4b2b9fa80e1b_f1142d93900d0235e_d20251102_m040955_c001_v0001134_t0050_u01762056595453") "./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 1m51.8907621s (try 5/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]