"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/11/16 05:09:41 DEBUG : Creating backend with remote "TestB2:rclone-test-qokokun7yoxu" 2025/11/16 05:09:41 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/11/16 05:09:46 INFO : dir/subdir: Making directory fstest.go:250: Filtering empty directory "dir" fstest.go:250: Filtering empty directory "dir/subdir" 2025/11/16 05:09:46 INFO : dir/subdir: Removing directory fstest.go:250: Filtering empty directory "dir" 2025/11/16 05:09:46 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/11/16 05:10:13 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/11/16 05:10:14 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 1 length 5242880 2025/11/16 05:10:18 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 1 2025/11/16 05:10:18 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 2 length 1048576 2025/11/16 05:10:19 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 2 2025/11/16 05:10:19 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 0 length 5242880 2025/11/16 05:10:21 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 0 2025/11/16 05:10:21 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/11/16 05:10:29 DEBUG : Creating backend with remote "TestB2:" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot 2025/11/16 05:10:30 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/11/16 05:10:35 INFO : B2 bucket rclone-test-qokokun7yoxu: cleaning bucket "rclone-test-qokokun7yoxu" of all files 2025/11/16 05:10:35 DEBUG : dirToPurge/fileToPurge.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb1e1_d20251116_m051034_c001_v0001136_t0045_u01763269834972") === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2025/11/16 05:10:36 DEBUG : Creating backend with remote "TestB2:rclone-test-qokokun7yoxu/dirToPurgeFromRoot" 2025/11/16 05:10:37 INFO : B2 bucket rclone-test-qokokun7yoxu path dirToPurgeFromRoot: cleaning bucket "rclone-test-qokokun7yoxu" of all files 2025/11/16 05:10:38 DEBUG : fileToPurgeFromRoot.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb1e9_d20251116_m051036_c001_v0001136_t0055_u01763269836200") === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2025/11/16 05:10:38 DEBUG : Creating backend with remote "TestB2:rclone-test-qokokun7yoxu/hello? sausage/êé" 2025/11/16 05:10:43 INFO : B2 bucket rclone-test-qokokun7yoxu path hello? sausage/êé: cleaning bucket "rclone-test-qokokun7yoxu" of all files 2025/11/16 05:10:43 DEBUG : a/b/c/d/e.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1114cbe78fa971bf_d20251116_m051041_c001_v0001150_t0053_u01763269841717") === 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/11/16 05:10:54 DEBUG : Creating backend with remote "TestB2:rclone-test-qokokun7yoxu/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2025/11/16 05:10:56 DEBUG : Creating backend with remote "TestB2:rclone-test-qokokun7yoxu/not found.txt" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1911: Opening root remote "TestB2:" path "rclone-test-qokokun7yoxu" from "TestB2:rclone-test-qokokun7yoxu" 2025/11/16 05:10:58 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/11/16 05:11:10 DEBUG : Creating backend with remote "TestB2:rclone-test-suvejav6xuga" 2025/11/16 05:11:13 DEBUG : B2 bucket rclone-test-suvejav6xuga: Purge remote 2025/11/16 05:11:13 INFO : B2 bucket rclone-test-suvejav6xuga: cleaning bucket "rclone-test-suvejav6xuga" of all files 2025/11/16 05:11:13 DEBUG : somefile: Deleting (id "4_z5f020d63103d8bbb9fa80e1b_f400cc63f443ec123_d20251116_m051112_c001_v0001042_t0009_u01763269872620") === 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/11/16 05:11:15 DEBUG : piped data.txt: File has 0 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2025/11/16 05:11:16 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/11/16 05:11:19 DEBUG : test-metadata: multipart upload: starting chunk 0 size 6Mi offset 0/6.106Mi 2025/11/16 05:11:19 DEBUG : test-metadata: Sending chunk 0 length 6291456 2025/11/16 05:11:19 DEBUG : test-metadata: multipart upload: starting chunk 1 size 108.659Ki offset 6Mi/6.106Mi 2025/11/16 05:11:19 DEBUG : test-metadata: Sending chunk 1 length 111267 2025/11/16 05:11:21 DEBUG : test-metadata: Done sending chunk 1 2025/11/16 05:11:21 DEBUG : test-metadata: Done sending chunk 0 2025/11/16 05:11:21 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/11/16 05:11:32 DEBUG : versions/test-versions.txt: Clearing upload URL because of error: Post "https://pod-000-1136-09.backblaze.com/b2api/v1/b2_upload_file/cfe2bd43101d8bbb9fa80e1b/c001_v0001136_t0002": EOF 2025/11/16 05:11:32 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1136-09.backblaze.com/b2api/v1/b2_upload_file/cfe2bd43101d8bbb9fa80e1b/c001_v0001136_t0002": EOF) 2025/11/16 05:11:32 DEBUG : pacer: Rate limited, increasing sleep to 20ms fstests.go:143: Put error: Post "https://pod-000-1136-09.backblaze.com/b2api/v1/b2_upload_file/cfe2bd43101d8bbb9fa80e1b/c001_v0001136_t0002": EOF - low level retry 1/10 2025/11/16 05:11:34 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-11-16-051126-877.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/11/16 05:11:36 DEBUG : oldPath = "TestB2:rclone-test-qokokun7yoxu/versions/test-versions-v2025-11-16-051126-877.txt" 2025/11/16 05:11:36 DEBUG : newPath = "TestB2,versions:rclone-test-qokokun7yoxu/versions/test-versions-v2025-11-16-051126-877.txt" 2025/11/16 05:11:36 DEBUG : Creating backend with remote "TestB2,versions:rclone-test-qokokun7yoxu/versions/test-versions-v2025-11-16-051126-877.txt" 2025/11/16 05:11:36 DEBUG : TestB2: detected overridden config - adding "{pO73u}" suffix to name 2025/11/16 05:11:37 DEBUG : fs cache: renaming child cache item "TestB2,versions:rclone-test-qokokun7yoxu/versions/test-versions-v2025-11-16-051126-877.txt" to be canonical for parent "TestB2{pO73u}:rclone-test-qokokun7yoxu/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/11/16 05:11:41 INFO : B2 bucket rclone-test-qokokun7yoxu: cleaning bucket "rclone-test-qokokun7yoxu" of all hidden files 2025/11/16 05:11:41 DEBUG : leading space/ leading space: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057f6_d20251116_m050954_c001_v7007000_t0000_u01763269794789") as it is a hide marker 2025/11/16 05:11:41 NOTICE: leading space/ leading space: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : leading space/ leading space: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb06f_d20251116_m050954_c001_v0001136_t0031_u01763269794108") 2025/11/16 05:11:41 NOTICE: leading space/ leading space: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057f5_d20251116_m050953_c001_v7007000_t0000_u01763269793745") as it is a hide marker 2025/11/16 05:11:41 NOTICE: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb063_d20251116_m050952_c001_v0001136_t0029_u01763269792998") 2025/11/16 05:11:41 NOTICE: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : .leading dot/.leading dot: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a05805_d20251116_m051001_c001_v7007000_t0000_u01763269801941") as it is a hide marker 2025/11/16 05:11:41 NOTICE: .leading dot/.leading dot: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : .leading dot/.leading dot: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0b5_d20251116_m051001_c001_v0001136_t0054_u01763269801251") 2025/11/16 05:11:41 NOTICE: .leading dot/.leading dot: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : created from root.txt: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4076efd2d5785e77_d20251116_m051106_c001_v7007000_t0000_u01763269866184") as it is a hide marker 2025/11/16 05:11:41 NOTICE: created from root.txt: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : created from root.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f103b70ad603c1cec_d20251116_m051105_c001_v0001172_t0026_u01763269865051") 2025/11/16 05:11:41 NOTICE: created from root.txt: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : file name.txt: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f40214feff7fc5aba_d20251116_m051115_c001_v7007000_t0000_u01763269875348") as it is a hide marker 2025/11/16 05:11:41 NOTICE: file name.txt: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : file name.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb276_d20251116_m051053_c001_v0001136_t0009_u01763269853956") 2025/11/16 05:11:41 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : file name.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f117b1151dc418293_d20251116_m051050_c001_v0001144_t0004_u01763269850019") 2025/11/16 05:11:41 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : file name.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb1a1_d20251116_m051026_c001_v0001136_t0048_u01763269826921") 2025/11/16 05:11:41 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : file name.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb18d_d20251116_m051025_c001_v0001136_t0029_u01763269825435") 2025/11/16 05:11:41 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb195_d20251116_m051026_c001_v0001136_t0041_u01763269826024") "upload" dated 2025-11-16 05:10:26.024 +0000 UTC (1m15.358050006s ago) 2025/11/16 05:11:41 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f41096641a42c4167_d20251116_m051047_c001_v7007000_t0000_u01763269847714") as it is a hide marker 2025/11/16 05:11:41 NOTICE: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f11353cab47ba770f_d20251116_m051047_c001_v0001041_t0013_u01763269847323") 2025/11/16 05:11:41 NOTICE: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f113b777f66d186dc_d20251116_m051046_c001_v0001177_t0038_u01763269846673") 2025/11/16 05:11:41 NOTICE: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : invalid utf-8/invalid utf-8: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a05819_d20251116_m051010_c001_v7007000_t0000_u01763269810479") as it is a hide marker 2025/11/16 05:11:41 NOTICE: invalid utf-8/invalid utf-8: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : invalid utf-8/invalid utf-8: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb10b_d20251116_m051009_c001_v0001136_t0015_u01763269809725") 2025/11/16 05:11:41 NOTICE: invalid utf-8/invalid utf-8: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : piped data.txt: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f40214feff7fc5ac4_d20251116_m051117_c001_v7007000_t0000_u01763269877160") as it is a hide marker 2025/11/16 05:11:41 NOTICE: piped data.txt: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : piped data.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb324_d20251116_m051116_c001_v0001136_t0046_u01763269876644") 2025/11/16 05:11:41 NOTICE: piped data.txt: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : piped data.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f40214feff7fc5ac0_d20251116_m051116_c001_v7007000_t0000_u01763269876451") 2025/11/16 05:11:41 NOTICE: piped data.txt: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : piped data.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f4095f3af0aefb31f_d20251116_m051116_c001_v0001136_t0002_u01763269876085") 2025/11/16 05:11:41 NOTICE: piped data.txt: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : test%46.txt/test%46.txt: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a0581b_d20251116_m051011_c001_v7007000_t0000_u01763269811812") as it is a hide marker 2025/11/16 05:11:41 NOTICE: test%46.txt/test%46.txt: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : test%46.txt/test%46.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb117_d20251116_m051010_c001_v0001136_t0012_u01763269810845") 2025/11/16 05:11:41 NOTICE: test%46.txt/test%46.txt: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : test-metadata: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f415744888f593a7e_d20251116_m051124_c001_v7007000_t0000_u01763269884328") as it is a hide marker 2025/11/16 05:11:41 NOTICE: test-metadata: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : test-metadata: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f20214feff7fc5ad7_d20251116_m051119_c001_v0001177_t0035_u01763269879271") 2025/11/16 05:11:41 NOTICE: test-metadata: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : test-metadata: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f40214feff7fc5acb_d20251116_m051118_c001_v7007000_t0000_u01763269878328") 2025/11/16 05:11:41 NOTICE: test-metadata: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : test-metadata: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb327_d20251116_m051117_c001_v0001136_t0055_u01763269877344") 2025/11/16 05:11:41 NOTICE: test-metadata: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : trailing CR␍/trailing CR␍: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a0580b_d20251116_m051004_c001_v7007000_t0000_u01763269804342") as it is a hide marker 2025/11/16 05:11:41 NOTICE: trailing CR␍/trailing CR␍: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : trailing CR␍/trailing CR␍: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0c7_d20251116_m051003_c001_v0001136_t0044_u01763269803389") 2025/11/16 05:11:41 NOTICE: trailing CR␍/trailing CR␍: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : trailing HT␉/trailing HT␉: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a05810_d20251116_m051006_c001_v7007000_t0000_u01763269806772") as it is a hide marker 2025/11/16 05:11:41 NOTICE: trailing HT␉/trailing HT␉: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : trailing HT␉/trailing HT␉: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0df_d20251116_m051005_c001_v0001136_t0011_u01763269805856") 2025/11/16 05:11:41 NOTICE: trailing HT␉/trailing HT␉: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : trailing LF␊/trailing LF␊: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a0580f_d20251116_m051005_c001_v7007000_t0000_u01763269805482") as it is a hide marker 2025/11/16 05:11:41 NOTICE: trailing LF␊/trailing LF␊: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : trailing LF␊/trailing LF␊: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0d3_d20251116_m051004_c001_v0001136_t0035_u01763269804705") 2025/11/16 05:11:41 NOTICE: trailing LF␊/trailing LF␊: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : trailing VT␋/trailing VT␋: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a05811_d20251116_m051008_c001_v7007000_t0000_u01763269808058") as it is a hide marker 2025/11/16 05:11:41 NOTICE: trailing VT␋/trailing VT␋: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : trailing VT␋/trailing VT␋: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0fa_d20251116_m051007_c001_v0001136_t0022_u01763269807146") 2025/11/16 05:11:41 NOTICE: trailing VT␋/trailing VT␋: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : trailing dot./trailing dot.: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a05816_d20251116_m051009_c001_v7007000_t0000_u01763269809279") as it is a hide marker 2025/11/16 05:11:41 NOTICE: trailing dot./trailing dot.: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : trailing dot./trailing dot.: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0fc_d20251116_m051008_c001_v0001136_t0049_u01763269808422") 2025/11/16 05:11:41 NOTICE: trailing dot./trailing dot.: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : trailing space /trailing space : Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a05806_d20251116_m051003_c001_v7007000_t0000_u01763269803024") as it is a hide marker 2025/11/16 05:11:41 NOTICE: trailing space /trailing space : Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : trailing space /trailing space : Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0bd_d20251116_m051002_c001_v0001136_t0036_u01763269802313") 2025/11/16 05:11:41 NOTICE: trailing space /trailing space : Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f1162fb57a9977510_d20251116_m051135_c001_v0001176_t0005_u01763269895012") "upload" dated 2025-11-16 05:11:35.012 +0000 UTC (6.3711717s ago) 2025/11/16 05:11:41 DEBUG : versions/test-versions.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f41389e634146f20b_d20251116_m051130_c001_v7007000_t0000_u01763269890211") 2025/11/16 05:11:41 NOTICE: versions/test-versions.txt: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : versions/test-versions.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb371_d20251116_m051126_c001_v0001136_t0030_u01763269886877") 2025/11/16 05:11:41 NOTICE: versions/test-versions.txt: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : writer-at-subdir/writer-at-file: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f41389e634146f0a8_d20251116_m051024_c001_v7007000_t0000_u01763269824852") as it is a hide marker 2025/11/16 05:11:41 NOTICE: writer-at-subdir/writer-at-file: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : writer-at-subdir/writer-at-file: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f2056998395a05821_d20251116_m051013_c001_v0001120_t0003_u01763269813990") 2025/11/16 05:11:41 NOTICE: writer-at-subdir/writer-at-file: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : zero-length-file: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a05820_d20251116_m051013_c001_v7007000_t0000_u01763269813680") as it is a hide marker 2025/11/16 05:11:41 NOTICE: zero-length-file: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : zero-length-file: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f4095f3af0aefb129_d20251116_m051013_c001_v0001136_t0002_u01763269813131") 2025/11/16 05:11:41 NOTICE: zero-length-file: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : ~leading tilde/~leading tilde: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057f8_d20251116_m050955_c001_v7007000_t0000_u01763269795960") as it is a hide marker 2025/11/16 05:11:41 NOTICE: ~leading tilde/~leading tilde: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : ~leading tilde/~leading tilde: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb07d_d20251116_m050955_c001_v0001136_t0031_u01763269795154") 2025/11/16 05:11:41 NOTICE: ~leading tilde/~leading tilde: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057f0_d20251116_m050949_c001_v7007000_t0000_u01763269789952") as it is a hide marker 2025/11/16 05:11:41 NOTICE: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb044_d20251116_m050948_c001_v0001136_t0048_u01763269788742") 2025/11/16 05:11:41 NOTICE: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : ␉leading HT/␉leading HT: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057fe_d20251116_m050959_c001_v7007000_t0000_u01763269799374") as it is a hide marker 2025/11/16 05:11:41 NOTICE: ␉leading HT/␉leading HT: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : ␉leading HT/␉leading HT: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0a3_d20251116_m050958_c001_v0001136_t0036_u01763269798620") 2025/11/16 05:11:41 NOTICE: ␉leading HT/␉leading HT: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : ␊leading LF/␊leading LF: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057fd_d20251116_m050958_c001_v7007000_t0000_u01763269798255") as it is a hide marker 2025/11/16 05:11:41 NOTICE: ␊leading LF/␊leading LF: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : ␊leading LF/␊leading LF: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb09d_d20251116_m050957_c001_v0001136_t0008_u01763269797530") 2025/11/16 05:11:41 NOTICE: ␊leading LF/␊leading LF: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : ␋leading VT/␋leading VT: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a05803_d20251116_m051000_c001_v7007000_t0000_u01763269800888") as it is a hide marker 2025/11/16 05:11:41 NOTICE: ␋leading VT/␋leading VT: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : ␋leading VT/␋leading VT: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0ad_d20251116_m050959_c001_v0001136_t0006_u01763269799737") 2025/11/16 05:11:41 NOTICE: ␋leading VT/␋leading VT: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : ␍leading CR/␍leading CR: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057fc_d20251116_m050957_c001_v7007000_t0000_u01763269797165") as it is a hide marker 2025/11/16 05:11:41 NOTICE: ␍leading CR/␍leading CR: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : ␍leading CR/␍leading CR: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb091_d20251116_m050956_c001_v0001136_t0029_u01763269796324") 2025/11/16 05:11:41 NOTICE: ␍leading CR/␍leading CR: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : ．/．: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057f1_d20251116_m050951_c001_v7007000_t0000_u01763269791338") as it is a hide marker 2025/11/16 05:11:41 NOTICE: ．/．: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : ．/．: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb04b_d20251116_m050950_c001_v0001136_t0025_u01763269790402") 2025/11/16 05:11:41 NOTICE: ．/．: Skipped delete as --dry-run is set 2025/11/16 05:11:41 DEBUG : ．．/．．: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057f4_d20251116_m050952_c001_v7007000_t0000_u01763269792635") as it is a hide marker 2025/11/16 05:11:41 NOTICE: ．．/．．: Skipped remove hide marker as --dry-run is set 2025/11/16 05:11:41 DEBUG : ．．/．．: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb055_d20251116_m050951_c001_v0001136_t0034_u01763269791716") 2025/11/16 05:11:41 NOTICE: ．．/．．: Skipped delete as --dry-run is set === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/RealThing 2025/11/16 05:11:41 INFO : B2 bucket rclone-test-qokokun7yoxu: cleaning bucket "rclone-test-qokokun7yoxu" of all hidden files 2025/11/16 05:11:41 DEBUG : leading space/ leading space: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057f6_d20251116_m050954_c001_v7007000_t0000_u01763269794789") as it is a hide marker 2025/11/16 05:11:41 DEBUG : leading space/ leading space: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb06f_d20251116_m050954_c001_v0001136_t0031_u01763269794108") 2025/11/16 05:11:41 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057f5_d20251116_m050953_c001_v7007000_t0000_u01763269793745") as it is a hide marker 2025/11/16 05:11:41 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb063_d20251116_m050952_c001_v0001136_t0029_u01763269792998") 2025/11/16 05:11:41 DEBUG : .leading dot/.leading dot: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a05805_d20251116_m051001_c001_v7007000_t0000_u01763269801941") as it is a hide marker 2025/11/16 05:11:41 DEBUG : .leading dot/.leading dot: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0b5_d20251116_m051001_c001_v0001136_t0054_u01763269801251") 2025/11/16 05:11:41 DEBUG : created from root.txt: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4076efd2d5785e77_d20251116_m051106_c001_v7007000_t0000_u01763269866184") as it is a hide marker 2025/11/16 05:11:41 DEBUG : created from root.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f103b70ad603c1cec_d20251116_m051105_c001_v0001172_t0026_u01763269865051") 2025/11/16 05:11:41 DEBUG : file name.txt: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f40214feff7fc5aba_d20251116_m051115_c001_v7007000_t0000_u01763269875348") as it is a hide marker 2025/11/16 05:11:42 DEBUG : file name.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb276_d20251116_m051053_c001_v0001136_t0009_u01763269853956") 2025/11/16 05:11:42 DEBUG : file name.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f117b1151dc418293_d20251116_m051050_c001_v0001144_t0004_u01763269850019") 2025/11/16 05:11:42 DEBUG : file name.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb1a1_d20251116_m051026_c001_v0001136_t0048_u01763269826921") 2025/11/16 05:11:42 DEBUG : file name.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb18d_d20251116_m051025_c001_v0001136_t0029_u01763269825435") 2025/11/16 05:11:42 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb195_d20251116_m051026_c001_v0001136_t0041_u01763269826024") "upload" dated 2025-11-16 05:10:26.024 +0000 UTC (1m16.38161121s ago) 2025/11/16 05:11:42 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f41096641a42c4167_d20251116_m051047_c001_v7007000_t0000_u01763269847714") as it is a hide marker 2025/11/16 05:11:42 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f11353cab47ba770f_d20251116_m051047_c001_v0001041_t0013_u01763269847323") 2025/11/16 05:11:42 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f113b777f66d186dc_d20251116_m051046_c001_v0001177_t0038_u01763269846673") 2025/11/16 05:11:42 DEBUG : invalid utf-8/invalid utf-8: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a05819_d20251116_m051010_c001_v7007000_t0000_u01763269810479") as it is a hide marker 2025/11/16 05:11:42 DEBUG : invalid utf-8/invalid utf-8: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb10b_d20251116_m051009_c001_v0001136_t0015_u01763269809725") 2025/11/16 05:11:42 DEBUG : piped data.txt: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f40214feff7fc5ac4_d20251116_m051117_c001_v7007000_t0000_u01763269877160") as it is a hide marker 2025/11/16 05:11:42 DEBUG : piped data.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb324_d20251116_m051116_c001_v0001136_t0046_u01763269876644") 2025/11/16 05:11:42 DEBUG : piped data.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f40214feff7fc5ac0_d20251116_m051116_c001_v7007000_t0000_u01763269876451") 2025/11/16 05:11:42 DEBUG : piped data.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f4095f3af0aefb31f_d20251116_m051116_c001_v0001136_t0002_u01763269876085") 2025/11/16 05:11:42 DEBUG : test%46.txt/test%46.txt: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a0581b_d20251116_m051011_c001_v7007000_t0000_u01763269811812") as it is a hide marker 2025/11/16 05:11:42 DEBUG : test%46.txt/test%46.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb117_d20251116_m051010_c001_v0001136_t0012_u01763269810845") 2025/11/16 05:11:42 DEBUG : test-metadata: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f415744888f593a7e_d20251116_m051124_c001_v7007000_t0000_u01763269884328") as it is a hide marker 2025/11/16 05:11:42 DEBUG : test-metadata: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f20214feff7fc5ad7_d20251116_m051119_c001_v0001177_t0035_u01763269879271") 2025/11/16 05:11:42 DEBUG : test-metadata: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f40214feff7fc5acb_d20251116_m051118_c001_v7007000_t0000_u01763269878328") 2025/11/16 05:11:43 DEBUG : test-metadata: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb327_d20251116_m051117_c001_v0001136_t0055_u01763269877344") 2025/11/16 05:11:43 DEBUG : trailing CR␍/trailing CR␍: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a0580b_d20251116_m051004_c001_v7007000_t0000_u01763269804342") as it is a hide marker 2025/11/16 05:11:43 DEBUG : trailing CR␍/trailing CR␍: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0c7_d20251116_m051003_c001_v0001136_t0044_u01763269803389") 2025/11/16 05:11:43 DEBUG : trailing HT␉/trailing HT␉: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a05810_d20251116_m051006_c001_v7007000_t0000_u01763269806772") as it is a hide marker 2025/11/16 05:11:43 DEBUG : trailing HT␉/trailing HT␉: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0df_d20251116_m051005_c001_v0001136_t0011_u01763269805856") 2025/11/16 05:11:43 DEBUG : trailing LF␊/trailing LF␊: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a0580f_d20251116_m051005_c001_v7007000_t0000_u01763269805482") as it is a hide marker 2025/11/16 05:11:43 DEBUG : trailing LF␊/trailing LF␊: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0d3_d20251116_m051004_c001_v0001136_t0035_u01763269804705") 2025/11/16 05:11:43 DEBUG : trailing VT␋/trailing VT␋: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a05811_d20251116_m051008_c001_v7007000_t0000_u01763269808058") as it is a hide marker 2025/11/16 05:11:43 DEBUG : trailing VT␋/trailing VT␋: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0fa_d20251116_m051007_c001_v0001136_t0022_u01763269807146") 2025/11/16 05:11:43 DEBUG : trailing dot./trailing dot.: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a05816_d20251116_m051009_c001_v7007000_t0000_u01763269809279") as it is a hide marker 2025/11/16 05:11:43 DEBUG : trailing dot./trailing dot.: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0fc_d20251116_m051008_c001_v0001136_t0049_u01763269808422") 2025/11/16 05:11:43 DEBUG : trailing space /trailing space : Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a05806_d20251116_m051003_c001_v7007000_t0000_u01763269803024") as it is a hide marker 2025/11/16 05:11:43 DEBUG : trailing space /trailing space : Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0bd_d20251116_m051002_c001_v0001136_t0036_u01763269802313") 2025/11/16 05:11:43 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f1162fb57a9977510_d20251116_m051135_c001_v0001176_t0005_u01763269895012") "upload" dated 2025-11-16 05:11:35.012 +0000 UTC (8.71746334s ago) 2025/11/16 05:11:43 DEBUG : versions/test-versions.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f41389e634146f20b_d20251116_m051130_c001_v7007000_t0000_u01763269890211") 2025/11/16 05:11:43 DEBUG : versions/test-versions.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb371_d20251116_m051126_c001_v0001136_t0030_u01763269886877") 2025/11/16 05:11:43 DEBUG : writer-at-subdir/writer-at-file: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f41389e634146f0a8_d20251116_m051024_c001_v7007000_t0000_u01763269824852") as it is a hide marker 2025/11/16 05:11:43 DEBUG : writer-at-subdir/writer-at-file: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f2056998395a05821_d20251116_m051013_c001_v0001120_t0003_u01763269813990") 2025/11/16 05:11:43 DEBUG : zero-length-file: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a05820_d20251116_m051013_c001_v7007000_t0000_u01763269813680") as it is a hide marker 2025/11/16 05:11:43 DEBUG : zero-length-file: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f4095f3af0aefb129_d20251116_m051013_c001_v0001136_t0002_u01763269813131") 2025/11/16 05:11:43 DEBUG : ~leading tilde/~leading tilde: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057f8_d20251116_m050955_c001_v7007000_t0000_u01763269795960") as it is a hide marker 2025/11/16 05:11:44 DEBUG : ~leading tilde/~leading tilde: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb07d_d20251116_m050955_c001_v0001136_t0031_u01763269795154") 2025/11/16 05:11:44 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057f0_d20251116_m050949_c001_v7007000_t0000_u01763269789952") as it is a hide marker 2025/11/16 05:11:44 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb044_d20251116_m050948_c001_v0001136_t0048_u01763269788742") 2025/11/16 05:11:44 DEBUG : ␉leading HT/␉leading HT: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057fe_d20251116_m050959_c001_v7007000_t0000_u01763269799374") as it is a hide marker 2025/11/16 05:11:44 DEBUG : ␉leading HT/␉leading HT: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0a3_d20251116_m050958_c001_v0001136_t0036_u01763269798620") 2025/11/16 05:11:44 DEBUG : ␊leading LF/␊leading LF: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057fd_d20251116_m050958_c001_v7007000_t0000_u01763269798255") as it is a hide marker 2025/11/16 05:11:44 DEBUG : ␊leading LF/␊leading LF: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb09d_d20251116_m050957_c001_v0001136_t0008_u01763269797530") 2025/11/16 05:11:44 DEBUG : ␋leading VT/␋leading VT: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a05803_d20251116_m051000_c001_v7007000_t0000_u01763269800888") as it is a hide marker 2025/11/16 05:11:44 DEBUG : ␋leading VT/␋leading VT: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb0ad_d20251116_m050959_c001_v0001136_t0006_u01763269799737") 2025/11/16 05:11:44 DEBUG : ␍leading CR/␍leading CR: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057fc_d20251116_m050957_c001_v7007000_t0000_u01763269797165") as it is a hide marker 2025/11/16 05:11:44 DEBUG : ␍leading CR/␍leading CR: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb091_d20251116_m050956_c001_v0001136_t0029_u01763269796324") 2025/11/16 05:11:44 DEBUG : ．/．: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057f1_d20251116_m050951_c001_v7007000_t0000_u01763269791338") as it is a hide marker 2025/11/16 05:11:44 DEBUG : ．/．: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb04b_d20251116_m050950_c001_v0001136_t0025_u01763269790402") 2025/11/16 05:11:44 DEBUG : ．．/．．: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f4056998395a057f4_d20251116_m050952_c001_v7007000_t0000_u01763269792635") as it is a hide marker 2025/11/16 05:11:44 DEBUG : ．．/．．: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb055_d20251116_m050951_c001_v0001136_t0034_u01763269791716") === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished 2025/11/16 05:11:45 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/11/16 05:11:46 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/11/16 05:11:46 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/11/16 05:11:46 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/11/16 05:11:46 INFO : B2 bucket rclone-test-qokokun7yoxu: cleaning bucket "rclone-test-qokokun7yoxu" of pending multipart uploads older than 0s 2025/11/16 05:11:46 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb195_d20251116_m051026_c001_v0001136_t0041_u01763269826024") "upload" dated 2025-11-16 05:10:26.024 +0000 UTC (1m20.96513944s ago) 2025/11/16 05:11:46 DEBUG : unfinished/unfinished-1: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f21389e634146f267_d20251116_m051145_c001_v0001157_t0002_u01763269905959") as it is a start marker (upload started at 2025-11-16 05:11:45.959 +0000 UTC) 2025/11/16 05:11:46 NOTICE: unfinished/unfinished-1: Skipped remove pending upload as --dry-run is set 2025/11/16 05:11:46 DEBUG : unfinished/unfinished-2: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f21389e634146f269_d20251116_m051146_c001_v0001037_t0056_u01763269906146") as it is a start marker (upload started at 2025-11-16 05:11:46.146 +0000 UTC) 2025/11/16 05:11:46 NOTICE: unfinished/unfinished-2: Skipped remove pending upload as --dry-run is set 2025/11/16 05:11:46 DEBUG : unfinished/unfinished-3: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f21389e634146f26a_d20251116_m051146_c001_v0001157_t0014_u01763269906338") as it is a start marker (upload started at 2025-11-16 05:11:46.338 +0000 UTC) 2025/11/16 05:11:46 NOTICE: unfinished/unfinished-3: Skipped remove pending upload as --dry-run is set 2025/11/16 05:11:46 DEBUG : unfinished/unfinished-4: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f21389e634146f26c_d20251116_m051146_c001_v0001136_t0046_u01763269906526") as it is a start marker (upload started at 2025-11-16 05:11:46.526 +0000 UTC) 2025/11/16 05:11:46 NOTICE: unfinished/unfinished-4: Skipped remove pending upload as --dry-run is set 2025/11/16 05:11:46 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f41389e634146f265_d20251116_m051145_c001_v7007000_t0000_u01763269905757") "hide" dated 2025-11-16 05:11:45.757 +0000 UTC (1.232414857s ago) 2025/11/16 05:11:46 DEBUG : versions/test-versions.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1162fb57a9977510_d20251116_m051135_c001_v0001176_t0005_u01763269895012") 2025/11/16 05:11:46 NOTICE: versions/test-versions.txt: Skipped delete as --dry-run is set === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished/RealThing 2025/11/16 05:11:47 INFO : B2 bucket rclone-test-qokokun7yoxu: cleaning bucket "rclone-test-qokokun7yoxu" of pending multipart uploads older than 0s 2025/11/16 05:11:47 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb195_d20251116_m051026_c001_v0001136_t0041_u01763269826024") "upload" dated 2025-11-16 05:10:26.024 +0000 UTC (1m21.332467336s ago) 2025/11/16 05:11:47 DEBUG : unfinished/unfinished-1: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f21389e634146f267_d20251116_m051145_c001_v0001157_t0002_u01763269905959") as it is a start marker (upload started at 2025-11-16 05:11:45.959 +0000 UTC) 2025/11/16 05:11:47 DEBUG : unfinished/unfinished-2: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f21389e634146f269_d20251116_m051146_c001_v0001037_t0056_u01763269906146") as it is a start marker (upload started at 2025-11-16 05:11:46.146 +0000 UTC) 2025/11/16 05:11:47 DEBUG : unfinished/unfinished-3: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f21389e634146f26a_d20251116_m051146_c001_v0001157_t0014_u01763269906338") as it is a start marker (upload started at 2025-11-16 05:11:46.338 +0000 UTC) 2025/11/16 05:11:47 DEBUG : unfinished/unfinished-4: Deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f21389e634146f26c_d20251116_m051146_c001_v0001136_t0046_u01763269906526") as it is a start marker (upload started at 2025-11-16 05:11:46.526 +0000 UTC) 2025/11/16 05:11:47 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_zcfe2bd43101d8bbb9fa80e1b_f41389e634146f265_d20251116_m051145_c001_v7007000_t0000_u01763269905757") "hide" dated 2025-11-16 05:11:45.757 +0000 UTC (1.599711203s ago) 2025/11/16 05:11:47 DEBUG : versions/test-versions.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1162fb57a9977510_d20251116_m051135_c001_v0001176_t0005_u01763269895012") === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/InitState === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/DryRun 2025/11/16 05:11:48 NOTICE: lifecycle: Skipped update lifecycle rules as --dry-run is set 2025/11/16 05:11:48 NOTICE: lifecycle: Skipped update lifecycle rules as --dry-run is set 2025/11/16 05:11:48 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/11/16 05:11:50 DEBUG : chunked-5Mi-5.000Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/5.000Mi 2025/11/16 05:11:50 DEBUG : chunked-5Mi-5.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 5Mi/5.000Mi 2025/11/16 05:11:50 DEBUG : chunked-5Mi-5.000Mi.bin: Sending chunk 0 length 5242880 2025/11/16 05:11:50 DEBUG : chunked-5Mi-5.000Mi.bin: Sending chunk 1 length 1 2025/11/16 05:11:51 DEBUG : chunked-5Mi-5.000Mi.bin: Done sending chunk 1 2025/11/16 05:11:52 DEBUG : chunked-5Mi-5.000Mi.bin: Done sending chunk 0 2025/11/16 05:11:52 DEBUG : chunked-5Mi-5.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881/Streamed 2025/11/16 05:11:55 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:11:55 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/11/16 05:11:55 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f216e43f41c25947e_d20251116_m051155_c001_v0001101_t0045_u01763269915751") 2025/11/16 05:11:55 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/16 05:11:55 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:11:55 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/11/16 05:11:56 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:11:58 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:11:58 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760 2025/11/16 05:11:59 DEBUG : chunked-5Mi-10Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/10Mi 2025/11/16 05:11:59 DEBUG : chunked-5Mi-10Mi.bin: Sending chunk 0 length 5242880 2025/11/16 05:11:59 DEBUG : chunked-5Mi-10Mi.bin: multipart upload: starting chunk 1 size 5Mi offset 5Mi/10Mi 2025/11/16 05:11:59 DEBUG : chunked-5Mi-10Mi.bin: Sending chunk 1 length 5242880 2025/11/16 05:12:02 DEBUG : chunked-5Mi-10Mi.bin: Done sending chunk 0 2025/11/16 05:12:02 DEBUG : chunked-5Mi-10Mi.bin: Done sending chunk 1 2025/11/16 05:12:02 DEBUG : chunked-5Mi-10Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760/Streamed 2025/11/16 05:12:04 DEBUG : chunked-5Mi-10Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:12:04 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/11/16 05:12:04 DEBUG : chunked-5Mi-10Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f216e43f41c25948f_d20251116_m051204_c001_v0001115_t0041_u01763269924108") 2025/11/16 05:12:04 DEBUG : chunked-5Mi-10Mi-streamed.bin: Sending chunk 0 length 5242880 2025/11/16 05:12:04 DEBUG : chunked-5Mi-10Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/11/16 05:12:04 DEBUG : chunked-5Mi-10Mi-streamed.bin: Sending chunk 1 length 5242880 2025/11/16 05:12:06 DEBUG : chunked-5Mi-10Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:12:07 DEBUG : chunked-5Mi-10Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:12:07 DEBUG : chunked-5Mi-10Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761 2025/11/16 05:12:08 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/10.000Mi 2025/11/16 05:12:08 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 0 length 5242880 2025/11/16 05:12:08 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 1 size 5Mi offset 5Mi/10.000Mi 2025/11/16 05:12:08 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 1 length 5242880 2025/11/16 05:12:08 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 10Mi/10.000Mi 2025/11/16 05:12:08 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 2 length 1 2025/11/16 05:12:09 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 2 2025/11/16 05:12:10 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 0 2025/11/16 05:12:11 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 1 2025/11/16 05:12:11 DEBUG : chunked-5Mi-10.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761/Streamed 2025/11/16 05:12:12 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:12:12 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/11/16 05:12:12 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f22028f44561f98bc_d20251116_m051212_c001_v0001131_t0036_u01763269932899") 2025/11/16 05:12:12 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/11/16 05:12:13 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/16 05:12:13 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 2 length 1 2025/11/16 05:12:13 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 1 length 5242880 2025/11/16 05:12:14 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 2 2025/11/16 05:12:15 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:12:16 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:12:16 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/11/16 05:12:17 DEBUG : chunked-5.054Mi-5.054Mi.bin: multipart upload: starting chunk 1 size 1 offset 5.054Mi/5.054Mi 2025/11/16 05:12:17 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 1 length 1 2025/11/16 05:12:17 DEBUG : chunked-5.054Mi-5.054Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/5.054Mi 2025/11/16 05:12:17 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 0 length 5300000 2025/11/16 05:12:18 DEBUG : chunked-5.054Mi-5.054Mi.bin: Error sending chunk 1 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0000dab10), retryAfter:1000000000} 2025/11/16 05:12:18 DEBUG : chunked-5.054Mi-5.054Mi.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/11/16 05:12:18 DEBUG : pacer: low level retry 1/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/11/16 05:12:18 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:12:18 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 1 length 1 2025/11/16 05:12:18 DEBUG : chunked-5.054Mi-5.054Mi.bin: Error sending chunk 0 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00031aab0), retryAfter:1000000000} 2025/11/16 05:12:18 DEBUG : chunked-5.054Mi-5.054Mi.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/11/16 05:12:18 DEBUG : pacer: low level retry 1/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/11/16 05:12:19 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:12:20 DEBUG : chunked-5.054Mi-5.054Mi.bin: Error sending chunk 1 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00031ac90), retryAfter:1000000000} 2025/11/16 05:12:20 DEBUG : chunked-5.054Mi-5.054Mi.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/11/16 05:12:20 DEBUG : pacer: low level retry 2/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/11/16 05:12:20 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:12:20 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 0 length 5300000 2025/11/16 05:12:21 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 1 length 1 2025/11/16 05:12:22 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:12:23 DEBUG : chunked-5.054Mi-5.054Mi.bin: Error sending chunk 0 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0012794d0), retryAfter:1000000000} 2025/11/16 05:12:23 DEBUG : chunked-5.054Mi-5.054Mi.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/11/16 05:12:23 DEBUG : pacer: low level retry 2/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/11/16 05:12:23 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:12:23 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:12:24 DEBUG : chunked-5.054Mi-5.054Mi.bin: Error sending chunk 1 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00023aa80), retryAfter:1000000000} 2025/11/16 05:12:24 DEBUG : chunked-5.054Mi-5.054Mi.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/11/16 05:12:24 DEBUG : pacer: low level retry 3/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/11/16 05:12:24 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:12:24 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 0 length 5300000 2025/11/16 05:12:25 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 1 length 1 2025/11/16 05:12:26 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:12:27 DEBUG : pacer: Reducing sleep to 250ms 2025/11/16 05:12:28 DEBUG : chunked-5.054Mi-5.054Mi.bin: Error sending chunk 1 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00028cc30), retryAfter:1000000000} 2025/11/16 05:12:28 DEBUG : chunked-5.054Mi-5.054Mi.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/11/16 05:12:28 DEBUG : pacer: low level retry 4/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/11/16 05:12:28 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:12:28 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 1 length 1 2025/11/16 05:12:28 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:12:28 DEBUG : chunked-5.054Mi-5.054Mi.bin: Done sending chunk 0 2025/11/16 05:12:29 DEBUG : pacer: Reducing sleep to 250ms 2025/11/16 05:12:30 DEBUG : pacer: Reducing sleep to 125ms 2025/11/16 05:12:30 DEBUG : chunked-5.054Mi-5.054Mi.bin: Done sending chunk 1 2025/11/16 05:12:30 DEBUG : chunked-5.054Mi-5.054Mi.bin: Finishing large file upload with 2 parts 2025/11/16 05:12:30 DEBUG : pacer: Reducing sleep to 62.5ms 2025/11/16 05:12:31 DEBUG : pacer: Reducing sleep to 31.25ms 2025/11/16 05:12:31 DEBUG : pacer: Reducing sleep to 15.625ms 2025/11/16 05:12:32 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001/Streamed 2025/11/16 05:12:33 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:12:33 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/11/16 05:12:33 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f22028f44561f9926_d20251116_m051233_c001_v0001029_t0027_u01763269953271") 2025/11/16 05:12:33 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/16 05:12:33 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:12:33 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Sending chunk 0 length 5300000 2025/11/16 05:12:34 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:12:36 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:12:36 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000 2025/11/16 05:12:37 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/10.109Mi 2025/11/16 05:12:37 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 0 length 5300000 2025/11/16 05:12:37 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.054Mi offset 5.054Mi/10.109Mi 2025/11/16 05:12:37 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 1 length 5300000 2025/11/16 05:12:39 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 0 2025/11/16 05:12:39 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 1 2025/11/16 05:12:39 DEBUG : chunked-5.054Mi-10.109Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000/Streamed 2025/11/16 05:12:41 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:12:41 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/11/16 05:12:41 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f22028f44561f9942_d20251116_m051241_c001_v0001040_t0042_u01763269961608") 2025/11/16 05:12:41 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300000 2025/11/16 05:12:41 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/11/16 05:12:41 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300000 2025/11/16 05:12:44 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:12:44 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:12:44 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001 2025/11/16 05:12:46 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/10.109Mi 2025/11/16 05:12:46 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 0 length 5300000 2025/11/16 05:12:46 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.054Mi offset 5.054Mi/10.109Mi 2025/11/16 05:12:46 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 1 length 5300000 2025/11/16 05:12:46 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 2 size 1 offset 10.109Mi/10.109Mi 2025/11/16 05:12:46 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 2 length 1 2025/11/16 05:12:47 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 2 2025/11/16 05:12:48 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 0 2025/11/16 05:12:48 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 1 2025/11/16 05:12:48 DEBUG : chunked-5.054Mi-10.109Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001/Streamed 2025/11/16 05:12:50 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:12:50 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/11/16 05:12:50 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f21955b45bf1828e9_d20251116_m051250_c001_v0001117_t0025_u01763269970791") 2025/11/16 05:12:50 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300000 2025/11/16 05:12:51 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/16 05:12:51 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 2 length 1 2025/11/16 05:12:51 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300000 2025/11/16 05:12:52 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 2 2025/11/16 05:12:53 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:12:53 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:12:53 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/11/16 05:12:55 DEBUG : chunked-5.055Mi-5.055Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/5.055Mi 2025/11/16 05:12:55 DEBUG : chunked-5.055Mi-5.055Mi.bin: Sending chunk 0 length 5300053 2025/11/16 05:12:55 DEBUG : chunked-5.055Mi-5.055Mi.bin: multipart upload: starting chunk 1 size 1 offset 5.055Mi/5.055Mi 2025/11/16 05:12:55 DEBUG : chunked-5.055Mi-5.055Mi.bin: Sending chunk 1 length 1 2025/11/16 05:12:56 DEBUG : chunked-5.055Mi-5.055Mi.bin: Done sending chunk 1 2025/11/16 05:12:57 DEBUG : chunked-5.055Mi-5.055Mi.bin: Done sending chunk 0 2025/11/16 05:12:57 DEBUG : chunked-5.055Mi-5.055Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054/Streamed 2025/11/16 05:12:59 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:12:59 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/11/16 05:12:59 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f21955b45bf182909_d20251116_m051259_c001_v0001157_t0021_u01763269979371") 2025/11/16 05:12:59 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/16 05:12:59 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:12:59 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:00 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc001278540), retryAfter:1000000000} 2025/11/16 05:13:00 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:00 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:00 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:00 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:13:00 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc001278690), retryAfter:1000000000} 2025/11/16 05:13:00 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:00 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:01 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:01 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc001278900), retryAfter:1000000000} 2025/11/16 05:13:01 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:01 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:01 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:02 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:03 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:13:04 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:04 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0011d1770), retryAfter:1000000000} 2025/11/16 05:13:04 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:04 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:04 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:05 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:06 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:06 DEBUG : pacer: Reducing sleep to 250ms 2025/11/16 05:13:06 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:13:06 DEBUG : pacer: Reducing sleep to 125ms 2025/11/16 05:13:07 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc001279740), retryAfter:1000000000} 2025/11/16 05:13:07 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:07 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:07 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:07 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:07 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00028cb70), retryAfter:1000000000} 2025/11/16 05:13:07 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:07 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:08 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:09 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:10 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00028d200), retryAfter:1000000000} 2025/11/16 05:13:10 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:10 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:10 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:10 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:11 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:13 DEBUG : pacer: Reducing sleep to 250ms 2025/11/16 05:13:13 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:13:13 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Finishing large file upload with 2 parts 2025/11/16 05:13:14 DEBUG : pacer: Reducing sleep to 125ms 2025/11/16 05:13:14 DEBUG : pacer: Reducing sleep to 62.5ms 2025/11/16 05:13:15 DEBUG : pacer: Reducing sleep to 31.25ms 2025/11/16 05:13:16 DEBUG : pacer: Reducing sleep to 15.625ms === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106 2025/11/16 05:13:16 DEBUG : pacer: Reducing sleep to 10ms 2025/11/16 05:13:17 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/10.109Mi 2025/11/16 05:13:17 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:17 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.055Mi offset 5.055Mi/10.109Mi 2025/11/16 05:13:17 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 1 length 5300053 2025/11/16 05:13:20 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 0 2025/11/16 05:13:20 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 1 2025/11/16 05:13:20 DEBUG : chunked-5.055Mi-10.109Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106/Streamed 2025/11/16 05:13:21 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:13:21 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/11/16 05:13:21 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f2034dd4fe8c494a6_d20251116_m051321_c001_v0001161_t0001_u01763270001888") 2025/11/16 05:13:21 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:22 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/11/16 05:13:22 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/11/16 05:13:22 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00038cba0), retryAfter:1000000000} 2025/11/16 05:13:22 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:22 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:22 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:22 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:22 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 1 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00016e180), retryAfter:1000000000} 2025/11/16 05:13:22 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/11/16 05:13:22 DEBUG : pacer: low level retry 1/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:23 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:24 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 0 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00016e450), retryAfter:1000000000} 2025/11/16 05:13:24 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/11/16 05:13:24 DEBUG : pacer: low level retry 2/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:24 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:24 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/11/16 05:13:25 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:26 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:27 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 1 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0003024b0), retryAfter:1000000000} 2025/11/16 05:13:27 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/11/16 05:13:27 DEBUG : pacer: low level retry 2/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:27 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:27 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:28 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00016f2c0), retryAfter:1000000000} 2025/11/16 05:13:28 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:28 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:28 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:28 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/11/16 05:13:29 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:30 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:31 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 1 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00016f7d0), retryAfter:1000000000} 2025/11/16 05:13:31 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/11/16 05:13:31 DEBUG : pacer: low level retry 3/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:31 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:31 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:32 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0003530b0), retryAfter:1000000000} 2025/11/16 05:13:32 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:32 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:32 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:32 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/11/16 05:13:33 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:34 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:35 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00036a7e0), retryAfter:1000000000} 2025/11/16 05:13:35 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:35 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:35 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:36 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:36 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00036b350), retryAfter:1000000000} 2025/11/16 05:13:36 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:36 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:36 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:36 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/11/16 05:13:37 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:38 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:39 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00031a210), retryAfter:1000000000} 2025/11/16 05:13:39 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:39 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:39 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:39 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:40 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 0 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0012f0930), retryAfter:1000000000} 2025/11/16 05:13:40 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/11/16 05:13:40 DEBUG : pacer: low level retry 6/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:40 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:40 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/11/16 05:13:41 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:42 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:43 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0012f0c00), retryAfter:1000000000} 2025/11/16 05:13:43 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:43 DEBUG : pacer: low level retry 6/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:43 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:43 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:44 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000133680), retryAfter:1000000000} 2025/11/16 05:13:44 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:44 DEBUG : pacer: low level retry 7/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:44 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:44 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/11/16 05:13:45 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:46 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:47 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc001278900), retryAfter:1000000000} 2025/11/16 05:13:47 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:47 DEBUG : pacer: low level retry 7/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:47 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:47 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:48 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0001788a0), retryAfter:1000000000} 2025/11/16 05:13:48 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:48 DEBUG : pacer: low level retry 8/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:48 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:48 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/11/16 05:13:49 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:50 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:51 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc001279410), retryAfter:1000000000} 2025/11/16 05:13:51 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:51 DEBUG : pacer: low level retry 8/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:51 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:51 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:52 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc001279680), retryAfter:1000000000} 2025/11/16 05:13:52 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:52 DEBUG : pacer: low level retry 9/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:52 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:52 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/11/16 05:13:53 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:13:54 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:55 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00028d5c0), retryAfter:1000000000} 2025/11/16 05:13:55 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:55 DEBUG : pacer: low level retry 9/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:55 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:55 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:13:56 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00023b0b0), retryAfter:1000000000} 2025/11/16 05:13:56 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:56 DEBUG : pacer: low level retry 10/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:13:56 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:13:56 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 0: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:56 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/11/16 05:13:57 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Error sending chunk 1: failed to get upload URL: Post "https://api001.backblazeb2.com/b2api/v1/b2_get_upload_part_url": no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:13:57 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Cancelling large file upload 2025/11/16 05:13:58 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/11/16 05:14:01 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:14:01 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/11/16 05:14:01 DEBUG : pacer: Reducing sleep to 250ms 2025/11/16 05:14:01 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f2034dd4fe8c495bb_d20251116_m051401_c001_v0001184_t0059_u01763270041279") 2025/11/16 05:14:01 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/11/16 05:14:01 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:14:01 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/11/16 05:14:02 DEBUG : pacer: Reducing sleep to 125ms 2025/11/16 05:14:02 DEBUG : pacer: Reducing sleep to 62.5ms 2025/11/16 05:14:04 DEBUG : pacer: Reducing sleep to 31.25ms 2025/11/16 05:14:04 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:14:04 DEBUG : pacer: Reducing sleep to 15.625ms 2025/11/16 05:14:04 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:14:04 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Finishing large file upload with 2 parts 2025/11/16 05:14:04 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107 2025/11/16 05:14:08 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/10.109Mi 2025/11/16 05:14:08 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 0 length 5300053 2025/11/16 05:14:08 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.055Mi offset 5.055Mi/10.109Mi 2025/11/16 05:14:08 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 1 length 5300053 2025/11/16 05:14:08 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 2 size 1 offset 10.109Mi/10.109Mi 2025/11/16 05:14:08 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 2 length 1 2025/11/16 05:14:09 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 2 2025/11/16 05:14:10 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 0 2025/11/16 05:14:10 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 1 2025/11/16 05:14:10 DEBUG : chunked-5.055Mi-10.109Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107/Streamed 2025/11/16 05:14:12 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:14:12 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/11/16 05:14:12 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f2196b5bd76db4d19_d20251116_m051412_c001_v0001184_t0019_u01763270052193") 2025/11/16 05:14:12 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/16 05:14:12 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/16 05:14:12 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 2 length 1 2025/11/16 05:14:12 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/11/16 05:14:13 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 2 2025/11/16 05:14:14 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:14:14 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:14:14 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/11/16 05:14:16 DEBUG : chunked-6.667Mi-6.667Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/6.667Mi 2025/11/16 05:14:16 DEBUG : chunked-6.667Mi-6.667Mi.bin: Sending chunk 0 length 6990506 2025/11/16 05:14:16 DEBUG : chunked-6.667Mi-6.667Mi.bin: multipart upload: starting chunk 1 size 1 offset 6.667Mi/6.667Mi 2025/11/16 05:14:16 DEBUG : chunked-6.667Mi-6.667Mi.bin: Sending chunk 1 length 1 2025/11/16 05:14:17 DEBUG : chunked-6.667Mi-6.667Mi.bin: Done sending chunk 1 2025/11/16 05:14:19 DEBUG : chunked-6.667Mi-6.667Mi.bin: Done sending chunk 0 2025/11/16 05:14:19 DEBUG : chunked-6.667Mi-6.667Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507/Streamed 2025/11/16 05:14:20 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:14:20 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/11/16 05:14:21 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f2196b5bd76db4d57_d20251116_m051420_c001_v0001115_t0029_u01763270060966") 2025/11/16 05:14:21 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/16 05:14:21 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:14:21 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/16 05:14:21 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00031d890), retryAfter:1000000000} 2025/11/16 05:14:21 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:14:21 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:14:21 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:14:21 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:14:22 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:14:23 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000332f90), retryAfter:1000000000} 2025/11/16 05:14:23 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:14:23 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:14:23 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:14:23 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:14:24 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:14:24 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:14:24 DEBUG : pacer: Reducing sleep to 250ms 2025/11/16 05:14:25 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Error sending chunk 1 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000173110), retryAfter:1000000000} 2025/11/16 05:14:25 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/11/16 05:14:25 DEBUG : pacer: low level retry 3/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/11/16 05:14:25 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:14:25 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:14:25 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000333e00), retryAfter:1000000000} 2025/11/16 05:14:25 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:14:25 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:14:26 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:14:27 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:14:28 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000341500), retryAfter:1000000000} 2025/11/16 05:14:28 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:14:28 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:14:28 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:14:28 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:14:29 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:14:30 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Error sending chunk 1 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0011d0b40), retryAfter:1000000000} 2025/11/16 05:14:30 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/11/16 05:14:30 DEBUG : pacer: low level retry 6/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/11/16 05:14:30 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:14:30 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:14:31 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:14:32 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00016a9f0), retryAfter:1000000000} 2025/11/16 05:14:32 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:14:32 DEBUG : pacer: low level retry 7/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:14:32 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:14:32 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:14:33 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:14:34 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000302390), retryAfter:1000000000} 2025/11/16 05:14:34 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:14:34 DEBUG : pacer: low level retry 8/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:14:34 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:14:34 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:14:35 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:14:36 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0011d1b60), retryAfter:1000000000} 2025/11/16 05:14:36 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:14:36 DEBUG : pacer: low level retry 9/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:14:36 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:14:36 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:14:37 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:14:38 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0011d1ef0), retryAfter:1000000000} 2025/11/16 05:14:38 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:14:38 DEBUG : pacer: low level retry 10/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:14:38 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:14:38 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Error sending chunk 1: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:14:38 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Cancelling large file upload 2025/11/16 05:14:38 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/11/16 05:14:41 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:14:41 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/11/16 05:14:41 DEBUG : pacer: Reducing sleep to 250ms 2025/11/16 05:14:41 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f2034dd4fe8c4967f_d20251116_m051441_c001_v0001176_t0040_u01763270081138") 2025/11/16 05:14:41 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/16 05:14:41 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/16 05:14:41 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:14:42 DEBUG : pacer: Reducing sleep to 125ms 2025/11/16 05:14:42 DEBUG : pacer: Reducing sleep to 62.5ms 2025/11/16 05:14:43 DEBUG : pacer: Reducing sleep to 31.25ms 2025/11/16 05:14:43 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:14:44 DEBUG : pacer: Reducing sleep to 15.625ms 2025/11/16 05:14:44 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:14:44 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Finishing large file upload with 2 parts 2025/11/16 05:14:44 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012 2025/11/16 05:14:47 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/13.333Mi 2025/11/16 05:14:47 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2025/11/16 05:14:47 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 1 size 6.667Mi offset 6.667Mi/13.333Mi 2025/11/16 05:14:47 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2025/11/16 05:14:50 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 0 2025/11/16 05:14:50 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 1 2025/11/16 05:14:50 DEBUG : chunked-6.667Mi-13.333Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012/Streamed 2025/11/16 05:14:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:14:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Streaming upload with --b2-chunk-size 6.667Mi allows uploads of up to 65.104Gi and will fail only when that limit is reached. 2025/11/16 05:14:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f2034dd4fe8c496cf_d20251116_m051452_c001_v0001101_t0059_u01763270092625") 2025/11/16 05:14:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/16 05:14:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/11/16 05:14:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/11/16 05:14:55 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:14:55 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:14:55 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013 2025/11/16 05:14:58 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/13.333Mi 2025/11/16 05:14:58 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2025/11/16 05:14:58 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 1 size 6.667Mi offset 6.667Mi/13.333Mi 2025/11/16 05:14:58 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2025/11/16 05:14:58 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 2 size 1 offset 13.333Mi/13.333Mi 2025/11/16 05:14:58 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 1 2025/11/16 05:14:59 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 2 2025/11/16 05:15:00 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 0 2025/11/16 05:15:00 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 1 2025/11/16 05:15:00 DEBUG : chunked-6.667Mi-13.333Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013/Streamed 2025/11/16 05:15:03 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:15:03 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/11/16 05:15:03 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f20214feff7fc6174_d20251116_m051503_c001_v0001161_t0040_u01763270103131") 2025/11/16 05:15:03 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/16 05:15:03 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/16 05:15:03 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 2 length 1 2025/11/16 05:15:03 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/11/16 05:15:03 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_get_upload_part_url": EOF) 2025/11/16 05:15:03 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/11/16 05:15:03 DEBUG : pacer: Reducing sleep to 10ms 2025/11/16 05:15:04 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 1 (retry=true): CPU too busy (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000327590), retryAfter:1000000000} 2025/11/16 05:15:04 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable): trying again in 1s 2025/11/16 05:15:04 DEBUG : pacer: low level retry 1/10 (error CPU too busy (503 service_unavailable): trying again in 1s) 2025/11/16 05:15:04 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:15:04 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/11/16 05:15:04 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:15:04 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 2 2025/11/16 05:15:05 DEBUG : pacer: Reducing sleep to 250ms 2025/11/16 05:15:06 DEBUG : pacer: Reducing sleep to 125ms 2025/11/16 05:15:06 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:15:08 DEBUG : pacer: Reducing sleep to 62.5ms 2025/11/16 05:15:08 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:15:08 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Finishing large file upload with 3 parts 2025/11/16 05:15:08 DEBUG : pacer: Reducing sleep to 31.25ms 2025/11/16 05:15:09 DEBUG : pacer: Reducing sleep to 15.625ms 2025/11/16 05:15:09 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609 2025/11/16 05:15:12 DEBUG : chunked-8Mi-8.000Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/8.000Mi 2025/11/16 05:15:12 DEBUG : chunked-8Mi-8.000Mi.bin: Sending chunk 0 length 8388608 2025/11/16 05:15:12 DEBUG : chunked-8Mi-8.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 8Mi/8.000Mi 2025/11/16 05:15:12 DEBUG : chunked-8Mi-8.000Mi.bin: Sending chunk 1 length 1 2025/11/16 05:15:13 DEBUG : chunked-8Mi-8.000Mi.bin: Done sending chunk 1 2025/11/16 05:15:15 DEBUG : chunked-8Mi-8.000Mi.bin: Done sending chunk 0 2025/11/16 05:15:15 DEBUG : chunked-8Mi-8.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609/Streamed 2025/11/16 05:15:16 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:15:16 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/11/16 05:15:16 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f2196b5bd76db4f33_d20251116_m051516_c001_v0001090_t0006_u01763270116721") 2025/11/16 05:15:16 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/16 05:15:16 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:15:16 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Sending chunk 0 length 8388608 2025/11/16 05:15:17 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:15:19 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:15:19 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216 2025/11/16 05:15:21 DEBUG : chunked-8Mi-16Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/16Mi 2025/11/16 05:15:21 DEBUG : chunked-8Mi-16Mi.bin: Sending chunk 0 length 8388608 2025/11/16 05:15:21 DEBUG : chunked-8Mi-16Mi.bin: multipart upload: starting chunk 1 size 8Mi offset 8Mi/16Mi 2025/11/16 05:15:21 DEBUG : chunked-8Mi-16Mi.bin: Sending chunk 1 length 8388608 2025/11/16 05:15:24 DEBUG : chunked-8Mi-16Mi.bin: Done sending chunk 0 2025/11/16 05:15:24 DEBUG : chunked-8Mi-16Mi.bin: Done sending chunk 1 2025/11/16 05:15:24 DEBUG : chunked-8Mi-16Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216/Streamed 2025/11/16 05:15:26 DEBUG : chunked-8Mi-16Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:15:26 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/11/16 05:15:26 DEBUG : chunked-8Mi-16Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f211f5bf7c3319034_d20251116_m051526_c001_v0001104_t0053_u01763270126658") 2025/11/16 05:15:26 DEBUG : chunked-8Mi-16Mi-streamed.bin: Sending chunk 0 length 8388608 2025/11/16 05:15:27 DEBUG : chunked-8Mi-16Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/11/16 05:15:27 DEBUG : chunked-8Mi-16Mi-streamed.bin: Sending chunk 1 length 8388608 2025/11/16 05:15:30 DEBUG : chunked-8Mi-16Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:15:30 DEBUG : chunked-8Mi-16Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:15:30 DEBUG : chunked-8Mi-16Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217 2025/11/16 05:15:32 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/16.000Mi 2025/11/16 05:15:32 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 0 length 8388608 2025/11/16 05:15:32 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 1 size 8Mi offset 8Mi/16.000Mi 2025/11/16 05:15:32 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 1 length 8388608 2025/11/16 05:15:32 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 16Mi/16.000Mi 2025/11/16 05:15:32 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 2 length 1 2025/11/16 05:15:33 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 2 2025/11/16 05:15:35 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 1 2025/11/16 05:15:35 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 0 2025/11/16 05:15:35 DEBUG : chunked-8Mi-16.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217/Streamed 2025/11/16 05:15:37 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:15:37 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/11/16 05:15:37 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f2196b5bd76db4fbc_d20251116_m051537_c001_v0001100_t0022_u01763270137574") 2025/11/16 05:15:37 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 0 length 8388608 2025/11/16 05:15:38 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/16 05:15:38 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 2 length 1 2025/11/16 05:15:38 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 1 length 8388608 2025/11/16 05:15:39 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 2 2025/11/16 05:15:40 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:15:40 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:15:40 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/11/16 05:15:43 DEBUG : chunked-10Mi-10.000Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/10.000Mi 2025/11/16 05:15:43 DEBUG : chunked-10Mi-10.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 10Mi/10.000Mi 2025/11/16 05:15:43 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/11/16 05:15:43 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/11/16 05:15:43 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00038cba0), retryAfter:1000000000} 2025/11/16 05:15:43 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:15:43 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:15:43 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:15:43 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/11/16 05:15:43 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00038d1a0), retryAfter:1000000000} 2025/11/16 05:15:43 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:15:43 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:15:45 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:15:45 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0012f03c0), retryAfter:1000000000} 2025/11/16 05:15:45 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:15:45 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:15:45 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:15:45 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/11/16 05:15:46 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/11/16 05:15:48 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:15:48 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0012f0810), retryAfter:1000000000} 2025/11/16 05:15:48 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:15:48 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:15:48 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:15:49 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:15:49 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0012f13b0), retryAfter:1000000000} 2025/11/16 05:15:49 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:15:49 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:15:49 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:15:49 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/11/16 05:15:50 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/11/16 05:15:52 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:15:52 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0012783f0), retryAfter:1000000000} 2025/11/16 05:15:52 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:15:52 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:15:52 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:15:53 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:15:53 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0011d1aa0), retryAfter:1000000000} 2025/11/16 05:15:53 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:15:53 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:15:53 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:15:53 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/11/16 05:15:54 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/11/16 05:15:56 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:15:56 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0012f1b60), retryAfter:1000000000} 2025/11/16 05:15:56 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:15:56 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:15:56 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:15:57 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:15:57 DEBUG : chunked-10Mi-10.000Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00028c000), retryAfter:1000000000} 2025/11/16 05:15:57 DEBUG : chunked-10Mi-10.000Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/16 05:15:57 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/16 05:15:57 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/16 05:15:57 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/11/16 05:15:58 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/11/16 05:16:00 DEBUG : pacer: Reducing sleep to 500ms 2025/11/16 05:16:01 DEBUG : pacer: Reducing sleep to 250ms 2025/11/16 05:16:02 DEBUG : pacer: Reducing sleep to 125ms 2025/11/16 05:16:02 DEBUG : chunked-10Mi-10.000Mi.bin: Done sending chunk 1 2025/11/16 05:16:03 DEBUG : pacer: Reducing sleep to 62.5ms 2025/11/16 05:16:03 DEBUG : chunked-10Mi-10.000Mi.bin: Done sending chunk 0 2025/11/16 05:16:03 DEBUG : chunked-10Mi-10.000Mi.bin: Finishing large file upload with 2 parts 2025/11/16 05:16:03 DEBUG : pacer: Reducing sleep to 31.25ms 2025/11/16 05:16:04 DEBUG : pacer: Reducing sleep to 15.625ms 2025/11/16 05:16:04 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761/Streamed 2025/11/16 05:16:06 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:16:06 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/11/16 05:16:07 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f2196b5bd76db5080_d20251116_m051606_c001_v0001181_t0033_u01763270166970") 2025/11/16 05:16:07 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/16 05:16:07 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Sending chunk 1 length 1 2025/11/16 05:16:07 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Sending chunk 0 length 10485760 2025/11/16 05:16:07 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:16:09 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:16:09 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520 2025/11/16 05:16:11 DEBUG : chunked-10Mi-20Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/20Mi 2025/11/16 05:16:11 DEBUG : chunked-10Mi-20Mi.bin: Sending chunk 0 length 10485760 2025/11/16 05:16:12 DEBUG : chunked-10Mi-20Mi.bin: multipart upload: starting chunk 1 size 10Mi offset 10Mi/20Mi 2025/11/16 05:16:12 DEBUG : chunked-10Mi-20Mi.bin: Sending chunk 1 length 10485760 2025/11/16 05:16:14 DEBUG : chunked-10Mi-20Mi.bin: Done sending chunk 0 2025/11/16 05:16:14 DEBUG : chunked-10Mi-20Mi.bin: Done sending chunk 1 2025/11/16 05:16:14 DEBUG : chunked-10Mi-20Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520/Streamed 2025/11/16 05:16:17 DEBUG : chunked-10Mi-20Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:16:17 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/11/16 05:16:18 DEBUG : chunked-10Mi-20Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f205d1538e83816b4_d20251116_m051617_c001_v0001100_t0007_u01763270177910") 2025/11/16 05:16:18 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 0 length 10485760 2025/11/16 05:16:18 DEBUG : chunked-10Mi-20Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/11/16 05:16:18 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 1 length 10485760 2025/11/16 05:16:20 DEBUG : chunked-10Mi-20Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:16:21 DEBUG : chunked-10Mi-20Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:16:21 DEBUG : chunked-10Mi-20Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521 2025/11/16 05:16:23 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/20.000Mi 2025/11/16 05:16:23 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 0 length 10485760 2025/11/16 05:16:24 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 1 size 10Mi offset 10Mi/20.000Mi 2025/11/16 05:16:24 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 1 length 10485760 2025/11/16 05:16:24 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 20Mi/20.000Mi 2025/11/16 05:16:24 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 2 length 1 2025/11/16 05:16:25 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 2 2025/11/16 05:16:26 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 0 2025/11/16 05:16:26 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 1 2025/11/16 05:16:26 DEBUG : chunked-10Mi-20.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521/Streamed 2025/11/16 05:16:29 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: File is big enough for chunked streaming 2025/11/16 05:16:29 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/11/16 05:16:29 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Starting streaming of large file (id "4_zcfe2bd43101d8bbb9fa80e1b_f20214feff7fc637c_d20251116_m051629_c001_v0001099_t0030_u01763270189308") 2025/11/16 05:16:29 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 0 length 10485760 2025/11/16 05:16:29 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/16 05:16:29 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 2 length 1 2025/11/16 05:16:29 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 1 length 10485760 2025/11/16 05:16:30 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 2 2025/11/16 05:16:32 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 0 2025/11/16 05:16:34 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 1 2025/11/16 05:16:34 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/11/16 05:16:44 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_zcfe2bd43101d8bbb9fa80e1b_f214a76597f46ec05_d20251116_m051644_c001_v0001131_t0004_u01763270204243") 2025/11/16 05:16:44 DEBUG : chunked-copy.copy: Copying chunk 1 length 1 2025/11/16 05:16:44 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/11/16 05:16:44 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/11/16 05:16:45 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/11/16 05:16:45 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485759 2025/11/16 05:16:47 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10.000Mi 2025/11/16 05:16:47 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/11/16 05:16:47 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4.000Mi offset 6Mi/10.000Mi 2025/11/16 05:16:47 DEBUG : chunked-copy: Sending chunk 1 length 4194303 2025/11/16 05:16:49 DEBUG : chunked-copy: Done sending chunk 0 2025/11/16 05:16:49 DEBUG : chunked-copy: Done sending chunk 1 2025/11/16 05:16:49 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2025/11/16 05:16:50 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_zcfe2bd43101d8bbb9fa80e1b_f214a76597f46ec21_d20251116_m051650_c001_v0001109_t0008_u01763270210213") 2025/11/16 05:16:50 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242879 2025/11/16 05:16:50 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/11/16 05:16:50 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/11/16 05:16:51 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/11/16 05:16:51 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485760 2025/11/16 05:16:53 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10Mi 2025/11/16 05:16:53 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/11/16 05:16:53 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4Mi offset 6Mi/10Mi 2025/11/16 05:16:53 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/11/16 05:16:55 DEBUG : chunked-copy: Done sending chunk 0 2025/11/16 05:16:55 DEBUG : chunked-copy: Done sending chunk 1 2025/11/16 05:16:55 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2025/11/16 05:16:56 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_zcfe2bd43101d8bbb9fa80e1b_f214a76597f46ec43_d20251116_m051656_c001_v0001036_t0059_u01763270216534") 2025/11/16 05:16:56 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242880 2025/11/16 05:16:56 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/11/16 05:16:57 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/11/16 05:16:57 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/11/16 05:16:57 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485761 2025/11/16 05:16:59 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10.000Mi 2025/11/16 05:16:59 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/11/16 05:16:59 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4.000Mi offset 6Mi/10.000Mi 2025/11/16 05:16:59 DEBUG : chunked-copy: Sending chunk 1 length 4194305 2025/11/16 05:17:02 DEBUG : chunked-copy: Done sending chunk 0 2025/11/16 05:17:02 DEBUG : chunked-copy: Done sending chunk 1 2025/11/16 05:17:02 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2025/11/16 05:17:03 DEBUG : chunked-copy.copy: Starting copy of large file in 3 chunks (id "4_zcfe2bd43101d8bbb9fa80e1b_f21871741754ae87f_d20251116_m051703_c001_v0001181_t0030_u01763270223453") 2025/11/16 05:17:03 DEBUG : chunked-copy.copy: Copying chunk 2 length 1 2025/11/16 05:17:03 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/11/16 05:17:03 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242880 2025/11/16 05:17:03 DEBUG : chunked-copy.copy: Done copying chunk 2 2025/11/16 05:17:03 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/11/16 05:17:04 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/11/16 05:17:04 DEBUG : chunked-copy.copy: Finishing large file copy with 3 parts === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2025/11/16 05:17:05 DEBUG : unknown-size-put.txt: File has 100 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2025/11/16 05:17:07 DEBUG : unknown-size-update.txt: File has 200 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsRootCollapse 2025/11/16 05:17:07 DEBUG : Creating backend with remote "TestB2:rclone-test-qokokun7yoxu/deeper/nonexisting/directory" 2025/11/16 05:17:07 DEBUG : Config file has changed externally - reloading === 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/11/16 05:17:08 INFO : B2 bucket rclone-test-qokokun7yoxu: cleaning bucket "rclone-test-qokokun7yoxu" of all files 2025/11/16 05:17:09 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f405d1538e8381689_d20251116_m051611_c001_v7007000_t0000_u01763270171167") 2025/11/16 05:17:09 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f2196b5bd76db5080_d20251116_m051606_c001_v0001181_t0033_u01763270166970") 2025/11/16 05:17:09 DEBUG : chunked-10Mi-10.000Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f4196b5bd76db5075_d20251116_m051606_c001_v7007000_t0000_u01763270166403") 2025/11/16 05:17:09 DEBUG : chunked-10Mi-10.000Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f211f5bf7c3319083_d20251116_m051542_c001_v0001161_t0022_u01763270142593") 2025/11/16 05:17:09 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f414a76597f46ebd9_d20251116_m051637_c001_v7007000_t0000_u01763270197758") 2025/11/16 05:17:09 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f20214feff7fc637c_d20251116_m051629_c001_v0001099_t0030_u01763270189308") 2025/11/16 05:17:09 DEBUG : chunked-10Mi-20.000Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f40214feff7fc637b_d20251116_m051628_c001_v7007000_t0000_u01763270188728") 2025/11/16 05:17:09 DEBUG : chunked-10Mi-20.000Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f205d1538e83816c8_d20251116_m051623_c001_v0001183_t0002_u01763270183263") 2025/11/16 05:17:09 DEBUG : chunked-10Mi-20Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f405d1538e83816c6_d20251116_m051623_c001_v7007000_t0000_u01763270183072") 2025/11/16 05:17:09 DEBUG : chunked-10Mi-20Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f205d1538e83816b4_d20251116_m051617_c001_v0001100_t0007_u01763270177910") 2025/11/16 05:17:09 DEBUG : chunked-10Mi-20Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f405d1538e83816b0_d20251116_m051617_c001_v7007000_t0000_u01763270177343") 2025/11/16 05:17:09 DEBUG : chunked-10Mi-20Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f205d1538e838168a_d20251116_m051611_c001_v0001099_t0051_u01763270171353") 2025/11/16 05:17:09 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f42028f44561f9988_d20251116_m051255_c001_v7007000_t0000_u01763269975065") 2025/11/16 05:17:09 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f21955b45bf1828e9_d20251116_m051250_c001_v0001117_t0025_u01763269970791") 2025/11/16 05:17:09 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f42028f44561f995a_d20251116_m051245_c001_v7007000_t0000_u01763269965982") 2025/11/16 05:17:09 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f22028f44561f9942_d20251116_m051241_c001_v0001040_t0042_u01763269961608") 2025/11/16 05:17:09 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f41955b45bf1828e3_d20251116_m051250_c001_v7007000_t0000_u01763269970330") 2025/11/16 05:17:09 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f22028f44561f995d_d20251116_m051246_c001_v0001177_t0011_u01763269966194") 2025/11/16 05:17:09 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f42028f44561f993e_d20251116_m051241_c001_v7007000_t0000_u01763269961223") 2025/11/16 05:17:09 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f21523415b8447de8_d20251116_m051237_c001_v0001178_t0035_u01763269957208") 2025/11/16 05:17:09 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f41523415b8447de7_d20251116_m051237_c001_v7007000_t0000_u01763269957027") 2025/11/16 05:17:10 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f22028f44561f9926_d20251116_m051233_c001_v0001029_t0027_u01763269953271") 2025/11/16 05:17:10 DEBUG : chunked-5.054Mi-5.054Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f42028f44561f991f_d20251116_m051232_c001_v7007000_t0000_u01763269952878") 2025/11/16 05:17:10 DEBUG : chunked-5.054Mi-5.054Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f216e43f41c2594b9_d20251116_m051217_c001_v0001165_t0057_u01763269937549") 2025/11/16 05:17:10 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f4034dd4fe8c49603_d20251116_m051416_c001_v7007000_t0000_u01763270056009") 2025/11/16 05:17:10 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f2196b5bd76db4d19_d20251116_m051412_c001_v0001184_t0019_u01763270052193") 2025/11/16 05:17:10 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f4034dd4fe8c495d4_d20251116_m051407_c001_v7007000_t0000_u01763270047471") 2025/11/16 05:17:10 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f2034dd4fe8c495bb_d20251116_m051401_c001_v0001184_t0059_u01763270041279") 2025/11/16 05:17:10 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f4196b5bd76db4d16_d20251116_m051411_c001_v7007000_t0000_u01763270051798") 2025/11/16 05:17:10 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f2034dd4fe8c495d6_d20251116_m051407_c001_v0001184_t0050_u01763270047666") 2025/11/16 05:17:10 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f4034dd4fe8c494a4_d20251116_m051321_c001_v7007000_t0000_u01763270001481") 2025/11/16 05:17:10 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f22028f44561f9a0f_d20251116_m051316_c001_v0001104_t0044_u01763269996716") 2025/11/16 05:17:10 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f42028f44561f9a0b_d20251116_m051316_c001_v7007000_t0000_u01763269996529") 2025/11/16 05:17:10 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f21955b45bf182909_d20251116_m051259_c001_v0001157_t0021_u01763269979371") 2025/11/16 05:17:10 DEBUG : chunked-5.055Mi-5.055Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f41955b45bf182907_d20251116_m051258_c001_v7007000_t0000_u01763269978967") 2025/11/16 05:17:10 DEBUG : chunked-5.055Mi-5.055Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f22028f44561f9989_d20251116_m051255_c001_v0001092_t0050_u01763269975262") 2025/11/16 05:17:10 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f416e43f41c2594b8_d20251116_m051217_c001_v7007000_t0000_u01763269937365") 2025/11/16 05:17:10 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f22028f44561f98bc_d20251116_m051212_c001_v0001131_t0036_u01763269932899") 2025/11/16 05:17:10 DEBUG : chunked-5Mi-10.000Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f42028f44561f98ba_d20251116_m051212_c001_v7007000_t0000_u01763269932513") 2025/11/16 05:17:10 DEBUG : chunked-5Mi-10.000Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f216e43f41c25949f_d20251116_m051208_c001_v0001176_t0022_u01763269928468") 2025/11/16 05:17:10 DEBUG : chunked-5Mi-10Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f416e43f41c25949e_d20251116_m051208_c001_v7007000_t0000_u01763269928272") 2025/11/16 05:17:11 DEBUG : chunked-5Mi-10Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f216e43f41c25948f_d20251116_m051204_c001_v0001115_t0041_u01763269924108") 2025/11/16 05:17:11 DEBUG : chunked-5Mi-10Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f416e43f41c25948d_d20251116_m051203_c001_v7007000_t0000_u01763269923695") 2025/11/16 05:17:11 DEBUG : chunked-5Mi-10Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f2034dd4fe8c492ee_d20251116_m051159_c001_v0001120_t0044_u01763269919520") 2025/11/16 05:17:11 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f4034dd4fe8c492eb_d20251116_m051159_c001_v7007000_t0000_u01763269919337") 2025/11/16 05:17:11 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f216e43f41c25947e_d20251116_m051155_c001_v0001101_t0045_u01763269915751") 2025/11/16 05:17:11 DEBUG : chunked-5Mi-5.000Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f416e43f41c259479_d20251116_m051155_c001_v7007000_t0000_u01763269915362") 2025/11/16 05:17:11 DEBUG : chunked-5Mi-5.000Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f2067c17e4aedc3c4_d20251116_m051149_c001_v0001137_t0046_u01763269909753") 2025/11/16 05:17:11 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f411f5bf7c3318fe6_d20251116_m051511_c001_v7007000_t0000_u01763270111864") 2025/11/16 05:17:11 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f20214feff7fc6174_d20251116_m051503_c001_v0001161_t0040_u01763270103131") 2025/11/16 05:17:11 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f4034dd4fe8c49712_d20251116_m051457_c001_v7007000_t0000_u01763270097542") 2025/11/16 05:17:11 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f2034dd4fe8c496cf_d20251116_m051452_c001_v0001101_t0059_u01763270092625") 2025/11/16 05:17:11 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f40214feff7fc616c_d20251116_m051502_c001_v7007000_t0000_u01763270102655") 2025/11/16 05:17:11 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f2034dd4fe8c49715_d20251116_m051457_c001_v0001120_t0050_u01763270097728") 2025/11/16 05:17:11 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f4034dd4fe8c496c7_d20251116_m051452_c001_v7007000_t0000_u01763270092161") 2025/11/16 05:17:11 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f2034dd4fe8c496a4_d20251116_m051447_c001_v0001105_t0000_u01763270087394") 2025/11/16 05:17:11 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f4034dd4fe8c496a1_d20251116_m051447_c001_v7007000_t0000_u01763270087197") 2025/11/16 05:17:11 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f2034dd4fe8c4967f_d20251116_m051441_c001_v0001176_t0040_u01763270081138") 2025/11/16 05:17:11 DEBUG : chunked-6.667Mi-6.667Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f4196b5bd76db4d51_d20251116_m051420_c001_v7007000_t0000_u01763270060520") 2025/11/16 05:17:11 DEBUG : chunked-6.667Mi-6.667Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f2034dd4fe8c49605_d20251116_m051416_c001_v0001100_t0002_u01763270056210") 2025/11/16 05:17:11 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f411f5bf7c3319082_d20251116_m051542_c001_v7007000_t0000_u01763270142407") 2025/11/16 05:17:12 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f2196b5bd76db4fbc_d20251116_m051537_c001_v0001100_t0022_u01763270137574") 2025/11/16 05:17:12 DEBUG : chunked-8Mi-16.000Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f4196b5bd76db4fb9_d20251116_m051536_c001_v7007000_t0000_u01763270136951") 2025/11/16 05:17:12 DEBUG : chunked-8Mi-16.000Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f211f5bf7c331904e_d20251116_m051531_c001_v0001044_t0051_u01763270131994") 2025/11/16 05:17:12 DEBUG : chunked-8Mi-16Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f411f5bf7c331904b_d20251116_m051531_c001_v7007000_t0000_u01763270131806") 2025/11/16 05:17:12 DEBUG : chunked-8Mi-16Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f211f5bf7c3319034_d20251116_m051526_c001_v0001104_t0053_u01763270126658") 2025/11/16 05:17:12 DEBUG : chunked-8Mi-16Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f411f5bf7c3319033_d20251116_m051526_c001_v7007000_t0000_u01763270126192") 2025/11/16 05:17:12 DEBUG : chunked-8Mi-16Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f211f5bf7c3319015_d20251116_m051520_c001_v0001115_t0003_u01763270120829") 2025/11/16 05:17:12 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f411f5bf7c3319013_d20251116_m051520_c001_v7007000_t0000_u01763270120645") 2025/11/16 05:17:12 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f2196b5bd76db4f33_d20251116_m051516_c001_v0001090_t0006_u01763270116721") 2025/11/16 05:17:12 DEBUG : chunked-8Mi-8.000Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f4196b5bd76db4f32_d20251116_m051516_c001_v7007000_t0000_u01763270116200") 2025/11/16 05:17:12 DEBUG : chunked-8Mi-8.000Mi.bin: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f211f5bf7c3318fea_d20251116_m051512_c001_v0001100_t0025_u01763270112132") 2025/11/16 05:17:12 DEBUG : chunked-copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f41871741754ae88d_d20251116_m051705_c001_v7007000_t0000_u01763270225451") 2025/11/16 05:17:12 DEBUG : chunked-copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f214a76597f46ec58_d20251116_m051659_c001_v0001040_t0033_u01763270219506") 2025/11/16 05:17:12 DEBUG : chunked-copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f414a76597f46ec56_d20251116_m051658_c001_v7007000_t0000_u01763270218876") 2025/11/16 05:17:12 DEBUG : chunked-copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f21871741754ae826_d20251116_m051652_c001_v0001134_t0055_u01763270212982") 2025/11/16 05:17:12 DEBUG : chunked-copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f41871741754ae821_d20251116_m051652_c001_v7007000_t0000_u01763270212363") 2025/11/16 05:17:12 DEBUG : chunked-copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f21871741754ae802_d20251116_m051646_c001_v0001184_t0007_u01763270206906") 2025/11/16 05:17:12 DEBUG : chunked-copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f41871741754ae7fc_d20251116_m051646_c001_v7007000_t0000_u01763270206298") 2025/11/16 05:17:12 DEBUG : chunked-copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1162fb57a9977a5e_d20251116_m051642_c001_v0001176_t0034_u01763270202582") 2025/11/16 05:17:12 DEBUG : chunked-copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f414a76597f46ebf2_d20251116_m051642_c001_v7007000_t0000_u01763270202086") 2025/11/16 05:17:13 DEBUG : chunked-copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1162fb57a9977a45_d20251116_m051638_c001_v0001176_t0059_u01763270198546") 2025/11/16 05:17:13 DEBUG : chunked-copy.copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f41871741754ae88b_d20251116_m051705_c001_v7007000_t0000_u01763270225268") 2025/11/16 05:17:13 DEBUG : chunked-copy.copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f21871741754ae87f_d20251116_m051703_c001_v0001181_t0030_u01763270223453") 2025/11/16 05:17:13 DEBUG : chunked-copy.copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f414a76597f46ec52_d20251116_m051658_c001_v7007000_t0000_u01763270218693") 2025/11/16 05:17:13 DEBUG : chunked-copy.copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f214a76597f46ec43_d20251116_m051656_c001_v0001036_t0059_u01763270216534") 2025/11/16 05:17:13 DEBUG : chunked-copy.copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f41871741754ae81e_d20251116_m051652_c001_v7007000_t0000_u01763270212180") 2025/11/16 05:17:13 DEBUG : chunked-copy.copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f214a76597f46ec21_d20251116_m051650_c001_v0001109_t0008_u01763270210213") 2025/11/16 05:17:13 DEBUG : chunked-copy.copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f41871741754ae7f9_d20251116_m051646_c001_v7007000_t0000_u01763270206114") 2025/11/16 05:17:13 DEBUG : chunked-copy.copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f214a76597f46ec05_d20251116_m051644_c001_v0001131_t0004_u01763270204243") 2025/11/16 05:17:13 DEBUG : chunked-copy.copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f414a76597f46ebef_d20251116_m051641_c001_v7007000_t0000_u01763270201903") 2025/11/16 05:17:13 DEBUG : chunked-copy.copy: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f111b9684878421a8_d20251116_m051640_c001_v0001040_t0021_u01763270200444") 2025/11/16 05:17:13 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1095f3af0aefb195_d20251116_m051026_c001_v0001136_t0041_u01763269826024") 2025/11/16 05:17:13 DEBUG : unknown-size-put.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f41871741754ae890_d20251116_m051706_c001_v7007000_t0000_u01763270226341") 2025/11/16 05:17:13 DEBUG : unknown-size-put.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1162fb57a9977adc_d20251116_m051706_c001_v0001176_t0025_u01763270226054") 2025/11/16 05:17:13 DEBUG : unknown-size-update.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f41871741754ae895_d20251116_m051707_c001_v7007000_t0000_u01763270227311") 2025/11/16 05:17:13 DEBUG : unknown-size-update.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1162fb57a9977ae4_d20251116_m051707_c001_v0001176_t0020_u01763270227105") 2025/11/16 05:17:13 DEBUG : unknown-size-update.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f1162fb57a9977ade_d20251116_m051706_c001_v0001176_t0023_u01763270226527") 2025/11/16 05:17:13 DEBUG : versions/test-versions.txt: Deleting (id "4_zcfe2bd43101d8bbb9fa80e1b_f41389e634146f265_d20251116_m051145_c001_v7007000_t0000_u01763269905757") 2025/11/16 05:17:15 INFO : B2 bucket rclone-test-qokokun7yoxu: cleaning bucket "rclone-test-qokokun7yoxu" of all files === RUN TestIntegration/FsShutdown fstests.go:2831: Shutdown method not supported --- PASS: TestIntegration (454.81s) --- SKIP: TestIntegration/FsCheckWrap (0.00s) --- PASS: TestIntegration/FsCommand (0.00s) --- PASS: TestIntegration/FsRmdirNotFound (0.55s) --- PASS: TestIntegration/FsString (0.00s) --- PASS: TestIntegration/FsName (0.00s) --- PASS: TestIntegration/FsRoot (0.00s) --- PASS: TestIntegration/FsRmdirEmpty (1.06s) --- PASS: TestIntegration/FsMkdir (451.25s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (0.54s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.18s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.18s) --- PASS: TestIntegration/FsMkdir/FsListRDirEmpty (0.27s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.18s) --- PASS: TestIntegration/FsMkdir/FsListRDirNotFound (0.18s) --- PASS: TestIntegration/FsMkdir/FsEncoding (24.17s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (2.21s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (1.31s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (1.28s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (1.11s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (1.05s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (1.17s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (1.21s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (1.09s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (1.12s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (1.51s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (1.06s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (1.08s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (1.32s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (1.15s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (1.29s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (1.28s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (1.30s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (1.12s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (1.34s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.38s) --- PASS: TestIntegration/FsMkdir/FsPutError (0.21s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (1.10s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- PASS: TestIntegration/FsMkdir/FsOpenChunkWriter (11.17s) --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (84.71s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (0.92s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.92s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (1.26s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (1.28s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.20s) --- 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.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (1.59s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (2.88s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir (4.58s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (4.23s) --- 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.22s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.22s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.19s) --- 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.18s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (1.26s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (0.36s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (0.90s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.36s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (1.69s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (1.83s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (1.84s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (7.97s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (0.82s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (4.35s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (2.06s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/PublicLink (8.61s) --- 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.54s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (1.63s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (0.92s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (0.71s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (32.41s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata (7.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize (1.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize/GzipEncoding (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize/GzipEncoding/NoDecompress (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB (6.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB/GzipEncoding (1.74s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB/GzipEncoding/NoDecompress (1.74s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions (21.45s) --- 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.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/NewObject (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/NewFs (1.29s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt (3.03s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before (1.12s) --- 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.81s) --- 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.65s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete/List (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete/NewObject (0.39s) --- 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.73s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/DryRun (0.63s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/RealThing (4.10s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished (2.07s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished (2.07s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished/DryRun (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished/RealThing (0.77s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules (1.72s) --- 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.97s) --- PASS: TestIntegration/FsMkdir/FsPutChunked (288.19s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi (27.80s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881 (9.77s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881/Streamed (3.97s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760 (8.95s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760/Streamed (4.59s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761 (9.08s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761/Streamed (4.85s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi (37.70s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001 (19.65s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001/Streamed (4.14s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000 (8.97s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000/Streamed (4.76s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001 (9.08s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001/Streamed (4.74s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi (80.96s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054 (21.46s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054/Streamed (17.55s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106 (50.95s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106/Streamed (45.99s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107 (8.54s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107/Streamed (4.22s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi (55.92s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507 (31.18s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507/Streamed (26.68s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012 (10.33s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012/Streamed (5.38s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013 (14.40s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013/Streamed (9.29s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi (30.46s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609 (8.70s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609/Streamed (4.43s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216 (11.17s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216/Streamed (5.62s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217 (10.60s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217/Streamed (5.33s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi (55.35s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761 (28.76s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761/Streamed (4.76s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520 (11.91s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520/Streamed (5.73s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521 (14.68s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521/Streamed (9.03s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked (27.75s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/5242880 (4.33s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/5242881 (4.21s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485759 (6.07s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485760 (6.51s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485761 (6.63s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (1.81s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (0.84s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (0.97s) --- 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 454.839s "go test -v -timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 7m35.792822813s (try 1/5)