"go test -v -timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Starting (try 1/5) === RUN TestUrlEncode --- PASS: TestUrlEncode (0.00s) === RUN TestTimeString --- PASS: TestTimeString (0.00s) === RUN TestParseTimeString b2_internal_test.go:183: potato: want error strconv.ParseInt: parsing "potato": invalid syntax got error --- PASS: TestParseTimeString (0.00s) === RUN TestIntegration fstests.go:438: Using remote "TestB2:" 2025/04/29 05:08:56 DEBUG : Creating backend with remote "TestB2:rclone-test-wifeyef5fixa" 2025/04/29 05:08:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" === RUN TestIntegration/FsCheckWrap fstests.go:479: Not a wrapping Fs === RUN TestIntegration/FsCommand === RUN TestIntegration/FsRmdirNotFound === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2025/04/29 05:08:59 INFO : dir/subdir: Making directory fstest.go:244: Filtering empty directory "dir" fstest.go:244: Filtering empty directory "dir/subdir" 2025/04/29 05:08:59 INFO : dir/subdir: Removing directory fstest.go:244: Filtering empty directory "dir" 2025/04/29 05:08:59 INFO : dir: Removing directory === RUN TestIntegration/FsMkdir/FsListEmpty === RUN TestIntegration/FsMkdir/FsListDirEmpty === RUN TestIntegration/FsMkdir/FsListRDirEmpty === RUN TestIntegration/FsMkdir/FsListDirNotFound === RUN TestIntegration/FsMkdir/FsListRDirNotFound === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:707: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:707: testing "．" === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:707: testing "．．" === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:707: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:707: testing " leading space" === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:707: testing "~leading tilde" === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:707: testing "␍leading CR" === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:707: testing "␊leading LF" === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:707: testing "␉leading HT" === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:707: testing "␋leading VT" === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:707: testing ".leading dot" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:707: testing "trailing space " === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:707: testing "trailing CR␍" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:707: testing "trailing LF␊" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:707: testing "trailing HT␉" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:707: testing "trailing VT␋" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:707: testing "trailing dot." === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:707: testing "invalid utf-8\xfe" === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:707: testing "test%46.txt" === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError === RUN TestIntegration/FsMkdir/FsPutZeroLength === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:786: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsOpenChunkWriter 2025/04/29 05:09:21 DEBUG : writer-at-subdir/writer-at-file: Streaming upload with --b2-chunk-size 96Mi allows uploads of up to 937.500Gi and will fail only when that limit is reached. 2025/04/29 05:09:22 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 1 length 5242880 2025/04/29 05:09:24 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 1 2025/04/29 05:09:24 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 2 length 1048576 2025/04/29 05:09:25 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 2 2025/04/29 05:09:25 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 0 length 5242880 2025/04/29 05:09:26 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 0 2025/04/29 05:09:26 DEBUG : writer-at-subdir/writer-at-file: Finishing large file upload with 0 parts === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:868: FS has no ChangeNotify interface === RUN TestIntegration/FsMkdir/FsPutFiles === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2025/04/29 05:09:33 DEBUG : Creating backend with remote "TestB2:" 2025/04/29 05:09:33 DEBUG : Config file has changed externally - reloading === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot 2025/04/29 05:09:34 DEBUG : Creating backend with remote "TestB2:" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1121: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2025/04/29 05:09:39 INFO : B2 bucket rclone-test-wifeyef5fixa: cleaning bucket "rclone-test-wifeyef5fixa" of all files 2025/04/29 05:09:39 DEBUG : dirToPurge/fileToPurge.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d3043_d20250429_m050938_c001_v0001183_t0003_u01745903378580") === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2025/04/29 05:09:40 DEBUG : Creating backend with remote "TestB2:rclone-test-wifeyef5fixa/dirToPurgeFromRoot" 2025/04/29 05:09:41 INFO : B2 bucket rclone-test-wifeyef5fixa path dirToPurgeFromRoot: cleaning bucket "rclone-test-wifeyef5fixa" of all files 2025/04/29 05:09:42 DEBUG : fileToPurgeFromRoot.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d304f_d20250429_m050940_c001_v0001183_t0055_u01745903380121") === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2025/04/29 05:09:42 DEBUG : Creating backend with remote "TestB2:rclone-test-wifeyef5fixa/hello? sausage/êé" 2025/04/29 05:09:47 INFO : B2 bucket rclone-test-wifeyef5fixa path hello? sausage/êé: cleaning bucket "rclone-test-wifeyef5fixa" of all files 2025/04/29 05:09:47 DEBUG : a/b/c/d/e.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f1055e0981f6d254b_d20250429_m050945_c001_v0001104_t0015_u01745903385465") === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1289: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove fstests.go:1342: FS has no Mover interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove fstests.go:1459: FS has no DirMover interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata fstests.go:1623: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1694: SetMetadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2025/04/29 05:09:56 DEBUG : Creating backend with remote "TestB2:rclone-test-wifeyef5fixa/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2025/04/29 05:09:58 DEBUG : Creating backend with remote "TestB2:rclone-test-wifeyef5fixa/not found.txt" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1911: Opening root remote "TestB2:" path "rclone-test-wifeyef5fixa" from "TestB2:rclone-test-wifeyef5fixa" 2025/04/29 05:10:00 DEBUG : Creating backend with remote "TestB2:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink 2025/04/29 05:10:11 DEBUG : Creating backend with remote "TestB2:rclone-test-detutis2recu" 2025/04/29 05:10:14 DEBUG : B2 bucket rclone-test-detutis2recu: Purge remote 2025/04/29 05:10:14 INFO : B2 bucket rclone-test-detutis2recu: cleaning bucket "rclone-test-detutis2recu" of all files 2025/04/29 05:10:14 DEBUG : somefile: Deleting (id "4_zef82ed53f03d7bfb9f680e1b_f409af4e6d90924f7_d20250429_m051013_c001_v0001092_t0015_u01745903413721") === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:412: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:2109: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/Bucket fstests.go:2131: Can't have // in paths === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout fstests.go:2281: FS does not support About === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 2025/04/29 05:10:16 DEBUG : piped data.txt: File has 0 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2025/04/29 05:10:17 DEBUG : piped data.txt: File has 100 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsPutFiles/Internal === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize/GzipEncoding === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize/GzipEncoding/NoDecompress === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB 2025/04/29 05:10:19 DEBUG : test-metadata: multipart upload: starting chunk 0 size 6Mi offset 0/6.106Mi 2025/04/29 05:10:19 DEBUG : test-metadata: Sending chunk 0 length 6291456 2025/04/29 05:10:19 DEBUG : test-metadata: multipart upload: starting chunk 1 size 108.573Ki offset 6Mi/6.106Mi 2025/04/29 05:10:19 DEBUG : test-metadata: Sending chunk 1 length 111179 2025/04/29 05:10:21 DEBUG : test-metadata: Done sending chunk 1 2025/04/29 05:10:22 DEBUG : test-metadata: Done sending chunk 0 2025/04/29 05:10:22 DEBUG : test-metadata: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB/GzipEncoding === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB/GzipEncoding/NoDecompress === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions 2025/04/29 05:10:30 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_hide_file": EOF) 2025/04/29 05:10:30 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/29 05:10:30 DEBUG : pacer: Reducing sleep to 10ms 2025/04/29 05:10:32 DEBUG : versions/test-versions.txt: Clearing upload URL because of error: Post "https://pod-000-1183-12.backblaze.com/b2api/v1/b2_upload_file/9f22cd33f02d7bfb9f680e1b/c001_v0001183_t0020": EOF 2025/04/29 05:10:32 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1183-12.backblaze.com/b2api/v1/b2_upload_file/9f22cd33f02d7bfb9f680e1b/c001_v0001183_t0020": EOF) 2025/04/29 05:10:32 DEBUG : pacer: Rate limited, increasing sleep to 20ms fstests.go:143: Put error: Post "https://pod-000-1183-12.backblaze.com/b2api/v1/b2_upload_file/9f22cd33f02d7bfb9f680e1b/c001_v0001183_t0020": EOF - low level retry 1/10 2025/04/29 05:10:35 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions b2_internal_test.go:357: versions/test-versions.txt === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/ReadCurrent === NAME TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions b2_internal_test.go:357: versions/test-versions-v2025-04-29-051027-809.txt === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/ReadVersion === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/NewObject === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/NewFs 2025/04/29 05:10:37 DEBUG : oldPath = "TestB2:rclone-test-wifeyef5fixa/versions/test-versions-v2025-04-29-051027-809.txt" 2025/04/29 05:10:37 DEBUG : newPath = "TestB2,versions:rclone-test-wifeyef5fixa/versions/test-versions-v2025-04-29-051027-809.txt" 2025/04/29 05:10:37 DEBUG : Creating backend with remote "TestB2,versions:rclone-test-wifeyef5fixa/versions/test-versions-v2025-04-29-051027-809.txt" 2025/04/29 05:10:37 DEBUG : TestB2: detected overridden config - adding "{pO73u}" suffix to name 2025/04/29 05:10:38 DEBUG : fs cache: renaming child cache item "TestB2,versions:rclone-test-wifeyef5fixa/versions/test-versions-v2025-04-29-051027-809.txt" to be canonical for parent "TestB2{pO73u}:rclone-test-wifeyef5fixa/versions" === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before/List === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne/List === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete/List === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo/List === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/DryRun 2025/04/29 05:10:39 INFO : B2 bucket rclone-test-wifeyef5fixa: cleaning bucket "rclone-test-wifeyef5fixa" of all hidden files 2025/04/29 05:10:40 DEBUG : leading space/ leading space: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697e0_d20250429_m050906_c001_v7007000_t0000_u01745903346231") as it is a hide marker 2025/04/29 05:10:40 NOTICE: leading space/ leading space: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : leading space/ leading space: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e02_d20250429_m050905_c001_v0001183_t0035_u01745903345626") 2025/04/29 05:10:40 NOTICE: leading space/ leading space: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697df_d20250429_m050905_c001_v7007000_t0000_u01745903345261") as it is a hide marker 2025/04/29 05:10:40 NOTICE: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2de8_d20250429_m050904_c001_v0001183_t0040_u01745903344663") 2025/04/29 05:10:40 NOTICE: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : .leading dot/.leading dot: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697ea_d20250429_m050912_c001_v7007000_t0000_u01745903352056") as it is a hide marker 2025/04/29 05:10:40 NOTICE: .leading dot/.leading dot: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : .leading dot/.leading dot: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e7c_d20250429_m050911_c001_v0001183_t0040_u01745903351482") 2025/04/29 05:10:40 NOTICE: .leading dot/.leading dot: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : created from root.txt: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f401306d4daaff931_d20250429_m051007_c001_v7007000_t0000_u01745903407734") as it is a hide marker 2025/04/29 05:10:40 NOTICE: created from root.txt: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : created from root.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f109c712a3e4b8133_d20250429_m051006_c001_v0001171_t0021_u01745903406546") 2025/04/29 05:10:40 NOTICE: created from root.txt: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : file name.txt: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f409b3212cf6e3b1e_d20250429_m051015_c001_v7007000_t0000_u01745903415937") as it is a hide marker 2025/04/29 05:10:40 NOTICE: file name.txt: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : file name.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d314f_d20250429_m050955_c001_v0001183_t0035_u01745903395741") 2025/04/29 05:10:40 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : file name.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f111b43a60302c19e_d20250429_m050952_c001_v0001103_t0018_u01745903392135") 2025/04/29 05:10:40 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : file name.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2fbe_d20250429_m050930_c001_v0001183_t0030_u01745903370801") 2025/04/29 05:10:40 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : file name.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2fa6_d20250429_m050930_c001_v0001183_t0037_u01745903370013") 2025/04/29 05:10:40 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2fac_d20250429_m050930_c001_v0001183_t0013_u01745903370405") "upload" dated 2025-04-29 05:09:30.405 +0000 UTC (1m9.760632791s ago) 2025/04/29 05:10:40 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f40040ee6b51be151_d20250429_m050949_c001_v7007000_t0000_u01745903389903") as it is a hide marker 2025/04/29 05:10:40 NOTICE: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f1164162d00bbc09e_d20250429_m050949_c001_v0001184_t0004_u01745903389616") 2025/04/29 05:10:40 NOTICE: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f1014e8f794fbc461_d20250429_m050948_c001_v0001184_t0021_u01745903388976") 2025/04/29 05:10:40 NOTICE: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : invalid utf-8/invalid utf-8: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa69816_d20250429_m050918_c001_v7007000_t0000_u01745903358890") as it is a hide marker 2025/04/29 05:10:40 NOTICE: invalid utf-8/invalid utf-8: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : invalid utf-8/invalid utf-8: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2ee0_d20250429_m050918_c001_v0001183_t0005_u01745903358293") 2025/04/29 05:10:40 NOTICE: invalid utf-8/invalid utf-8: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : piped data.txt: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f409b3212cf6e3b23_d20250429_m051017_c001_v7007000_t0000_u01745903417644") as it is a hide marker 2025/04/29 05:10:40 NOTICE: piped data.txt: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : piped data.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d32cf_d20250429_m051017_c001_v0001183_t0000_u01745903417234") 2025/04/29 05:10:40 NOTICE: piped data.txt: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : piped data.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f409b3212cf6e3b21_d20250429_m051017_c001_v7007000_t0000_u01745903417050") 2025/04/29 05:10:40 NOTICE: piped data.txt: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : piped data.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f41233797df1d32c1_d20250429_m051016_c001_v0001183_t0020_u01745903416664") 2025/04/29 05:10:40 NOTICE: piped data.txt: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : test%46.txt/test%46.txt: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa69824_d20250429_m050919_c001_v7007000_t0000_u01745903359880") as it is a hide marker 2025/04/29 05:10:40 NOTICE: test%46.txt/test%46.txt: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : test%46.txt/test%46.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2ef0_d20250429_m050919_c001_v0001183_t0022_u01745903359259") 2025/04/29 05:10:40 NOTICE: test%46.txt/test%46.txt: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : test-metadata: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f4023012e7deb3a60_d20250429_m051025_c001_v7007000_t0000_u01745903425259") as it is a hide marker 2025/04/29 05:10:40 NOTICE: test-metadata: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : test-metadata: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f209b3212cf6e3b28_d20250429_m051019_c001_v0001183_t0041_u01745903419488") 2025/04/29 05:10:40 NOTICE: test-metadata: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : test-metadata: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f409b3212cf6e3b26_d20250429_m051018_c001_v7007000_t0000_u01745903418585") 2025/04/29 05:10:40 NOTICE: test-metadata: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : test-metadata: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d32dd_d20250429_m051017_c001_v0001183_t0006_u01745903417832") 2025/04/29 05:10:40 NOTICE: test-metadata: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : trailing CR␍/trailing CR␍: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697ed_d20250429_m050913_c001_v7007000_t0000_u01745903353986") as it is a hide marker 2025/04/29 05:10:40 NOTICE: trailing CR␍/trailing CR␍: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : trailing CR␍/trailing CR␍: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e92_d20250429_m050913_c001_v0001183_t0050_u01745903353404") 2025/04/29 05:10:40 NOTICE: trailing CR␍/trailing CR␍: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : trailing HT␉/trailing HT␉: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697f2_d20250429_m050915_c001_v7007000_t0000_u01745903355938") as it is a hide marker 2025/04/29 05:10:40 NOTICE: trailing HT␉/trailing HT␉: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : trailing HT␉/trailing HT␉: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2eb2_d20250429_m050915_c001_v0001183_t0028_u01745903355370") 2025/04/29 05:10:40 NOTICE: trailing HT␉/trailing HT␉: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : trailing LF␊/trailing LF␊: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697f0_d20250429_m050914_c001_v7007000_t0000_u01745903354995") as it is a hide marker 2025/04/29 05:10:40 NOTICE: trailing LF␊/trailing LF␊: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : trailing LF␊/trailing LF␊: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2ea4_d20250429_m050914_c001_v0001183_t0033_u01745903354352") 2025/04/29 05:10:40 NOTICE: trailing LF␊/trailing LF␊: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : trailing VT␋/trailing VT␋: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697fd_d20250429_m050916_c001_v7007000_t0000_u01745903356914") as it is a hide marker 2025/04/29 05:10:40 NOTICE: trailing VT␋/trailing VT␋: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : trailing VT␋/trailing VT␋: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2ec2_d20250429_m050916_c001_v0001183_t0016_u01745903356312") 2025/04/29 05:10:40 NOTICE: trailing VT␋/trailing VT␋: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : trailing dot./trailing dot.: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa69809_d20250429_m050917_c001_v7007000_t0000_u01745903357919") as it is a hide marker 2025/04/29 05:10:40 NOTICE: trailing dot./trailing dot.: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : trailing dot./trailing dot.: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2ed0_d20250429_m050917_c001_v0001183_t0019_u01745903357286") 2025/04/29 05:10:40 NOTICE: trailing dot./trailing dot.: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : trailing space /trailing space : Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697eb_d20250429_m050913_c001_v7007000_t0000_u01745903353028") as it is a hide marker 2025/04/29 05:10:40 NOTICE: trailing space /trailing space : Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : trailing space /trailing space : Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e84_d20250429_m050912_c001_v0001183_t0002_u01745903352434") 2025/04/29 05:10:40 NOTICE: trailing space /trailing space : Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f114ace8682a85726_d20250429_m051035_c001_v0001183_t0006_u01745903435562") "upload" dated 2025-04-29 05:10:35.562 +0000 UTC (4.604014718s ago) 2025/04/29 05:10:40 DEBUG : versions/test-versions.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4023012e7deb3a69_d20250429_m051030_c001_v7007000_t0000_u01745903430808") 2025/04/29 05:10:40 NOTICE: versions/test-versions.txt: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : versions/test-versions.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d33ad_d20250429_m051027_c001_v0001183_t0015_u01745903427809") 2025/04/29 05:10:40 NOTICE: versions/test-versions.txt: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : writer-at-subdir/writer-at-file: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa6987c_d20250429_m050929_c001_v7007000_t0000_u01745903369448") as it is a hide marker 2025/04/29 05:10:40 NOTICE: writer-at-subdir/writer-at-file: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : writer-at-subdir/writer-at-file: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f219a281d9aa6983f_d20250429_m050922_c001_v0001117_t0041_u01745903362091") 2025/04/29 05:10:40 NOTICE: writer-at-subdir/writer-at-file: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : zero-length-file: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa6983c_d20250429_m050921_c001_v7007000_t0000_u01745903361764") as it is a hide marker 2025/04/29 05:10:40 NOTICE: zero-length-file: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : zero-length-file: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f41233797df1d2f14_d20250429_m050921_c001_v0001183_t0020_u01745903361206") 2025/04/29 05:10:40 NOTICE: zero-length-file: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : ~leading tilde/~leading tilde: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697e2_d20250429_m050907_c001_v7007000_t0000_u01745903347265") as it is a hide marker 2025/04/29 05:10:40 NOTICE: ~leading tilde/~leading tilde: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : ~leading tilde/~leading tilde: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e0e_d20250429_m050906_c001_v0001183_t0038_u01745903346633") 2025/04/29 05:10:40 NOTICE: ~leading tilde/~leading tilde: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697d6_d20250429_m050902_c001_v7007000_t0000_u01745903342312") as it is a hide marker 2025/04/29 05:10:40 NOTICE: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2dae_d20250429_m050901_c001_v0001183_t0049_u01745903341363") 2025/04/29 05:10:40 NOTICE: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : ␉leading HT/␉leading HT: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697e7_d20250429_m050910_c001_v7007000_t0000_u01745903350123") as it is a hide marker 2025/04/29 05:10:40 NOTICE: ␉leading HT/␉leading HT: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : ␉leading HT/␉leading HT: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e48_d20250429_m050909_c001_v0001183_t0043_u01745903349555") 2025/04/29 05:10:40 NOTICE: ␉leading HT/␉leading HT: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : ␊leading LF/␊leading LF: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697e5_d20250429_m050909_c001_v7007000_t0000_u01745903349181") as it is a hide marker 2025/04/29 05:10:40 NOTICE: ␊leading LF/␊leading LF: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : ␊leading LF/␊leading LF: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e36_d20250429_m050908_c001_v0001183_t0003_u01745903348605") 2025/04/29 05:10:40 NOTICE: ␊leading LF/␊leading LF: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : ␋leading VT/␋leading VT: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697e8_d20250429_m050911_c001_v7007000_t0000_u01745903351091") as it is a hide marker 2025/04/29 05:10:40 NOTICE: ␋leading VT/␋leading VT: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : ␋leading VT/␋leading VT: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e60_d20250429_m050910_c001_v0001183_t0029_u01745903350496") 2025/04/29 05:10:40 NOTICE: ␋leading VT/␋leading VT: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : ␍leading CR/␍leading CR: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697e4_d20250429_m050908_c001_v7007000_t0000_u01745903348236") as it is a hide marker 2025/04/29 05:10:40 NOTICE: ␍leading CR/␍leading CR: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : ␍leading CR/␍leading CR: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e26_d20250429_m050907_c001_v0001183_t0035_u01745903347636") 2025/04/29 05:10:40 NOTICE: ␍leading CR/␍leading CR: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : ．/．: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697d8_d20250429_m050903_c001_v7007000_t0000_u01745903343338") as it is a hide marker 2025/04/29 05:10:40 NOTICE: ．/．: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : ．/．: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2dc8_d20250429_m050902_c001_v0001183_t0053_u01745903342686") 2025/04/29 05:10:40 NOTICE: ．/．: Skipped delete as --dry-run is set 2025/04/29 05:10:40 DEBUG : ．．/．．: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697db_d20250429_m050904_c001_v7007000_t0000_u01745903344293") as it is a hide marker 2025/04/29 05:10:40 NOTICE: ．．/．．: Skipped remove hide marker as --dry-run is set 2025/04/29 05:10:40 DEBUG : ．．/．．: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2dda_d20250429_m050903_c001_v0001183_t0031_u01745903343728") 2025/04/29 05:10:40 NOTICE: ．．/．．: Skipped delete as --dry-run is set === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/RealThing 2025/04/29 05:10:40 INFO : B2 bucket rclone-test-wifeyef5fixa: cleaning bucket "rclone-test-wifeyef5fixa" of all hidden files 2025/04/29 05:10:40 DEBUG : leading space/ leading space: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697e0_d20250429_m050906_c001_v7007000_t0000_u01745903346231") as it is a hide marker 2025/04/29 05:10:40 DEBUG : leading space/ leading space: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e02_d20250429_m050905_c001_v0001183_t0035_u01745903345626") 2025/04/29 05:10:40 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697df_d20250429_m050905_c001_v7007000_t0000_u01745903345261") as it is a hide marker 2025/04/29 05:10:40 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2de8_d20250429_m050904_c001_v0001183_t0040_u01745903344663") 2025/04/29 05:10:40 DEBUG : .leading dot/.leading dot: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697ea_d20250429_m050912_c001_v7007000_t0000_u01745903352056") as it is a hide marker 2025/04/29 05:10:40 DEBUG : .leading dot/.leading dot: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e7c_d20250429_m050911_c001_v0001183_t0040_u01745903351482") 2025/04/29 05:10:40 DEBUG : created from root.txt: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f401306d4daaff931_d20250429_m051007_c001_v7007000_t0000_u01745903407734") as it is a hide marker 2025/04/29 05:10:40 DEBUG : created from root.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f109c712a3e4b8133_d20250429_m051006_c001_v0001171_t0021_u01745903406546") 2025/04/29 05:10:40 DEBUG : file name.txt: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f409b3212cf6e3b1e_d20250429_m051015_c001_v7007000_t0000_u01745903415937") as it is a hide marker 2025/04/29 05:10:40 DEBUG : file name.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d314f_d20250429_m050955_c001_v0001183_t0035_u01745903395741") 2025/04/29 05:10:41 DEBUG : file name.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f111b43a60302c19e_d20250429_m050952_c001_v0001103_t0018_u01745903392135") 2025/04/29 05:10:41 DEBUG : file name.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2fbe_d20250429_m050930_c001_v0001183_t0030_u01745903370801") 2025/04/29 05:10:41 DEBUG : file name.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2fa6_d20250429_m050930_c001_v0001183_t0037_u01745903370013") 2025/04/29 05:10:41 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2fac_d20250429_m050930_c001_v0001183_t0013_u01745903370405") "upload" dated 2025-04-29 05:09:30.405 +0000 UTC (1m10.791989789s ago) 2025/04/29 05:10:41 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f40040ee6b51be151_d20250429_m050949_c001_v7007000_t0000_u01745903389903") as it is a hide marker 2025/04/29 05:10:41 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f1164162d00bbc09e_d20250429_m050949_c001_v0001184_t0004_u01745903389616") 2025/04/29 05:10:41 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f1014e8f794fbc461_d20250429_m050948_c001_v0001184_t0021_u01745903388976") 2025/04/29 05:10:41 DEBUG : invalid utf-8/invalid utf-8: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa69816_d20250429_m050918_c001_v7007000_t0000_u01745903358890") as it is a hide marker 2025/04/29 05:10:41 DEBUG : invalid utf-8/invalid utf-8: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2ee0_d20250429_m050918_c001_v0001183_t0005_u01745903358293") 2025/04/29 05:10:41 DEBUG : piped data.txt: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f409b3212cf6e3b23_d20250429_m051017_c001_v7007000_t0000_u01745903417644") as it is a hide marker 2025/04/29 05:10:41 DEBUG : piped data.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d32cf_d20250429_m051017_c001_v0001183_t0000_u01745903417234") 2025/04/29 05:10:41 DEBUG : piped data.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f409b3212cf6e3b21_d20250429_m051017_c001_v7007000_t0000_u01745903417050") 2025/04/29 05:10:41 DEBUG : piped data.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f41233797df1d32c1_d20250429_m051016_c001_v0001183_t0020_u01745903416664") 2025/04/29 05:10:41 DEBUG : test%46.txt/test%46.txt: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa69824_d20250429_m050919_c001_v7007000_t0000_u01745903359880") as it is a hide marker 2025/04/29 05:10:41 DEBUG : test%46.txt/test%46.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2ef0_d20250429_m050919_c001_v0001183_t0022_u01745903359259") 2025/04/29 05:10:41 DEBUG : test-metadata: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f4023012e7deb3a60_d20250429_m051025_c001_v7007000_t0000_u01745903425259") as it is a hide marker 2025/04/29 05:10:41 DEBUG : test-metadata: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f209b3212cf6e3b28_d20250429_m051019_c001_v0001183_t0041_u01745903419488") 2025/04/29 05:10:41 DEBUG : test-metadata: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f409b3212cf6e3b26_d20250429_m051018_c001_v7007000_t0000_u01745903418585") 2025/04/29 05:10:41 DEBUG : test-metadata: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d32dd_d20250429_m051017_c001_v0001183_t0006_u01745903417832") 2025/04/29 05:10:42 DEBUG : trailing CR␍/trailing CR␍: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697ed_d20250429_m050913_c001_v7007000_t0000_u01745903353986") as it is a hide marker 2025/04/29 05:10:42 DEBUG : trailing CR␍/trailing CR␍: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e92_d20250429_m050913_c001_v0001183_t0050_u01745903353404") 2025/04/29 05:10:42 DEBUG : trailing HT␉/trailing HT␉: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697f2_d20250429_m050915_c001_v7007000_t0000_u01745903355938") as it is a hide marker 2025/04/29 05:10:42 DEBUG : trailing HT␉/trailing HT␉: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2eb2_d20250429_m050915_c001_v0001183_t0028_u01745903355370") 2025/04/29 05:10:42 DEBUG : trailing LF␊/trailing LF␊: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697f0_d20250429_m050914_c001_v7007000_t0000_u01745903354995") as it is a hide marker 2025/04/29 05:10:42 DEBUG : trailing LF␊/trailing LF␊: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2ea4_d20250429_m050914_c001_v0001183_t0033_u01745903354352") 2025/04/29 05:10:42 DEBUG : trailing VT␋/trailing VT␋: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697fd_d20250429_m050916_c001_v7007000_t0000_u01745903356914") as it is a hide marker 2025/04/29 05:10:42 DEBUG : trailing VT␋/trailing VT␋: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2ec2_d20250429_m050916_c001_v0001183_t0016_u01745903356312") 2025/04/29 05:10:42 DEBUG : trailing dot./trailing dot.: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa69809_d20250429_m050917_c001_v7007000_t0000_u01745903357919") as it is a hide marker 2025/04/29 05:10:42 DEBUG : trailing dot./trailing dot.: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2ed0_d20250429_m050917_c001_v0001183_t0019_u01745903357286") 2025/04/29 05:10:42 DEBUG : trailing space /trailing space : Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697eb_d20250429_m050913_c001_v7007000_t0000_u01745903353028") as it is a hide marker 2025/04/29 05:10:42 DEBUG : trailing space /trailing space : Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e84_d20250429_m050912_c001_v0001183_t0002_u01745903352434") 2025/04/29 05:10:42 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f114ace8682a85726_d20250429_m051035_c001_v0001183_t0006_u01745903435562") "upload" dated 2025-04-29 05:10:35.562 +0000 UTC (7.062410784s ago) 2025/04/29 05:10:42 DEBUG : versions/test-versions.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4023012e7deb3a69_d20250429_m051030_c001_v7007000_t0000_u01745903430808") 2025/04/29 05:10:42 DEBUG : versions/test-versions.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d33ad_d20250429_m051027_c001_v0001183_t0015_u01745903427809") 2025/04/29 05:10:42 DEBUG : writer-at-subdir/writer-at-file: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa6987c_d20250429_m050929_c001_v7007000_t0000_u01745903369448") as it is a hide marker 2025/04/29 05:10:42 DEBUG : writer-at-subdir/writer-at-file: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f219a281d9aa6983f_d20250429_m050922_c001_v0001117_t0041_u01745903362091") 2025/04/29 05:10:42 DEBUG : zero-length-file: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa6983c_d20250429_m050921_c001_v7007000_t0000_u01745903361764") as it is a hide marker 2025/04/29 05:10:42 DEBUG : zero-length-file: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f41233797df1d2f14_d20250429_m050921_c001_v0001183_t0020_u01745903361206") 2025/04/29 05:10:42 DEBUG : ~leading tilde/~leading tilde: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697e2_d20250429_m050907_c001_v7007000_t0000_u01745903347265") as it is a hide marker 2025/04/29 05:10:42 DEBUG : ~leading tilde/~leading tilde: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e0e_d20250429_m050906_c001_v0001183_t0038_u01745903346633") 2025/04/29 05:10:42 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697d6_d20250429_m050902_c001_v7007000_t0000_u01745903342312") as it is a hide marker 2025/04/29 05:10:43 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2dae_d20250429_m050901_c001_v0001183_t0049_u01745903341363") 2025/04/29 05:10:43 DEBUG : ␉leading HT/␉leading HT: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697e7_d20250429_m050910_c001_v7007000_t0000_u01745903350123") as it is a hide marker 2025/04/29 05:10:43 DEBUG : ␉leading HT/␉leading HT: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e48_d20250429_m050909_c001_v0001183_t0043_u01745903349555") 2025/04/29 05:10:43 DEBUG : ␊leading LF/␊leading LF: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697e5_d20250429_m050909_c001_v7007000_t0000_u01745903349181") as it is a hide marker 2025/04/29 05:10:43 DEBUG : ␊leading LF/␊leading LF: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e36_d20250429_m050908_c001_v0001183_t0003_u01745903348605") 2025/04/29 05:10:43 DEBUG : ␋leading VT/␋leading VT: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697e8_d20250429_m050911_c001_v7007000_t0000_u01745903351091") as it is a hide marker 2025/04/29 05:10:43 DEBUG : ␋leading VT/␋leading VT: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e60_d20250429_m050910_c001_v0001183_t0029_u01745903350496") 2025/04/29 05:10:43 DEBUG : ␍leading CR/␍leading CR: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697e4_d20250429_m050908_c001_v7007000_t0000_u01745903348236") as it is a hide marker 2025/04/29 05:10:43 DEBUG : ␍leading CR/␍leading CR: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2e26_d20250429_m050907_c001_v0001183_t0035_u01745903347636") 2025/04/29 05:10:43 DEBUG : ．/．: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697d8_d20250429_m050903_c001_v7007000_t0000_u01745903343338") as it is a hide marker 2025/04/29 05:10:43 DEBUG : ．/．: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2dc8_d20250429_m050902_c001_v0001183_t0053_u01745903342686") 2025/04/29 05:10:43 DEBUG : ．．/．．: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f419a281d9aa697db_d20250429_m050904_c001_v7007000_t0000_u01745903344293") as it is a hide marker 2025/04/29 05:10:43 DEBUG : ．．/．．: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2dda_d20250429_m050903_c001_v0001183_t0031_u01745903343728") === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished 2025/04/29 05:10:44 DEBUG : unfinished/unfinished-1: Streaming upload with --b2-chunk-size 6Mi allows uploads of up to 58.594Gi and will fail only when that limit is reached. 2025/04/29 05:10:44 DEBUG : unfinished/unfinished-2: Streaming upload with --b2-chunk-size 6Mi allows uploads of up to 58.594Gi and will fail only when that limit is reached. 2025/04/29 05:10:44 DEBUG : unfinished/unfinished-3: Streaming upload with --b2-chunk-size 6Mi allows uploads of up to 58.594Gi and will fail only when that limit is reached. 2025/04/29 05:10:44 DEBUG : unfinished/unfinished-4: Streaming upload with --b2-chunk-size 6Mi allows uploads of up to 58.594Gi and will fail only when that limit is reached. === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished/DryRun 2025/04/29 05:10:45 INFO : B2 bucket rclone-test-wifeyef5fixa: cleaning bucket "rclone-test-wifeyef5fixa" of pending multipart uploads older than 0s 2025/04/29 05:10:45 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2fac_d20250429_m050930_c001_v0001183_t0013_u01745903370405") "upload" dated 2025-04-29 05:09:30.405 +0000 UTC (1m15.127001299s ago) 2025/04/29 05:10:45 DEBUG : unfinished/unfinished-1: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f2023012e7deb3aaa_d20250429_m051044_c001_v0001040_t0013_u01745903444471") as it is a start marker (upload started at 2025-04-29 05:10:44.471 +0000 UTC) 2025/04/29 05:10:45 NOTICE: unfinished/unfinished-1: Skipped remove pending upload as --dry-run is set 2025/04/29 05:10:45 DEBUG : unfinished/unfinished-2: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f2023012e7deb3aac_d20250429_m051044_c001_v0001146_t0034_u01745903444670") as it is a start marker (upload started at 2025-04-29 05:10:44.67 +0000 UTC) 2025/04/29 05:10:45 NOTICE: unfinished/unfinished-2: Skipped remove pending upload as --dry-run is set 2025/04/29 05:10:45 DEBUG : unfinished/unfinished-3: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f2023012e7deb3aae_d20250429_m051044_c001_v0001177_t0025_u01745903444855") as it is a start marker (upload started at 2025-04-29 05:10:44.855 +0000 UTC) 2025/04/29 05:10:45 NOTICE: unfinished/unfinished-3: Skipped remove pending upload as --dry-run is set 2025/04/29 05:10:45 DEBUG : unfinished/unfinished-4: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f2023012e7deb3aaf_d20250429_m051045_c001_v0001043_t0019_u01745903445069") as it is a start marker (upload started at 2025-04-29 05:10:45.069 +0000 UTC) 2025/04/29 05:10:45 NOTICE: unfinished/unfinished-4: Skipped remove pending upload as --dry-run is set 2025/04/29 05:10:45 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f4023012e7deb3aa9_d20250429_m051044_c001_v7007000_t0000_u01745903444288") "hide" dated 2025-04-29 05:10:44.288 +0000 UTC (1.244184393s ago) 2025/04/29 05:10:45 DEBUG : versions/test-versions.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f114ace8682a85726_d20250429_m051035_c001_v0001183_t0006_u01745903435562") 2025/04/29 05:10:45 NOTICE: versions/test-versions.txt: Skipped delete as --dry-run is set === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished/RealThing 2025/04/29 05:10:45 INFO : B2 bucket rclone-test-wifeyef5fixa: cleaning bucket "rclone-test-wifeyef5fixa" of pending multipart uploads older than 0s 2025/04/29 05:10:45 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2fac_d20250429_m050930_c001_v0001183_t0013_u01745903370405") "upload" dated 2025-04-29 05:09:30.405 +0000 UTC (1m15.496291513s ago) 2025/04/29 05:10:45 DEBUG : unfinished/unfinished-1: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f2023012e7deb3aaa_d20250429_m051044_c001_v0001040_t0013_u01745903444471") as it is a start marker (upload started at 2025-04-29 05:10:44.471 +0000 UTC) 2025/04/29 05:10:45 DEBUG : unfinished/unfinished-2: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f2023012e7deb3aac_d20250429_m051044_c001_v0001146_t0034_u01745903444670") as it is a start marker (upload started at 2025-04-29 05:10:44.67 +0000 UTC) 2025/04/29 05:10:45 DEBUG : unfinished/unfinished-3: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f2023012e7deb3aae_d20250429_m051044_c001_v0001177_t0025_u01745903444855") as it is a start marker (upload started at 2025-04-29 05:10:44.855 +0000 UTC) 2025/04/29 05:10:45 DEBUG : unfinished/unfinished-4: Deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f2023012e7deb3aaf_d20250429_m051045_c001_v0001043_t0019_u01745903445069") as it is a start marker (upload started at 2025-04-29 05:10:45.069 +0000 UTC) 2025/04/29 05:10:45 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z9f22cd33f02d7bfb9f680e1b_f4023012e7deb3aa9_d20250429_m051044_c001_v7007000_t0000_u01745903444288") "hide" dated 2025-04-29 05:10:44.288 +0000 UTC (1.613454188s ago) 2025/04/29 05:10:45 DEBUG : versions/test-versions.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f114ace8682a85726_d20250429_m051035_c001_v0001183_t0006_u01745903435562") === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/InitState === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/DryRun 2025/04/29 05:10:46 NOTICE: lifecycle: Skipped update lifecycle rules as --dry-run is set 2025/04/29 05:10:46 NOTICE: lifecycle: Skipped update lifecycle rules as --dry-run is set 2025/04/29 05:10:47 NOTICE: lifecycle: Skipped update lifecycle rules as --dry-run is set === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/RealThing === RUN TestIntegration/FsMkdir/FsPutChunked === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881 2025/04/29 05:10:48 DEBUG : chunked-5Mi-5.000Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/5.000Mi 2025/04/29 05:10:48 DEBUG : chunked-5Mi-5.000Mi.bin: Sending chunk 0 length 5242880 2025/04/29 05:10:48 DEBUG : chunked-5Mi-5.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 5Mi/5.000Mi 2025/04/29 05:10:48 DEBUG : chunked-5Mi-5.000Mi.bin: Sending chunk 1 length 1 2025/04/29 05:10:49 DEBUG : chunked-5Mi-5.000Mi.bin: Done sending chunk 1 2025/04/29 05:11:03 DEBUG : chunked-5Mi-5.000Mi.bin: Done sending chunk 0 2025/04/29 05:11:03 DEBUG : chunked-5Mi-5.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881/Streamed 2025/04/29 05:11:06 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:11:06 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Streaming upload with --b2-chunk-size 5Mi allows uploads of up to 48.828Gi and will fail only when that limit is reached. 2025/04/29 05:11:06 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f20170bfa1596d61c_d20250429_m051106_c001_v0001113_t0017_u01745903466215") 2025/04/29 05:11:06 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/04/29 05:11:06 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/04/29 05:11:06 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 1 length 1 2025/04/29 05:11:07 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:11:09 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:11:09 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760 2025/04/29 05:11:10 DEBUG : chunked-5Mi-10Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/10Mi 2025/04/29 05:11:10 DEBUG : chunked-5Mi-10Mi.bin: Sending chunk 0 length 5242880 2025/04/29 05:11:10 DEBUG : chunked-5Mi-10Mi.bin: multipart upload: starting chunk 1 size 5Mi offset 5Mi/10Mi 2025/04/29 05:11:10 DEBUG : chunked-5Mi-10Mi.bin: Sending chunk 1 length 5242880 2025/04/29 05:11:13 DEBUG : chunked-5Mi-10Mi.bin: Done sending chunk 1 2025/04/29 05:11:15 DEBUG : chunked-5Mi-10Mi.bin: Done sending chunk 0 2025/04/29 05:11:15 DEBUG : chunked-5Mi-10Mi.bin: Finishing large file upload with 2 parts 2025/04/29 05:11:15 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_finish_large_file": EOF) 2025/04/29 05:11:15 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/29 05:11:16 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760/Streamed 2025/04/29 05:11:19 DEBUG : chunked-5Mi-10Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:11:19 DEBUG : chunked-5Mi-10Mi-streamed.bin: Streaming upload with --b2-chunk-size 5Mi allows uploads of up to 48.828Gi and will fail only when that limit is reached. 2025/04/29 05:11:19 DEBUG : chunked-5Mi-10Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f2155e906ed48c6be_d20250429_m051119_c001_v0001183_t0056_u01745903479596") 2025/04/29 05:11:19 DEBUG : chunked-5Mi-10Mi-streamed.bin: Sending chunk 0 length 5242880 2025/04/29 05:11:19 DEBUG : chunked-5Mi-10Mi-streamed.bin: Sending chunk 1 length 5242880 2025/04/29 05:11:19 DEBUG : chunked-5Mi-10Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/04/29 05:11:21 DEBUG : chunked-5Mi-10Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:11:22 DEBUG : chunked-5Mi-10Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:11:22 DEBUG : chunked-5Mi-10Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761 2025/04/29 05:11:24 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/10.000Mi 2025/04/29 05:11:24 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 0 length 5242880 2025/04/29 05:11:24 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 10Mi/10.000Mi 2025/04/29 05:11:24 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 2 length 1 2025/04/29 05:11:24 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 1 size 5Mi offset 5Mi/10.000Mi 2025/04/29 05:11:24 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 1 length 5242880 2025/04/29 05:11:25 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 2 2025/04/29 05:11:26 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 0 2025/04/29 05:11:26 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 1 2025/04/29 05:11:26 DEBUG : chunked-5Mi-10.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761/Streamed 2025/04/29 05:11:28 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:11:28 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Streaming upload with --b2-chunk-size 5Mi allows uploads of up to 48.828Gi and will fail only when that limit is reached. 2025/04/29 05:11:28 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f20833a2b02e363ee_d20250429_m051128_c001_v0001101_t0007_u01745903488597") 2025/04/29 05:11:28 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/04/29 05:11:28 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/04/29 05:11:28 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 1 length 5242880 2025/04/29 05:11:28 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 2 length 1 2025/04/29 05:11:29 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 2 2025/04/29 05:11:31 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:11:32 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:11:32 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001 2025/04/29 05:11:34 DEBUG : chunked-5.054Mi-5.054Mi.bin: multipart upload: starting chunk 1 size 1 offset 5.054Mi/5.054Mi 2025/04/29 05:11:34 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 1 length 1 2025/04/29 05:11:34 DEBUG : chunked-5.054Mi-5.054Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/5.054Mi 2025/04/29 05:11:34 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 0 length 5300000 2025/04/29 05:11:35 DEBUG : chunked-5.054Mi-5.054Mi.bin: Done sending chunk 1 2025/04/29 05:11:36 DEBUG : chunked-5.054Mi-5.054Mi.bin: Done sending chunk 0 2025/04/29 05:11:36 DEBUG : chunked-5.054Mi-5.054Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001/Streamed 2025/04/29 05:11:38 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:11:38 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Streaming upload with --b2-chunk-size 5.054Mi allows uploads of up to 49.360Gi and will fail only when that limit is reached. 2025/04/29 05:11:38 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f20833a2b02e363f5_d20250429_m051138_c001_v0001115_t0042_u01745903498153") 2025/04/29 05:11:38 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/04/29 05:11:38 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Sending chunk 1 length 1 2025/04/29 05:11:38 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Sending chunk 0 length 5300000 2025/04/29 05:11:39 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:11:41 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:11:41 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000 2025/04/29 05:11:42 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/10.109Mi 2025/04/29 05:11:42 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 0 length 5300000 2025/04/29 05:11:42 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.054Mi offset 5.054Mi/10.109Mi 2025/04/29 05:11:42 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 1 length 5300000 2025/04/29 05:11:45 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 0 2025/04/29 05:11:54 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 1 2025/04/29 05:11:54 DEBUG : chunked-5.054Mi-10.109Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000/Streamed 2025/04/29 05:11:58 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:11:58 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Streaming upload with --b2-chunk-size 5.054Mi allows uploads of up to 49.360Gi and will fail only when that limit is reached. 2025/04/29 05:11:58 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f2057b9f1568e4791_d20250429_m051158_c001_v0001171_t0055_u01745903518238") 2025/04/29 05:11:58 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300000 2025/04/29 05:11:58 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300000 2025/04/29 05:11:58 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/04/29 05:12:00 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:12:01 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:12:01 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001 2025/04/29 05:12:04 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/10.109Mi 2025/04/29 05:12:04 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 0 length 5300000 2025/04/29 05:12:04 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 2 size 1 offset 10.109Mi/10.109Mi 2025/04/29 05:12:04 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 2 length 1 2025/04/29 05:12:04 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.054Mi offset 5.054Mi/10.109Mi 2025/04/29 05:12:04 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 1 length 5300000 2025/04/29 05:12:05 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 2 2025/04/29 05:12:06 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 0 2025/04/29 05:12:06 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 1 2025/04/29 05:12:06 DEBUG : chunked-5.054Mi-10.109Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001/Streamed 2025/04/29 05:12:08 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:12:08 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Streaming upload with --b2-chunk-size 5.054Mi allows uploads of up to 49.360Gi and will fail only when that limit is reached. 2025/04/29 05:12:08 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f2057b9f1568e4796_d20250429_m051208_c001_v0001172_t0053_u01745903528563") 2025/04/29 05:12:08 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300000 2025/04/29 05:12:08 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/04/29 05:12:08 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 2 length 1 2025/04/29 05:12:08 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300000 2025/04/29 05:12:09 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 2 2025/04/29 05:12:10 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:12:11 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:12:11 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054 2025/04/29 05:12:13 DEBUG : chunked-5.055Mi-5.055Mi.bin: multipart upload: starting chunk 1 size 1 offset 5.055Mi/5.055Mi 2025/04/29 05:12:13 DEBUG : chunked-5.055Mi-5.055Mi.bin: Sending chunk 1 length 1 2025/04/29 05:12:13 DEBUG : chunked-5.055Mi-5.055Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/5.055Mi 2025/04/29 05:12:13 DEBUG : chunked-5.055Mi-5.055Mi.bin: Sending chunk 0 length 5300053 2025/04/29 05:12:14 DEBUG : chunked-5.055Mi-5.055Mi.bin: Done sending chunk 1 2025/04/29 05:12:15 DEBUG : chunked-5.055Mi-5.055Mi.bin: Done sending chunk 0 2025/04/29 05:12:15 DEBUG : chunked-5.055Mi-5.055Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054/Streamed 2025/04/29 05:12:16 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:12:16 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Streaming upload with --b2-chunk-size 5.055Mi allows uploads of up to 49.361Gi and will fail only when that limit is reached. 2025/04/29 05:12:17 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f2057b9f1568e4799_d20250429_m051217_c001_v0001181_t0055_u01745903537091") 2025/04/29 05:12:17 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/04/29 05:12:17 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 1 length 1 2025/04/29 05:12:17 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 0 length 5300053 2025/04/29 05:12:18 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:12:19 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:12:19 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106 2025/04/29 05:12:21 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/10.109Mi 2025/04/29 05:12:21 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 0 length 5300053 2025/04/29 05:12:21 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.055Mi offset 5.055Mi/10.109Mi 2025/04/29 05:12:21 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 1 length 5300053 2025/04/29 05:12:23 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 0 2025/04/29 05:12:23 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 1 2025/04/29 05:12:23 DEBUG : chunked-5.055Mi-10.109Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106/Streamed 2025/04/29 05:12:25 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:12:25 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Streaming upload with --b2-chunk-size 5.055Mi allows uploads of up to 49.361Gi and will fail only when that limit is reached. 2025/04/29 05:12:25 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f2057b9f1568e479f_d20250429_m051225_c001_v0001177_t0044_u01745903545477") 2025/04/29 05:12:25 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/04/29 05:12:25 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/04/29 05:12:25 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/04/29 05:12:27 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:12:28 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:12:28 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107 2025/04/29 05:12:29 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/10.109Mi 2025/04/29 05:12:29 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 0 length 5300053 2025/04/29 05:12:30 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 2 size 1 offset 10.109Mi/10.109Mi 2025/04/29 05:12:30 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 2 length 1 2025/04/29 05:12:30 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.055Mi offset 5.055Mi/10.109Mi 2025/04/29 05:12:30 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 1 length 5300053 2025/04/29 05:12:31 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 2 2025/04/29 05:12:32 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 0 2025/04/29 05:12:32 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 1 2025/04/29 05:12:32 DEBUG : chunked-5.055Mi-10.109Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107/Streamed 2025/04/29 05:12:34 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:12:34 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Streaming upload with --b2-chunk-size 5.055Mi allows uploads of up to 49.361Gi and will fail only when that limit is reached. 2025/04/29 05:12:34 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f2057b9f1568e47a6_d20250429_m051234_c001_v0001182_t0006_u01745903554194") 2025/04/29 05:12:34 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/04/29 05:12:34 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/04/29 05:12:34 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 2 length 1 2025/04/29 05:12:34 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/04/29 05:12:35 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 2 2025/04/29 05:12:36 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:12:36 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:12:36 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507 2025/04/29 05:12:38 DEBUG : chunked-6.667Mi-6.667Mi.bin: multipart upload: starting chunk 1 size 1 offset 6.667Mi/6.667Mi 2025/04/29 05:12:38 DEBUG : chunked-6.667Mi-6.667Mi.bin: Sending chunk 1 length 1 2025/04/29 05:12:38 DEBUG : chunked-6.667Mi-6.667Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/6.667Mi 2025/04/29 05:12:38 DEBUG : chunked-6.667Mi-6.667Mi.bin: Sending chunk 0 length 6990506 2025/04/29 05:12:39 DEBUG : chunked-6.667Mi-6.667Mi.bin: Done sending chunk 1 2025/04/29 05:12:41 DEBUG : chunked-6.667Mi-6.667Mi.bin: Done sending chunk 0 2025/04/29 05:12:41 DEBUG : chunked-6.667Mi-6.667Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507/Streamed 2025/04/29 05:12:42 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:12:42 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Streaming upload with --b2-chunk-size 6.667Mi allows uploads of up to 65.104Gi and will fail only when that limit is reached. 2025/04/29 05:12:42 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f2057b9f1568e47ab_d20250429_m051242_c001_v0001106_t0046_u01745903562851") 2025/04/29 05:12:42 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/04/29 05:12:42 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 1 length 1 2025/04/29 05:12:42 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 0 length 6990506 2025/04/29 05:12:44 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:12:45 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:12:45 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012 2025/04/29 05:12:47 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/13.333Mi 2025/04/29 05:12:47 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2025/04/29 05:12:47 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 1 size 6.667Mi offset 6.667Mi/13.333Mi 2025/04/29 05:12:47 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2025/04/29 05:12:49 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 0 2025/04/29 05:12:50 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 1 2025/04/29 05:12:50 DEBUG : chunked-6.667Mi-13.333Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012/Streamed 2025/04/29 05:12:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:12:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Streaming upload with --b2-chunk-size 6.667Mi allows uploads of up to 65.104Gi and will fail only when that limit is reached. 2025/04/29 05:12:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f2174e756d236ea5c_d20250429_m051252_c001_v0001183_t0039_u01745903572222") 2025/04/29 05:12:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/04/29 05:12:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/04/29 05:12:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/04/29 05:12:54 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:12:54 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:12:54 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013 2025/04/29 05:12:57 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/13.333Mi 2025/04/29 05:12:57 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2025/04/29 05:12:57 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 2 size 1 offset 13.333Mi/13.333Mi 2025/04/29 05:12:57 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 1 2025/04/29 05:12:57 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 1 size 6.667Mi offset 6.667Mi/13.333Mi 2025/04/29 05:12:57 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2025/04/29 05:12:58 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 2 2025/04/29 05:12:59 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 0 2025/04/29 05:12:59 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 1 2025/04/29 05:12:59 DEBUG : chunked-6.667Mi-13.333Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013/Streamed 2025/04/29 05:13:01 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:13:01 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Streaming upload with --b2-chunk-size 6.667Mi allows uploads of up to 65.104Gi and will fail only when that limit is reached. 2025/04/29 05:13:02 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f2162eda904a161f6_d20250429_m051302_c001_v0001171_t0014_u01745903582067") 2025/04/29 05:13:02 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/04/29 05:13:02 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/04/29 05:13:02 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 2 length 1 2025/04/29 05:13:02 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/04/29 05:13:03 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 2 2025/04/29 05:13:04 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:13:05 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:13:05 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609 2025/04/29 05:13:07 DEBUG : chunked-8Mi-8.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 8Mi/8.000Mi 2025/04/29 05:13:07 DEBUG : chunked-8Mi-8.000Mi.bin: Sending chunk 1 length 1 2025/04/29 05:13:07 DEBUG : chunked-8Mi-8.000Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/8.000Mi 2025/04/29 05:13:07 DEBUG : chunked-8Mi-8.000Mi.bin: Sending chunk 0 length 8388608 2025/04/29 05:13:08 DEBUG : chunked-8Mi-8.000Mi.bin: Done sending chunk 1 2025/04/29 05:13:09 DEBUG : chunked-8Mi-8.000Mi.bin: Done sending chunk 0 2025/04/29 05:13:09 DEBUG : chunked-8Mi-8.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609/Streamed 2025/04/29 05:13:11 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:13:11 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Streaming upload with --b2-chunk-size 8Mi allows uploads of up to 78.125Gi and will fail only when that limit is reached. 2025/04/29 05:13:11 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f2162eda904a169cd_d20250429_m051311_c001_v0001041_t0018_u01745903591594") 2025/04/29 05:13:11 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/04/29 05:13:11 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Sending chunk 1 length 1 2025/04/29 05:13:11 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Sending chunk 0 length 8388608 2025/04/29 05:13:12 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:13:14 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:13:14 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216 2025/04/29 05:13:16 DEBUG : chunked-8Mi-16Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/16Mi 2025/04/29 05:13:16 DEBUG : chunked-8Mi-16Mi.bin: Sending chunk 0 length 8388608 2025/04/29 05:13:16 DEBUG : chunked-8Mi-16Mi.bin: multipart upload: starting chunk 1 size 8Mi offset 8Mi/16Mi 2025/04/29 05:13:16 DEBUG : chunked-8Mi-16Mi.bin: Sending chunk 1 length 8388608 2025/04/29 05:13:18 DEBUG : chunked-8Mi-16Mi.bin: Done sending chunk 0 2025/04/29 05:13:18 DEBUG : chunked-8Mi-16Mi.bin: Done sending chunk 1 2025/04/29 05:13:18 DEBUG : chunked-8Mi-16Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216/Streamed 2025/04/29 05:13:21 DEBUG : chunked-8Mi-16Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:13:21 DEBUG : chunked-8Mi-16Mi-streamed.bin: Streaming upload with --b2-chunk-size 8Mi allows uploads of up to 78.125Gi and will fail only when that limit is reached. 2025/04/29 05:13:21 DEBUG : chunked-8Mi-16Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f2174e756d236fe40_d20250429_m051321_c001_v0001161_t0002_u01745903601320") 2025/04/29 05:13:21 DEBUG : chunked-8Mi-16Mi-streamed.bin: Sending chunk 0 length 8388608 2025/04/29 05:13:21 DEBUG : chunked-8Mi-16Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/04/29 05:13:21 DEBUG : chunked-8Mi-16Mi-streamed.bin: Sending chunk 1 length 8388608 2025/04/29 05:13:24 DEBUG : chunked-8Mi-16Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:13:30 DEBUG : chunked-8Mi-16Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:13:30 DEBUG : chunked-8Mi-16Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217 2025/04/29 05:13:34 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/16.000Mi 2025/04/29 05:13:34 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 0 length 8388608 2025/04/29 05:13:34 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 1 size 8Mi offset 8Mi/16.000Mi 2025/04/29 05:13:34 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 1 length 8388608 2025/04/29 05:13:34 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 16Mi/16.000Mi 2025/04/29 05:13:34 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 2 length 1 2025/04/29 05:13:35 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 2 2025/04/29 05:13:36 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 0 2025/04/29 05:13:37 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 1 2025/04/29 05:13:37 DEBUG : chunked-8Mi-16.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217/Streamed 2025/04/29 05:13:39 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:13:39 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Streaming upload with --b2-chunk-size 8Mi allows uploads of up to 78.125Gi and will fail only when that limit is reached. 2025/04/29 05:13:39 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f2142581cd53ac70d_d20250429_m051339_c001_v0001180_t0054_u01745903619500") 2025/04/29 05:13:39 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 0 length 8388608 2025/04/29 05:13:39 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/04/29 05:13:39 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 2 length 1 2025/04/29 05:13:39 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 1 length 8388608 2025/04/29 05:13:40 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_get_upload_part_url": EOF) 2025/04/29 05:13:40 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/29 05:13:40 DEBUG : pacer: Reducing sleep to 10ms 2025/04/29 05:13:40 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 2 2025/04/29 05:13:42 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:13:42 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:13:42 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761 2025/04/29 05:13:45 DEBUG : chunked-10Mi-10.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 10Mi/10.000Mi 2025/04/29 05:13:45 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/04/29 05:13:45 DEBUG : chunked-10Mi-10.000Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/10.000Mi 2025/04/29 05:13:45 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/04/29 05:13:45 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00020adb0), retryAfter:1000000000} 2025/04/29 05:13:45 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/04/29 05:13:45 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable)) 2025/04/29 05:13:45 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/04/29 05:13:45 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/04/29 05:13:45 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00020ae10), retryAfter:1000000000} 2025/04/29 05:13:45 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/04/29 05:13:45 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable)) 2025/04/29 05:13:46 DEBUG : pacer: Reducing sleep to 500ms 2025/04/29 05:13:47 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00020ae70), retryAfter:1000000000} 2025/04/29 05:13:47 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/04/29 05:13:47 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable)) 2025/04/29 05:13:47 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/04/29 05:13:47 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/04/29 05:13:48 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/04/29 05:13:49 DEBUG : pacer: Reducing sleep to 500ms 2025/04/29 05:13:50 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0029bd650), retryAfter:1000000000} 2025/04/29 05:13:50 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/04/29 05:13:50 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable)) 2025/04/29 05:13:50 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/04/29 05:13:50 DEBUG : pacer: Reducing sleep to 500ms 2025/04/29 05:13:51 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00020b650), retryAfter:1000000000} 2025/04/29 05:13:51 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/04/29 05:13:51 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable)) 2025/04/29 05:13:51 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/04/29 05:13:51 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/04/29 05:13:52 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/04/29 05:13:53 DEBUG : pacer: Reducing sleep to 500ms 2025/04/29 05:13:54 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0029bdd40), retryAfter:1000000000} 2025/04/29 05:13:54 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/04/29 05:13:54 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable)) 2025/04/29 05:13:54 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/04/29 05:13:54 DEBUG : pacer: Reducing sleep to 500ms 2025/04/29 05:13:55 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 1 (retry=true): CPU too busy (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc000136b10), retryAfter:1000000000} 2025/04/29 05:13:55 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable) 2025/04/29 05:13:55 DEBUG : pacer: low level retry 4/10 (error CPU too busy (503 service_unavailable)) 2025/04/29 05:13:55 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/04/29 05:13:55 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/04/29 05:13:56 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/04/29 05:13:57 DEBUG : pacer: Reducing sleep to 500ms 2025/04/29 05:13:58 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc002a96270), retryAfter:1000000000} 2025/04/29 05:13:58 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/04/29 05:13:58 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable)) 2025/04/29 05:13:58 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/04/29 05:13:58 DEBUG : pacer: Reducing sleep to 500ms 2025/04/29 05:13:59 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc002a96330), retryAfter:1000000000} 2025/04/29 05:13:59 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/04/29 05:13:59 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable)) 2025/04/29 05:13:59 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/04/29 05:13:59 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/04/29 05:14:00 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/04/29 05:14:01 DEBUG : pacer: Reducing sleep to 500ms 2025/04/29 05:14:02 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 0 (retry=true): CPU too busy (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc002a96930), retryAfter:1000000000} 2025/04/29 05:14:02 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable) 2025/04/29 05:14:02 DEBUG : pacer: low level retry 5/10 (error CPU too busy (503 service_unavailable)) 2025/04/29 05:14:02 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/04/29 05:14:02 DEBUG : pacer: Reducing sleep to 500ms 2025/04/29 05:14:03 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc002a96000), retryAfter:1000000000} 2025/04/29 05:14:03 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/04/29 05:14:03 DEBUG : pacer: low level retry 6/10 (error no tomes available (503 service_unavailable)) 2025/04/29 05:14:03 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/04/29 05:14:03 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/04/29 05:14:04 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/04/29 05:14:05 DEBUG : pacer: Reducing sleep to 500ms 2025/04/29 05:14:06 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0001365d0), retryAfter:1000000000} 2025/04/29 05:14:06 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/04/29 05:14:06 DEBUG : pacer: low level retry 6/10 (error no tomes available (503 service_unavailable)) 2025/04/29 05:14:06 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/04/29 05:14:06 DEBUG : pacer: Reducing sleep to 500ms 2025/04/29 05:14:07 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc002a96ba0), retryAfter:1000000000} 2025/04/29 05:14:07 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/04/29 05:14:07 DEBUG : pacer: low level retry 7/10 (error no tomes available (503 service_unavailable)) 2025/04/29 05:14:07 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/04/29 05:14:07 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/04/29 05:14:08 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/04/29 05:14:09 DEBUG : pacer: Reducing sleep to 500ms 2025/04/29 05:14:10 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00129a120), retryAfter:1000000000} 2025/04/29 05:14:10 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/04/29 05:14:10 DEBUG : pacer: low level retry 7/10 (error no tomes available (503 service_unavailable)) 2025/04/29 05:14:10 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/04/29 05:14:10 DEBUG : pacer: Reducing sleep to 500ms 2025/04/29 05:14:11 DEBUG : pacer: Reducing sleep to 250ms 2025/04/29 05:14:11 DEBUG : chunked-10Mi-10.000Mi.bin: Done sending chunk 1 2025/04/29 05:14:11 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/04/29 05:14:11 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc002a97680), retryAfter:1000000000} 2025/04/29 05:14:11 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/04/29 05:14:11 DEBUG : pacer: low level retry 8/10 (error no tomes available (503 service_unavailable)) 2025/04/29 05:14:11 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/04/29 05:14:12 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/04/29 05:14:13 DEBUG : pacer: Reducing sleep to 500ms 2025/04/29 05:14:13 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00129a7e0), retryAfter:1000000000} 2025/04/29 05:14:13 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/04/29 05:14:13 DEBUG : pacer: low level retry 9/10 (error no tomes available (503 service_unavailable)) 2025/04/29 05:14:13 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/04/29 05:14:14 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/04/29 05:14:15 DEBUG : pacer: Reducing sleep to 500ms 2025/04/29 05:14:15 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0000acb40), retryAfter:1000000000} 2025/04/29 05:14:15 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/04/29 05:14:15 DEBUG : pacer: low level retry 10/10 (error no tomes available (503 service_unavailable)) 2025/04/29 05:14:15 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/04/29 05:14:15 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 0: no tomes available (503 service_unavailable) 2025/04/29 05:14:15 DEBUG : chunked-10Mi-10.000Mi.bin: Cancelling multipart upload 2025/04/29 05:14:15 DEBUG : chunked-10Mi-10.000Mi.bin: Cancelling large file upload 2025/04/29 05:14:16 DEBUG : pacer: Reducing sleep to 500ms fstests.go:143: PutLarge error: no tomes available (503 service_unavailable) - low level retry 1/10 2025/04/29 05:14:18 DEBUG : pacer: Reducing sleep to 250ms 2025/04/29 05:14:18 DEBUG : chunked-10Mi-10.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 10Mi/10.000Mi 2025/04/29 05:14:18 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/04/29 05:14:18 DEBUG : chunked-10Mi-10.000Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/10.000Mi 2025/04/29 05:14:19 DEBUG : pacer: Reducing sleep to 125ms 2025/04/29 05:14:19 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/04/29 05:14:19 DEBUG : pacer: Reducing sleep to 62.5ms 2025/04/29 05:14:19 DEBUG : pacer: Reducing sleep to 31.25ms 2025/04/29 05:14:19 DEBUG : chunked-10Mi-10.000Mi.bin: Done sending chunk 1 2025/04/29 05:14:22 DEBUG : pacer: Reducing sleep to 15.625ms 2025/04/29 05:14:22 DEBUG : chunked-10Mi-10.000Mi.bin: Done sending chunk 0 2025/04/29 05:14:22 DEBUG : chunked-10Mi-10.000Mi.bin: Finishing large file upload with 2 parts 2025/04/29 05:14:22 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761/Streamed 2025/04/29 05:14:25 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:14:25 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Streaming upload with --b2-chunk-size 10Mi allows uploads of up to 97.656Gi and will fail only when that limit is reached. 2025/04/29 05:14:25 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f2142581cd53ac747_d20250429_m051425_c001_v0001092_t0057_u01745903665721") 2025/04/29 05:14:25 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/04/29 05:14:25 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Sending chunk 1 length 1 2025/04/29 05:14:25 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Sending chunk 0 length 10485760 2025/04/29 05:14:26 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:14:28 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:14:28 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520 2025/04/29 05:14:30 DEBUG : chunked-10Mi-20Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/20Mi 2025/04/29 05:14:30 DEBUG : chunked-10Mi-20Mi.bin: Sending chunk 0 length 10485760 2025/04/29 05:14:31 DEBUG : chunked-10Mi-20Mi.bin: multipart upload: starting chunk 1 size 10Mi offset 10Mi/20Mi 2025/04/29 05:14:31 DEBUG : chunked-10Mi-20Mi.bin: Sending chunk 1 length 10485760 2025/04/29 05:14:33 DEBUG : chunked-10Mi-20Mi.bin: Done sending chunk 0 2025/04/29 05:14:34 DEBUG : chunked-10Mi-20Mi.bin: Done sending chunk 1 2025/04/29 05:14:34 DEBUG : chunked-10Mi-20Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520/Streamed 2025/04/29 05:14:36 DEBUG : chunked-10Mi-20Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:14:36 DEBUG : chunked-10Mi-20Mi-streamed.bin: Streaming upload with --b2-chunk-size 10Mi allows uploads of up to 97.656Gi and will fail only when that limit is reached. 2025/04/29 05:14:36 DEBUG : chunked-10Mi-20Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f219d64187d85e4f6_d20250429_m051436_c001_v0001105_t0031_u01745903676630") 2025/04/29 05:14:36 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 0 length 10485760 2025/04/29 05:14:37 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 1 length 10485760 2025/04/29 05:14:37 DEBUG : chunked-10Mi-20Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/04/29 05:14:39 DEBUG : chunked-10Mi-20Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:14:39 DEBUG : chunked-10Mi-20Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:14:39 DEBUG : chunked-10Mi-20Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521 2025/04/29 05:14:42 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/20.000Mi 2025/04/29 05:14:42 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 0 length 10485760 2025/04/29 05:14:43 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 20Mi/20.000Mi 2025/04/29 05:14:43 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 2 length 1 2025/04/29 05:14:43 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 1 size 10Mi offset 10Mi/20.000Mi 2025/04/29 05:14:43 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 1 length 10485760 2025/04/29 05:14:43 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 2 2025/04/29 05:14:45 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 0 2025/04/29 05:14:45 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 1 2025/04/29 05:14:45 DEBUG : chunked-10Mi-20.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521/Streamed 2025/04/29 05:14:48 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: File is big enough for chunked streaming 2025/04/29 05:14:48 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Streaming upload with --b2-chunk-size 10Mi allows uploads of up to 97.656Gi and will fail only when that limit is reached. 2025/04/29 05:14:48 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Starting streaming of large file (id "4_z9f22cd33f02d7bfb9f680e1b_f218f955f4535bc7a_d20250429_m051448_c001_v0001137_t0029_u01745903688481") 2025/04/29 05:14:48 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 0 length 10485760 2025/04/29 05:14:49 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/04/29 05:14:49 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 2 length 1 2025/04/29 05:14:49 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 1 length 10485760 2025/04/29 05:14:50 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 2 2025/04/29 05:14:51 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 0 2025/04/29 05:14:51 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 1 2025/04/29 05:14:51 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsCopyChunked === RUN TestIntegration/FsMkdir/FsCopyChunked/5242880 === RUN TestIntegration/FsMkdir/FsCopyChunked/5242881 2025/04/29 05:14:59 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_z9f22cd33f02d7bfb9f680e1b_f20040ee6b51c50e7_d20250429_m051459_c001_v0001182_t0007_u01745903699731") 2025/04/29 05:14:59 DEBUG : chunked-copy.copy: Copying chunk 1 length 1 2025/04/29 05:14:59 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/04/29 05:15:00 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/04/29 05:15:00 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/04/29 05:15:00 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485759 2025/04/29 05:15:02 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10.000Mi 2025/04/29 05:15:02 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/04/29 05:15:02 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4.000Mi offset 6Mi/10.000Mi 2025/04/29 05:15:02 DEBUG : chunked-copy: Sending chunk 1 length 4194303 2025/04/29 05:15:05 DEBUG : chunked-copy: Done sending chunk 0 2025/04/29 05:15:05 DEBUG : chunked-copy: Done sending chunk 1 2025/04/29 05:15:05 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2025/04/29 05:15:05 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_z9f22cd33f02d7bfb9f680e1b_f205b84a9dcc5fcfd_d20250429_m051505_c001_v0001171_t0029_u01745903705685") 2025/04/29 05:15:05 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242879 2025/04/29 05:15:05 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/04/29 05:15:06 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/04/29 05:15:06 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/04/29 05:15:06 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485760 2025/04/29 05:15:09 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10Mi 2025/04/29 05:15:09 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/04/29 05:15:09 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4Mi offset 6Mi/10Mi 2025/04/29 05:15:09 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/04/29 05:15:11 DEBUG : chunked-copy: Done sending chunk 0 2025/04/29 05:15:11 DEBUG : chunked-copy: Done sending chunk 1 2025/04/29 05:15:11 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2025/04/29 05:15:12 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_z9f22cd33f02d7bfb9f680e1b_f205b84a9dcc5fd2e_d20250429_m051512_c001_v0001180_t0021_u01745903712282") 2025/04/29 05:15:12 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242880 2025/04/29 05:15:12 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/04/29 05:15:12 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/04/29 05:15:12 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/04/29 05:15:12 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485761 2025/04/29 05:15:15 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10.000Mi 2025/04/29 05:15:15 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/04/29 05:15:15 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4.000Mi offset 6Mi/10.000Mi 2025/04/29 05:15:15 DEBUG : chunked-copy: Sending chunk 1 length 4194305 2025/04/29 05:15:17 DEBUG : chunked-copy: Done sending chunk 0 2025/04/29 05:15:17 DEBUG : chunked-copy: Done sending chunk 1 2025/04/29 05:15:17 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2025/04/29 05:15:18 DEBUG : chunked-copy.copy: Starting copy of large file in 3 chunks (id "4_z9f22cd33f02d7bfb9f680e1b_f20040ee6b51c50fa_d20250429_m051518_c001_v0001182_t0050_u01745903718316") 2025/04/29 05:15:18 DEBUG : chunked-copy.copy: Copying chunk 2 length 1 2025/04/29 05:15:18 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/04/29 05:15:18 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242880 2025/04/29 05:15:18 DEBUG : chunked-copy.copy: Done copying chunk 2 2025/04/29 05:15:18 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/04/29 05:15:19 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/04/29 05:15:19 DEBUG : chunked-copy.copy: Finishing large file copy with 3 parts === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2025/04/29 05:15:20 DEBUG : unknown-size-put.txt: File has 100 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2025/04/29 05:15:22 DEBUG : unknown-size-update.txt: File has 200 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsRootCollapse 2025/04/29 05:15:22 DEBUG : Creating backend with remote "TestB2:rclone-test-wifeyef5fixa/deeper/nonexisting/directory" === RUN TestIntegration/FsMkdir/FsDirSetModTime fstests.go:2625: FS has no DirSetModTime interface === RUN TestIntegration/FsMkdir/FsMkdirMetadata fstests.go:2668: FS has no MkdirMetadata interface === RUN TestIntegration/FsMkdir/FsDirectory fstests.go:2717: Can't test if can't have empty directories 2025/04/29 05:15:23 INFO : B2 bucket rclone-test-wifeyef5fixa: cleaning bucket "rclone-test-wifeyef5fixa" of all files 2025/04/29 05:15:24 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f419d64187d85e4eb_d20250429_m051430_c001_v7007000_t0000_u01745903670147") 2025/04/29 05:15:24 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2142581cd53ac747_d20250429_m051425_c001_v0001092_t0057_u01745903665721") 2025/04/29 05:15:24 DEBUG : chunked-10Mi-10.000Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4142581cd53ac745_d20250429_m051425_c001_v7007000_t0000_u01745903665127") 2025/04/29 05:15:24 DEBUG : chunked-10Mi-10.000Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2142581cd53ac73b_d20250429_m051418_c001_v0001183_t0015_u01745903658415") 2025/04/29 05:15:24 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f40040ee6b51c50dd_d20250429_m051454_c001_v7007000_t0000_u01745903694002") 2025/04/29 05:15:24 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f218f955f4535bc7a_d20250429_m051448_c001_v0001137_t0029_u01745903688481") 2025/04/29 05:15:24 DEBUG : chunked-10Mi-20.000Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f418f955f4535bc77_d20250429_m051447_c001_v7007000_t0000_u01745903687891") 2025/04/29 05:15:24 DEBUG : chunked-10Mi-20.000Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f219d64187d85e509_d20250429_m051442_c001_v0001182_t0015_u01745903682207") 2025/04/29 05:15:24 DEBUG : chunked-10Mi-20Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f419d64187d85e508_d20250429_m051442_c001_v7007000_t0000_u01745903682022") 2025/04/29 05:15:24 DEBUG : chunked-10Mi-20Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f219d64187d85e4f6_d20250429_m051436_c001_v0001105_t0031_u01745903676630") 2025/04/29 05:15:24 DEBUG : chunked-10Mi-20Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f419d64187d85e4f5_d20250429_m051436_c001_v7007000_t0000_u01745903676062") 2025/04/29 05:15:24 DEBUG : chunked-10Mi-20Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f219d64187d85e4ec_d20250429_m051430_c001_v0001145_t0041_u01745903670356") 2025/04/29 05:15:24 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f411ac54a1ecdfc66_d20250429_m051212_c001_v7007000_t0000_u01745903532783") 2025/04/29 05:15:24 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2057b9f1568e4796_d20250429_m051208_c001_v0001172_t0053_u01745903528563") 2025/04/29 05:15:24 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4057b9f1568e4793_d20250429_m051203_c001_v7007000_t0000_u01745903523702") 2025/04/29 05:15:24 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2057b9f1568e4791_d20250429_m051158_c001_v0001171_t0055_u01745903518238") 2025/04/29 05:15:25 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4057b9f1568e4795_d20250429_m051208_c001_v7007000_t0000_u01745903528128") 2025/04/29 05:15:25 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2057b9f1568e4794_d20250429_m051203_c001_v0001183_t0015_u01745903523887") 2025/04/29 05:15:25 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4057b9f1568e4790_d20250429_m051157_c001_v7007000_t0000_u01745903517831") 2025/04/29 05:15:25 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2155e906ed48c6dd_d20250429_m051142_c001_v0001120_t0033_u01745903502320") 2025/04/29 05:15:25 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4155e906ed48c6dc_d20250429_m051142_c001_v7007000_t0000_u01745903502136") 2025/04/29 05:15:25 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f20833a2b02e363f5_d20250429_m051138_c001_v0001115_t0042_u01745903498153") 2025/04/29 05:15:25 DEBUG : chunked-5.054Mi-5.054Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f40833a2b02e363f4_d20250429_m051137_c001_v7007000_t0000_u01745903497754") 2025/04/29 05:15:25 DEBUG : chunked-5.054Mi-5.054Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2155e906ed48c6d6_d20250429_m051134_c001_v0001099_t0037_u01745903494008") 2025/04/29 05:15:25 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4174e756d236e21e_d20250429_m051238_c001_v7007000_t0000_u01745903558214") 2025/04/29 05:15:25 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2057b9f1568e47a6_d20250429_m051234_c001_v0001182_t0006_u01745903554194") 2025/04/29 05:15:25 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4057b9f1568e47a1_d20250429_m051229_c001_v7007000_t0000_u01745903549381") 2025/04/29 05:15:25 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2057b9f1568e479f_d20250429_m051225_c001_v0001177_t0044_u01745903545477") 2025/04/29 05:15:25 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4057b9f1568e47a5_d20250429_m051233_c001_v7007000_t0000_u01745903553809") 2025/04/29 05:15:25 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2057b9f1568e47a2_d20250429_m051229_c001_v0001146_t0057_u01745903549567") 2025/04/29 05:15:25 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4057b9f1568e479e_d20250429_m051225_c001_v7007000_t0000_u01745903545085") 2025/04/29 05:15:25 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f211ac54a1ece1ab8_d20250429_m051220_c001_v0001092_t0041_u01745903540702") 2025/04/29 05:15:25 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f411ac54a1ece1ab7_d20250429_m051220_c001_v7007000_t0000_u01745903540499") 2025/04/29 05:15:25 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2057b9f1568e4799_d20250429_m051217_c001_v0001181_t0055_u01745903537091") 2025/04/29 05:15:26 DEBUG : chunked-5.055Mi-5.055Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4057b9f1568e4798_d20250429_m051216_c001_v7007000_t0000_u01745903536685") 2025/04/29 05:15:26 DEBUG : chunked-5.055Mi-5.055Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f211ac54a1ecdff5a_d20250429_m051213_c001_v0001179_t0042_u01745903533013") 2025/04/29 05:15:26 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4155e906ed48c6d5_d20250429_m051133_c001_v7007000_t0000_u01745903493823") 2025/04/29 05:15:26 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f20833a2b02e363ee_d20250429_m051128_c001_v0001101_t0007_u01745903488597") 2025/04/29 05:15:26 DEBUG : chunked-5Mi-10.000Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f40833a2b02e363ed_d20250429_m051128_c001_v7007000_t0000_u01745903488201") 2025/04/29 05:15:26 DEBUG : chunked-5Mi-10.000Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2155e906ed48c6cc_d20250429_m051123_c001_v0001131_t0032_u01745903483784") 2025/04/29 05:15:26 DEBUG : chunked-5Mi-10Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4155e906ed48c6ca_d20250429_m051123_c001_v7007000_t0000_u01745903483600") 2025/04/29 05:15:26 DEBUG : chunked-5Mi-10Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2155e906ed48c6be_d20250429_m051119_c001_v0001183_t0056_u01745903479596") 2025/04/29 05:15:26 DEBUG : chunked-5Mi-10Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4155e906ed48c6bd_d20250429_m051119_c001_v7007000_t0000_u01745903479185") 2025/04/29 05:15:26 DEBUG : chunked-5Mi-10Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f205b84a9dcc5ad3a_d20250429_m051110_c001_v0001171_t0012_u01745903470240") 2025/04/29 05:15:26 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f405b84a9dcc5ad39_d20250429_m051110_c001_v7007000_t0000_u01745903470057") 2025/04/29 05:15:26 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f20170bfa1596d61c_d20250429_m051106_c001_v0001113_t0017_u01745903466215") 2025/04/29 05:15:26 DEBUG : chunked-5Mi-5.000Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f40170bfa1596d617_d20250429_m051105_c001_v7007000_t0000_u01745903465799") 2025/04/29 05:15:26 DEBUG : chunked-5Mi-5.000Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2023012e7deb3ab5_d20250429_m051047_c001_v0001171_t0016_u01745903447984") 2025/04/29 05:15:26 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4174e756d236f634_d20250429_m051306_c001_v7007000_t0000_u01745903586804") 2025/04/29 05:15:26 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2162eda904a161f6_d20250429_m051302_c001_v0001171_t0014_u01745903582067") 2025/04/29 05:15:26 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4174e756d236ea6b_d20250429_m051256_c001_v7007000_t0000_u01745903576593") 2025/04/29 05:15:27 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2174e756d236ea5c_d20250429_m051252_c001_v0001183_t0039_u01745903572222") 2025/04/29 05:15:27 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4162eda904a161f5_d20250429_m051301_c001_v7007000_t0000_u01745903581594") 2025/04/29 05:15:27 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2174e756d236ea6c_d20250429_m051256_c001_v0001183_t0017_u01745903576783") 2025/04/29 05:15:27 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4174e756d236ea5b_d20250429_m051251_c001_v7007000_t0000_u01745903571771") 2025/04/29 05:15:27 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2174e756d236e649_d20250429_m051247_c001_v0001183_t0015_u01745903567178") 2025/04/29 05:15:27 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4174e756d236e645_d20250429_m051246_c001_v7007000_t0000_u01745903566993") 2025/04/29 05:15:27 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2057b9f1568e47ab_d20250429_m051242_c001_v0001106_t0046_u01745903562851") 2025/04/29 05:15:27 DEBUG : chunked-6.667Mi-6.667Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4057b9f1568e47aa_d20250429_m051242_c001_v7007000_t0000_u01745903562391") 2025/04/29 05:15:27 DEBUG : chunked-6.667Mi-6.667Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2174e756d236e21f_d20250429_m051238_c001_v0001183_t0040_u01745903558398") 2025/04/29 05:15:27 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f40615452e2dd6203_d20250429_m051344_c001_v7007000_t0000_u01745903624312") 2025/04/29 05:15:27 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2142581cd53ac70d_d20250429_m051339_c001_v0001180_t0054_u01745903619500") 2025/04/29 05:15:27 DEBUG : chunked-8Mi-16.000Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4142581cd53ac70b_d20250429_m051339_c001_v7007000_t0000_u01745903619001") 2025/04/29 05:15:27 DEBUG : chunked-8Mi-16.000Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2036cebb0029408c_d20250429_m051333_c001_v0001180_t0007_u01745903613992") 2025/04/29 05:15:27 DEBUG : chunked-8Mi-16Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4036cebb0029408b_d20250429_m051333_c001_v7007000_t0000_u01745903613788") 2025/04/29 05:15:27 DEBUG : chunked-8Mi-16Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2174e756d236fe40_d20250429_m051321_c001_v0001161_t0002_u01745903601320") 2025/04/29 05:15:27 DEBUG : chunked-8Mi-16Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4174e756d236fe3d_d20250429_m051320_c001_v7007000_t0000_u01745903600779") 2025/04/29 05:15:27 DEBUG : chunked-8Mi-16Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2174e756d236fe28_d20250429_m051315_c001_v0001181_t0034_u01745903595834") 2025/04/29 05:15:27 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4174e756d236fe26_d20250429_m051315_c001_v7007000_t0000_u01745903595649") 2025/04/29 05:15:27 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2162eda904a169cd_d20250429_m051311_c001_v0001041_t0018_u01745903591594") 2025/04/29 05:15:28 DEBUG : chunked-8Mi-8.000Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4162eda904a169cc_d20250429_m051311_c001_v7007000_t0000_u01745903591090") 2025/04/29 05:15:28 DEBUG : chunked-8Mi-8.000Mi.bin: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f2174e756d236f636_d20250429_m051306_c001_v0001183_t0058_u01745903586988") 2025/04/29 05:15:28 DEBUG : chunked-copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f40040ee6b51c50fc_d20250429_m051520_c001_v7007000_t0000_u01745903720662") 2025/04/29 05:15:28 DEBUG : chunked-copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f205b84a9dcc5fd37_d20250429_m051514_c001_v0001181_t0040_u01745903714932") 2025/04/29 05:15:28 DEBUG : chunked-copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f405b84a9dcc5fd36_d20250429_m051514_c001_v7007000_t0000_u01745903714303") 2025/04/29 05:15:28 DEBUG : chunked-copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f20040ee6b51c50f0_d20250429_m051508_c001_v0001182_t0031_u01745903708904") 2025/04/29 05:15:28 DEBUG : chunked-copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f40040ee6b51c50ef_d20250429_m051508_c001_v7007000_t0000_u01745903708288") 2025/04/29 05:15:28 DEBUG : chunked-copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f20040ee6b51c50eb_d20250429_m051502_c001_v0001184_t0058_u01745903702367") 2025/04/29 05:15:28 DEBUG : chunked-copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f40040ee6b51c50e9_d20250429_m051501_c001_v7007000_t0000_u01745903701728") 2025/04/29 05:15:28 DEBUG : chunked-copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f114ace8682a8643d_d20250429_m051458_c001_v0001183_t0059_u01745903698264") 2025/04/29 05:15:28 DEBUG : chunked-copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f40040ee6b51c50e3_d20250429_m051457_c001_v7007000_t0000_u01745903697851") 2025/04/29 05:15:28 DEBUG : chunked-copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f114ace8682a8640d_d20250429_m051454_c001_v0001183_t0031_u01745903694834") 2025/04/29 05:15:28 DEBUG : chunked-copy.copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f40040ee6b51c50fb_d20250429_m051520_c001_v7007000_t0000_u01745903720474") 2025/04/29 05:15:28 DEBUG : chunked-copy.copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f20040ee6b51c50fa_d20250429_m051518_c001_v0001182_t0050_u01745903718316") 2025/04/29 05:15:28 DEBUG : chunked-copy.copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f405b84a9dcc5fd34_d20250429_m051514_c001_v7007000_t0000_u01745903714120") 2025/04/29 05:15:28 DEBUG : chunked-copy.copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f205b84a9dcc5fd2e_d20250429_m051512_c001_v0001180_t0021_u01745903712282") 2025/04/29 05:15:28 DEBUG : chunked-copy.copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f40040ee6b51c50ee_d20250429_m051508_c001_v7007000_t0000_u01745903708048") 2025/04/29 05:15:28 DEBUG : chunked-copy.copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f205b84a9dcc5fcfd_d20250429_m051505_c001_v0001171_t0029_u01745903705685") 2025/04/29 05:15:28 DEBUG : chunked-copy.copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f40040ee6b51c50e8_d20250429_m051501_c001_v7007000_t0000_u01745903701539") 2025/04/29 05:15:28 DEBUG : chunked-copy.copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f20040ee6b51c50e7_d20250429_m051459_c001_v0001182_t0007_u01745903699731") 2025/04/29 05:15:29 DEBUG : chunked-copy.copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f40040ee6b51c50e2_d20250429_m051457_c001_v7007000_t0000_u01745903697652") 2025/04/29 05:15:29 DEBUG : chunked-copy.copy: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11598caa93f1783e_d20250429_m051456_c001_v0001178_t0052_u01745903696729") 2025/04/29 05:15:29 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f11233797df1d2fac_d20250429_m050930_c001_v0001183_t0013_u01745903370405") 2025/04/29 05:15:29 DEBUG : unknown-size-put.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f40040ee6b51c50fd_d20250429_m051521_c001_v7007000_t0000_u01745903721454") 2025/04/29 05:15:29 DEBUG : unknown-size-put.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f114ace8682a86579_d20250429_m051521_c001_v0001183_t0051_u01745903721211") 2025/04/29 05:15:29 DEBUG : unknown-size-update.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f40040ee6b51c5100_d20250429_m051522_c001_v7007000_t0000_u01745903722434") 2025/04/29 05:15:29 DEBUG : unknown-size-update.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f114ace8682a86589_d20250429_m051522_c001_v0001183_t0039_u01745903722222") 2025/04/29 05:15:29 DEBUG : unknown-size-update.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f114ace8682a8657f_d20250429_m051521_c001_v0001183_t0030_u01745903721640") 2025/04/29 05:15:29 DEBUG : versions/test-versions.txt: Deleting (id "4_z9f22cd33f02d7bfb9f680e1b_f4023012e7deb3aa9_d20250429_m051044_c001_v7007000_t0000_u01745903444288") 2025/04/29 05:15:30 INFO : B2 bucket rclone-test-wifeyef5fixa: cleaning bucket "rclone-test-wifeyef5fixa" of all files === RUN TestIntegration/FsShutdown fstests.go:2831: Shutdown method not supported --- PASS: TestIntegration (394.56s) --- SKIP: TestIntegration/FsCheckWrap (0.00s) --- PASS: TestIntegration/FsCommand (0.00s) --- PASS: TestIntegration/FsRmdirNotFound (0.57s) --- PASS: TestIntegration/FsString (0.00s) --- PASS: TestIntegration/FsName (0.00s) --- PASS: TestIntegration/FsRoot (0.00s) --- PASS: TestIntegration/FsRmdirEmpty (0.40s) --- PASS: TestIntegration/FsMkdir (392.35s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (0.56s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.18s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.18s) --- PASS: TestIntegration/FsMkdir/FsListRDirEmpty (0.19s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.18s) --- PASS: TestIntegration/FsMkdir/FsListRDirNotFound (0.20s) --- PASS: TestIntegration/FsMkdir/FsEncoding (19.66s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (1.88s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (1.04s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (0.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (0.96s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (1.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (1.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (0.97s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (0.95s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (0.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (0.99s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (0.95s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (0.97s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (0.95s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (1.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (0.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (0.97s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (1.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (0.97s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (1.01s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutError (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (1.11s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- PASS: TestIntegration/FsMkdir/FsOpenChunkWriter (7.70s) --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (78.33s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (0.95s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.96s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.20s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (1.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (1.15s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.38s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.55s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (1.90s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (2.82s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir (4.96s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (2.18s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.18s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata (0.19s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (0.92s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (0.36s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (0.91s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.36s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (1.50s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (1.84s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (1.87s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (7.88s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (0.74s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (4.28s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (2.11s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/PublicLink (7.64s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Bucket (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (0.55s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (1.53s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (0.93s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (0.60s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (30.15s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata (7.61s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize (0.94s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize/GzipEncoding (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize/GzipEncoding/NoDecompress (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB (6.68s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB/GzipEncoding (2.57s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB/GzipEncoding/NoDecompress (2.57s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions (19.03s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions (2.05s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/ReadCurrent (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/ReadVersion (0.21s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/NewObject (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/NewFs (1.29s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt (1.25s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before (0.44s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before/List (0.44s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne/List (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete/List (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo/List (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup (4.49s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/DryRun (0.65s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/RealThing (3.84s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished (2.10s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished (2.10s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished/DryRun (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished/RealThing (0.76s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules (1.41s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/InitState (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/DryRun (0.56s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/RealThing (0.67s) --- PASS: TestIntegration/FsMkdir/FsPutChunked (246.21s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi (46.03s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881 (22.26s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881/Streamed (4.26s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760 (13.54s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760/Streamed (4.42s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761 (10.22s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761/Streamed (5.62s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi (39.00s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001 (8.31s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001/Streamed (4.38s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000 (21.57s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000/Streamed (5.87s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001 (9.13s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001/Streamed (4.70s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi (25.39s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054 (7.69s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054/Streamed (3.83s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106 (8.86s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106/Streamed (4.29s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107 (8.83s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107/Streamed (4.41s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi (28.59s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507 (8.78s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507/Streamed (4.61s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012 (9.60s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012/Streamed (4.83s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013 (10.20s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013/Streamed (5.19s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi (37.52s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609 (8.85s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609/Streamed (4.56s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216 (18.16s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216/Streamed (13.01s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217 (10.52s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217/Streamed (5.33s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi (69.68s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761 (45.84s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761/Streamed (5.03s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520 (11.86s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520/Streamed (5.96s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521 (11.99s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521/Streamed (6.11s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked (26.66s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/5242880 (3.84s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/5242881 (3.88s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485759 (6.57s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485760 (6.01s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485761 (6.36s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (1.78s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (0.79s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (0.99s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (1.29s) --- SKIP: TestIntegration/FsMkdir/FsDirSetModTime (0.00s) --- SKIP: TestIntegration/FsMkdir/FsMkdirMetadata (0.00s) --- SKIP: TestIntegration/FsMkdir/FsDirectory (0.00s) --- SKIP: TestIntegration/FsShutdown (0.00s) PASS ok github.com/rclone/rclone/backend/b2 394.587s "go test -v -timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 6m35.418089647s (try 1/5)