"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/09/17 05:12:46 NOTICE: TestB2: Starting server 2025/09/17 05:12:46 DEBUG : Creating backend with remote "TestB2:rclone-test-kasowaj0noza" 2025/09/17 05:12:46 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/09/17 05:12:49 INFO : dir/subdir: Making directory fstest.go:250: Filtering empty directory "dir" fstest.go:250: Filtering empty directory "dir/subdir" 2025/09/17 05:12:49 INFO : dir/subdir: Removing directory fstest.go:250: Filtering empty directory "dir" 2025/09/17 05:12:49 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/09/17 05:13:11 DEBUG : writer-at-subdir/writer-at-file: Streaming upload with --b2-chunk-size 8Mi allows uploads of up to 78.125Gi and will fail only when that limit is reached. 2025/09/17 05:13:12 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 1 length 5242880 2025/09/17 05:13:14 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 1 2025/09/17 05:13:14 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 2 length 1048576 2025/09/17 05:13:15 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 2 2025/09/17 05:13:15 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 0 length 5242880 2025/09/17 05:13:16 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 0 2025/09/17 05:13:16 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/09/17 05:13:23 DEBUG : Creating backend with remote "TestB2:" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot 2025/09/17 05:13:24 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/09/17 05:13:29 INFO : B2 bucket rclone-test-kasowaj0noza: cleaning bucket "rclone-test-kasowaj0noza" of all files 2025/09/17 05:13:30 DEBUG : dirToPurge/fileToPurge.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f750_d20250917_m051329_c001_v0001177_t0014_u01758086009041") === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2025/09/17 05:13:31 DEBUG : Creating backend with remote "TestB2:rclone-test-kasowaj0noza/dirToPurgeFromRoot" 2025/09/17 05:13:32 INFO : B2 bucket rclone-test-kasowaj0noza path dirToPurgeFromRoot: cleaning bucket "rclone-test-kasowaj0noza" of all files 2025/09/17 05:13:32 DEBUG : fileToPurgeFromRoot.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f760_d20250917_m051330_c001_v0001177_t0042_u01758086010579") === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2025/09/17 05:13:33 DEBUG : Creating backend with remote "TestB2:rclone-test-kasowaj0noza/hello? sausage/êé" 2025/09/17 05:13:37 INFO : B2 bucket rclone-test-kasowaj0noza path hello? sausage/êé: cleaning bucket "rclone-test-kasowaj0noza" of all files 2025/09/17 05:13:37 DEBUG : a/b/c/d/e.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1056b4d34147a227_d20250917_m051335_c001_v0001171_t0042_u01758086015749") === 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/09/17 05:13:47 DEBUG : Creating backend with remote "TestB2:rclone-test-kasowaj0noza/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2025/09/17 05:13:48 DEBUG : Creating backend with remote "TestB2:rclone-test-kasowaj0noza/not found.txt" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1911: Opening root remote "TestB2:" path "rclone-test-kasowaj0noza" from "TestB2:rclone-test-kasowaj0noza" 2025/09/17 05:13:50 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/09/17 05:14:03 DEBUG : Creating backend with remote "TestB2:rclone-test-laniwum1zere" 2025/09/17 05:14:06 DEBUG : B2 bucket rclone-test-laniwum1zere: Purge remote 2025/09/17 05:14:06 INFO : B2 bucket rclone-test-laniwum1zere: cleaning bucket "rclone-test-laniwum1zere" of all files 2025/09/17 05:14:06 DEBUG : somefile: Deleting (id "4_zefb21da3108d5b6b9f980e1b_f4056c19a27e127d9_d20250917_m051405_c001_v0001184_t0019_u01758086045420") === 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/09/17 05:14:07 DEBUG : piped data.txt: File has 0 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2025/09/17 05:14:08 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/09/17 05:14:11 DEBUG : test-metadata: multipart upload: starting chunk 0 size 6Mi offset 0/6.106Mi 2025/09/17 05:14:11 DEBUG : test-metadata: Sending chunk 0 length 6291456 2025/09/17 05:14:11 DEBUG : test-metadata: multipart upload: starting chunk 1 size 108.896Ki offset 6Mi/6.106Mi 2025/09/17 05:14:11 DEBUG : test-metadata: Sending chunk 1 length 111510 2025/09/17 05:14:12 DEBUG : test-metadata: Done sending chunk 1 2025/09/17 05:14:13 DEBUG : test-metadata: Done sending chunk 0 2025/09/17 05:14:13 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/09/17 05:14:21 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_hide_file": EOF) 2025/09/17 05:14:21 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/17 05:14:21 DEBUG : pacer: Reducing sleep to 10ms 2025/09/17 05:14:23 DEBUG : versions/test-versions.txt: Clearing upload URL because of error: Post "https://pod-000-1177-11.backblaze.com/b2api/v1/b2_upload_file/8fb2fd23106d5b6b9f980e1b/c001_v0001177_t0054": EOF 2025/09/17 05:14:23 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1177-11.backblaze.com/b2api/v1/b2_upload_file/8fb2fd23106d5b6b9f980e1b/c001_v0001177_t0054": EOF) 2025/09/17 05:14:23 DEBUG : pacer: Rate limited, increasing sleep to 20ms fstests.go:143: Put error: Post "https://pod-000-1177-11.backblaze.com/b2api/v1/b2_upload_file/8fb2fd23106d5b6b9f980e1b/c001_v0001177_t0054": EOF - low level retry 1/10 2025/09/17 05:14:25 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-09-17-051418-563.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/09/17 05:14:27 DEBUG : oldPath = "TestB2:rclone-test-kasowaj0noza/versions/test-versions-v2025-09-17-051418-563.txt" 2025/09/17 05:14:27 DEBUG : newPath = "TestB2,versions:rclone-test-kasowaj0noza/versions/test-versions-v2025-09-17-051418-563.txt" 2025/09/17 05:14:27 DEBUG : Creating backend with remote "TestB2,versions:rclone-test-kasowaj0noza/versions/test-versions-v2025-09-17-051418-563.txt" 2025/09/17 05:14:27 DEBUG : TestB2: detected overridden config - adding "{pO73u}" suffix to name 2025/09/17 05:14:29 DEBUG : fs cache: renaming child cache item "TestB2,versions:rclone-test-kasowaj0noza/versions/test-versions-v2025-09-17-051418-563.txt" to be canonical for parent "TestB2{pO73u}:rclone-test-kasowaj0noza/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/Before/NewObject === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne/List === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne/NewObject === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete/List === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete/NewObject === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo/List === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo/NewObject === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/DryRun 2025/09/17 05:14:32 INFO : B2 bucket rclone-test-kasowaj0noza: cleaning bucket "rclone-test-kasowaj0noza" of all hidden files 2025/09/17 05:14:32 DEBUG : leading space/ leading space: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b036f7_d20250917_m051256_c001_v7007000_t0000_u01758085976415") as it is a hide marker 2025/09/17 05:14:32 NOTICE: leading space/ leading space: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : leading space/ leading space: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f594_d20250917_m051255_c001_v0001177_t0009_u01758085975834") 2025/09/17 05:14:32 NOTICE: leading space/ leading space: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b036e9_d20250917_m051255_c001_v7007000_t0000_u01758085975456") as it is a hide marker 2025/09/17 05:14:32 NOTICE: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f57c_d20250917_m051254_c001_v0001177_t0049_u01758085974740") 2025/09/17 05:14:32 NOTICE: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : .leading dot/.leading dot: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b03741_d20250917_m051302_c001_v7007000_t0000_u01758085982155") as it is a hide marker 2025/09/17 05:14:32 NOTICE: .leading dot/.leading dot: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : .leading dot/.leading dot: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f632_d20250917_m051301_c001_v0001177_t0029_u01758085981577") 2025/09/17 05:14:32 NOTICE: .leading dot/.leading dot: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : created from root.txt: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f412a8b411da5819d_d20250917_m051359_c001_v7007000_t0000_u01758086039514") as it is a hide marker 2025/09/17 05:14:32 NOTICE: created from root.txt: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : created from root.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1086d8421a6c38e3_d20250917_m051358_c001_v0001104_t0029_u01758086038025") 2025/09/17 05:14:32 NOTICE: created from root.txt: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : file name.txt: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f41854ab3ff7564dd_d20250917_m051407_c001_v7007000_t0000_u01758086047443") as it is a hide marker 2025/09/17 05:14:32 NOTICE: file name.txt: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : file name.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f7c5_d20250917_m051346_c001_v0001177_t0038_u01758086026347") 2025/09/17 05:14:32 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : file name.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f107a3613f35f23ac_d20250917_m051342_c001_v0001029_t0020_u01758086022298") 2025/09/17 05:14:32 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : file name.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f70e_d20250917_m051321_c001_v0001177_t0007_u01758086001029") 2025/09/17 05:14:32 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : file name.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f70a_d20250917_m051320_c001_v0001177_t0050_u01758086000109") 2025/09/17 05:14:32 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f70c_d20250917_m051320_c001_v0001177_t0045_u01758086000593") "upload" dated 2025-09-17 05:13:20.593 +0000 UTC (1m12.066662029s ago) 2025/09/17 05:14:32 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f4027163b1d7f8003_d20250917_m051340_c001_v7007000_t0000_u01758086020073") as it is a hide marker 2025/09/17 05:14:32 NOTICE: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1051b4e47e83a8a6_d20250917_m051339_c001_v0001032_t0015_u01758086019721") 2025/09/17 05:14:32 NOTICE: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1006320731a88541_d20250917_m051338_c001_v0001104_t0012_u01758086018820") 2025/09/17 05:14:32 NOTICE: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : invalid utf-8/invalid utf-8: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b037ba_d20250917_m051308_c001_v7007000_t0000_u01758085988827") as it is a hide marker 2025/09/17 05:14:32 NOTICE: invalid utf-8/invalid utf-8: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : invalid utf-8/invalid utf-8: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f6a8_d20250917_m051308_c001_v0001177_t0038_u01758085988260") 2025/09/17 05:14:32 NOTICE: invalid utf-8/invalid utf-8: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : piped data.txt: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f41854ab3ff7564e2_d20250917_m051409_c001_v7007000_t0000_u01758086049198") as it is a hide marker 2025/09/17 05:14:32 NOTICE: piped data.txt: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : piped data.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f852_d20250917_m051408_c001_v0001177_t0045_u01758086048799") 2025/09/17 05:14:32 NOTICE: piped data.txt: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : piped data.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f41854ab3ff7564e0_d20250917_m051408_c001_v7007000_t0000_u01758086048617") 2025/09/17 05:14:32 NOTICE: piped data.txt: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : piped data.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f4118d4737605f850_d20250917_m051408_c001_v0001177_t0054_u01758086048174") 2025/09/17 05:14:32 NOTICE: piped data.txt: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : test%46.txt/test%46.txt: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b037bf_d20250917_m051309_c001_v7007000_t0000_u01758085989783") as it is a hide marker 2025/09/17 05:14:32 NOTICE: test%46.txt/test%46.txt: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : test%46.txt/test%46.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f6ae_d20250917_m051309_c001_v0001177_t0020_u01758085989200") 2025/09/17 05:14:32 NOTICE: test%46.txt/test%46.txt: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : test-metadata: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f415583662cec7b96_d20250917_m051416_c001_v7007000_t0000_u01758086056019") as it is a hide marker 2025/09/17 05:14:32 NOTICE: test-metadata: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : test-metadata: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f21854ab3ff7564f2_d20250917_m051411_c001_v0001182_t0017_u01758086051075") 2025/09/17 05:14:32 NOTICE: test-metadata: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : test-metadata: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f41854ab3ff7564e8_d20250917_m051410_c001_v7007000_t0000_u01758086050167") 2025/09/17 05:14:32 NOTICE: test-metadata: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : test-metadata: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f858_d20250917_m051409_c001_v0001177_t0039_u01758086049379") 2025/09/17 05:14:32 NOTICE: test-metadata: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : trailing CR␍/trailing CR␍: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b03755_d20250917_m051304_c001_v7007000_t0000_u01758085984062") as it is a hide marker 2025/09/17 05:14:32 NOTICE: trailing CR␍/trailing CR␍: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : trailing CR␍/trailing CR␍: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f666_d20250917_m051303_c001_v0001177_t0000_u01758085983497") 2025/09/17 05:14:32 NOTICE: trailing CR␍/trailing CR␍: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : trailing HT␉/trailing HT␉: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b0377c_d20250917_m051305_c001_v7007000_t0000_u01758085985997") as it is a hide marker 2025/09/17 05:14:32 NOTICE: trailing HT␉/trailing HT␉: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : trailing HT␉/trailing HT␉: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f68c_d20250917_m051305_c001_v0001177_t0057_u01758085985379") 2025/09/17 05:14:32 NOTICE: trailing HT␉/trailing HT␉: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : trailing LF␊/trailing LF␊: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b03765_d20250917_m051305_c001_v7007000_t0000_u01758085985007") as it is a hide marker 2025/09/17 05:14:32 NOTICE: trailing LF␊/trailing LF␊: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : trailing LF␊/trailing LF␊: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f680_d20250917_m051304_c001_v0001177_t0041_u01758085984437") 2025/09/17 05:14:32 NOTICE: trailing LF␊/trailing LF␊: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : trailing VT␋/trailing VT␋: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b0378d_d20250917_m051306_c001_v7007000_t0000_u01758085986947") as it is a hide marker 2025/09/17 05:14:32 NOTICE: trailing VT␋/trailing VT␋: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : trailing VT␋/trailing VT␋: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f698_d20250917_m051306_c001_v0001177_t0023_u01758085986370") 2025/09/17 05:14:32 NOTICE: trailing VT␋/trailing VT␋: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : trailing dot./trailing dot.: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b037a7_d20250917_m051307_c001_v7007000_t0000_u01758085987887") as it is a hide marker 2025/09/17 05:14:32 NOTICE: trailing dot./trailing dot.: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : trailing dot./trailing dot.: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f6a0_d20250917_m051307_c001_v0001177_t0016_u01758085987321") 2025/09/17 05:14:32 NOTICE: trailing dot./trailing dot.: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : trailing space /trailing space : Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b0374d_d20250917_m051303_c001_v7007000_t0000_u01758085983121") as it is a hide marker 2025/09/17 05:14:32 NOTICE: trailing space /trailing space : Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : trailing space /trailing space : Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f64c_d20250917_m051302_c001_v0001177_t0051_u01758085982529") 2025/09/17 05:14:32 NOTICE: trailing space /trailing space : Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f119e5e84f30d5525_d20250917_m051426_c001_v0001184_t0023_u01758086066318") "upload" dated 2025-09-17 05:14:26.318 +0000 UTC (6.342505715s ago) 2025/09/17 05:14:32 DEBUG : versions/test-versions.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f417c3ec94f2d4118_d20250917_m051421_c001_v7007000_t0000_u01758086061566") 2025/09/17 05:14:32 NOTICE: versions/test-versions.txt: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : versions/test-versions.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f8a9_d20250917_m051418_c001_v0001177_t0048_u01758086058563") 2025/09/17 05:14:32 NOTICE: versions/test-versions.txt: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : writer-at-subdir/writer-at-file: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b037cf_d20250917_m051319_c001_v7007000_t0000_u01758085999552") as it is a hide marker 2025/09/17 05:14:32 NOTICE: writer-at-subdir/writer-at-file: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : writer-at-subdir/writer-at-file: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f208302b639b037c9_d20250917_m051311_c001_v0001117_t0051_u01758085991958") 2025/09/17 05:14:32 NOTICE: writer-at-subdir/writer-at-file: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : zero-length-file: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b037c8_d20250917_m051311_c001_v7007000_t0000_u01758085991622") as it is a hide marker 2025/09/17 05:14:32 NOTICE: zero-length-file: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : zero-length-file: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f4118d4737605f6c4_d20250917_m051311_c001_v0001177_t0054_u01758085991079") 2025/09/17 05:14:32 NOTICE: zero-length-file: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : ~leading tilde/~leading tilde: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b03710_d20250917_m051257_c001_v7007000_t0000_u01758085977349") as it is a hide marker 2025/09/17 05:14:32 NOTICE: ~leading tilde/~leading tilde: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : ~leading tilde/~leading tilde: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f5b0_d20250917_m051256_c001_v0001177_t0021_u01758085976789") 2025/09/17 05:14:32 NOTICE: ~leading tilde/~leading tilde: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b036b2_d20250917_m051252_c001_v7007000_t0000_u01758085972440") as it is a hide marker 2025/09/17 05:14:32 NOTICE: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f533_d20250917_m051251_c001_v0001177_t0000_u01758085971480") 2025/09/17 05:14:32 NOTICE: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : ␉leading HT/␉leading HT: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b0372d_d20250917_m051300_c001_v7007000_t0000_u01758085980241") as it is a hide marker 2025/09/17 05:14:32 NOTICE: ␉leading HT/␉leading HT: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : ␉leading HT/␉leading HT: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f5fc_d20250917_m051259_c001_v0001177_t0024_u01758085979668") 2025/09/17 05:14:32 NOTICE: ␉leading HT/␉leading HT: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : ␊leading LF/␊leading LF: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b03721_d20250917_m051259_c001_v7007000_t0000_u01758085979295") as it is a hide marker 2025/09/17 05:14:32 NOTICE: ␊leading LF/␊leading LF: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : ␊leading LF/␊leading LF: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f5e0_d20250917_m051258_c001_v0001177_t0025_u01758085978725") 2025/09/17 05:14:32 NOTICE: ␊leading LF/␊leading LF: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : ␋leading VT/␋leading VT: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b03735_d20250917_m051301_c001_v7007000_t0000_u01758085981205") as it is a hide marker 2025/09/17 05:14:32 NOTICE: ␋leading VT/␋leading VT: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : ␋leading VT/␋leading VT: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f616_d20250917_m051300_c001_v0001177_t0030_u01758085980613") 2025/09/17 05:14:32 NOTICE: ␋leading VT/␋leading VT: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : ␍leading CR/␍leading CR: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b0371e_d20250917_m051258_c001_v7007000_t0000_u01758085978352") as it is a hide marker 2025/09/17 05:14:32 NOTICE: ␍leading CR/␍leading CR: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : ␍leading CR/␍leading CR: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f5c4_d20250917_m051257_c001_v0001177_t0051_u01758085977724") 2025/09/17 05:14:32 NOTICE: ␍leading CR/␍leading CR: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : ．/．: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b036b8_d20250917_m051253_c001_v7007000_t0000_u01758085973389") as it is a hide marker 2025/09/17 05:14:32 NOTICE: ．/．: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : ．/．: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f554_d20250917_m051252_c001_v0001177_t0035_u01758085972811") 2025/09/17 05:14:32 NOTICE: ．/．: Skipped delete as --dry-run is set 2025/09/17 05:14:32 DEBUG : ．．/．．: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b036c8_d20250917_m051254_c001_v7007000_t0000_u01758085974368") as it is a hide marker 2025/09/17 05:14:32 NOTICE: ．．/．．: Skipped remove hide marker as --dry-run is set 2025/09/17 05:14:32 DEBUG : ．．/．．: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f566_d20250917_m051253_c001_v0001177_t0018_u01758085973763") 2025/09/17 05:14:32 NOTICE: ．．/．．: Skipped delete as --dry-run is set === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/RealThing 2025/09/17 05:14:32 INFO : B2 bucket rclone-test-kasowaj0noza: cleaning bucket "rclone-test-kasowaj0noza" of all hidden files 2025/09/17 05:14:33 DEBUG : leading space/ leading space: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b036f7_d20250917_m051256_c001_v7007000_t0000_u01758085976415") as it is a hide marker 2025/09/17 05:14:33 DEBUG : leading space/ leading space: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f594_d20250917_m051255_c001_v0001177_t0009_u01758085975834") 2025/09/17 05:14:33 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b036e9_d20250917_m051255_c001_v7007000_t0000_u01758085975456") as it is a hide marker 2025/09/17 05:14:33 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f57c_d20250917_m051254_c001_v0001177_t0049_u01758085974740") 2025/09/17 05:14:33 DEBUG : .leading dot/.leading dot: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b03741_d20250917_m051302_c001_v7007000_t0000_u01758085982155") as it is a hide marker 2025/09/17 05:14:33 DEBUG : .leading dot/.leading dot: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f632_d20250917_m051301_c001_v0001177_t0029_u01758085981577") 2025/09/17 05:14:33 DEBUG : created from root.txt: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f412a8b411da5819d_d20250917_m051359_c001_v7007000_t0000_u01758086039514") as it is a hide marker 2025/09/17 05:14:33 DEBUG : created from root.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1086d8421a6c38e3_d20250917_m051358_c001_v0001104_t0029_u01758086038025") 2025/09/17 05:14:33 DEBUG : file name.txt: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f41854ab3ff7564dd_d20250917_m051407_c001_v7007000_t0000_u01758086047443") as it is a hide marker 2025/09/17 05:14:33 DEBUG : file name.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f7c5_d20250917_m051346_c001_v0001177_t0038_u01758086026347") 2025/09/17 05:14:33 DEBUG : file name.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f107a3613f35f23ac_d20250917_m051342_c001_v0001029_t0020_u01758086022298") 2025/09/17 05:14:33 DEBUG : file name.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f70e_d20250917_m051321_c001_v0001177_t0007_u01758086001029") 2025/09/17 05:14:33 DEBUG : file name.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f70a_d20250917_m051320_c001_v0001177_t0050_u01758086000109") 2025/09/17 05:14:33 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f70c_d20250917_m051320_c001_v0001177_t0045_u01758086000593") "upload" dated 2025-09-17 05:13:20.593 +0000 UTC (1m13.116547103s ago) 2025/09/17 05:14:33 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f4027163b1d7f8003_d20250917_m051340_c001_v7007000_t0000_u01758086020073") as it is a hide marker 2025/09/17 05:14:33 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1051b4e47e83a8a6_d20250917_m051339_c001_v0001032_t0015_u01758086019721") 2025/09/17 05:14:33 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1006320731a88541_d20250917_m051338_c001_v0001104_t0012_u01758086018820") 2025/09/17 05:14:33 DEBUG : invalid utf-8/invalid utf-8: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b037ba_d20250917_m051308_c001_v7007000_t0000_u01758085988827") as it is a hide marker 2025/09/17 05:14:33 DEBUG : invalid utf-8/invalid utf-8: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f6a8_d20250917_m051308_c001_v0001177_t0038_u01758085988260") 2025/09/17 05:14:33 DEBUG : piped data.txt: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f41854ab3ff7564e2_d20250917_m051409_c001_v7007000_t0000_u01758086049198") as it is a hide marker 2025/09/17 05:14:34 DEBUG : piped data.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f852_d20250917_m051408_c001_v0001177_t0045_u01758086048799") 2025/09/17 05:14:34 DEBUG : piped data.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f41854ab3ff7564e0_d20250917_m051408_c001_v7007000_t0000_u01758086048617") 2025/09/17 05:14:34 DEBUG : piped data.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f4118d4737605f850_d20250917_m051408_c001_v0001177_t0054_u01758086048174") 2025/09/17 05:14:34 DEBUG : test%46.txt/test%46.txt: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b037bf_d20250917_m051309_c001_v7007000_t0000_u01758085989783") as it is a hide marker 2025/09/17 05:14:34 DEBUG : test%46.txt/test%46.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f6ae_d20250917_m051309_c001_v0001177_t0020_u01758085989200") 2025/09/17 05:14:34 DEBUG : test-metadata: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f415583662cec7b96_d20250917_m051416_c001_v7007000_t0000_u01758086056019") as it is a hide marker 2025/09/17 05:14:34 DEBUG : test-metadata: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f21854ab3ff7564f2_d20250917_m051411_c001_v0001182_t0017_u01758086051075") 2025/09/17 05:14:34 DEBUG : test-metadata: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f41854ab3ff7564e8_d20250917_m051410_c001_v7007000_t0000_u01758086050167") 2025/09/17 05:14:34 DEBUG : test-metadata: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f858_d20250917_m051409_c001_v0001177_t0039_u01758086049379") 2025/09/17 05:14:34 DEBUG : trailing CR␍/trailing CR␍: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b03755_d20250917_m051304_c001_v7007000_t0000_u01758085984062") as it is a hide marker 2025/09/17 05:14:34 DEBUG : trailing CR␍/trailing CR␍: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f666_d20250917_m051303_c001_v0001177_t0000_u01758085983497") 2025/09/17 05:14:34 DEBUG : trailing HT␉/trailing HT␉: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b0377c_d20250917_m051305_c001_v7007000_t0000_u01758085985997") as it is a hide marker 2025/09/17 05:14:34 DEBUG : trailing HT␉/trailing HT␉: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f68c_d20250917_m051305_c001_v0001177_t0057_u01758085985379") 2025/09/17 05:14:34 DEBUG : trailing LF␊/trailing LF␊: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b03765_d20250917_m051305_c001_v7007000_t0000_u01758085985007") as it is a hide marker 2025/09/17 05:14:34 DEBUG : trailing LF␊/trailing LF␊: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f680_d20250917_m051304_c001_v0001177_t0041_u01758085984437") 2025/09/17 05:14:34 DEBUG : trailing VT␋/trailing VT␋: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b0378d_d20250917_m051306_c001_v7007000_t0000_u01758085986947") as it is a hide marker 2025/09/17 05:14:34 DEBUG : trailing VT␋/trailing VT␋: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f698_d20250917_m051306_c001_v0001177_t0023_u01758085986370") 2025/09/17 05:14:34 DEBUG : trailing dot./trailing dot.: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b037a7_d20250917_m051307_c001_v7007000_t0000_u01758085987887") as it is a hide marker 2025/09/17 05:14:34 DEBUG : trailing dot./trailing dot.: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f6a0_d20250917_m051307_c001_v0001177_t0016_u01758085987321") 2025/09/17 05:14:34 DEBUG : trailing space /trailing space : Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b0374d_d20250917_m051303_c001_v7007000_t0000_u01758085983121") as it is a hide marker 2025/09/17 05:14:34 DEBUG : trailing space /trailing space : Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f64c_d20250917_m051302_c001_v0001177_t0051_u01758085982529") 2025/09/17 05:14:35 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f119e5e84f30d5525_d20250917_m051426_c001_v0001184_t0023_u01758086066318") "upload" dated 2025-09-17 05:14:26.318 +0000 UTC (8.758968478s ago) 2025/09/17 05:14:35 DEBUG : versions/test-versions.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f417c3ec94f2d4118_d20250917_m051421_c001_v7007000_t0000_u01758086061566") 2025/09/17 05:14:35 DEBUG : versions/test-versions.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f8a9_d20250917_m051418_c001_v0001177_t0048_u01758086058563") 2025/09/17 05:14:35 DEBUG : writer-at-subdir/writer-at-file: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b037cf_d20250917_m051319_c001_v7007000_t0000_u01758085999552") as it is a hide marker 2025/09/17 05:14:35 DEBUG : writer-at-subdir/writer-at-file: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f208302b639b037c9_d20250917_m051311_c001_v0001117_t0051_u01758085991958") 2025/09/17 05:14:35 DEBUG : zero-length-file: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b037c8_d20250917_m051311_c001_v7007000_t0000_u01758085991622") as it is a hide marker 2025/09/17 05:14:35 DEBUG : zero-length-file: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f4118d4737605f6c4_d20250917_m051311_c001_v0001177_t0054_u01758085991079") 2025/09/17 05:14:35 DEBUG : ~leading tilde/~leading tilde: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b03710_d20250917_m051257_c001_v7007000_t0000_u01758085977349") as it is a hide marker 2025/09/17 05:14:35 DEBUG : ~leading tilde/~leading tilde: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f5b0_d20250917_m051256_c001_v0001177_t0021_u01758085976789") 2025/09/17 05:14:35 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b036b2_d20250917_m051252_c001_v7007000_t0000_u01758085972440") as it is a hide marker 2025/09/17 05:14:35 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f533_d20250917_m051251_c001_v0001177_t0000_u01758085971480") 2025/09/17 05:14:35 DEBUG : ␉leading HT/␉leading HT: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b0372d_d20250917_m051300_c001_v7007000_t0000_u01758085980241") as it is a hide marker 2025/09/17 05:14:35 DEBUG : ␉leading HT/␉leading HT: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f5fc_d20250917_m051259_c001_v0001177_t0024_u01758085979668") 2025/09/17 05:14:35 DEBUG : ␊leading LF/␊leading LF: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b03721_d20250917_m051259_c001_v7007000_t0000_u01758085979295") as it is a hide marker 2025/09/17 05:14:35 DEBUG : ␊leading LF/␊leading LF: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f5e0_d20250917_m051258_c001_v0001177_t0025_u01758085978725") 2025/09/17 05:14:35 DEBUG : ␋leading VT/␋leading VT: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b03735_d20250917_m051301_c001_v7007000_t0000_u01758085981205") as it is a hide marker 2025/09/17 05:14:35 DEBUG : ␋leading VT/␋leading VT: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f616_d20250917_m051300_c001_v0001177_t0030_u01758085980613") 2025/09/17 05:14:35 DEBUG : ␍leading CR/␍leading CR: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b0371e_d20250917_m051258_c001_v7007000_t0000_u01758085978352") as it is a hide marker 2025/09/17 05:14:35 DEBUG : ␍leading CR/␍leading CR: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f5c4_d20250917_m051257_c001_v0001177_t0051_u01758085977724") 2025/09/17 05:14:35 DEBUG : ．/．: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b036b8_d20250917_m051253_c001_v7007000_t0000_u01758085973389") as it is a hide marker 2025/09/17 05:14:35 DEBUG : ．/．: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f554_d20250917_m051252_c001_v0001177_t0035_u01758085972811") 2025/09/17 05:14:36 DEBUG : ．．/．．: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b036c8_d20250917_m051254_c001_v7007000_t0000_u01758085974368") as it is a hide marker 2025/09/17 05:14:36 DEBUG : ．．/．．: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f566_d20250917_m051253_c001_v0001177_t0018_u01758085973763") === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished 2025/09/17 05:14:36 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/09/17 05:14:36 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/09/17 05:14:37 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/09/17 05:14:37 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/09/17 05:14:37 INFO : B2 bucket rclone-test-kasowaj0noza: cleaning bucket "rclone-test-kasowaj0noza" of pending multipart uploads older than 0s 2025/09/17 05:14:37 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f70c_d20250917_m051320_c001_v0001177_t0045_u01758086000593") "upload" dated 2025-09-17 05:13:20.593 +0000 UTC (1m17.322422075s ago) 2025/09/17 05:14:37 DEBUG : unfinished/unfinished-1: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f208302b639b0393d_d20250917_m051436_c001_v0001106_t0036_u01758086076891") as it is a start marker (upload started at 2025-09-17 05:14:36.891 +0000 UTC) 2025/09/17 05:14:37 NOTICE: unfinished/unfinished-1: Skipped remove pending upload as --dry-run is set 2025/09/17 05:14:37 DEBUG : unfinished/unfinished-2: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f208302b639b0393e_d20250917_m051437_c001_v0001177_t0008_u01758086077076") as it is a start marker (upload started at 2025-09-17 05:14:37.076 +0000 UTC) 2025/09/17 05:14:37 NOTICE: unfinished/unfinished-2: Skipped remove pending upload as --dry-run is set 2025/09/17 05:14:37 DEBUG : unfinished/unfinished-3: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f208302b639b0393f_d20250917_m051437_c001_v0001177_t0013_u01758086077263") as it is a start marker (upload started at 2025-09-17 05:14:37.263 +0000 UTC) 2025/09/17 05:14:37 NOTICE: unfinished/unfinished-3: Skipped remove pending upload as --dry-run is set 2025/09/17 05:14:37 DEBUG : unfinished/unfinished-4: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f208302b639b03943_d20250917_m051437_c001_v0001182_t0007_u01758086077446") as it is a start marker (upload started at 2025-09-17 05:14:37.446 +0000 UTC) 2025/09/17 05:14:37 NOTICE: unfinished/unfinished-4: Skipped remove pending upload as --dry-run is set 2025/09/17 05:14:37 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b0393c_d20250917_m051436_c001_v7007000_t0000_u01758086076704") "hide" dated 2025-09-17 05:14:36.704 +0000 UTC (1.211798713s ago) 2025/09/17 05:14:37 DEBUG : versions/test-versions.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f119e5e84f30d5525_d20250917_m051426_c001_v0001184_t0023_u01758086066318") 2025/09/17 05:14:37 NOTICE: versions/test-versions.txt: Skipped delete as --dry-run is set === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished/RealThing 2025/09/17 05:14:38 INFO : B2 bucket rclone-test-kasowaj0noza: cleaning bucket "rclone-test-kasowaj0noza" of pending multipart uploads older than 0s 2025/09/17 05:14:38 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f70c_d20250917_m051320_c001_v0001177_t0045_u01758086000593") "upload" dated 2025-09-17 05:13:20.593 +0000 UTC (1m17.693263727s ago) 2025/09/17 05:14:38 DEBUG : unfinished/unfinished-1: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f208302b639b0393d_d20250917_m051436_c001_v0001106_t0036_u01758086076891") as it is a start marker (upload started at 2025-09-17 05:14:36.891 +0000 UTC) 2025/09/17 05:14:38 DEBUG : unfinished/unfinished-2: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f208302b639b0393e_d20250917_m051437_c001_v0001177_t0008_u01758086077076") as it is a start marker (upload started at 2025-09-17 05:14:37.076 +0000 UTC) 2025/09/17 05:14:38 DEBUG : unfinished/unfinished-3: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f208302b639b0393f_d20250917_m051437_c001_v0001177_t0013_u01758086077263") as it is a start marker (upload started at 2025-09-17 05:14:37.263 +0000 UTC) 2025/09/17 05:14:38 DEBUG : unfinished/unfinished-4: Deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f208302b639b03943_d20250917_m051437_c001_v0001182_t0007_u01758086077446") as it is a start marker (upload started at 2025-09-17 05:14:37.446 +0000 UTC) 2025/09/17 05:14:38 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b0393c_d20250917_m051436_c001_v7007000_t0000_u01758086076704") "hide" dated 2025-09-17 05:14:36.704 +0000 UTC (1.582520139s ago) 2025/09/17 05:14:38 DEBUG : versions/test-versions.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f119e5e84f30d5525_d20250917_m051426_c001_v0001184_t0023_u01758086066318") === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/InitState === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/DryRun 2025/09/17 05:14:39 NOTICE: lifecycle: Skipped update lifecycle rules as --dry-run is set 2025/09/17 05:14:39 NOTICE: lifecycle: Skipped update lifecycle rules as --dry-run is set 2025/09/17 05:14:39 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/09/17 05:14:40 DEBUG : chunked-5Mi-5.000Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/5.000Mi 2025/09/17 05:14:40 DEBUG : chunked-5Mi-5.000Mi.bin: Sending chunk 0 length 5242880 2025/09/17 05:14:40 DEBUG : chunked-5Mi-5.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 5Mi/5.000Mi 2025/09/17 05:14:40 DEBUG : chunked-5Mi-5.000Mi.bin: Sending chunk 1 length 1 2025/09/17 05:14:41 DEBUG : chunked-5Mi-5.000Mi.bin: Done sending chunk 1 2025/09/17 05:14:42 DEBUG : chunked-5Mi-5.000Mi.bin: Done sending chunk 0 2025/09/17 05:14:42 DEBUG : chunked-5Mi-5.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881/Streamed 2025/09/17 05:14:45 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:14:45 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/09/17 05:14:46 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f223fb2df74cac57a_d20250917_m051445_c001_v0001144_t0036_u01758086085965") 2025/09/17 05:14:46 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/09/17 05:14:46 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 1 length 1 2025/09/17 05:14:46 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/09/17 05:14:47 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:14:48 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:14:48 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760 2025/09/17 05:14:50 DEBUG : chunked-5Mi-10Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/10Mi 2025/09/17 05:14:50 DEBUG : chunked-5Mi-10Mi.bin: Sending chunk 0 length 5242880 2025/09/17 05:14:50 DEBUG : chunked-5Mi-10Mi.bin: multipart upload: starting chunk 1 size 5Mi offset 5Mi/10Mi 2025/09/17 05:14:50 DEBUG : chunked-5Mi-10Mi.bin: Sending chunk 1 length 5242880 2025/09/17 05:14:52 DEBUG : chunked-5Mi-10Mi.bin: Done sending chunk 0 2025/09/17 05:14:52 DEBUG : chunked-5Mi-10Mi.bin: Done sending chunk 1 2025/09/17 05:14:52 DEBUG : chunked-5Mi-10Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760/Streamed 2025/09/17 05:14:54 DEBUG : chunked-5Mi-10Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:14:54 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/09/17 05:14:54 DEBUG : chunked-5Mi-10Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f207317f3aeb493fb_d20250917_m051454_c001_v0001179_t0005_u01758086094579") 2025/09/17 05:14:54 DEBUG : chunked-5Mi-10Mi-streamed.bin: Sending chunk 0 length 5242880 2025/09/17 05:14:54 DEBUG : chunked-5Mi-10Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/09/17 05:14:54 DEBUG : chunked-5Mi-10Mi-streamed.bin: Sending chunk 1 length 5242880 2025/09/17 05:14:57 DEBUG : chunked-5Mi-10Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:14:57 DEBUG : chunked-5Mi-10Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:14:57 DEBUG : chunked-5Mi-10Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761 2025/09/17 05:14:59 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/10.000Mi 2025/09/17 05:14:59 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 0 length 5242880 2025/09/17 05:14:59 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 1 size 5Mi offset 5Mi/10.000Mi 2025/09/17 05:14:59 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 1 length 5242880 2025/09/17 05:14:59 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 10Mi/10.000Mi 2025/09/17 05:14:59 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 2 length 1 2025/09/17 05:15:00 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 2 2025/09/17 05:15:01 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 1 2025/09/17 05:15:01 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 0 2025/09/17 05:15:01 DEBUG : chunked-5Mi-10.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761/Streamed 2025/09/17 05:15:03 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:15:03 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/09/17 05:15:03 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f20056f63f8bd77b0_d20250917_m051503_c001_v0001136_t0057_u01758086103427") 2025/09/17 05:15:03 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/09/17 05:15:03 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/09/17 05:15:03 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 2 length 1 2025/09/17 05:15:03 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 1 length 5242880 2025/09/17 05:15:04 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 2 2025/09/17 05:15:05 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:15:06 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:15:06 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/09/17 05:15:07 DEBUG : chunked-5.054Mi-5.054Mi.bin: multipart upload: starting chunk 1 size 1 offset 5.054Mi/5.054Mi 2025/09/17 05:15:07 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 1 length 1 2025/09/17 05:15:07 DEBUG : chunked-5.054Mi-5.054Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/5.054Mi 2025/09/17 05:15:07 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 0 length 5300000 2025/09/17 05:15:08 DEBUG : chunked-5.054Mi-5.054Mi.bin: Done sending chunk 1 2025/09/17 05:15:10 DEBUG : chunked-5.054Mi-5.054Mi.bin: Done sending chunk 0 2025/09/17 05:15:10 DEBUG : chunked-5.054Mi-5.054Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001/Streamed 2025/09/17 05:15:11 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:15:11 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/09/17 05:15:11 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f20056f63f8bd782c_d20250917_m051511_c001_v0001180_t0020_u01758086111680") 2025/09/17 05:15:11 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/09/17 05:15:11 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Sending chunk 1 length 1 2025/09/17 05:15:11 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Sending chunk 0 length 5300000 2025/09/17 05:15:12 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:15:14 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:15:14 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000 2025/09/17 05:15:15 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/10.109Mi 2025/09/17 05:15:15 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 0 length 5300000 2025/09/17 05:15:15 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.054Mi offset 5.054Mi/10.109Mi 2025/09/17 05:15:15 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 1 length 5300000 2025/09/17 05:15:17 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 0 2025/09/17 05:15:18 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 1 2025/09/17 05:15:18 DEBUG : chunked-5.054Mi-10.109Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000/Streamed 2025/09/17 05:15:19 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:15:19 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/09/17 05:15:19 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f20056f63f8bd7862_d20250917_m051519_c001_v0001183_t0005_u01758086119861") 2025/09/17 05:15:19 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300000 2025/09/17 05:15:20 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/09/17 05:15:20 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300000 2025/09/17 05:15:22 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:15:22 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:15:22 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001 2025/09/17 05:15:24 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/10.109Mi 2025/09/17 05:15:24 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 0 length 5300000 2025/09/17 05:15:24 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.054Mi offset 5.054Mi/10.109Mi 2025/09/17 05:15:24 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 1 length 5300000 2025/09/17 05:15:24 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 2 size 1 offset 10.109Mi/10.109Mi 2025/09/17 05:15:24 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 2 length 1 2025/09/17 05:15:25 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 2 2025/09/17 05:15:26 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 0 2025/09/17 05:15:26 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 1 2025/09/17 05:15:26 DEBUG : chunked-5.054Mi-10.109Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001/Streamed 2025/09/17 05:15:28 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:15:28 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/09/17 05:15:28 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f205a05ac630796bb_d20250917_m051528_c001_v0001171_t0017_u01758086128573") 2025/09/17 05:15:28 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300000 2025/09/17 05:15:28 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/09/17 05:15:28 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 2 length 1 2025/09/17 05:15:28 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300000 2025/09/17 05:15:29 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 2 2025/09/17 05:15:31 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:15:31 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:15:31 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/09/17 05:15:33 DEBUG : chunked-5.055Mi-5.055Mi.bin: multipart upload: starting chunk 1 size 1 offset 5.055Mi/5.055Mi 2025/09/17 05:15:33 DEBUG : chunked-5.055Mi-5.055Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/5.055Mi 2025/09/17 05:15:33 DEBUG : chunked-5.055Mi-5.055Mi.bin: Sending chunk 1 length 1 2025/09/17 05:15:33 DEBUG : chunked-5.055Mi-5.055Mi.bin: Sending chunk 0 length 5300053 2025/09/17 05:15:34 DEBUG : chunked-5.055Mi-5.055Mi.bin: Done sending chunk 1 2025/09/17 05:15:35 DEBUG : chunked-5.055Mi-5.055Mi.bin: Done sending chunk 0 2025/09/17 05:15:35 DEBUG : chunked-5.055Mi-5.055Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054/Streamed 2025/09/17 05:15:36 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:15:36 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/09/17 05:15:36 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f205a05ac630796c3_d20250917_m051536_c001_v0001179_t0019_u01758086136815") 2025/09/17 05:15:36 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/09/17 05:15:36 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 1 length 1 2025/09/17 05:15:36 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 0 length 5300053 2025/09/17 05:15:37 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Error sending chunk 1 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000369c20), retryAfter:1000000000} 2025/09/17 05:15:37 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/09/17 05:15:37 DEBUG : pacer: low level retry 1/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/09/17 05:15:37 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/09/17 05:15:37 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 1 length 1 2025/09/17 05:15:37 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Error sending chunk 0 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000369e90), retryAfter:1000000000} 2025/09/17 05:15:37 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/09/17 05:15:37 DEBUG : pacer: low level retry 1/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/09/17 05:15:38 DEBUG : pacer: Reducing sleep to 500ms 2025/09/17 05:15:39 DEBUG : pacer: Reducing sleep to 250ms 2025/09/17 05:15:39 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:15:39 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 0 length 5300053 2025/09/17 05:15:42 DEBUG : pacer: Reducing sleep to 125ms 2025/09/17 05:15:42 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:15:42 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Finishing large file upload with 2 parts 2025/09/17 05:15:42 DEBUG : pacer: Reducing sleep to 62.5ms 2025/09/17 05:15:42 DEBUG : pacer: Reducing sleep to 31.25ms 2025/09/17 05:15:43 DEBUG : pacer: Reducing sleep to 15.625ms 2025/09/17 05:15:44 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106 2025/09/17 05:15:45 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/10.109Mi 2025/09/17 05:15:45 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 0 length 5300053 2025/09/17 05:15:45 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.055Mi offset 5.055Mi/10.109Mi 2025/09/17 05:15:45 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 1 length 5300053 2025/09/17 05:15:47 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 0 2025/09/17 05:15:47 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 1 2025/09/17 05:15:47 DEBUG : chunked-5.055Mi-10.109Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106/Streamed 2025/09/17 05:15:49 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:15:49 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/09/17 05:15:49 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f20056f63f8bd78f2_d20250917_m051549_c001_v0001157_t0015_u01758086149264") 2025/09/17 05:15:49 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/09/17 05:15:49 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/09/17 05:15:49 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/09/17 05:15:51 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:15:52 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:15:52 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107 2025/09/17 05:15:53 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/10.109Mi 2025/09/17 05:15:53 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 0 length 5300053 2025/09/17 05:15:54 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.055Mi offset 5.055Mi/10.109Mi 2025/09/17 05:15:54 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 1 length 5300053 2025/09/17 05:15:54 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 2 size 1 offset 10.109Mi/10.109Mi 2025/09/17 05:15:54 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 2 length 1 2025/09/17 05:15:55 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 2 2025/09/17 05:15:56 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 0 2025/09/17 05:15:57 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 1 2025/09/17 05:15:57 DEBUG : chunked-5.055Mi-10.109Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107/Streamed 2025/09/17 05:15:58 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:15:58 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/09/17 05:15:59 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f218a9cce0273f9ad_d20250917_m051558_c001_v0001180_t0056_u01758086158938") 2025/09/17 05:15:59 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/09/17 05:15:59 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/09/17 05:15:59 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 2 length 1 2025/09/17 05:15:59 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/09/17 05:15:59 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_get_upload_part_url": EOF) 2025/09/17 05:15:59 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/17 05:15:59 DEBUG : pacer: Reducing sleep to 10ms 2025/09/17 05:16:00 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 2 2025/09/17 05:16:01 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:16:01 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:16:01 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/09/17 05:16:03 DEBUG : chunked-6.667Mi-6.667Mi.bin: multipart upload: starting chunk 1 size 1 offset 6.667Mi/6.667Mi 2025/09/17 05:16:03 DEBUG : chunked-6.667Mi-6.667Mi.bin: Sending chunk 1 length 1 2025/09/17 05:16:03 DEBUG : chunked-6.667Mi-6.667Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/6.667Mi 2025/09/17 05:16:03 DEBUG : chunked-6.667Mi-6.667Mi.bin: Sending chunk 0 length 6990506 2025/09/17 05:16:04 DEBUG : chunked-6.667Mi-6.667Mi.bin: Done sending chunk 1 2025/09/17 05:16:06 DEBUG : chunked-6.667Mi-6.667Mi.bin: Done sending chunk 0 2025/09/17 05:16:06 DEBUG : chunked-6.667Mi-6.667Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507/Streamed 2025/09/17 05:16:07 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:16:07 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/09/17 05:16:07 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f218a9cce0273f9be_d20250917_m051607_c001_v0001183_t0036_u01758086167684") 2025/09/17 05:16:07 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/09/17 05:16:07 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 1 length 1 2025/09/17 05:16:07 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 0 length 6990506 2025/09/17 05:16:08 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:16:10 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:16:10 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012 2025/09/17 05:16:11 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/13.333Mi 2025/09/17 05:16:11 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2025/09/17 05:16:12 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 1 size 6.667Mi offset 6.667Mi/13.333Mi 2025/09/17 05:16:12 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2025/09/17 05:16:14 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 0 2025/09/17 05:16:14 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 1 2025/09/17 05:16:14 DEBUG : chunked-6.667Mi-13.333Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012/Streamed 2025/09/17 05:16:16 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:16:16 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/09/17 05:16:16 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f204f3555810952a2_d20250917_m051616_c001_v0001177_t0040_u01758086176489") 2025/09/17 05:16:16 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/09/17 05:16:16 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/09/17 05:16:16 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/09/17 05:16:19 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:16:19 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:16:19 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013 2025/09/17 05:16:21 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/13.333Mi 2025/09/17 05:16:21 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2025/09/17 05:16:21 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 2 size 1 offset 13.333Mi/13.333Mi 2025/09/17 05:16:21 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 1 2025/09/17 05:16:21 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 1 size 6.667Mi offset 6.667Mi/13.333Mi 2025/09/17 05:16:21 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2025/09/17 05:16:22 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 2 2025/09/17 05:16:24 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 0 2025/09/17 05:16:24 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 1 2025/09/17 05:16:24 DEBUG : chunked-6.667Mi-13.333Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013/Streamed 2025/09/17 05:16:26 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:16:26 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/09/17 05:16:26 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f215e55e7bffc072b_d20250917_m051626_c001_v0001165_t0005_u01758086186241") 2025/09/17 05:16:26 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/09/17 05:16:26 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/09/17 05:16:26 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 2 length 1 2025/09/17 05:16:26 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/09/17 05:16:26 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_get_upload_part_url": EOF) 2025/09/17 05:16:26 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/17 05:16:26 DEBUG : pacer: Reducing sleep to 10ms 2025/09/17 05:16:27 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 2 2025/09/17 05:16:28 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:16:29 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:16:29 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/09/17 05:16:31 DEBUG : chunked-8Mi-8.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 8Mi/8.000Mi 2025/09/17 05:16:31 DEBUG : chunked-8Mi-8.000Mi.bin: Sending chunk 1 length 1 2025/09/17 05:16:31 DEBUG : chunked-8Mi-8.000Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/8.000Mi 2025/09/17 05:16:31 DEBUG : chunked-8Mi-8.000Mi.bin: Sending chunk 0 length 8388608 2025/09/17 05:16:32 DEBUG : chunked-8Mi-8.000Mi.bin: Done sending chunk 1 2025/09/17 05:16:33 DEBUG : chunked-8Mi-8.000Mi.bin: Done sending chunk 0 2025/09/17 05:16:33 DEBUG : chunked-8Mi-8.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609/Streamed 2025/09/17 05:16:35 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:16:35 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/09/17 05:16:35 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f215e55e7bffc072e_d20250917_m051635_c001_v0001153_t0013_u01758086195654") 2025/09/17 05:16:35 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/09/17 05:16:35 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Sending chunk 1 length 1 2025/09/17 05:16:35 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Sending chunk 0 length 8388608 2025/09/17 05:16:36 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:16:38 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:16:38 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216 2025/09/17 05:16:40 DEBUG : chunked-8Mi-16Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/16Mi 2025/09/17 05:16:40 DEBUG : chunked-8Mi-16Mi.bin: Sending chunk 0 length 8388608 2025/09/17 05:16:40 DEBUG : chunked-8Mi-16Mi.bin: multipart upload: starting chunk 1 size 8Mi offset 8Mi/16Mi 2025/09/17 05:16:40 DEBUG : chunked-8Mi-16Mi.bin: Sending chunk 1 length 8388608 2025/09/17 05:16:42 DEBUG : chunked-8Mi-16Mi.bin: Done sending chunk 0 2025/09/17 05:16:42 DEBUG : chunked-8Mi-16Mi.bin: Done sending chunk 1 2025/09/17 05:16:42 DEBUG : chunked-8Mi-16Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216/Streamed 2025/09/17 05:16:44 DEBUG : chunked-8Mi-16Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:16:44 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/09/17 05:16:45 DEBUG : chunked-8Mi-16Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f21854ab3ff75684f_d20250917_m051644_c001_v0001109_t0031_u01758086204963") 2025/09/17 05:16:45 DEBUG : chunked-8Mi-16Mi-streamed.bin: Sending chunk 0 length 8388608 2025/09/17 05:16:45 DEBUG : chunked-8Mi-16Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/09/17 05:16:45 DEBUG : chunked-8Mi-16Mi-streamed.bin: Sending chunk 1 length 8388608 2025/09/17 05:16:47 DEBUG : chunked-8Mi-16Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:16:47 DEBUG : chunked-8Mi-16Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:16:47 DEBUG : chunked-8Mi-16Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217 2025/09/17 05:16:50 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/16.000Mi 2025/09/17 05:16:50 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 0 length 8388608 2025/09/17 05:16:50 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 1 size 8Mi offset 8Mi/16.000Mi 2025/09/17 05:16:50 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 1 length 8388608 2025/09/17 05:16:50 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 16Mi/16.000Mi 2025/09/17 05:16:50 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 2 length 1 2025/09/17 05:16:51 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 2 2025/09/17 05:16:52 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 0 2025/09/17 05:16:52 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 1 2025/09/17 05:16:52 DEBUG : chunked-8Mi-16.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217/Streamed 2025/09/17 05:16:54 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:16:54 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/09/17 05:16:55 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f2163d92aba0c7fed_d20250917_m051654_c001_v0001171_t0053_u01758086214941") 2025/09/17 05:16:55 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 0 length 8388608 2025/09/17 05:16:55 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/09/17 05:16:55 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 2 length 1 2025/09/17 05:16:55 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 1 length 8388608 2025/09/17 05:16:56 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 2 2025/09/17 05:16:57 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:16:58 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:16:58 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/09/17 05:17:00 DEBUG : chunked-10Mi-10.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 10Mi/10.000Mi 2025/09/17 05:17:00 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/09/17 05:17:00 DEBUG : chunked-10Mi-10.000Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/10.000Mi 2025/09/17 05:17:00 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/09/17 05:17:00 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_get_upload_part_url": EOF) 2025/09/17 05:17:00 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/17 05:17:00 DEBUG : pacer: Reducing sleep to 10ms 2025/09/17 05:17:01 DEBUG : chunked-10Mi-10.000Mi.bin: Done sending chunk 1 2025/09/17 05:17:03 DEBUG : chunked-10Mi-10.000Mi.bin: Done sending chunk 0 2025/09/17 05:17:03 DEBUG : chunked-10Mi-10.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761/Streamed 2025/09/17 05:17:07 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:17:07 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/09/17 05:17:08 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f219e045569a65e08_d20250917_m051707_c001_v0001165_t0017_u01758086227921") 2025/09/17 05:17:08 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/09/17 05:17:08 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Sending chunk 0 length 10485760 2025/09/17 05:17:08 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Sending chunk 1 length 1 2025/09/17 05:17:09 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:17:10 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:17:10 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520 2025/09/17 05:17:13 DEBUG : chunked-10Mi-20Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/20Mi 2025/09/17 05:17:13 DEBUG : chunked-10Mi-20Mi.bin: Sending chunk 0 length 10485760 2025/09/17 05:17:14 DEBUG : chunked-10Mi-20Mi.bin: multipart upload: starting chunk 1 size 10Mi offset 10Mi/20Mi 2025/09/17 05:17:14 DEBUG : chunked-10Mi-20Mi.bin: Sending chunk 1 length 10485760 2025/09/17 05:17:16 DEBUG : chunked-10Mi-20Mi.bin: Done sending chunk 0 2025/09/17 05:17:17 DEBUG : chunked-10Mi-20Mi.bin: Done sending chunk 1 2025/09/17 05:17:17 DEBUG : chunked-10Mi-20Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520/Streamed 2025/09/17 05:17:20 DEBUG : chunked-10Mi-20Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:17:20 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/09/17 05:17:21 DEBUG : chunked-10Mi-20Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f219e045569a65eb0_d20250917_m051720_c001_v0001179_t0023_u01758086240907") 2025/09/17 05:17:21 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 0 length 10485760 2025/09/17 05:17:21 DEBUG : chunked-10Mi-20Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/09/17 05:17:21 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 1 length 10485760 2025/09/17 05:17:21 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000255ad0), retryAfter:1000000000} 2025/09/17 05:17:21 DEBUG : chunked-10Mi-20Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/09/17 05:17:21 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/09/17 05:17:21 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/09/17 05:17:21 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 0 length 10485760 2025/09/17 05:17:21 DEBUG : pacer: Reducing sleep to 500ms 2025/09/17 05:17:22 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0002680f0), retryAfter:1000000000} 2025/09/17 05:17:22 DEBUG : chunked-10Mi-20Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/09/17 05:17:22 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/09/17 05:17:22 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/09/17 05:17:23 DEBUG : pacer: Reducing sleep to 500ms 2025/09/17 05:17:23 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 0 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000414180), retryAfter:1000000000} 2025/09/17 05:17:23 DEBUG : chunked-10Mi-20Mi-streamed.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/09/17 05:17:23 DEBUG : pacer: low level retry 2/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/09/17 05:17:23 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/09/17 05:17:23 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 1 length 10485760 2025/09/17 05:17:24 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 0 length 10485760 2025/09/17 05:17:26 DEBUG : pacer: Reducing sleep to 500ms 2025/09/17 05:17:26 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000269860), retryAfter:1000000000} 2025/09/17 05:17:26 DEBUG : chunked-10Mi-20Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/09/17 05:17:26 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/09/17 05:17:26 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/09/17 05:17:27 DEBUG : pacer: Reducing sleep to 500ms 2025/09/17 05:17:27 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00032cb40), retryAfter:1000000000} 2025/09/17 05:17:27 DEBUG : chunked-10Mi-20Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/09/17 05:17:27 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/09/17 05:17:27 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/09/17 05:17:27 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 1 length 10485760 2025/09/17 05:17:28 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 0 length 10485760 2025/09/17 05:17:30 DEBUG : pacer: Reducing sleep to 500ms 2025/09/17 05:17:30 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00152c2a0), retryAfter:1000000000} 2025/09/17 05:17:30 DEBUG : chunked-10Mi-20Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/09/17 05:17:30 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/09/17 05:17:30 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/09/17 05:17:31 DEBUG : pacer: Reducing sleep to 500ms 2025/09/17 05:17:31 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00049a5d0), retryAfter:1000000000} 2025/09/17 05:17:31 DEBUG : chunked-10Mi-20Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/09/17 05:17:31 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/09/17 05:17:31 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/09/17 05:17:31 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 1 length 10485760 2025/09/17 05:17:32 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 0 length 10485760 2025/09/17 05:17:34 DEBUG : pacer: Reducing sleep to 500ms 2025/09/17 05:17:34 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00049ac00), retryAfter:1000000000} 2025/09/17 05:17:34 DEBUG : chunked-10Mi-20Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/09/17 05:17:34 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/09/17 05:17:34 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/09/17 05:17:35 DEBUG : pacer: Reducing sleep to 500ms 2025/09/17 05:17:35 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000415c20), retryAfter:1000000000} 2025/09/17 05:17:35 DEBUG : chunked-10Mi-20Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/09/17 05:17:35 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/09/17 05:17:35 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/09/17 05:17:35 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 1 length 10485760 2025/09/17 05:17:36 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 0 length 10485760 2025/09/17 05:17:38 DEBUG : pacer: Reducing sleep to 500ms 2025/09/17 05:17:38 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00049ba40), retryAfter:1000000000} 2025/09/17 05:17:38 DEBUG : chunked-10Mi-20Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/09/17 05:17:38 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/09/17 05:17:38 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/09/17 05:17:39 DEBUG : pacer: Reducing sleep to 500ms 2025/09/17 05:17:39 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0002201e0), retryAfter:1000000000} 2025/09/17 05:17:39 DEBUG : chunked-10Mi-20Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/09/17 05:17:39 DEBUG : pacer: low level retry 6/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/09/17 05:17:39 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/09/17 05:17:39 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 1 length 10485760 2025/09/17 05:17:40 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 0 length 10485760 2025/09/17 05:17:42 DEBUG : pacer: Reducing sleep to 500ms 2025/09/17 05:17:42 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0002211d0), retryAfter:1000000000} 2025/09/17 05:17:42 DEBUG : chunked-10Mi-20Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/09/17 05:17:42 DEBUG : pacer: low level retry 6/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/09/17 05:17:42 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/09/17 05:17:43 DEBUG : pacer: Reducing sleep to 500ms 2025/09/17 05:17:43 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 1 length 10485760 2025/09/17 05:17:43 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000172ed0), retryAfter:1000000000} 2025/09/17 05:17:43 DEBUG : chunked-10Mi-20Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/09/17 05:17:43 DEBUG : pacer: low level retry 7/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/09/17 05:17:43 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/09/17 05:17:44 DEBUG : pacer: Reducing sleep to 500ms 2025/09/17 05:17:44 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 0 length 10485760 2025/09/17 05:17:45 DEBUG : pacer: Reducing sleep to 250ms 2025/09/17 05:17:46 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0000bcc90), retryAfter:1000000000} 2025/09/17 05:17:46 DEBUG : chunked-10Mi-20Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/09/17 05:17:46 DEBUG : pacer: low level retry 8/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/09/17 05:17:46 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/09/17 05:17:46 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 0 length 10485760 2025/09/17 05:17:46 DEBUG : pacer: Reducing sleep to 500ms 2025/09/17 05:17:46 DEBUG : chunked-10Mi-20Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:17:47 DEBUG : pacer: Reducing sleep to 250ms 2025/09/17 05:17:48 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00034d590), retryAfter:1000000000} 2025/09/17 05:17:48 DEBUG : chunked-10Mi-20Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/09/17 05:17:48 DEBUG : pacer: low level retry 9/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/09/17 05:17:48 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/09/17 05:17:48 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 0 length 10485760 2025/09/17 05:17:48 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00034dbc0), retryAfter:1000000000} 2025/09/17 05:17:48 DEBUG : chunked-10Mi-20Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/09/17 05:17:48 DEBUG : pacer: low level retry 10/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/09/17 05:17:48 DEBUG : chunked-10Mi-20Mi-streamed.bin: Error sending chunk 0: no tomes available (503 service_unavailable): trying again in 1s 2025/09/17 05:17:48 DEBUG : chunked-10Mi-20Mi-streamed.bin: Cancelling large file upload 2025/09/17 05:17:49 DEBUG : pacer: Reducing sleep to 500ms fstests.go:143: PutLarge error: no tomes available (503 service_unavailable): trying again in 1s - low level retry 1/10 2025/09/17 05:17:51 DEBUG : chunked-10Mi-20Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:17:51 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/09/17 05:17:52 DEBUG : pacer: Reducing sleep to 250ms 2025/09/17 05:17:52 DEBUG : chunked-10Mi-20Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f219e045569a660e0_d20250917_m051751_c001_v0001134_t0054_u01758086271927") 2025/09/17 05:17:52 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 0 length 10485760 2025/09/17 05:17:52 DEBUG : chunked-10Mi-20Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/09/17 05:17:52 DEBUG : pacer: Reducing sleep to 125ms 2025/09/17 05:17:52 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 1 length 10485760 2025/09/17 05:17:53 DEBUG : pacer: Reducing sleep to 62.5ms 2025/09/17 05:17:55 DEBUG : pacer: Reducing sleep to 31.25ms 2025/09/17 05:17:55 DEBUG : chunked-10Mi-20Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:17:56 DEBUG : pacer: Reducing sleep to 15.625ms 2025/09/17 05:17:56 DEBUG : chunked-10Mi-20Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:17:56 DEBUG : chunked-10Mi-20Mi-streamed.bin: Finishing large file upload with 2 parts 2025/09/17 05:17:57 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521 2025/09/17 05:18:02 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/20.000Mi 2025/09/17 05:18:02 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 0 length 10485760 2025/09/17 05:18:02 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 1 size 10Mi offset 10Mi/20.000Mi 2025/09/17 05:18:02 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 1 length 10485760 2025/09/17 05:18:02 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 20Mi/20.000Mi 2025/09/17 05:18:02 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 2 length 1 2025/09/17 05:18:03 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 2 2025/09/17 05:18:04 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 0 2025/09/17 05:18:05 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 1 2025/09/17 05:18:05 DEBUG : chunked-10Mi-20.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521/Streamed 2025/09/17 05:18:09 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: File is big enough for chunked streaming 2025/09/17 05:18:09 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/09/17 05:18:09 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Starting streaming of large file (id "4_z8fb2fd23106d5b6b9f980e1b_f219e045569a66431_d20250917_m051809_c001_v0001131_t0019_u01758086289291") 2025/09/17 05:18:09 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 0 length 10485760 2025/09/17 05:18:09 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/09/17 05:18:09 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 2 length 1 2025/09/17 05:18:09 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 1 length 10485760 2025/09/17 05:18:10 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 2 2025/09/17 05:18:12 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 0 2025/09/17 05:18:13 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 1 2025/09/17 05:18:13 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/09/17 05:18:24 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_z8fb2fd23106d5b6b9f980e1b_f219e045569a66523_d20250917_m051824_c001_v0001171_t0028_u01758086304311") 2025/09/17 05:18:24 DEBUG : chunked-copy.copy: Copying chunk 1 length 1 2025/09/17 05:18:24 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/09/17 05:18:24 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/09/17 05:18:24 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/09/17 05:18:24 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485759 2025/09/17 05:18:28 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10.000Mi 2025/09/17 05:18:28 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/09/17 05:18:28 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4.000Mi offset 6Mi/10.000Mi 2025/09/17 05:18:28 DEBUG : chunked-copy: Sending chunk 1 length 4194303 2025/09/17 05:18:30 DEBUG : chunked-copy: Done sending chunk 0 2025/09/17 05:18:30 DEBUG : chunked-copy: Done sending chunk 1 2025/09/17 05:18:30 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2025/09/17 05:18:31 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_z8fb2fd23106d5b6b9f980e1b_f207ae4c2edf00a46_d20250917_m051831_c001_v0001171_t0050_u01758086311661") 2025/09/17 05:18:31 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242879 2025/09/17 05:18:31 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/09/17 05:18:32 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/09/17 05:18:32 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/09/17 05:18:32 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485760 2025/09/17 05:18:37 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10Mi 2025/09/17 05:18:37 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/09/17 05:18:37 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4Mi offset 6Mi/10Mi 2025/09/17 05:18:37 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/09/17 05:18:40 DEBUG : chunked-copy: Done sending chunk 1 2025/09/17 05:18:49 DEBUG : chunked-copy: Done sending chunk 0 2025/09/17 05:18:49 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2025/09/17 05:18:50 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_z8fb2fd23106d5b6b9f980e1b_f217c3ec94f2d47a1_d20250917_m051850_c001_v0001182_t0036_u01758086330904") 2025/09/17 05:18:50 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242880 2025/09/17 05:18:51 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/09/17 05:18:51 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/09/17 05:18:51 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/09/17 05:18:51 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485761 2025/09/17 05:18:55 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10.000Mi 2025/09/17 05:18:55 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/09/17 05:18:55 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4.000Mi offset 6Mi/10.000Mi 2025/09/17 05:18:55 DEBUG : chunked-copy: Sending chunk 1 length 4194305 2025/09/17 05:18:57 DEBUG : chunked-copy: Done sending chunk 0 2025/09/17 05:18:57 DEBUG : chunked-copy: Done sending chunk 1 2025/09/17 05:18:57 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2025/09/17 05:18:58 DEBUG : chunked-copy.copy: Starting copy of large file in 3 chunks (id "4_z8fb2fd23106d5b6b9f980e1b_f208302b639b04038_d20250917_m051858_c001_v0001183_t0030_u01758086338037") 2025/09/17 05:18:58 DEBUG : chunked-copy.copy: Copying chunk 2 length 1 2025/09/17 05:18:58 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/09/17 05:18:58 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242880 2025/09/17 05:18:58 DEBUG : chunked-copy.copy: Done copying chunk 2 2025/09/17 05:18:58 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/09/17 05:18:58 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/09/17 05:18:58 DEBUG : chunked-copy.copy: Finishing large file copy with 3 parts === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2025/09/17 05:19:00 DEBUG : unknown-size-put.txt: File has 100 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2025/09/17 05:19:01 DEBUG : unknown-size-update.txt: File has 200 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsRootCollapse 2025/09/17 05:19:01 DEBUG : Creating backend with remote "TestB2:rclone-test-kasowaj0noza/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/09/17 05:19:03 INFO : B2 bucket rclone-test-kasowaj0noza: cleaning bucket "rclone-test-kasowaj0noza" of all files 2025/09/17 05:19:03 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f419e045569a65e45_d20250917_m051712_c001_v7007000_t0000_u01758086232875") 2025/09/17 05:19:03 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f219e045569a65e08_d20250917_m051707_c001_v0001165_t0017_u01758086227921") 2025/09/17 05:19:03 DEBUG : chunked-10Mi-10.000Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f419e045569a65dff_d20250917_m051707_c001_v7007000_t0000_u01758086227289") 2025/09/17 05:19:03 DEBUG : chunked-10Mi-10.000Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f21854ab3ff756891_d20250917_m051659_c001_v0001098_t0011_u01758086219922") 2025/09/17 05:19:03 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f419e045569a66495_d20250917_m051817_c001_v7007000_t0000_u01758086297864") 2025/09/17 05:19:03 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f219e045569a66431_d20250917_m051809_c001_v0001131_t0019_u01758086289291") 2025/09/17 05:19:03 DEBUG : chunked-10Mi-20.000Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f419e045569a6642c_d20250917_m051808_c001_v7007000_t0000_u01758086288701") 2025/09/17 05:19:03 DEBUG : chunked-10Mi-20.000Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f219e045569a66160_d20250917_m051801_c001_v0001184_t0004_u01758086281500") 2025/09/17 05:19:03 DEBUG : chunked-10Mi-20Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f419e045569a6615b_d20250917_m051800_c001_v7007000_t0000_u01758086280430") 2025/09/17 05:19:03 DEBUG : chunked-10Mi-20Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f219e045569a660e0_d20250917_m051751_c001_v0001134_t0054_u01758086271927") 2025/09/17 05:19:03 DEBUG : chunked-10Mi-20Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f419e045569a65e8f_d20250917_m051719_c001_v7007000_t0000_u01758086239231") 2025/09/17 05:19:03 DEBUG : chunked-10Mi-20Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f219e045569a65e48_d20250917_m051713_c001_v0001113_t0016_u01758086233142") 2025/09/17 05:19:04 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f40056f63f8bd7885_d20250917_m051532_c001_v7007000_t0000_u01758086132564") 2025/09/17 05:19:04 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f205a05ac630796bb_d20250917_m051528_c001_v0001171_t0017_u01758086128573") 2025/09/17 05:19:04 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f40056f63f8bd787b_d20250917_m051523_c001_v7007000_t0000_u01758086123747") 2025/09/17 05:19:04 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f20056f63f8bd7862_d20250917_m051519_c001_v0001183_t0005_u01758086119861") 2025/09/17 05:19:04 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f405a05ac630796ba_d20250917_m051528_c001_v7007000_t0000_u01758086128195") 2025/09/17 05:19:04 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f20056f63f8bd787c_d20250917_m051523_c001_v0001041_t0054_u01758086123930") 2025/09/17 05:19:04 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f40056f63f8bd785e_d20250917_m051519_c001_v7007000_t0000_u01758086119451") 2025/09/17 05:19:04 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f207317f3aeb49409_d20250917_m051515_c001_v0001029_t0020_u01758086115128") 2025/09/17 05:19:04 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f407317f3aeb49408_d20250917_m051514_c001_v7007000_t0000_u01758086114943") 2025/09/17 05:19:04 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f20056f63f8bd782c_d20250917_m051511_c001_v0001180_t0020_u01758086111680") 2025/09/17 05:19:04 DEBUG : chunked-5.054Mi-5.054Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f40056f63f8bd7824_d20250917_m051511_c001_v7007000_t0000_u01758086111278") 2025/09/17 05:19:04 DEBUG : chunked-5.054Mi-5.054Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f207317f3aeb49400_d20250917_m051507_c001_v0001150_t0048_u01758086107629") 2025/09/17 05:19:04 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f404f355581095233_d20250917_m051602_c001_v7007000_t0000_u01758086162982") 2025/09/17 05:19:04 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f218a9cce0273f9ad_d20250917_m051558_c001_v0001180_t0056_u01758086158938") 2025/09/17 05:19:04 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f40056f63f8bd7901_d20250917_m051553_c001_v7007000_t0000_u01758086153328") 2025/09/17 05:19:04 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f20056f63f8bd78f2_d20250917_m051549_c001_v0001157_t0015_u01758086149264") 2025/09/17 05:19:04 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f418a9cce0273f9ac_d20250917_m051558_c001_v7007000_t0000_u01758086158529") 2025/09/17 05:19:04 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f20056f63f8bd7902_d20250917_m051553_c001_v0001144_t0003_u01758086153513") 2025/09/17 05:19:04 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f40056f63f8bd78f1_d20250917_m051548_c001_v7007000_t0000_u01758086148852") 2025/09/17 05:19:04 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f20056f63f8bd78da_d20250917_m051544_c001_v0001180_t0039_u01758086144781") 2025/09/17 05:19:05 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f40056f63f8bd78d7_d20250917_m051544_c001_v7007000_t0000_u01758086144597") 2025/09/17 05:19:05 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f205a05ac630796c3_d20250917_m051536_c001_v0001179_t0019_u01758086136815") 2025/09/17 05:19:05 DEBUG : chunked-5.055Mi-5.055Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f405a05ac630796c2_d20250917_m051536_c001_v7007000_t0000_u01758086136431") 2025/09/17 05:19:05 DEBUG : chunked-5.055Mi-5.055Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f20056f63f8bd7886_d20250917_m051532_c001_v0001090_t0043_u01758086132747") 2025/09/17 05:19:05 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f407317f3aeb493ff_d20250917_m051507_c001_v7007000_t0000_u01758086107444") 2025/09/17 05:19:05 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f20056f63f8bd77b0_d20250917_m051503_c001_v0001136_t0057_u01758086103427") 2025/09/17 05:19:05 DEBUG : chunked-5Mi-10.000Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f40056f63f8bd77a9_d20250917_m051503_c001_v7007000_t0000_u01758086103039") 2025/09/17 05:19:05 DEBUG : chunked-5Mi-10.000Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f207317f3aeb493fd_d20250917_m051458_c001_v0001179_t0046_u01758086098872") 2025/09/17 05:19:05 DEBUG : chunked-5Mi-10Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f407317f3aeb493fc_d20250917_m051458_c001_v7007000_t0000_u01758086098687") 2025/09/17 05:19:05 DEBUG : chunked-5Mi-10Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f207317f3aeb493fb_d20250917_m051454_c001_v0001179_t0005_u01758086094579") 2025/09/17 05:19:05 DEBUG : chunked-5Mi-10Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f407317f3aeb493fa_d20250917_m051454_c001_v7007000_t0000_u01758086094103") 2025/09/17 05:19:05 DEBUG : chunked-5Mi-10Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f207317f3aeb493f9_d20250917_m051449_c001_v0001161_t0053_u01758086089883") 2025/09/17 05:19:05 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f407317f3aeb493f8_d20250917_m051449_c001_v7007000_t0000_u01758086089697") 2025/09/17 05:19:05 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f223fb2df74cac57a_d20250917_m051445_c001_v0001144_t0036_u01758086085965") 2025/09/17 05:19:05 DEBUG : chunked-5Mi-5.000Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f423fb2df74cac575_d20250917_m051445_c001_v7007000_t0000_u01758086085548") 2025/09/17 05:19:05 DEBUG : chunked-5Mi-5.000Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f208302b639b0395f_d20250917_m051440_c001_v0001180_t0047_u01758086080475") 2025/09/17 05:19:05 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f41854ab3ff756826_d20250917_m051630_c001_v7007000_t0000_u01758086190898") 2025/09/17 05:19:05 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f215e55e7bffc072b_d20250917_m051626_c001_v0001165_t0005_u01758086186241") 2025/09/17 05:19:05 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f404f3555810952ae_d20250917_m051620_c001_v7007000_t0000_u01758086180649") 2025/09/17 05:19:05 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f204f3555810952a2_d20250917_m051616_c001_v0001177_t0040_u01758086176489") 2025/09/17 05:19:05 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f415e55e7bffc072a_d20250917_m051625_c001_v7007000_t0000_u01758086185755") 2025/09/17 05:19:06 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f204f3555810952af_d20250917_m051620_c001_v0001179_t0011_u01758086180833") 2025/09/17 05:19:06 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f404f355581095299_d20250917_m051616_c001_v7007000_t0000_u01758086176015") 2025/09/17 05:19:06 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f204f35558109524d_d20250917_m051611_c001_v0001184_t0038_u01758086171510") 2025/09/17 05:19:06 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f404f35558109524a_d20250917_m051611_c001_v7007000_t0000_u01758086171326") 2025/09/17 05:19:06 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f218a9cce0273f9be_d20250917_m051607_c001_v0001183_t0036_u01758086167684") 2025/09/17 05:19:06 DEBUG : chunked-6.667Mi-6.667Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f418a9cce0273f9bd_d20250917_m051607_c001_v7007000_t0000_u01758086167249") 2025/09/17 05:19:06 DEBUG : chunked-6.667Mi-6.667Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f204f355581095234_d20250917_m051603_c001_v0001092_t0007_u01758086163167") 2025/09/17 05:19:06 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f41854ab3ff756890_d20250917_m051659_c001_v7007000_t0000_u01758086219738") 2025/09/17 05:19:06 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f2163d92aba0c7fed_d20250917_m051654_c001_v0001171_t0053_u01758086214941") 2025/09/17 05:19:06 DEBUG : chunked-8Mi-16.000Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f4163d92aba0c7fec_d20250917_m051654_c001_v7007000_t0000_u01758086214424") 2025/09/17 05:19:06 DEBUG : chunked-8Mi-16.000Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f21854ab3ff75685b_d20250917_m051649_c001_v0001181_t0037_u01758086209728") 2025/09/17 05:19:06 DEBUG : chunked-8Mi-16Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f41854ab3ff756859_d20250917_m051649_c001_v7007000_t0000_u01758086209544") 2025/09/17 05:19:06 DEBUG : chunked-8Mi-16Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f21854ab3ff75684f_d20250917_m051644_c001_v0001109_t0031_u01758086204963") 2025/09/17 05:19:06 DEBUG : chunked-8Mi-16Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f41854ab3ff75684e_d20250917_m051644_c001_v7007000_t0000_u01758086204456") 2025/09/17 05:19:06 DEBUG : chunked-8Mi-16Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f21854ab3ff75684c_d20250917_m051639_c001_v0001183_t0001_u01758086199784") 2025/09/17 05:19:06 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f41854ab3ff75684b_d20250917_m051639_c001_v7007000_t0000_u01758086199599") 2025/09/17 05:19:06 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f215e55e7bffc072e_d20250917_m051635_c001_v0001153_t0013_u01758086195654") 2025/09/17 05:19:06 DEBUG : chunked-8Mi-8.000Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f415e55e7bffc072d_d20250917_m051635_c001_v7007000_t0000_u01758086195149") 2025/09/17 05:19:06 DEBUG : chunked-8Mi-8.000Mi.bin: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f21854ab3ff756827_d20250917_m051631_c001_v0001181_t0009_u01758086191083") 2025/09/17 05:19:06 DEBUG : chunked-copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b0403f_d20250917_m051900_c001_v7007000_t0000_u01758086340046") 2025/09/17 05:19:06 DEBUG : chunked-copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f217c3ec94f2d47c9_d20250917_m051854_c001_v0001182_t0027_u01758086334739") 2025/09/17 05:19:07 DEBUG : chunked-copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f417c3ec94f2d47c0_d20250917_m051854_c001_v7007000_t0000_u01758086334094") 2025/09/17 05:19:07 DEBUG : chunked-copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f219e045569a66619_d20250917_m051837_c001_v0001093_t0054_u01758086317295") 2025/09/17 05:19:07 DEBUG : chunked-copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f419e045569a66612_d20250917_m051836_c001_v7007000_t0000_u01758086316370") 2025/09/17 05:19:07 DEBUG : chunked-copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f219e045569a66568_d20250917_m051827_c001_v0001165_t0023_u01758086307851") 2025/09/17 05:19:07 DEBUG : chunked-copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f419e045569a6654f_d20250917_m051827_c001_v7007000_t0000_u01758086307234") 2025/09/17 05:19:07 DEBUG : chunked-copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f119e5e84f30d5e32_d20250917_m051822_c001_v0001184_t0042_u01758086302654") 2025/09/17 05:19:07 DEBUG : chunked-copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f419e045569a664f1_d20250917_m051822_c001_v7007000_t0000_u01758086302199") 2025/09/17 05:19:07 DEBUG : chunked-copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f119e5e84f30d5dfd_d20250917_m051818_c001_v0001184_t0052_u01758086298703") 2025/09/17 05:19:07 DEBUG : chunked-copy.copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b0403e_d20250917_m051859_c001_v7007000_t0000_u01758086339864") 2025/09/17 05:19:07 DEBUG : chunked-copy.copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f208302b639b04038_d20250917_m051858_c001_v0001183_t0030_u01758086338037") 2025/09/17 05:19:07 DEBUG : chunked-copy.copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f417c3ec94f2d47bf_d20250917_m051853_c001_v7007000_t0000_u01758086333913") 2025/09/17 05:19:07 DEBUG : chunked-copy.copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f217c3ec94f2d47a1_d20250917_m051850_c001_v0001182_t0036_u01758086330904") 2025/09/17 05:19:07 DEBUG : chunked-copy.copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f419e045569a665ff_d20250917_m051836_c001_v7007000_t0000_u01758086316056") 2025/09/17 05:19:07 DEBUG : chunked-copy.copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f207ae4c2edf00a46_d20250917_m051831_c001_v0001171_t0050_u01758086311661") 2025/09/17 05:19:07 DEBUG : chunked-copy.copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f419e045569a6654d_d20250917_m051826_c001_v7007000_t0000_u01758086306034") 2025/09/17 05:19:07 DEBUG : chunked-copy.copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f219e045569a66523_d20250917_m051824_c001_v0001171_t0028_u01758086304311") 2025/09/17 05:19:07 DEBUG : chunked-copy.copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f419e045569a664ee_d20250917_m051822_c001_v7007000_t0000_u01758086302000") 2025/09/17 05:19:07 DEBUG : chunked-copy.copy: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1037fd55025cb844_d20250917_m051820_c001_v0001171_t0047_u01758086300585") 2025/09/17 05:19:07 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f1118d4737605f70c_d20250917_m051320_c001_v0001177_t0045_u01758086000593") 2025/09/17 05:19:07 DEBUG : unknown-size-put.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b04047_d20250917_m051900_c001_v7007000_t0000_u01758086340783") 2025/09/17 05:19:08 DEBUG : unknown-size-put.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f119e5e84f30d5f88_d20250917_m051900_c001_v0001184_t0030_u01758086340590") 2025/09/17 05:19:08 DEBUG : unknown-size-update.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b04052_d20250917_m051901_c001_v7007000_t0000_u01758086341765") 2025/09/17 05:19:08 DEBUG : unknown-size-update.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f119e5e84f30d5f96_d20250917_m051901_c001_v0001184_t0043_u01758086341545") 2025/09/17 05:19:08 DEBUG : unknown-size-update.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f119e5e84f30d5f8c_d20250917_m051900_c001_v0001184_t0044_u01758086340969") 2025/09/17 05:19:08 DEBUG : versions/test-versions.txt: Deleting (id "4_z8fb2fd23106d5b6b9f980e1b_f408302b639b0393c_d20250917_m051436_c001_v7007000_t0000_u01758086076704") 2025/09/17 05:19:09 INFO : B2 bucket rclone-test-kasowaj0noza: cleaning bucket "rclone-test-kasowaj0noza" of all files === RUN TestIntegration/FsShutdown fstests.go:2831: Shutdown method not supported --- PASS: TestIntegration (383.32s) --- SKIP: TestIntegration/FsCheckWrap (0.00s) --- PASS: TestIntegration/FsCommand (0.00s) --- PASS: TestIntegration/FsRmdirNotFound (0.56s) --- PASS: TestIntegration/FsString (0.00s) --- PASS: TestIntegration/FsName (0.00s) --- PASS: TestIntegration/FsRoot (0.00s) --- PASS: TestIntegration/FsRmdirEmpty (0.48s) --- PASS: TestIntegration/FsMkdir (381.02s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (0.55s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.18s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.18s) --- PASS: TestIntegration/FsMkdir/FsListRDirEmpty (0.18s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.18s) --- PASS: TestIntegration/FsMkdir/FsListRDirNotFound (0.18s) --- PASS: TestIntegration/FsMkdir/FsEncoding (19.41s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (1.89s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (0.95s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (0.98s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (1.09s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (0.96s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (0.93s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (1.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (0.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (0.95s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (0.96s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (0.95s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (0.97s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (0.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (0.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (0.99s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (0.95s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (0.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (0.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (0.95s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.36s) --- PASS: TestIntegration/FsMkdir/FsPutError (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (1.11s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- PASS: TestIntegration/FsMkdir/FsOpenChunkWriter (7.93s) --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (80.74s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (0.93s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.93s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (0.22s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (1.26s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (1.33s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.39s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.54s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (1.91s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (2.76s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir (4.51s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (2.41s) --- 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 (1.29s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (0.96s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.36s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (1.48s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (1.88s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (1.84s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (9.06s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (0.73s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (5.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (2.40s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/PublicLink (7.38s) --- 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.57s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (0.99s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (0.58s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (31.10s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata (6.83s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize (0.97s) --- 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 (5.85s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB/GzipEncoding (1.72s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB/GzipEncoding/NoDecompress (1.72s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions (20.69s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions (2.03s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/ReadCurrent (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/ReadVersion (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/NewObject (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/NewFs (1.30s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt (3.03s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before (1.13s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before/List (0.40s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before/NewObject (0.72s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne (0.82s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne/List (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne/NewObject (0.54s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete (0.63s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete/List (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete/NewObject (0.36s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo (0.45s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo/List (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo/NewObject (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup (4.41s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/DryRun (0.63s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/RealThing (3.78s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished (2.05s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished (2.05s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished/DryRun (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished/RealThing (0.75s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules (1.54s) --- 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.79s) --- PASS: TestIntegration/FsMkdir/FsPutChunked (217.59s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi (27.15s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881 (9.40s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881/Streamed (4.15s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760 (8.99s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760/Streamed (4.51s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761 (8.76s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761/Streamed (4.40s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi (25.12s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001 (7.50s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001/Streamed (3.66s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000 (8.81s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000/Streamed (4.29s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001 (8.82s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001/Streamed (4.37s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi (30.42s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054 (12.03s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054/Streamed (8.17s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106 (8.73s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106/Streamed (4.48s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107 (9.65s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107/Streamed (4.45s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi (27.91s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507 (8.34s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507/Streamed (4.07s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012 (9.32s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012/Streamed (4.63s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013 (10.25s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013/Streamed (5.13s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi (28.84s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609 (8.70s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609/Streamed (4.45s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216 (9.94s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216/Streamed (5.09s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217 (10.19s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217/Streamed (5.31s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi (78.15s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761 (13.20s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761/Streamed (5.61s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520 (48.38s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520/Streamed (41.19s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521 (16.58s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521/Streamed (9.19s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked (42.16s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/5242880 (4.31s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/5242881 (5.03s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485759 (9.14s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485760 (17.72s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485761 (5.96s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (1.72s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (0.74s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (0.98s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (1.28s) --- 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 383.368s "go test -v -timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 6m24.232041877s (try 1/5)