"./sync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^(TestBase64|TestManualTransformFile|TestMoveWithDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncAfterChangingModtimeOnly|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncCopyDest|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime|TestTransformFile)$'" - Starting (try 4/5) 2025/11/02 04:05:21 DEBUG : Creating backend with remote "TestB2:rclone-test-vemowac1jeno" 2025/11/02 04:05:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 04:05:21 DEBUG : Creating backend with remote "/tmp/rclone1447717072" === RUN TestServerSideCopy run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:05:23 DEBUG : Creating backend with remote "TestB2:rclone-test-papuqoy1jutu" sync_test.go:620: Server side copy (if possible) B2 bucket rclone-test-vemowac1jeno -> B2 bucket rclone-test-papuqoy1jutu 2025/11/02 04:05:24 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 04:05:24 DEBUG : B2 bucket rclone-test-papuqoy1jutu: Waiting for checks to finish 2025/11/02 04:05:24 DEBUG : B2 bucket rclone-test-papuqoy1jutu: Waiting for transfers to finish 2025/11/02 04:05:25 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:05:25 DEBUG : B2 bucket rclone-test-papuqoy1jutu: Purge remote 2025/11/02 04:05:25 INFO : B2 bucket rclone-test-papuqoy1jutu: cleaning bucket "rclone-test-papuqoy1jutu" of all files --- FAIL: TestServerSideCopy (4.95s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:05:27 DEBUG : Creating backend with remote "TestB2:rclone-test-pilarih0fito" sync_test.go:656: Server side copy (if possible) B2 bucket rclone-test-vemowac1jeno -> B2 bucket rclone-test-pilarih0fito 2025/11/02 04:05:28 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 04:05:28 DEBUG : B2 bucket rclone-test-pilarih0fito: Waiting for checks to finish 2025/11/02 04:05:28 DEBUG : B2 bucket rclone-test-pilarih0fito: Waiting for transfers to finish 2025/11/02 04:05:29 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:05:29 DEBUG : B2 bucket rclone-test-pilarih0fito: Purge remote 2025/11/02 04:05:29 INFO : B2 bucket rclone-test-pilarih0fito: cleaning bucket "rclone-test-pilarih0fito" of all files --- FAIL: TestServerSideCopyOverSelf (3.65s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:05:30 DEBUG : Creating backend with remote "TestB2:rclone-test-jeyazov1foyo" sync_test.go:701: Server side copy (if possible) B2 bucket rclone-test-vemowac1jeno -> B2 bucket rclone-test-jeyazov1foyo 2025/11/02 04:05:32 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 04:05:32 DEBUG : B2 bucket rclone-test-jeyazov1foyo: Waiting for checks to finish 2025/11/02 04:05:32 DEBUG : B2 bucket rclone-test-jeyazov1foyo: Waiting for transfers to finish 2025/11/02 04:05:32 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:05:32 DEBUG : B2 bucket rclone-test-jeyazov1foyo: Purge remote 2025/11/02 04:05:32 INFO : B2 bucket rclone-test-jeyazov1foyo: cleaning bucket "rclone-test-jeyazov1foyo" of all files --- FAIL: TestServerSideMoveOverSelf (3.72s) === RUN TestSyncAfterChangingModtimeOnly run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:05:34 DEBUG : empty space: size = 1 OK 2025/11/02 04:05:34 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:05:34 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for checks to finish 2025/11/02 04:05:34 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/02 04:05:34 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2025/11/02 04:05:34 DEBUG : empty space: Unchanged skipping 2025/11/02 04:05:34 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for transfers to finish 2025/11/02 04:05:34 DEBUG : Waiting for deletions to finish 2025/11/02 04:05:34 INFO : There was nothing to transfer 2025/11/02 04:05:35 DEBUG : empty space: size = 1 OK 2025/11/02 04:05:35 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for checks to finish 2025/11/02 04:05:35 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:05:35 DEBUG : empty space: sha1 = 3bc15c8aae3e4124dd409035f32ea2fd6835efc9 OK 2025/11/02 04:05:35 ERROR : empty space: Failed to set modification time: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:05:35 DEBUG : empty space: Unchanged skipping 2025/11/02 04:05:35 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for transfers to finish 2025/11/02 04:05:35 ERROR : B2 bucket rclone-test-vemowac1jeno: not deleting files as there were IO errors 2025/11/02 04:05:35 ERROR : B2 bucket rclone-test-vemowac1jeno: 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.28s) === RUN TestSyncWithTrackRenames run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" sync_test.go:1568: Can track renames: true 2025/11/02 04:05:36 INFO : B2 bucket rclone-test-vemowac1jeno: Making map for --track-renames 2025/11/02 04:05:36 INFO : B2 bucket rclone-test-vemowac1jeno: Finished making map for --track-renames 2025/11/02 04:05:36 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for checks to finish 2025/11/02 04:05:36 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 04:05:36 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for renames to finish 2025/11/02 04:05:36 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 04:05:36 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for transfers to finish 2025/11/02 04:05:36 DEBUG : potato: size = 14 OK 2025/11/02 04:05:36 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 04:05:36 INFO : potato: Copied (new) 2025/11/02 04:05:37 DEBUG : yam: size = 11 OK 2025/11/02 04:05:37 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:05:37 INFO : yam: Copied (new) 2025/11/02 04:05:37 DEBUG : Waiting for deletions to finish 2025/11/02 04:05:37 DEBUG : potato: size = 14 OK 2025/11/02 04:05:37 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:05:37 INFO : B2 bucket rclone-test-vemowac1jeno: Making map for --track-renames 2025/11/02 04:05:37 INFO : B2 bucket rclone-test-vemowac1jeno: Finished making map for --track-renames 2025/11/02 04:05:37 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for checks to finish 2025/11/02 04:05:37 DEBUG : potato: Unchanged skipping 2025/11/02 04:05:37 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for renames to finish 2025/11/02 04:05:38 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:05:38 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:05:38 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:05:38 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/02 04:05:38 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for transfers to finish 2025/11/02 04:05:38 DEBUG : yaml: size = 11 OK 2025/11/02 04:05:38 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:05:38 INFO : yaml: Copied (new) 2025/11/02 04:05:38 ERROR : B2 bucket rclone-test-vemowac1jeno: not deleting files as there were IO errors 2025/11/02 04:05:38 ERROR : B2 bucket rclone-test-vemowac1jeno: 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.99s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" sync_test.go:1640: Can track renames: true 2025/11/02 04:05:39 INFO : B2 bucket rclone-test-vemowac1jeno: Making map for --track-renames 2025/11/02 04:05:39 INFO : B2 bucket rclone-test-vemowac1jeno: Finished making map for --track-renames 2025/11/02 04:05:39 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for checks to finish 2025/11/02 04:05:39 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 04:05:39 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for renames to finish 2025/11/02 04:05:39 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 04:05:39 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for transfers to finish 2025/11/02 04:05:39 DEBUG : potato: size = 14 OK 2025/11/02 04:05:39 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 04:05:39 INFO : potato: Copied (new) 2025/11/02 04:05:40 DEBUG : yam: size = 11 OK 2025/11/02 04:05:40 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:05:40 INFO : yam: Copied (new) 2025/11/02 04:05:40 DEBUG : Waiting for deletions to finish 2025/11/02 04:05:40 DEBUG : potato: size = 14 OK 2025/11/02 04:05:40 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:05:40 INFO : B2 bucket rclone-test-vemowac1jeno: Making map for --track-renames 2025/11/02 04:05:40 INFO : B2 bucket rclone-test-vemowac1jeno: Finished making map for --track-renames 2025/11/02 04:05:40 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for checks to finish 2025/11/02 04:05:40 DEBUG : potato: Unchanged skipping 2025/11/02 04:05:40 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for renames to finish 2025/11/02 04:05:40 ERROR : yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:05:40 ERROR : yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:05:40 DEBUG : yaml: Failed to rename to "yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:05:40 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/11/02 04:05:40 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for transfers to finish 2025/11/02 04:05:41 DEBUG : yaml: size = 11 OK 2025/11/02 04:05:41 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:05:41 INFO : yaml: Copied (new) 2025/11/02 04:05:41 ERROR : B2 bucket rclone-test-vemowac1jeno: not deleting files as there were IO errors 2025/11/02 04:05:41 ERROR : B2 bucket rclone-test-vemowac1jeno: 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.62s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" sync_test.go:1679: Can track renames: true 2025/11/02 04:05:42 INFO : B2 bucket rclone-test-vemowac1jeno: Making map for --track-renames 2025/11/02 04:05:42 INFO : B2 bucket rclone-test-vemowac1jeno: Finished making map for --track-renames 2025/11/02 04:05:42 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for checks to finish 2025/11/02 04:05:42 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/11/02 04:05:42 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/11/02 04:05:42 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for renames to finish 2025/11/02 04:05:42 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for transfers to finish 2025/11/02 04:05:42 DEBUG : potato: size = 14 OK 2025/11/02 04:05:42 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/11/02 04:05:42 INFO : potato: Copied (new) 2025/11/02 04:05:42 DEBUG : sub/yam: size = 11 OK 2025/11/02 04:05:42 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:05:42 INFO : sub/yam: Copied (new) 2025/11/02 04:05:42 DEBUG : Waiting for deletions to finish 2025/11/02 04:05:42 DEBUG : potato: size = 14 OK 2025/11/02 04:05:42 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:05:42 DEBUG : potato: Unchanged skipping 2025/11/02 04:05:43 INFO : B2 bucket rclone-test-vemowac1jeno: Making map for --track-renames 2025/11/02 04:05:43 INFO : B2 bucket rclone-test-vemowac1jeno: Finished making map for --track-renames 2025/11/02 04:05:43 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for checks to finish 2025/11/02 04:05:43 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for renames to finish 2025/11/02 04:05:43 ERROR : sub/yam: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:05:43 ERROR : sub/yam: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:05:43 DEBUG : yam: Failed to rename to "sub/yam": expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:05:43 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/11/02 04:05:43 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for transfers to finish 2025/11/02 04:05:43 DEBUG : yam: size = 11 OK 2025/11/02 04:05:43 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/11/02 04:05:43 INFO : yam: Copied (new) 2025/11/02 04:05:43 ERROR : B2 bucket rclone-test-vemowac1jeno: not deleting files as there were IO errors 2025/11/02 04:05:43 ERROR : B2 bucket rclone-test-vemowac1jeno: 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.69s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:05:44 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/02 04:05:44 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/11/02 04:05:44 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for checks to finish 2025/11/02 04:05:44 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for transfers to finish 2025/11/02 04:05:45 DEBUG : sub dir/hello world: size = 11 OK 2025/11/02 04:05:45 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 04:05:45 INFO : sub dir/hello world: Copied (new) 2025/11/02 04:05:45 INFO : sub dir/hello world: Deleted 2025/11/02 04:05:45 DEBUG : nested/sub dir/file: size = 6 OK 2025/11/02 04:05:45 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/11/02 04:05:45 INFO : nested/sub dir/file: Copied (new) 2025/11/02 04:05:45 INFO : nested/sub dir/file: Deleted 2025/11/02 04:05:45 ERROR : Local file system at /tmp/rclone1447717072: 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.44s) === RUN TestServerSideMove run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:05:46 DEBUG : Creating backend with remote "TestB2:rclone-test-cuxakap0fomi" 2025/11/02 04:05:46 DEBUG : pacer: low level retry 1/10 (error incident id 3894aee67876-b5888f9186fb89e7 (500 internal_error)) 2025/11/02 04:05:46 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/02 04:05:46 DEBUG : pacer: low level retry 2/10 (error incident id 3894aee67876-4d669d2ceb301714 (500 internal_error)) 2025/11/02 04:05:46 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/11/02 04:05:47 DEBUG : pacer: low level retry 3/10 (error incident id 3894aee67876-4e0ef952e08f1f8d (500 internal_error)) 2025/11/02 04:05:47 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/11/02 04:05:47 DEBUG : pacer: low level retry 4/10 (error incident id 3894aee67876-97ecdbe6dfc3e500 (500 internal_error)) 2025/11/02 04:05:47 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/11/02 04:05:47 DEBUG : pacer: low level retry 5/10 (error incident id 3894aee67876-5529b8098db42a86 (500 internal_error)) 2025/11/02 04:05:47 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/11/02 04:05:47 DEBUG : pacer: low level retry 6/10 (error incident id 3894aee67876-4de939d25bc54ee3 (500 internal_error)) 2025/11/02 04:05:47 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/11/02 04:05:48 DEBUG : pacer: low level retry 7/10 (error incident id 3894aee67876-a886d3e9859adb0e (500 internal_error)) 2025/11/02 04:05:48 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/11/02 04:05:48 DEBUG : pacer: low level retry 8/10 (error incident id 3894aee67876-8245fc795c9b9507 (500 internal_error)) 2025/11/02 04:05:48 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2025/11/02 04:05:49 DEBUG : pacer: low level retry 9/10 (error incident id 3894aee67876-6b2fae60e367c935 (500 internal_error)) 2025/11/02 04:05:49 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2025/11/02 04:05:52 DEBUG : pacer: low level retry 10/10 (error incident id 3894aee67876-d243b644833b9491 (500 internal_error)) 2025/11/02 04:05:52 DEBUG : pacer: Rate limited, increasing sleep to 10.24s sync_test.go:1721: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1721 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1911 Error: Received unexpected error: failed to authorize account: failed to authenticate: incident id 3894aee67876-d243b644833b9491 (500 internal_error) Test: TestServerSideMove --- FAIL: TestServerSideMove (7.12s) === RUN TestServerSideMoveWithFilter run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:05:53 DEBUG : Creating backend with remote "TestB2:rclone-test-cezozud6tude" sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-vemowac1jeno -> B2 bucket rclone-test-cezozud6tude 2025/11/02 04:05:58 DEBUG : empty space: Excluded (Size Filter) 2025/11/02 04:05:58 DEBUG : empty space: Excluded 2025/11/02 04:05:58 DEBUG : empty space: Excluded (Size Filter) 2025/11/02 04:05:58 DEBUG : empty space: Excluded 2025/11/02 04:05:58 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 04:05:58 DEBUG : potato3: size = 68 (B2 bucket rclone-test-vemowac1jeno) 2025/11/02 04:05:58 DEBUG : potato3: size = 60 (B2 bucket rclone-test-cezozud6tude) 2025/11/02 04:05:58 DEBUG : potato3: Sizes differ 2025/11/02 04:05:58 DEBUG : B2 bucket rclone-test-cezozud6tude: Waiting for checks to finish 2025/11/02 04:05:58 DEBUG : B2 bucket rclone-test-cezozud6tude: Waiting for transfers to finish 2025/11/02 04:05:58 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:05:58 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:05:58 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:05:58 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:05:58 DEBUG : B2 bucket rclone-test-cezozud6tude: Purge remote 2025/11/02 04:05:58 INFO : B2 bucket rclone-test-cezozud6tude: cleaning bucket "rclone-test-cezozud6tude" of all files 2025/11/02 04:05:58 DEBUG : empty space: Deleting (id "4_z5fd24d13b0ed4b2b9fa80e1b_f1097087eb445b4a7_d20251102_m040557_c001_v0001161_t0053_u01762056357098") 2025/11/02 04:05:58 DEBUG : potato3: Deleting (id "4_z5fd24d13b0ed4b2b9fa80e1b_f1097087eb445b4ad_d20251102_m040557_c001_v0001161_t0025_u01762056357521") --- FAIL: TestServerSideMoveWithFilter (7.47s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:06:00 DEBUG : Creating backend with remote "TestB2:rclone-test-kaceneq7juta" 2025/11/02 04:06:03 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) B2 bucket rclone-test-vemowac1jeno -> B2 bucket rclone-test-kaceneq7juta 2025/11/02 04:06:06 DEBUG : potato2: Need to transfer - File not found at Destination 2025/11/02 04:06:06 DEBUG : empty space: size = 1 OK 2025/11/02 04:06:06 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/11/02 04:06:06 DEBUG : empty space: Unchanged skipping 2025/11/02 04:06:06 DEBUG : B2 bucket rclone-test-kaceneq7juta: Waiting for checks to finish 2025/11/02 04:06:06 DEBUG : potato3: size = 68 (B2 bucket rclone-test-vemowac1jeno) 2025/11/02 04:06:06 DEBUG : potato3: size = 60 (B2 bucket rclone-test-kaceneq7juta) 2025/11/02 04:06:06 DEBUG : potato3: Sizes differ 2025/11/02 04:06:06 ERROR : potato2: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:06:06 ERROR : potato2: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:06:06 INFO : empty space: Deleted 2025/11/02 04:06:06 DEBUG : B2 bucket rclone-test-kaceneq7juta: Waiting for transfers to finish 2025/11/02 04:06:06 ERROR : potato3: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:06:06 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:06:06 DEBUG : B2 bucket rclone-test-kaceneq7juta: Purge remote 2025/11/02 04:06:06 INFO : B2 bucket rclone-test-kaceneq7juta: cleaning bucket "rclone-test-kaceneq7juta" of all files 2025/11/02 04:06:06 DEBUG : empty space: Deleting (id "4_z5ff25df3b0ed4b2b9fa80e1b_f112d344f8f167b69_d20251102_m040604_c001_v0001106_t0046_u01762056364842") 2025/11/02 04:06:06 DEBUG : potato3: Deleting (id "4_z5ff25df3b0ed4b2b9fa80e1b_f112d344f8f167b6d_d20251102_m040605_c001_v0001106_t0038_u01762056365391") --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (7.26s) === RUN TestSyncCopyDest run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:06:07 DEBUG : Creating backend with remote "TestB2:rclone-test-vemowac1jeno/dst" 2025/11/02 04:06:09 DEBUG : Creating backend with remote "TestB2:rclone-test-vemowac1jeno/CopyDest" 2025/11/02 04:06:11 DEBUG : one: Need to transfer - File not found at Destination 2025/11/02 04:06:11 DEBUG : B2 bucket rclone-test-vemowac1jeno path dst: Waiting for checks to finish 2025/11/02 04:06:11 DEBUG : B2 bucket rclone-test-vemowac1jeno path dst: Waiting for transfers to finish 2025/11/02 04:06:12 DEBUG : one: size = 3 OK 2025/11/02 04:06:12 DEBUG : one: sha1 = fe05bcdcdc4928012781a5f1a2a77cbb5398e106 OK 2025/11/02 04:06:12 INFO : one: Copied (new) 2025/11/02 04:06:12 DEBUG : Waiting for deletions to finish 2025/11/02 04:06:12 DEBUG : one: size = 5 (Local file system at /tmp/rclone1447717072) 2025/11/02 04:06:12 DEBUG : B2 bucket rclone-test-vemowac1jeno path dst: Waiting for checks to finish 2025/11/02 04:06:12 DEBUG : one: size = 3 (B2 bucket rclone-test-vemowac1jeno path dst) 2025/11/02 04:06:12 DEBUG : one: Sizes differ 2025/11/02 04:06:12 DEBUG : B2 bucket rclone-test-vemowac1jeno path dst: Waiting for transfers to finish 2025/11/02 04:06:13 DEBUG : one: size = 5 OK 2025/11/02 04:06:13 DEBUG : one: sha1 = 7f376fd65843a1267de8f986d40e164e5a321c13 OK 2025/11/02 04:06:13 INFO : one: Copied (replaced existing) 2025/11/02 04:06:13 DEBUG : Waiting for deletions to finish 2025/11/02 04:06:15 DEBUG : Creating backend with remote "TestB2:rclone-test-vemowac1jeno/BackupDir" 2025/11/02 04:06:15 DEBUG : pacer: low level retry 1/10 (error incident id 3894aee67876-af26fc42af160337 (500 internal_error)) 2025/11/02 04:06:15 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/02 04:06:16 DEBUG : pacer: low level retry 2/10 (error incident id 3894aee67876-46c7b24ccb8db520 (500 internal_error)) 2025/11/02 04:06:16 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/11/02 04:06:16 DEBUG : pacer: low level retry 3/10 (error incident id 3894aee67876-06161d0c1aef91e7 (500 internal_error)) 2025/11/02 04:06:16 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/11/02 04:06:16 DEBUG : pacer: low level retry 4/10 (error incident id 3894aee67876-4e3c4ff8ddd8e860 (500 internal_error)) 2025/11/02 04:06:16 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/11/02 04:06:16 DEBUG : pacer: low level retry 5/10 (error incident id 3894aee67876-3a368b114b0dcc41 (500 internal_error)) 2025/11/02 04:06:16 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/11/02 04:06:16 DEBUG : pacer: low level retry 6/10 (error incident id 3894aee67876-2700ad0892ce1dca (500 internal_error)) 2025/11/02 04:06:16 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/11/02 04:06:17 DEBUG : pacer: low level retry 7/10 (error incident id 3894aee67876-f8715304e2c8fa69 (500 internal_error)) 2025/11/02 04:06:17 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/11/02 04:06:17 DEBUG : pacer: low level retry 8/10 (error incident id 3894aee67876-b49b92b8cf802c3a (500 internal_error)) 2025/11/02 04:06:17 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2025/11/02 04:06:19 DEBUG : pacer: low level retry 9/10 (error incident id 3894aee67876-c30b3ee7d95cb2b4 (500 internal_error)) 2025/11/02 04:06:19 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2025/11/02 04:06:21 DEBUG : pacer: low level retry 10/10 (error incident id 3894aee67876-f59c98aedd38a2e1 (500 internal_error)) 2025/11/02 04:06:21 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-vemowac1jeno/BackupDir": failed to authorize account: failed to authenticate: incident id 3894aee67876-f59c98aedd38a2e1 (500 internal_error) Test: TestSyncCopyDest --- FAIL: TestSyncCopyDest (14.88s) === RUN TestSyncBackupDir run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:06:24 DEBUG : Creating backend with remote "TestB2:rclone-test-vemowac1jeno/dst" 2025/11/02 04:06:25 DEBUG : Creating backend with remote "TestB2:rclone-test-vemowac1jeno/backup" 2025/11/02 04:06:26 DEBUG : pacer: low level retry 1/10 (error incident id 3894aee67876-eb82b3bf84618a9d (500 internal_error)) 2025/11/02 04:06:26 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/02 04:06:26 DEBUG : pacer: low level retry 2/10 (error incident id 3894aee67876-f839c4034ba4bf73 (500 internal_error)) 2025/11/02 04:06:26 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/11/02 04:06:26 DEBUG : pacer: low level retry 3/10 (error incident id 3894aee67876-9c8019fda0b8f920 (500 internal_error)) 2025/11/02 04:06:26 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/11/02 04:06:27 DEBUG : pacer: low level retry 4/10 (error incident id 3894aee67876-d042b4f21597aa86 (500 internal_error)) 2025/11/02 04:06:27 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/11/02 04:06:27 DEBUG : pacer: low level retry 5/10 (error incident id 3894aee67876-e3784a76c1655b07 (500 internal_error)) 2025/11/02 04:06:27 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/11/02 04:06:27 DEBUG : pacer: low level retry 6/10 (error incident id 3894aee67876-3716cbc5cb374c3c (500 internal_error)) 2025/11/02 04:06:27 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/11/02 04:06:28 DEBUG : pacer: low level retry 7/10 (error incident id 3894aee67876-cceec9a2acd8d471 (500 internal_error)) 2025/11/02 04:06:28 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/11/02 04:06:28 DEBUG : pacer: low level retry 8/10 (error incident id 3894aee67876-182a6ccf6c80b5de (500 internal_error)) 2025/11/02 04:06:28 DEBUG : pacer: Rate limited, increasing sleep to 2.56s 2025/11/02 04:06:29 DEBUG : pacer: low level retry 9/10 (error incident id 3894aee67876-962cb52c2394f735 (500 internal_error)) 2025/11/02 04:06:29 DEBUG : pacer: Rate limited, increasing sleep to 5.12s 2025/11/02 04:06:32 DEBUG : pacer: low level retry 10/10 (error incident id 3894aee67876-bcf3a636d29c9b56 (500 internal_error)) 2025/11/02 04:06:32 DEBUG : pacer: Rate limited, increasing sleep to 10.24s 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: failed to make fs for --backup-dir "TestB2:rclone-test-vemowac1jeno/backup": failed to authorize account: failed to authenticate: incident id 3894aee67876-bcf3a636d29c9b56 (500 internal_error) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (10.98s) === RUN TestSyncBackupDirWithSuffix run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:06:36 DEBUG : Creating backend with remote "TestB2:rclone-test-vemowac1jeno/dst" 2025/11/02 04:06:36 DEBUG : Config file has changed externally - reloading 2025/11/02 04:06:37 DEBUG : Creating backend with remote "TestB2:rclone-test-vemowac1jeno/backup" 2025/11/02 04:06:38 DEBUG : one: size = 4 (Local file system at /tmp/rclone1447717072) 2025/11/02 04:06:38 DEBUG : one: size = 3 (B2 bucket rclone-test-vemowac1jeno path dst) 2025/11/02 04:06:38 DEBUG : one: Sizes differ 2025/11/02 04:06:38 DEBUG : two: size = 3 OK 2025/11/02 04:06:38 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:06:38 DEBUG : two: Unchanged skipping 2025/11/02 04:06:38 DEBUG : B2 bucket rclone-test-vemowac1jeno path dst: Waiting for checks to finish 2025/11/02 04:06:40 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:06:40 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:06:40 DEBUG : B2 bucket rclone-test-vemowac1jeno path dst: Waiting for transfers to finish 2025/11/02 04:06:40 ERROR : B2 bucket rclone-test-vemowac1jeno path dst: not deleting files as there were IO errors 2025/11/02 04:06:40 ERROR : B2 bucket rclone-test-vemowac1jeno path dst: not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2461 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (7.18s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:06:43 DEBUG : Creating backend with remote "TestB2:rclone-test-vemowac1jeno/dst" 2025/11/02 04:06:44 DEBUG : Creating backend with remote "TestB2:rclone-test-vemowac1jeno/backup" 2025/11/02 04:06:46 DEBUG : one: size = 4 (Local file system at /tmp/rclone1447717072) 2025/11/02 04:06:46 DEBUG : one: size = 3 (B2 bucket rclone-test-vemowac1jeno path dst) 2025/11/02 04:06:46 DEBUG : one: Sizes differ 2025/11/02 04:06:46 DEBUG : two: size = 3 OK 2025/11/02 04:06:46 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:06:46 DEBUG : two: Unchanged skipping 2025/11/02 04:06:46 DEBUG : B2 bucket rclone-test-vemowac1jeno path dst: Waiting for checks to finish 2025/11/02 04:06:47 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:06:47 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:06:47 DEBUG : B2 bucket rclone-test-vemowac1jeno path dst: Waiting for transfers to finish 2025/11/02 04:06:47 ERROR : B2 bucket rclone-test-vemowac1jeno path dst: not deleting files as there were IO errors 2025/11/02 04:06:47 ERROR : B2 bucket rclone-test-vemowac1jeno path dst: not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2465 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (7.01s) === RUN TestSyncBackupDirSuffixOnly run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:06:49 DEBUG : Creating backend with remote "TestB2:rclone-test-vemowac1jeno/dst" 2025/11/02 04:06:51 DEBUG : two: size = 3 OK 2025/11/02 04:06:51 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2025/11/02 04:06:51 DEBUG : two: Unchanged skipping 2025/11/02 04:06:51 DEBUG : one: size = 4 (Local file system at /tmp/rclone1447717072) 2025/11/02 04:06:51 DEBUG : B2 bucket rclone-test-vemowac1jeno path dst: Waiting for checks to finish 2025/11/02 04:06:51 DEBUG : one: size = 3 (B2 bucket rclone-test-vemowac1jeno path dst) 2025/11/02 04:06:51 DEBUG : one: Sizes differ 2025/11/02 04:06:52 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:06:52 ERROR : one: Not deleting source as copy failed: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:06:52 DEBUG : B2 bucket rclone-test-vemowac1jeno path dst: Waiting for transfers to finish 2025/11/02 04:06:52 ERROR : B2 bucket rclone-test-vemowac1jeno path dst: not deleting files as there were IO errors 2025/11/02 04:06:52 ERROR : B2 bucket rclone-test-vemowac1jeno path dst: not deleting directories as there were IO errors sync_test.go:2415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2415 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2469 Error: Received unexpected error: expected a FileSseMode but found: '' (400 bad_request) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (4.94s) === RUN TestSyncSuffix run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:06:54 DEBUG : Creating backend with remote "TestB2:rclone-test-vemowac1jeno/dst" 2025/11/02 04:06:55 DEBUG : one: size = 4 (Local file system at /tmp/rclone1447717072) 2025/11/02 04:06:55 DEBUG : one: size = 3 (B2 bucket rclone-test-vemowac1jeno path dst) 2025/11/02 04:06:55 DEBUG : one: Sizes differ 2025/11/02 04:06:56 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:06:56 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.92s) === RUN TestSyncSuffixKeepExtension run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:06:59 DEBUG : Creating backend with remote "TestB2:rclone-test-vemowac1jeno/dst" 2025/11/02 04:07:00 DEBUG : one: size = 4 (Local file system at /tmp/rclone1447717072) 2025/11/02 04:07:00 DEBUG : one: size = 3 (B2 bucket rclone-test-vemowac1jeno path dst) 2025/11/02 04:07:00 DEBUG : one: Sizes differ 2025/11/02 04:07:01 ERROR : one: Failed to copy: expected a FileSseMode but found: '' (400 bad_request) 2025/11/02 04:07:01 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.50s) === RUN TestTransformFile run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:07:02 DEBUG : empty_dir: Making directory with metadata 2025/11/02 04:07:02 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/11/02 04:07:02 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:07:02 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:07:02 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:07:02 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:07:02 INFO : tictacempty_dir: Making directory 2025/11/02 04:07:02 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:07:02 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:07:02 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:07:02 INFO : tictactoe: Making directory 2025/11/02 04:07:02 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:07:02 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:07:02 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/02 04:07:02 INFO : tictactoe/tictactoe: Making directory 2025/11/02 04:07:02 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/02 04:07:02 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/11/02 04:07:02 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:07:02 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/02 04:07:02 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:07:02 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:07:02 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for checks to finish 2025/11/02 04:07:02 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for transfers to finish 2025/11/02 04:07:02 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2025/11/02 04:07:02 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 04:07:02 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:07:02 INFO : toe/toe/toe.txt: Deleted 2025/11/02 04:07:02 ERROR : Local file system at /tmp/rclone1447717072: 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.08s) === RUN TestManualTransformFile run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:07:03 DEBUG : Reset feature "Copy" 2025/11/02 04:07:03 DEBUG : Reset feature "Move" 2025/11/02 04:07:03 DEBUG : Reset feature "Copy" 2025/11/02 04:07:03 DEBUG : Reset feature "Move" 2025/11/02 04:07:03 DEBUG : empty_dir: Making directory with metadata 2025/11/02 04:07:03 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/11/02 04:07:03 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:07:03 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:07:03 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:07:03 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:07:03 INFO : tictacempty_dir: Making directory 2025/11/02 04:07:03 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/11/02 04:07:03 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:07:03 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:07:03 INFO : tictactoe: Making directory 2025/11/02 04:07:03 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:07:03 DEBUG : toe: transformed to: tictactoe 2025/11/02 04:07:03 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/02 04:07:03 INFO : tictactoe/tictactoe: Making directory 2025/11/02 04:07:03 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/11/02 04:07:03 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/11/02 04:07:03 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:07:03 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/02 04:07:03 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:07:03 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for checks to finish 2025/11/02 04:07:03 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:07:03 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for transfers to finish 2025/11/02 04:07:04 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2025/11/02 04:07:04 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 04:07:04 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/11/02 04:07:04 INFO : toe/toe/toe.txt: Deleted 2025/11/02 04:07:04 ERROR : Local file system at /tmp/rclone1447717072: 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.17s) === RUN TestBase64 run.go:185: Remote "B2 bucket rclone-test-vemowac1jeno", Local "Local file system at /tmp/rclone1447717072", Modify Window "1ms" 2025/11/02 04:07:04 DEBUG : toe: transformed to: dG9l 2025/11/02 04:07:04 DEBUG : toe: transformed to: dG9l 2025/11/02 04:07:04 INFO : dG9l: Making directory 2025/11/02 04:07:04 DEBUG : toe: transformed to: dG9l 2025/11/02 04:07:04 DEBUG : toe: transformed to: dG9l 2025/11/02 04:07:04 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/11/02 04:07:04 INFO : dG9l/dG9l: Making directory 2025/11/02 04:07:04 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/11/02 04:07:04 DEBUG : toe.txt: transformed to: dG9lLnR4dA== 2025/11/02 04:07:04 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/11/02 04:07:04 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/11/02 04:07:04 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/11/02 04:07:04 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for checks to finish 2025/11/02 04:07:04 DEBUG : B2 bucket rclone-test-vemowac1jeno: Waiting for transfers to finish 2025/11/02 04:07:05 DEBUG : dG9l/dG9l/dG9lLnR4dA==: size = 11 OK 2025/11/02 04:07:05 DEBUG : toe/toe/toe.txt: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/11/02 04:07:05 INFO : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA== 2025/11/02 04:07:05 ERROR : B2 bucket rclone-test-vemowac1jeno: not deleting files as there were IO errors 2025/11/02 04:07:05 ERROR : B2 bucket rclone-test-vemowac1jeno: 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.13s) FAIL 2025/11/02 04:07:05 DEBUG : B2 bucket rclone-test-vemowac1jeno: Purge remote 2025/11/02 04:07:05 INFO : B2 bucket rclone-test-vemowac1jeno: cleaning bucket "rclone-test-vemowac1jeno" of all files 2025/11/02 04:07:06 DEBUG : CopyDest/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f403b71a1918286b0_d20251102_m040622_c001_v7007000_t0000_u01762056382397") 2025/11/02 04:07:06 DEBUG : CopyDest/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c959396a_d20251102_m040614_c001_v0001161_t0019_u01762056374811") 2025/11/02 04:07:06 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d95641_d20251102_m040705_c001_v7007000_t0000_u01762056425489") 2025/11/02 04:07:06 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080deab_d20251102_m040704_c001_v0001130_t0059_u01762056424906") 2025/11/02 04:07:06 DEBUG : dst/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d9562f_d20251102_m040701_c001_v7007000_t0000_u01762056421751") 2025/11/02 04:07:06 DEBUG : dst/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95939f5_d20251102_m040657_c001_v0001161_t0004_u01762056417979") 2025/11/02 04:07:06 DEBUG : dst/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d9561e_d20251102_m040657_c001_v7007000_t0000_u01762056417248") 2025/11/02 04:07:06 DEBUG : dst/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080de7e_d20251102_m040653_c001_v0001130_t0044_u01762056413061") 2025/11/02 04:07:06 DEBUG : dst/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d95614_d20251102_m040652_c001_v7007000_t0000_u01762056412333") 2025/11/02 04:07:06 DEBUG : dst/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95939de_d20251102_m040648_c001_v0001161_t0035_u01762056408477") 2025/11/02 04:07:06 DEBUG : dst/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d9560b_d20251102_m040647_c001_v7007000_t0000_u01762056407383") 2025/11/02 04:07:06 DEBUG : dst/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080de66_d20251102_m040641_c001_v0001130_t0022_u01762056401470") 2025/11/02 04:07:06 DEBUG : dst/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d955e1_d20251102_m040640_c001_v7007000_t0000_u01762056400377") 2025/11/02 04:07:06 DEBUG : dst/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95939a6_d20251102_m040634_c001_v0001161_t0018_u01762056394287") 2025/11/02 04:07:06 DEBUG : dst/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d955ae_d20251102_m040633_c001_v7007000_t0000_u01762056393200") 2025/11/02 04:07:06 DEBUG : dst/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080de32_d20251102_m040623_c001_v0001130_t0021_u01762056383311") 2025/11/02 04:07:06 DEBUG : dst/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f403b71a1918286b1_d20251102_m040622_c001_v7007000_t0000_u01762056382581") 2025/11/02 04:07:06 DEBUG : dst/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080de0c_d20251102_m040614_c001_v0001130_t0016_u01762056374017") 2025/11/02 04:07:06 DEBUG : dst/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f1060a752e6a08d12_d20251102_m040612_c001_v0001131_t0048_u01762056372979") 2025/11/02 04:07:07 DEBUG : dst/one: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f1060a752e6a08d10_d20251102_m040611_c001_v0001131_t0049_u01762056371788") 2025/11/02 04:07:07 DEBUG : dst/three.txt: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d95631_d20251102_m040701_c001_v7007000_t0000_u01762056421932") 2025/11/02 04:07:07 DEBUG : dst/three.txt: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95939f9_d20251102_m040658_c001_v0001161_t0034_u01762056418681") 2025/11/02 04:07:07 DEBUG : dst/three.txt: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d95620_d20251102_m040657_c001_v7007000_t0000_u01762056417434") 2025/11/02 04:07:07 DEBUG : dst/three.txt: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080de82_d20251102_m040653_c001_v0001130_t0014_u01762056413969") 2025/11/02 04:07:07 DEBUG : dst/three.txt: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d95615_d20251102_m040652_c001_v7007000_t0000_u01762056412514") 2025/11/02 04:07:07 DEBUG : dst/three.txt: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95939e4_d20251102_m040649_c001_v0001161_t0041_u01762056409491") 2025/11/02 04:07:07 DEBUG : dst/three.txt: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d9560c_d20251102_m040647_c001_v7007000_t0000_u01762056407567") 2025/11/02 04:07:07 DEBUG : dst/three.txt: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080de68_d20251102_m040642_c001_v0001130_t0016_u01762056402535") 2025/11/02 04:07:07 DEBUG : dst/three.txt: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d955e3_d20251102_m040640_c001_v7007000_t0000_u01762056400559") 2025/11/02 04:07:07 DEBUG : dst/three.txt: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95939b0_d20251102_m040635_c001_v0001161_t0003_u01762056395487") 2025/11/02 04:07:07 DEBUG : dst/three.txt: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d955b1_d20251102_m040633_c001_v7007000_t0000_u01762056393381") 2025/11/02 04:07:07 DEBUG : dst/three.txt: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080de38_d20251102_m040624_c001_v0001130_t0006_u01762056384505") 2025/11/02 04:07:07 DEBUG : dst/two: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d95633_d20251102_m040702_c001_v7007000_t0000_u01762056422117") 2025/11/02 04:07:07 DEBUG : dst/two: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080de96_d20251102_m040658_c001_v0001130_t0020_u01762056418323") 2025/11/02 04:07:07 DEBUG : dst/two: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d95621_d20251102_m040657_c001_v7007000_t0000_u01762056417615") 2025/11/02 04:07:07 DEBUG : dst/two: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95939ec_d20251102_m040653_c001_v0001161_t0014_u01762056413531") 2025/11/02 04:07:07 DEBUG : dst/two: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d95616_d20251102_m040652_c001_v7007000_t0000_u01762056412696") 2025/11/02 04:07:07 DEBUG : dst/two: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080de76_d20251102_m040649_c001_v0001130_t0055_u01762056409187") 2025/11/02 04:07:07 DEBUG : dst/two: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d9560d_d20251102_m040647_c001_v7007000_t0000_u01762056407749") 2025/11/02 04:07:08 DEBUG : dst/two: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95939ca_d20251102_m040642_c001_v0001161_t0046_u01762056402258") 2025/11/02 04:07:08 DEBUG : dst/two: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d955e4_d20251102_m040640_c001_v7007000_t0000_u01762056400744") 2025/11/02 04:07:08 DEBUG : dst/two: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080de54_d20251102_m040635_c001_v0001130_t0022_u01762056395130") 2025/11/02 04:07:08 DEBUG : dst/two: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d955b3_d20251102_m040633_c001_v7007000_t0000_u01762056393562") 2025/11/02 04:07:08 DEBUG : dst/two: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c959398b_d20251102_m040624_c001_v0001161_t0027_u01762056384097") 2025/11/02 04:07:08 DEBUG : empty space: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d9554d_d20251102_m040606_c001_v7007000_t0000_u01762056366208") 2025/11/02 04:07:08 DEBUG : empty space: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080ddef_d20251102_m040602_c001_v0001130_t0037_u01762056362506") 2025/11/02 04:07:08 DEBUG : empty space: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d9553b_d20251102_m040600_c001_v7007000_t0000_u01762056360069") 2025/11/02 04:07:08 DEBUG : empty space: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c9593925_d20251102_m040555_c001_v0001161_t0026_u01762056355014") 2025/11/02 04:07:08 DEBUG : empty space: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f413849d483df228c_d20251102_m040536_c001_v7007000_t0000_u01762056336108") 2025/11/02 04:07:08 DEBUG : empty space: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95938d8_d20251102_m040534_c001_v0001161_t0056_u01762056334188") 2025/11/02 04:07:08 DEBUG : nested/sub dir/file: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f413849d483df22a4_d20251102_m040545_c001_v7007000_t0000_u01762056345670") 2025/11/02 04:07:08 DEBUG : nested/sub dir/file: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95938fb_d20251102_m040544_c001_v0001161_t0007_u01762056344968") 2025/11/02 04:07:08 DEBUG : potato: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f413849d483df229c_d20251102_m040544_c001_v7007000_t0000_u01762056344046") 2025/11/02 04:07:08 DEBUG : potato: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95938f3_d20251102_m040542_c001_v0001161_t0050_u01762056342283") 2025/11/02 04:07:08 DEBUG : potato: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f413849d483df2296_d20251102_m040541_c001_v7007000_t0000_u01762056341361") 2025/11/02 04:07:08 DEBUG : potato: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95938eb_d20251102_m040539_c001_v0001161_t0037_u01762056339656") 2025/11/02 04:07:08 DEBUG : potato: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f413849d483df2291_d20251102_m040538_c001_v7007000_t0000_u01762056338736") 2025/11/02 04:07:08 DEBUG : potato: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95938e3_d20251102_m040536_c001_v0001161_t0006_u01762056336651") 2025/11/02 04:07:08 DEBUG : potato2: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d9554e_d20251102_m040607_c001_v7007000_t0000_u01762056367513") 2025/11/02 04:07:09 DEBUG : potato2: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c9593940_d20251102_m040601_c001_v0001161_t0012_u01762056361733") 2025/11/02 04:07:09 DEBUG : potato2: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d9553d_d20251102_m040600_c001_v7007000_t0000_u01762056360263") 2025/11/02 04:07:09 DEBUG : potato2: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080ddd1_d20251102_m040554_c001_v0001130_t0053_u01762056354245") 2025/11/02 04:07:09 DEBUG : potato3: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d95550_d20251102_m040607_c001_v7007000_t0000_u01762056367702") 2025/11/02 04:07:09 DEBUG : potato3: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c9593942_d20251102_m040602_c001_v0001161_t0027_u01762056362914") 2025/11/02 04:07:09 DEBUG : potato3: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d9553e_d20251102_m040600_c001_v7007000_t0000_u01762056360444") 2025/11/02 04:07:09 DEBUG : potato3: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080ddd7_d20251102_m040555_c001_v0001130_t0052_u01762056355323") 2025/11/02 04:07:09 DEBUG : sub dir/hello world: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f413849d483df22a6_d20251102_m040545_c001_v7007000_t0000_u01762056345851") 2025/11/02 04:07:09 DEBUG : sub dir/hello world: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080ddb4_d20251102_m040544_c001_v0001130_t0026_u01762056344961") 2025/11/02 04:07:09 DEBUG : sub dir/hello world: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f413849d483df228a_d20251102_m040533_c001_v7007000_t0000_u01762056333824") 2025/11/02 04:07:09 DEBUG : sub dir/hello world: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95938ce_d20251102_m040530_c001_v0001161_t0058_u01762056330465") 2025/11/02 04:07:09 DEBUG : sub dir/hello world: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f413849d483df2282_d20251102_m040530_c001_v7007000_t0000_u01762056330103") 2025/11/02 04:07:09 DEBUG : sub dir/hello world: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95938ca_d20251102_m040526_c001_v0001161_t0055_u01762056326812") 2025/11/02 04:07:09 DEBUG : sub dir/hello world: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f413849d483df2277_d20251102_m040526_c001_v7007000_t0000_u01762056326407") 2025/11/02 04:07:09 DEBUG : sub dir/hello world: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95938c6_d20251102_m040523_c001_v0001161_t0022_u01762056323149") 2025/11/02 04:07:09 DEBUG : sub/yam: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f413849d483df229f_d20251102_m040544_c001_v7007000_t0000_u01762056344226") 2025/11/02 04:07:09 DEBUG : sub/yam: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080ddaa_d20251102_m040542_c001_v0001130_t0011_u01762056342275") 2025/11/02 04:07:09 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d9563e_d20251102_m040704_c001_v7007000_t0000_u01762056424360") 2025/11/02 04:07:09 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95939ff_d20251102_m040703_c001_v0001161_t0003_u01762056423739") 2025/11/02 04:07:09 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f4064ce6046d95637_d20251102_m040703_c001_v7007000_t0000_u01762056423193") 2025/11/02 04:07:10 DEBUG : tictactoe/tictactoe/tictactoe.txt: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080dea0_d20251102_m040702_c001_v0001130_t0005_u01762056422669") 2025/11/02 04:07:10 DEBUG : yam: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f413849d483df22a0_d20251102_m040544_c001_v7007000_t0000_u01762056344408") 2025/11/02 04:07:10 DEBUG : yam: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95938f7_d20251102_m040543_c001_v0001161_t0035_u01762056343569") 2025/11/02 04:07:10 DEBUG : yam: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f413849d483df2297_d20251102_m040541_c001_v7007000_t0000_u01762056341541") 2025/11/02 04:07:10 DEBUG : yam: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080dda6_d20251102_m040539_c001_v0001130_t0044_u01762056339648") 2025/11/02 04:07:10 DEBUG : yam: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f413849d483df2292_d20251102_m040538_c001_v7007000_t0000_u01762056338919") 2025/11/02 04:07:10 DEBUG : yam: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f101f01b71080dd9e_d20251102_m040537_c001_v0001130_t0009_u01762056337205") 2025/11/02 04:07:10 DEBUG : yaml: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f413849d483df2298_d20251102_m040541_c001_v7007000_t0000_u01762056341724") 2025/11/02 04:07:10 DEBUG : yaml: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95938ed_d20251102_m040540_c001_v0001161_t0042_u01762056340874") 2025/11/02 04:07:10 DEBUG : yaml: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f413849d483df2293_d20251102_m040539_c001_v7007000_t0000_u01762056339101") 2025/11/02 04:07:10 DEBUG : yaml: Deleting (id "4_zcf82bdc3b0dd4b2b9fa80e1b_f114c4f49c95938e9_d20251102_m040538_c001_v0001161_t0045_u01762056338256") "./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 1m50.193639502s (try 4/5): exit status 1: Failed [TestServerSideCopy TestServerSideCopyOverSelf TestServerSideMoveOverSelf TestSyncAfterChangingModtimeOnly TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncCopyDest TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension TestTransformFile TestManualTransformFile TestBase64]