"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/07 04:54:02 DEBUG : Creating backend with remote "TestB2:rclone-test-qomives5tofe" 2025/11/07 04:54:02 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/07 04:54:05 INFO : dir/subdir: Making directory fstest.go:250: Filtering empty directory "dir" fstest.go:250: Filtering empty directory "dir/subdir" 2025/11/07 04:54:05 INFO : dir/subdir: Removing directory fstest.go:250: Filtering empty directory "dir" 2025/11/07 04:54:05 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/07 04:54:35 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/07 04:54:36 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 1 length 5242880 2025/11/07 04:54:38 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 1 2025/11/07 04:54:38 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 2 length 1048576 2025/11/07 04:54:39 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 2 2025/11/07 04:54:39 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 0 length 5242880 2025/11/07 04:54:40 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 0 2025/11/07 04:54:40 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/07 04:54:48 DEBUG : Creating backend with remote "TestB2:" 2025/11/07 04:54:48 DEBUG : Config file has changed externally - reloading === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot 2025/11/07 04:54:50 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/07 04:54:55 INFO : B2 bucket rclone-test-qomives5tofe: cleaning bucket "rclone-test-qomives5tofe" of all files 2025/11/07 04:54:55 DEBUG : dirToPurge/fileToPurge.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f804b_d20251107_m045454_c001_v0001116_t0055_u01762491294288") === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2025/11/07 04:54:57 DEBUG : Creating backend with remote "TestB2:rclone-test-qomives5tofe/dirToPurgeFromRoot" 2025/11/07 04:54:58 INFO : B2 bucket rclone-test-qomives5tofe path dirToPurgeFromRoot: cleaning bucket "rclone-test-qomives5tofe" of all files 2025/11/07 04:54:59 DEBUG : fileToPurgeFromRoot.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f804f_d20251107_m045456_c001_v0001116_t0022_u01762491296105") === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2025/11/07 04:54:59 DEBUG : Creating backend with remote "TestB2:rclone-test-qomives5tofe/hello? sausage/êé" 2025/11/07 04:55:03 INFO : B2 bucket rclone-test-qomives5tofe path hello? sausage/êé: cleaning bucket "rclone-test-qomives5tofe" of all files 2025/11/07 04:55:03 DEBUG : a/b/c/d/e.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f110c55d363b144f1_d20251107_m045501_c001_v0001106_t0013_u01762491301903") === 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/07 04:55:13 DEBUG : Creating backend with remote "TestB2:rclone-test-qomives5tofe/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2025/11/07 04:55:15 DEBUG : Creating backend with remote "TestB2:rclone-test-qomives5tofe/not found.txt" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1911: Opening root remote "TestB2:" path "rclone-test-qomives5tofe" from "TestB2:rclone-test-qomives5tofe" 2025/11/07 04:55:17 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/07 04:55:28 DEBUG : Creating backend with remote "TestB2:rclone-test-xasajod8guvo" 2025/11/07 04:55:31 DEBUG : B2 bucket rclone-test-xasajod8guvo: Purge remote 2025/11/07 04:55:31 INFO : B2 bucket rclone-test-xasajod8guvo: cleaning bucket "rclone-test-xasajod8guvo" of all files 2025/11/07 04:55:31 DEBUG : somefile: Deleting (id "4_z6fc26db3a0bd5bcb9fa80e1b_f409fdb670c22fde7_d20251107_m045530_c001_v0001161_t0038_u01762491330432") === 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/07 04:55:32 DEBUG : piped data.txt: File has 0 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2025/11/07 04:55:33 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/07 04:55:36 DEBUG : test-metadata: multipart upload: starting chunk 0 size 6Mi offset 0/6.106Mi 2025/11/07 04:55:36 DEBUG : test-metadata: Sending chunk 0 length 6291456 2025/11/07 04:55:36 DEBUG : test-metadata: multipart upload: starting chunk 1 size 108.733Ki offset 6Mi/6.106Mi 2025/11/07 04:55:36 DEBUG : test-metadata: Sending chunk 1 length 111343 2025/11/07 04:55:38 DEBUG : test-metadata: Done sending chunk 1 2025/11/07 04:55:39 DEBUG : test-metadata: Done sending chunk 0 2025/11/07 04:55:39 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/07 04:55:49 DEBUG : versions/test-versions.txt: Clearing upload URL because of error: Post "https://pod-000-1116-05.backblaze.com/b2api/v1/b2_upload_file/5f522d23a0ad5bcb9fa80e1b/c001_v0001116_t0018": EOF 2025/11/07 04:55:49 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1116-05.backblaze.com/b2api/v1/b2_upload_file/5f522d23a0ad5bcb9fa80e1b/c001_v0001116_t0018": EOF) 2025/11/07 04:55:49 DEBUG : pacer: Rate limited, increasing sleep to 20ms fstests.go:143: Put error: Post "https://pod-000-1116-05.backblaze.com/b2api/v1/b2_upload_file/5f522d23a0ad5bcb9fa80e1b/c001_v0001116_t0018": EOF - low level retry 1/10 2025/11/07 04:55:51 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-07-045544-026.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/07 04:55:54 DEBUG : oldPath = "TestB2:rclone-test-qomives5tofe/versions/test-versions-v2025-11-07-045544-026.txt" 2025/11/07 04:55:54 DEBUG : newPath = "TestB2,versions:rclone-test-qomives5tofe/versions/test-versions-v2025-11-07-045544-026.txt" 2025/11/07 04:55:54 DEBUG : Creating backend with remote "TestB2,versions:rclone-test-qomives5tofe/versions/test-versions-v2025-11-07-045544-026.txt" 2025/11/07 04:55:54 DEBUG : TestB2: detected overridden config - adding "{pO73u}" suffix to name 2025/11/07 04:55:55 DEBUG : fs cache: renaming child cache item "TestB2,versions:rclone-test-qomives5tofe/versions/test-versions-v2025-11-07-045544-026.txt" to be canonical for parent "TestB2{pO73u}:rclone-test-qomives5tofe/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/07 04:55:58 INFO : B2 bucket rclone-test-qomives5tofe: cleaning bucket "rclone-test-qomives5tofe" of all hidden files 2025/11/07 04:55:58 DEBUG : leading space/ leading space: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b6c_d20251107_m045414_c001_v7007000_t0000_u01762491254572") as it is a hide marker 2025/11/07 04:55:58 NOTICE: leading space/ leading space: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : leading space/ leading space: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7f9e_d20251107_m045413_c001_v0001116_t0007_u01762491253684") 2025/11/07 04:55:58 NOTICE: leading space/ leading space: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b6b_d20251107_m045413_c001_v7007000_t0000_u01762491253318") as it is a hide marker 2025/11/07 04:55:58 NOTICE: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7f9a_d20251107_m045412_c001_v0001116_t0018_u01762491251999") 2025/11/07 04:55:58 NOTICE: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : .leading dot/.leading dot: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b75_d20251107_m045422_c001_v7007000_t0000_u01762491262584") as it is a hide marker 2025/11/07 04:55:58 NOTICE: .leading dot/.leading dot: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : .leading dot/.leading dot: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fba_d20251107_m045421_c001_v0001116_t0036_u01762491261662") 2025/11/07 04:55:58 NOTICE: .leading dot/.leading dot: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : created from root.txt: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f406602b19265f84e_d20251107_m045524_c001_v7007000_t0000_u01762491324460") as it is a hide marker 2025/11/07 04:55:58 NOTICE: created from root.txt: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : created from root.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10877602f76edf2c_d20251107_m045523_c001_v0001176_t0045_u01762491323518") 2025/11/07 04:55:58 NOTICE: created from root.txt: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : file name.txt: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4162881cf412b967_d20251107_m045532_c001_v7007000_t0000_u01762491332495") as it is a hide marker 2025/11/07 04:55:58 NOTICE: file name.txt: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : file name.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f8080_d20251107_m045512_c001_v0001116_t0006_u01762491312251") 2025/11/07 04:55:58 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : file name.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f1149020bce4743eb_d20251107_m045508_c001_v0001117_t0003_u01762491308450") 2025/11/07 04:55:58 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : file name.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f8034_d20251107_m045446_c001_v0001116_t0024_u01762491286103") 2025/11/07 04:55:58 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : file name.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f802c_d20251107_m045444_c001_v0001116_t0025_u01762491284358") 2025/11/07 04:55:58 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f802e_d20251107_m045445_c001_v0001116_t0003_u01762491285112") "upload" dated 2025-11-07 04:54:45.112 +0000 UTC (1m13.673575659s ago) 2025/11/07 04:55:58 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f420ad3cb3292daff_d20251107_m045506_c001_v7007000_t0000_u01762491306256") as it is a hide marker 2025/11/07 04:55:58 NOTICE: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10708d3d78d92007_d20251107_m045505_c001_v0001182_t0008_u01762491305967") 2025/11/07 04:55:58 NOTICE: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f118a54b7f5e01be6_d20251107_m045505_c001_v0001038_t0009_u01762491305102") 2025/11/07 04:55:58 NOTICE: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : invalid utf-8/invalid utf-8: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b83_d20251107_m045432_c001_v7007000_t0000_u01762491272319") as it is a hide marker 2025/11/07 04:55:58 NOTICE: invalid utf-8/invalid utf-8: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : invalid utf-8/invalid utf-8: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7ff2_d20251107_m045431_c001_v0001116_t0048_u01762491271401") 2025/11/07 04:55:58 NOTICE: invalid utf-8/invalid utf-8: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : piped data.txt: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4162881cf412b96a_d20251107_m045534_c001_v7007000_t0000_u01762491334390") as it is a hide marker 2025/11/07 04:55:58 NOTICE: piped data.txt: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : piped data.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f80b8_d20251107_m045533_c001_v0001116_t0026_u01762491333787") 2025/11/07 04:55:58 NOTICE: piped data.txt: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : piped data.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f4162881cf412b969_d20251107_m045533_c001_v7007000_t0000_u01762491333600") 2025/11/07 04:55:58 NOTICE: piped data.txt: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : piped data.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f40512b66aa0f80b4_d20251107_m045533_c001_v0001116_t0018_u01762491333241") 2025/11/07 04:55:58 NOTICE: piped data.txt: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : test%46.txt/test%46.txt: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b84_d20251107_m045433_c001_v7007000_t0000_u01762491273742") as it is a hide marker 2025/11/07 04:55:58 NOTICE: test%46.txt/test%46.txt: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : test%46.txt/test%46.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f8002_d20251107_m045432_c001_v0001116_t0050_u01762491272686") 2025/11/07 04:55:58 NOTICE: test%46.txt/test%46.txt: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : test-metadata: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f403872197e0d138a_d20251107_m045541_c001_v7007000_t0000_u01762491341487") as it is a hide marker 2025/11/07 04:55:58 NOTICE: test-metadata: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : test-metadata: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f2162881cf412b96d_d20251107_m045536_c001_v0001105_t0023_u01762491336564") 2025/11/07 04:55:58 NOTICE: test-metadata: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : test-metadata: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f4162881cf412b96c_d20251107_m045535_c001_v7007000_t0000_u01762491335690") 2025/11/07 04:55:58 NOTICE: test-metadata: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : test-metadata: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f80ba_d20251107_m045534_c001_v0001116_t0000_u01762491334586") 2025/11/07 04:55:58 NOTICE: test-metadata: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : trailing CR␍/trailing CR␍: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b7a_d20251107_m045425_c001_v7007000_t0000_u01762491265329") as it is a hide marker 2025/11/07 04:55:58 NOTICE: trailing CR␍/trailing CR␍: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : trailing CR␍/trailing CR␍: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fc7_d20251107_m045424_c001_v0001116_t0029_u01762491264519") 2025/11/07 04:55:58 NOTICE: trailing CR␍/trailing CR␍: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : trailing HT␉/trailing HT␉: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b7f_d20251107_m045427_c001_v7007000_t0000_u01762491267965") as it is a hide marker 2025/11/07 04:55:58 NOTICE: trailing HT␉/trailing HT␉: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : trailing HT␉/trailing HT␉: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fd5_d20251107_m045426_c001_v0001116_t0002_u01762491266967") 2025/11/07 04:55:58 NOTICE: trailing HT␉/trailing HT␉: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : trailing LF␊/trailing LF␊: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b7b_d20251107_m045426_c001_v7007000_t0000_u01762491266598") as it is a hide marker 2025/11/07 04:55:58 NOTICE: trailing LF␊/trailing LF␊: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : trailing LF␊/trailing LF␊: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fd1_d20251107_m045425_c001_v0001116_t0018_u01762491265696") 2025/11/07 04:55:58 NOTICE: trailing LF␊/trailing LF␊: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : trailing VT␋/trailing VT␋: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b80_d20251107_m045429_c001_v7007000_t0000_u01762491269296") as it is a hide marker 2025/11/07 04:55:58 NOTICE: trailing VT␋/trailing VT␋: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : trailing VT␋/trailing VT␋: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fdf_d20251107_m045428_c001_v0001116_t0031_u01762491268331") 2025/11/07 04:55:58 NOTICE: trailing VT␋/trailing VT␋: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : trailing dot./trailing dot.: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b82_d20251107_m045431_c001_v7007000_t0000_u01762491271032") as it is a hide marker 2025/11/07 04:55:58 NOTICE: trailing dot./trailing dot.: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : trailing dot./trailing dot.: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7feb_d20251107_m045429_c001_v0001116_t0051_u01762491269661") 2025/11/07 04:55:58 NOTICE: trailing dot./trailing dot.: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : trailing space /trailing space : Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b78_d20251107_m045424_c001_v7007000_t0000_u01762491264153") as it is a hide marker 2025/11/07 04:55:58 NOTICE: trailing space /trailing space : Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : trailing space /trailing space : Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fbd_d20251107_m045422_c001_v0001116_t0038_u01762491262950") 2025/11/07 04:55:58 NOTICE: trailing space /trailing space : Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f1017043c92c1451e_d20251107_m045552_c001_v0001040_t0022_u01762491352220") "upload" dated 2025-11-07 04:55:52.22 +0000 UTC (6.565931185s ago) 2025/11/07 04:55:58 DEBUG : versions/test-versions.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f405cd22c891f8087_d20251107_m045547_c001_v7007000_t0000_u01762491347414") 2025/11/07 04:55:58 NOTICE: versions/test-versions.txt: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : versions/test-versions.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f80d8_d20251107_m045544_c001_v0001116_t0053_u01762491344026") 2025/11/07 04:55:58 NOTICE: versions/test-versions.txt: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : writer-at-subdir/writer-at-file: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814bbe_d20251107_m045443_c001_v7007000_t0000_u01762491283808") as it is a hide marker 2025/11/07 04:55:58 NOTICE: writer-at-subdir/writer-at-file: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : writer-at-subdir/writer-at-file: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f2216311f44814b8b_d20251107_m045435_c001_v0001144_t0026_u01762491275912") 2025/11/07 04:55:58 NOTICE: writer-at-subdir/writer-at-file: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : zero-length-file: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b88_d20251107_m045435_c001_v7007000_t0000_u01762491275584") as it is a hide marker 2025/11/07 04:55:58 NOTICE: zero-length-file: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : zero-length-file: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f40512b66aa0f800a_d20251107_m045435_c001_v0001116_t0018_u01762491275022") 2025/11/07 04:55:58 NOTICE: zero-length-file: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : ~leading tilde/~leading tilde: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b6d_d20251107_m045415_c001_v7007000_t0000_u01762491255970") as it is a hide marker 2025/11/07 04:55:58 NOTICE: ~leading tilde/~leading tilde: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : ~leading tilde/~leading tilde: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fa4_d20251107_m045414_c001_v0001116_t0040_u01762491254939") 2025/11/07 04:55:58 NOTICE: ~leading tilde/~leading tilde: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b66_d20251107_m045409_c001_v7007000_t0000_u01762491249242") as it is a hide marker 2025/11/07 04:55:58 NOTICE: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7f8c_d20251107_m045407_c001_v0001116_t0025_u01762491247901") 2025/11/07 04:55:58 NOTICE: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : ␉leading HT/␉leading HT: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b71_d20251107_m045420_c001_v7007000_t0000_u01762491260112") as it is a hide marker 2025/11/07 04:55:58 NOTICE: ␉leading HT/␉leading HT: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : ␉leading HT/␉leading HT: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fac_d20251107_m045419_c001_v0001116_t0035_u01762491259263") 2025/11/07 04:55:58 NOTICE: ␉leading HT/␉leading HT: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : ␊leading LF/␊leading LF: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b70_d20251107_m045418_c001_v7007000_t0000_u01762491258896") as it is a hide marker 2025/11/07 04:55:58 NOTICE: ␊leading LF/␊leading LF: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : ␊leading LF/␊leading LF: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fa8_d20251107_m045417_c001_v0001116_t0030_u01762491257782") 2025/11/07 04:55:58 NOTICE: ␊leading LF/␊leading LF: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : ␋leading VT/␋leading VT: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b74_d20251107_m045421_c001_v7007000_t0000_u01762491261296") as it is a hide marker 2025/11/07 04:55:58 NOTICE: ␋leading VT/␋leading VT: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : ␋leading VT/␋leading VT: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fb0_d20251107_m045420_c001_v0001116_t0017_u01762491260479") 2025/11/07 04:55:58 NOTICE: ␋leading VT/␋leading VT: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : ␍leading CR/␍leading CR: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b6e_d20251107_m045417_c001_v7007000_t0000_u01762491257415") as it is a hide marker 2025/11/07 04:55:58 NOTICE: ␍leading CR/␍leading CR: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : ␍leading CR/␍leading CR: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fa6_d20251107_m045416_c001_v0001116_t0033_u01762491256337") 2025/11/07 04:55:58 NOTICE: ␍leading CR/␍leading CR: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : ．/．: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b67_d20251107_m045410_c001_v7007000_t0000_u01762491250442") as it is a hide marker 2025/11/07 04:55:58 NOTICE: ．/．: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : ．/．: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7f90_d20251107_m045409_c001_v0001116_t0017_u01762491249607") 2025/11/07 04:55:58 NOTICE: ．/．: Skipped delete as --dry-run is set 2025/11/07 04:55:58 DEBUG : ．．/．．: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b6a_d20251107_m045411_c001_v7007000_t0000_u01762491251632") as it is a hide marker 2025/11/07 04:55:58 NOTICE: ．．/．．: Skipped remove hide marker as --dry-run is set 2025/11/07 04:55:58 DEBUG : ．．/．．: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7f96_d20251107_m045410_c001_v0001116_t0023_u01762491250807") 2025/11/07 04:55:58 NOTICE: ．．/．．: Skipped delete as --dry-run is set === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/RealThing 2025/11/07 04:55:58 INFO : B2 bucket rclone-test-qomives5tofe: cleaning bucket "rclone-test-qomives5tofe" of all hidden files 2025/11/07 04:55:59 DEBUG : leading space/ leading space: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b6c_d20251107_m045414_c001_v7007000_t0000_u01762491254572") as it is a hide marker 2025/11/07 04:55:59 DEBUG : leading space/ leading space: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7f9e_d20251107_m045413_c001_v0001116_t0007_u01762491253684") 2025/11/07 04:55:59 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b6b_d20251107_m045413_c001_v7007000_t0000_u01762491253318") as it is a hide marker 2025/11/07 04:55:59 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7f9a_d20251107_m045412_c001_v0001116_t0018_u01762491251999") 2025/11/07 04:55:59 DEBUG : .leading dot/.leading dot: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b75_d20251107_m045422_c001_v7007000_t0000_u01762491262584") as it is a hide marker 2025/11/07 04:55:59 DEBUG : .leading dot/.leading dot: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fba_d20251107_m045421_c001_v0001116_t0036_u01762491261662") 2025/11/07 04:55:59 DEBUG : created from root.txt: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f406602b19265f84e_d20251107_m045524_c001_v7007000_t0000_u01762491324460") as it is a hide marker 2025/11/07 04:55:59 DEBUG : created from root.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10877602f76edf2c_d20251107_m045523_c001_v0001176_t0045_u01762491323518") 2025/11/07 04:55:59 DEBUG : file name.txt: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4162881cf412b967_d20251107_m045532_c001_v7007000_t0000_u01762491332495") as it is a hide marker 2025/11/07 04:55:59 DEBUG : file name.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f8080_d20251107_m045512_c001_v0001116_t0006_u01762491312251") 2025/11/07 04:55:59 DEBUG : file name.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f1149020bce4743eb_d20251107_m045508_c001_v0001117_t0003_u01762491308450") 2025/11/07 04:55:59 DEBUG : file name.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f8034_d20251107_m045446_c001_v0001116_t0024_u01762491286103") 2025/11/07 04:55:59 DEBUG : file name.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f802c_d20251107_m045444_c001_v0001116_t0025_u01762491284358") 2025/11/07 04:55:59 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f802e_d20251107_m045445_c001_v0001116_t0003_u01762491285112") "upload" dated 2025-11-07 04:54:45.112 +0000 UTC (1m14.690798763s ago) 2025/11/07 04:55:59 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f420ad3cb3292daff_d20251107_m045506_c001_v7007000_t0000_u01762491306256") as it is a hide marker 2025/11/07 04:55:59 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10708d3d78d92007_d20251107_m045505_c001_v0001182_t0008_u01762491305967") 2025/11/07 04:55:59 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f118a54b7f5e01be6_d20251107_m045505_c001_v0001038_t0009_u01762491305102") 2025/11/07 04:55:59 DEBUG : invalid utf-8/invalid utf-8: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b83_d20251107_m045432_c001_v7007000_t0000_u01762491272319") as it is a hide marker 2025/11/07 04:56:00 DEBUG : invalid utf-8/invalid utf-8: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7ff2_d20251107_m045431_c001_v0001116_t0048_u01762491271401") 2025/11/07 04:56:00 DEBUG : piped data.txt: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4162881cf412b96a_d20251107_m045534_c001_v7007000_t0000_u01762491334390") as it is a hide marker 2025/11/07 04:56:00 DEBUG : piped data.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f80b8_d20251107_m045533_c001_v0001116_t0026_u01762491333787") 2025/11/07 04:56:00 DEBUG : piped data.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f4162881cf412b969_d20251107_m045533_c001_v7007000_t0000_u01762491333600") 2025/11/07 04:56:00 DEBUG : piped data.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f40512b66aa0f80b4_d20251107_m045533_c001_v0001116_t0018_u01762491333241") 2025/11/07 04:56:00 DEBUG : test%46.txt/test%46.txt: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b84_d20251107_m045433_c001_v7007000_t0000_u01762491273742") as it is a hide marker 2025/11/07 04:56:00 DEBUG : test%46.txt/test%46.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f8002_d20251107_m045432_c001_v0001116_t0050_u01762491272686") 2025/11/07 04:56:00 DEBUG : test-metadata: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f403872197e0d138a_d20251107_m045541_c001_v7007000_t0000_u01762491341487") as it is a hide marker 2025/11/07 04:56:00 DEBUG : test-metadata: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f2162881cf412b96d_d20251107_m045536_c001_v0001105_t0023_u01762491336564") 2025/11/07 04:56:00 DEBUG : test-metadata: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f4162881cf412b96c_d20251107_m045535_c001_v7007000_t0000_u01762491335690") 2025/11/07 04:56:00 DEBUG : test-metadata: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f80ba_d20251107_m045534_c001_v0001116_t0000_u01762491334586") 2025/11/07 04:56:00 DEBUG : trailing CR␍/trailing CR␍: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b7a_d20251107_m045425_c001_v7007000_t0000_u01762491265329") as it is a hide marker 2025/11/07 04:56:00 DEBUG : trailing CR␍/trailing CR␍: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fc7_d20251107_m045424_c001_v0001116_t0029_u01762491264519") 2025/11/07 04:56:00 DEBUG : trailing HT␉/trailing HT␉: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b7f_d20251107_m045427_c001_v7007000_t0000_u01762491267965") as it is a hide marker 2025/11/07 04:56:00 DEBUG : trailing HT␉/trailing HT␉: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fd5_d20251107_m045426_c001_v0001116_t0002_u01762491266967") 2025/11/07 04:56:00 DEBUG : trailing LF␊/trailing LF␊: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b7b_d20251107_m045426_c001_v7007000_t0000_u01762491266598") as it is a hide marker 2025/11/07 04:56:00 DEBUG : trailing LF␊/trailing LF␊: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fd1_d20251107_m045425_c001_v0001116_t0018_u01762491265696") 2025/11/07 04:56:00 DEBUG : trailing VT␋/trailing VT␋: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b80_d20251107_m045429_c001_v7007000_t0000_u01762491269296") as it is a hide marker 2025/11/07 04:56:00 DEBUG : trailing VT␋/trailing VT␋: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fdf_d20251107_m045428_c001_v0001116_t0031_u01762491268331") 2025/11/07 04:56:00 DEBUG : trailing dot./trailing dot.: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b82_d20251107_m045431_c001_v7007000_t0000_u01762491271032") as it is a hide marker 2025/11/07 04:56:00 DEBUG : trailing dot./trailing dot.: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7feb_d20251107_m045429_c001_v0001116_t0051_u01762491269661") 2025/11/07 04:56:00 DEBUG : trailing space /trailing space : Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b78_d20251107_m045424_c001_v7007000_t0000_u01762491264153") as it is a hide marker 2025/11/07 04:56:01 DEBUG : trailing space /trailing space : Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fbd_d20251107_m045422_c001_v0001116_t0038_u01762491262950") 2025/11/07 04:56:01 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f1017043c92c1451e_d20251107_m045552_c001_v0001040_t0022_u01762491352220") "upload" dated 2025-11-07 04:55:52.22 +0000 UTC (8.917508606s ago) 2025/11/07 04:56:01 DEBUG : versions/test-versions.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f405cd22c891f8087_d20251107_m045547_c001_v7007000_t0000_u01762491347414") 2025/11/07 04:56:01 DEBUG : versions/test-versions.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f80d8_d20251107_m045544_c001_v0001116_t0053_u01762491344026") 2025/11/07 04:56:01 DEBUG : writer-at-subdir/writer-at-file: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814bbe_d20251107_m045443_c001_v7007000_t0000_u01762491283808") as it is a hide marker 2025/11/07 04:56:01 DEBUG : writer-at-subdir/writer-at-file: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f2216311f44814b8b_d20251107_m045435_c001_v0001144_t0026_u01762491275912") 2025/11/07 04:56:01 DEBUG : zero-length-file: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b88_d20251107_m045435_c001_v7007000_t0000_u01762491275584") as it is a hide marker 2025/11/07 04:56:01 DEBUG : zero-length-file: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f40512b66aa0f800a_d20251107_m045435_c001_v0001116_t0018_u01762491275022") 2025/11/07 04:56:01 DEBUG : ~leading tilde/~leading tilde: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b6d_d20251107_m045415_c001_v7007000_t0000_u01762491255970") as it is a hide marker 2025/11/07 04:56:01 DEBUG : ~leading tilde/~leading tilde: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fa4_d20251107_m045414_c001_v0001116_t0040_u01762491254939") 2025/11/07 04:56:01 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b66_d20251107_m045409_c001_v7007000_t0000_u01762491249242") as it is a hide marker 2025/11/07 04:56:01 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7f8c_d20251107_m045407_c001_v0001116_t0025_u01762491247901") 2025/11/07 04:56:01 DEBUG : ␉leading HT/␉leading HT: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b71_d20251107_m045420_c001_v7007000_t0000_u01762491260112") as it is a hide marker 2025/11/07 04:56:01 DEBUG : ␉leading HT/␉leading HT: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fac_d20251107_m045419_c001_v0001116_t0035_u01762491259263") 2025/11/07 04:56:01 DEBUG : ␊leading LF/␊leading LF: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b70_d20251107_m045418_c001_v7007000_t0000_u01762491258896") as it is a hide marker 2025/11/07 04:56:01 DEBUG : ␊leading LF/␊leading LF: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fa8_d20251107_m045417_c001_v0001116_t0030_u01762491257782") 2025/11/07 04:56:01 DEBUG : ␋leading VT/␋leading VT: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b74_d20251107_m045421_c001_v7007000_t0000_u01762491261296") as it is a hide marker 2025/11/07 04:56:01 DEBUG : ␋leading VT/␋leading VT: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fb0_d20251107_m045420_c001_v0001116_t0017_u01762491260479") 2025/11/07 04:56:01 DEBUG : ␍leading CR/␍leading CR: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b6e_d20251107_m045417_c001_v7007000_t0000_u01762491257415") as it is a hide marker 2025/11/07 04:56:01 DEBUG : ␍leading CR/␍leading CR: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7fa6_d20251107_m045416_c001_v0001116_t0033_u01762491256337") 2025/11/07 04:56:01 DEBUG : ．/．: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b67_d20251107_m045410_c001_v7007000_t0000_u01762491250442") as it is a hide marker 2025/11/07 04:56:02 DEBUG : ．/．: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7f90_d20251107_m045409_c001_v0001116_t0017_u01762491249607") 2025/11/07 04:56:02 DEBUG : ．．/．．: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f4216311f44814b6a_d20251107_m045411_c001_v7007000_t0000_u01762491251632") as it is a hide marker 2025/11/07 04:56:02 DEBUG : ．．/．．: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f7f96_d20251107_m045410_c001_v0001116_t0023_u01762491250807") === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished 2025/11/07 04:56:02 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/07 04:56:03 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/07 04:56:03 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/07 04:56:03 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/07 04:56:03 INFO : B2 bucket rclone-test-qomives5tofe: cleaning bucket "rclone-test-qomives5tofe" of pending multipart uploads older than 0s 2025/11/07 04:56:03 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f802e_d20251107_m045445_c001_v0001116_t0003_u01762491285112") "upload" dated 2025-11-07 04:54:45.112 +0000 UTC (1m18.852370443s ago) 2025/11/07 04:56:03 DEBUG : unfinished/unfinished-1: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f217ea52e047008c1_d20251107_m045602_c001_v0001180_t0001_u01762491362932") as it is a start marker (upload started at 2025-11-07 04:56:02.932 +0000 UTC) 2025/11/07 04:56:03 NOTICE: unfinished/unfinished-1: Skipped remove pending upload as --dry-run is set 2025/11/07 04:56:03 DEBUG : unfinished/unfinished-2: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f217ea52e047008c2_d20251107_m045603_c001_v0001181_t0034_u01762491363116") as it is a start marker (upload started at 2025-11-07 04:56:03.116 +0000 UTC) 2025/11/07 04:56:03 NOTICE: unfinished/unfinished-2: Skipped remove pending upload as --dry-run is set 2025/11/07 04:56:03 DEBUG : unfinished/unfinished-3: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f217ea52e047008c3_d20251107_m045603_c001_v0001180_t0003_u01762491363300") as it is a start marker (upload started at 2025-11-07 04:56:03.3 +0000 UTC) 2025/11/07 04:56:03 NOTICE: unfinished/unfinished-3: Skipped remove pending upload as --dry-run is set 2025/11/07 04:56:03 DEBUG : unfinished/unfinished-4: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f217ea52e047008c4_d20251107_m045603_c001_v0001130_t0008_u01762491363486") as it is a start marker (upload started at 2025-11-07 04:56:03.486 +0000 UTC) 2025/11/07 04:56:03 NOTICE: unfinished/unfinished-4: Skipped remove pending upload as --dry-run is set 2025/11/07 04:56:03 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f417ea52e047008c0_d20251107_m045602_c001_v7007000_t0000_u01762491362747") "hide" dated 2025-11-07 04:56:02.747 +0000 UTC (1.217542667s ago) 2025/11/07 04:56:03 DEBUG : versions/test-versions.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f1017043c92c1451e_d20251107_m045552_c001_v0001040_t0022_u01762491352220") 2025/11/07 04:56:03 NOTICE: versions/test-versions.txt: Skipped delete as --dry-run is set === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished/RealThing 2025/11/07 04:56:04 INFO : B2 bucket rclone-test-qomives5tofe: cleaning bucket "rclone-test-qomives5tofe" of pending multipart uploads older than 0s 2025/11/07 04:56:04 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f802e_d20251107_m045445_c001_v0001116_t0003_u01762491285112") "upload" dated 2025-11-07 04:54:45.112 +0000 UTC (1m19.221891078s ago) 2025/11/07 04:56:04 DEBUG : unfinished/unfinished-1: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f217ea52e047008c1_d20251107_m045602_c001_v0001180_t0001_u01762491362932") as it is a start marker (upload started at 2025-11-07 04:56:02.932 +0000 UTC) 2025/11/07 04:56:04 DEBUG : unfinished/unfinished-2: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f217ea52e047008c2_d20251107_m045603_c001_v0001181_t0034_u01762491363116") as it is a start marker (upload started at 2025-11-07 04:56:03.116 +0000 UTC) 2025/11/07 04:56:04 DEBUG : unfinished/unfinished-3: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f217ea52e047008c3_d20251107_m045603_c001_v0001180_t0003_u01762491363300") as it is a start marker (upload started at 2025-11-07 04:56:03.3 +0000 UTC) 2025/11/07 04:56:04 DEBUG : unfinished/unfinished-4: Deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f217ea52e047008c4_d20251107_m045603_c001_v0001130_t0008_u01762491363486") as it is a start marker (upload started at 2025-11-07 04:56:03.486 +0000 UTC) 2025/11/07 04:56:04 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z5f522d23a0ad5bcb9fa80e1b_f417ea52e047008c0_d20251107_m045602_c001_v7007000_t0000_u01762491362747") "hide" dated 2025-11-07 04:56:02.747 +0000 UTC (1.58703075s ago) 2025/11/07 04:56:04 DEBUG : versions/test-versions.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f1017043c92c1451e_d20251107_m045552_c001_v0001040_t0022_u01762491352220") === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/InitState === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/DryRun 2025/11/07 04:56:05 NOTICE: lifecycle: Skipped update lifecycle rules as --dry-run is set 2025/11/07 04:56:05 NOTICE: lifecycle: Skipped update lifecycle rules as --dry-run is set 2025/11/07 04:56:05 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/07 04:56:06 DEBUG : chunked-5Mi-5.000Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/5.000Mi 2025/11/07 04:56:06 DEBUG : chunked-5Mi-5.000Mi.bin: Sending chunk 0 length 5242880 2025/11/07 04:56:06 DEBUG : chunked-5Mi-5.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 5Mi/5.000Mi 2025/11/07 04:56:06 DEBUG : chunked-5Mi-5.000Mi.bin: Sending chunk 1 length 1 2025/11/07 04:56:07 DEBUG : chunked-5Mi-5.000Mi.bin: Done sending chunk 1 2025/11/07 04:56:09 DEBUG : chunked-5Mi-5.000Mi.bin: Done sending chunk 0 2025/11/07 04:56:09 DEBUG : chunked-5Mi-5.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881/Streamed 2025/11/07 04:56:12 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:56:12 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/07 04:56:12 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f2162881cf412b9e1_d20251107_m045612_c001_v0001032_t0035_u01762491372093") 2025/11/07 04:56:12 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/07 04:56:12 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 1 length 1 2025/11/07 04:56:12 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/11/07 04:56:12 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0011c6ea0), retryAfter:1000000000} 2025/11/07 04:56:12 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:12 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:12 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:12 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 1 length 1 2025/11/07 04:56:12 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00128edb0), retryAfter:1000000000} 2025/11/07 04:56:12 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:12 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:14 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:14 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0011c76b0), retryAfter:1000000000} 2025/11/07 04:56:14 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:14 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:14 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:14 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/11/07 04:56:15 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 1 length 1 2025/11/07 04:56:17 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:17 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc001214030), retryAfter:1000000000} 2025/11/07 04:56:17 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:17 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:17 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:18 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:18 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0012143c0), retryAfter:1000000000} 2025/11/07 04:56:18 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:18 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:18 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:18 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/11/07 04:56:19 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 1 length 1 2025/11/07 04:56:21 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:21 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00128ee10), retryAfter:1000000000} 2025/11/07 04:56:21 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:21 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:21 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:22 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:22 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00128f4a0), retryAfter:1000000000} 2025/11/07 04:56:22 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:22 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:22 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:22 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/11/07 04:56:23 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 1 length 1 2025/11/07 04:56:25 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:25 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0011c6bd0), retryAfter:1000000000} 2025/11/07 04:56:25 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:25 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:25 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:26 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:26 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00128fda0), retryAfter:1000000000} 2025/11/07 04:56:26 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:26 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:26 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:26 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/11/07 04:56:27 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 1 length 1 2025/11/07 04:56:29 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:29 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0011c7b00), retryAfter:1000000000} 2025/11/07 04:56:29 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:29 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:29 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:30 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:30 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc001214b10), retryAfter:1000000000} 2025/11/07 04:56:30 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:30 DEBUG : pacer: low level retry 6/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:30 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:30 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/11/07 04:56:31 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 1 length 1 2025/11/07 04:56:33 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:33 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0000dd8f0), retryAfter:1000000000} 2025/11/07 04:56:33 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:33 DEBUG : pacer: low level retry 6/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:33 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:34 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:34 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0000ddec0), retryAfter:1000000000} 2025/11/07 04:56:34 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:34 DEBUG : pacer: low level retry 7/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:34 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:34 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/11/07 04:56:35 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 1 length 1 2025/11/07 04:56:37 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:37 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0016a6cc0), retryAfter:1000000000} 2025/11/07 04:56:37 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:37 DEBUG : pacer: low level retry 7/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:37 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:38 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:38 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0000e4fc0), retryAfter:1000000000} 2025/11/07 04:56:38 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:38 DEBUG : pacer: low level retry 8/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:38 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:38 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/11/07 04:56:39 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 1 length 1 2025/11/07 04:56:41 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:41 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0016a6fc0), retryAfter:1000000000} 2025/11/07 04:56:41 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:41 DEBUG : pacer: low level retry 8/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:41 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:42 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:42 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00029cd80), retryAfter:1000000000} 2025/11/07 04:56:42 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:42 DEBUG : pacer: low level retry 9/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:42 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:42 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/11/07 04:56:43 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 1 length 1 2025/11/07 04:56:45 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:45 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00029d0e0), retryAfter:1000000000} 2025/11/07 04:56:45 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:45 DEBUG : pacer: low level retry 9/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:45 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:46 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:56:46 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00029d350), retryAfter:1000000000} 2025/11/07 04:56:46 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:46 DEBUG : pacer: low level retry 10/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:56:46 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:56:46 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 1: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:56:46 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/11/07 04:56:47 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Error sending chunk 0: 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/07 04:56:47 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Cancelling large file upload 2025/11/07 04:56:49 DEBUG : pacer: Reducing sleep to 500ms fstests.go:143: PutLarge error: no tomes available (503 service_unavailable): trying again in 1s - low level retry 1/10 2025/11/07 04:56:51 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:56:51 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/07 04:56:51 DEBUG : pacer: Reducing sleep to 250ms 2025/11/07 04:56:51 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f202687500d788793_d20251107_m045651_c001_v0001115_t0057_u01762491411432") 2025/11/07 04:56:51 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/07 04:56:51 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 1 length 1 2025/11/07 04:56:52 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/11/07 04:56:52 DEBUG : pacer: Reducing sleep to 125ms 2025/11/07 04:56:52 DEBUG : pacer: Reducing sleep to 62.5ms 2025/11/07 04:56:53 DEBUG : pacer: Reducing sleep to 31.25ms 2025/11/07 04:56:53 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Done sending chunk 1 2025/11/07 04:56:55 DEBUG : pacer: Reducing sleep to 15.625ms 2025/11/07 04:56:55 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Done sending chunk 0 2025/11/07 04:56:55 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Finishing large file upload with 2 parts 2025/11/07 04:56:55 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760 2025/11/07 04:56:58 DEBUG : chunked-5Mi-10Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/10Mi 2025/11/07 04:56:58 DEBUG : chunked-5Mi-10Mi.bin: Sending chunk 0 length 5242880 2025/11/07 04:56:58 DEBUG : chunked-5Mi-10Mi.bin: multipart upload: starting chunk 1 size 5Mi offset 5Mi/10Mi 2025/11/07 04:56:58 DEBUG : chunked-5Mi-10Mi.bin: Sending chunk 1 length 5242880 2025/11/07 04:57:10 DEBUG : chunked-5Mi-10Mi.bin: Done sending chunk 1 2025/11/07 04:57:10 DEBUG : chunked-5Mi-10Mi.bin: Done sending chunk 0 2025/11/07 04:57:10 DEBUG : chunked-5Mi-10Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760/Streamed 2025/11/07 04:57:13 DEBUG : chunked-5Mi-10Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:57:13 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/07 04:57:14 DEBUG : chunked-5Mi-10Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f218bef918b7b9eed_d20251107_m045713_c001_v0001116_t0036_u01762491433908") 2025/11/07 04:57:14 DEBUG : chunked-5Mi-10Mi-streamed.bin: Sending chunk 0 length 5242880 2025/11/07 04:57:14 DEBUG : chunked-5Mi-10Mi-streamed.bin: Sending chunk 1 length 5242880 2025/11/07 04:57:14 DEBUG : chunked-5Mi-10Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/11/07 04:57:16 DEBUG : chunked-5Mi-10Mi-streamed.bin: Done sending chunk 0 2025/11/07 04:57:16 DEBUG : chunked-5Mi-10Mi-streamed.bin: Done sending chunk 1 2025/11/07 04:57:16 DEBUG : chunked-5Mi-10Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761 2025/11/07 04:57:18 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/10.000Mi 2025/11/07 04:57:18 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 0 length 5242880 2025/11/07 04:57:18 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 1 size 5Mi offset 5Mi/10.000Mi 2025/11/07 04:57:18 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 1 length 5242880 2025/11/07 04:57:18 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 10Mi/10.000Mi 2025/11/07 04:57:18 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 2 length 1 2025/11/07 04:57:20 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 2 2025/11/07 04:57:20 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 0 2025/11/07 04:57:21 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 1 2025/11/07 04:57:21 DEBUG : chunked-5Mi-10.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761/Streamed 2025/11/07 04:57:22 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:57:22 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/07 04:57:22 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f208fa5ef1da64476_d20251107_m045722_c001_v0001113_t0025_u01762491442864") 2025/11/07 04:57:22 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/11/07 04:57:23 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/07 04:57:23 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 2 length 1 2025/11/07 04:57:23 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 1 length 5242880 2025/11/07 04:57:24 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 2 2025/11/07 04:57:25 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 0 2025/11/07 04:57:25 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 1 2025/11/07 04:57:25 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/07 04:57:27 DEBUG : chunked-5.054Mi-5.054Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/5.054Mi 2025/11/07 04:57:27 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 0 length 5300000 2025/11/07 04:57:27 DEBUG : chunked-5.054Mi-5.054Mi.bin: multipart upload: starting chunk 1 size 1 offset 5.054Mi/5.054Mi 2025/11/07 04:57:27 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 1 length 1 2025/11/07 04:57:28 DEBUG : chunked-5.054Mi-5.054Mi.bin: Done sending chunk 1 2025/11/07 04:57:29 DEBUG : chunked-5.054Mi-5.054Mi.bin: Done sending chunk 0 2025/11/07 04:57:29 DEBUG : chunked-5.054Mi-5.054Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001/Streamed 2025/11/07 04:57:30 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:57:30 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/07 04:57:30 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f218bef918b7b9f3f_d20251107_m045730_c001_v0001150_t0038_u01762491450864") 2025/11/07 04:57:30 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/07 04:57:30 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Sending chunk 1 length 1 2025/11/07 04:57:30 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Sending chunk 0 length 5300000 2025/11/07 04:57:31 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Done sending chunk 1 2025/11/07 04:57:33 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Done sending chunk 0 2025/11/07 04:57:33 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000 2025/11/07 04:57:34 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/10.109Mi 2025/11/07 04:57:34 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 0 length 5300000 2025/11/07 04:57:34 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.054Mi offset 5.054Mi/10.109Mi 2025/11/07 04:57:34 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 1 length 5300000 2025/11/07 04:57:37 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 0 2025/11/07 04:57:37 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 1 2025/11/07 04:57:37 DEBUG : chunked-5.054Mi-10.109Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000/Streamed 2025/11/07 04:57:39 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:57:39 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/07 04:57:39 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f208fa5ef1da6447f_d20251107_m045739_c001_v0001146_t0010_u01762491459205") 2025/11/07 04:57:39 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300000 2025/11/07 04:57:39 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/11/07 04:57:39 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300000 2025/11/07 04:57:41 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/11/07 04:57:41 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/11/07 04:57:41 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001 2025/11/07 04:57:43 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/10.109Mi 2025/11/07 04:57:43 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 0 length 5300000 2025/11/07 04:57:43 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.054Mi offset 5.054Mi/10.109Mi 2025/11/07 04:57:43 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 1 length 5300000 2025/11/07 04:57:43 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 2 size 1 offset 10.109Mi/10.109Mi 2025/11/07 04:57:43 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 2 length 1 2025/11/07 04:57:44 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 2 2025/11/07 04:57:45 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 0 2025/11/07 04:57:46 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 1 2025/11/07 04:57:46 DEBUG : chunked-5.054Mi-10.109Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001/Streamed 2025/11/07 04:57:47 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:57:47 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/07 04:57:47 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f203872197e0d1573_d20251107_m045747_c001_v0001136_t0024_u01762491467751") 2025/11/07 04:57:47 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300000 2025/11/07 04:57:48 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/07 04:57:48 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 2 length 1 2025/11/07 04:57:48 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300000 2025/11/07 04:57:48 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 2 2025/11/07 04:57:50 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/11/07 04:57:50 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/11/07 04:57:50 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/07 04:57:52 DEBUG : chunked-5.055Mi-5.055Mi.bin: multipart upload: starting chunk 1 size 1 offset 5.055Mi/5.055Mi 2025/11/07 04:57:52 DEBUG : chunked-5.055Mi-5.055Mi.bin: Sending chunk 1 length 1 2025/11/07 04:57:52 DEBUG : chunked-5.055Mi-5.055Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/5.055Mi 2025/11/07 04:57:52 DEBUG : chunked-5.055Mi-5.055Mi.bin: Sending chunk 0 length 5300053 2025/11/07 04:57:53 DEBUG : chunked-5.055Mi-5.055Mi.bin: Done sending chunk 1 2025/11/07 04:57:54 DEBUG : chunked-5.055Mi-5.055Mi.bin: Done sending chunk 0 2025/11/07 04:57:54 DEBUG : chunked-5.055Mi-5.055Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054/Streamed 2025/11/07 04:57:55 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:57:55 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/07 04:57:55 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f203872197e0d1577_d20251107_m045755_c001_v0001090_t0005_u01762491475837") 2025/11/07 04:57:55 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/07 04:57:55 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 1 length 1 2025/11/07 04:57:55 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/07 04:57:57 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Done sending chunk 1 2025/11/07 04:57:58 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Done sending chunk 0 2025/11/07 04:57:58 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106 2025/11/07 04:57:59 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/10.109Mi 2025/11/07 04:57:59 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 0 length 5300053 2025/11/07 04:57:59 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.055Mi offset 5.055Mi/10.109Mi 2025/11/07 04:57:59 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 1 length 5300053 2025/11/07 04:58:02 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 0 2025/11/07 04:58:02 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 1 2025/11/07 04:58:02 DEBUG : chunked-5.055Mi-10.109Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106/Streamed 2025/11/07 04:58:04 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:58:04 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/07 04:58:04 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f208fa5ef1da64489_d20251107_m045804_c001_v0001044_t0010_u01762491484202") 2025/11/07 04:58:04 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/07 04:58:04 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/11/07 04:58:04 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/11/07 04:58:06 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/11/07 04:58:07 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/11/07 04:58:07 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107 2025/11/07 04:58:08 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/10.109Mi 2025/11/07 04:58:08 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 0 length 5300053 2025/11/07 04:58:09 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.055Mi offset 5.055Mi/10.109Mi 2025/11/07 04:58:09 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 1 length 5300053 2025/11/07 04:58:09 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 2 size 1 offset 10.109Mi/10.109Mi 2025/11/07 04:58:09 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 2 length 1 2025/11/07 04:58:10 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 2 2025/11/07 04:58:11 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 0 2025/11/07 04:58:11 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 1 2025/11/07 04:58:11 DEBUG : chunked-5.055Mi-10.109Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107/Streamed 2025/11/07 04:58:13 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:58:13 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/07 04:58:13 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f208fa5ef1da6448c_d20251107_m045813_c001_v0001043_t0000_u01762491493476") 2025/11/07 04:58:13 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/11/07 04:58:13 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/07 04:58:13 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 2 length 1 2025/11/07 04:58:13 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/11/07 04:58:14 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 2 2025/11/07 04:58:16 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/11/07 04:58:16 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/11/07 04:58:16 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/07 04:58:17 DEBUG : chunked-6.667Mi-6.667Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/6.667Mi 2025/11/07 04:58:17 DEBUG : chunked-6.667Mi-6.667Mi.bin: Sending chunk 0 length 6990506 2025/11/07 04:58:17 DEBUG : chunked-6.667Mi-6.667Mi.bin: multipart upload: starting chunk 1 size 1 offset 6.667Mi/6.667Mi 2025/11/07 04:58:18 DEBUG : chunked-6.667Mi-6.667Mi.bin: Sending chunk 1 length 1 2025/11/07 04:58:18 DEBUG : chunked-6.667Mi-6.667Mi.bin: Done sending chunk 1 2025/11/07 04:58:20 DEBUG : chunked-6.667Mi-6.667Mi.bin: Done sending chunk 0 2025/11/07 04:58:20 DEBUG : chunked-6.667Mi-6.667Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507/Streamed 2025/11/07 04:58:21 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:58:21 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/07 04:58:22 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f208fa5ef1da6448e_d20251107_m045821_c001_v0001037_t0005_u01762491501932") 2025/11/07 04:58:22 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/07 04:58:22 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 1 length 1 2025/11/07 04:58:22 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/07 04:58:22 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Done sending chunk 1 2025/11/07 04:58:24 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Done sending chunk 0 2025/11/07 04:58:24 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012 2025/11/07 04:58:26 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/13.333Mi 2025/11/07 04:58:26 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2025/11/07 04:58:26 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 1 size 6.667Mi offset 6.667Mi/13.333Mi 2025/11/07 04:58:26 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2025/11/07 04:58:28 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 0 2025/11/07 04:58:29 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 1 2025/11/07 04:58:29 DEBUG : chunked-6.667Mi-13.333Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012/Streamed 2025/11/07 04:58:30 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:58:30 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/07 04:58:31 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f21587366531465e1_d20251107_m045831_c001_v0001032_t0036_u01762491511075") 2025/11/07 04:58:31 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/07 04:58:31 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/11/07 04:58:31 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/11/07 04:58:31 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0011c79e0), retryAfter:1000000000} 2025/11/07 04:58:31 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:58:31 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:58:31 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:58:31 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/07 04:58:32 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0011c7b60), retryAfter:1000000000} 2025/11/07 04:58:32 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:58:32 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:58:33 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:58:33 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00128f470), retryAfter:1000000000} 2025/11/07 04:58:33 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:58:33 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:58:33 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:58:33 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/11/07 04:58:34 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/07 04:58:36 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:58:36 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc001154c00), retryAfter:1000000000} 2025/11/07 04:58:36 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:58:36 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:58:36 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:58:37 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:58:37 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc001154f90), retryAfter:1000000000} 2025/11/07 04:58:37 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:58:37 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:58:37 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:58:37 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/11/07 04:58:38 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/07 04:58:40 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:58:40 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000364f00), retryAfter:1000000000} 2025/11/07 04:58:40 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:58:40 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:58:40 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:58:41 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:58:41 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0000e5980), retryAfter:1000000000} 2025/11/07 04:58:41 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:58:41 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:58:41 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:58:41 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/11/07 04:58:42 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/07 04:58:44 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:58:44 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000398510), retryAfter:1000000000} 2025/11/07 04:58:44 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:58:44 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:58:44 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:58:45 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:58:45 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000398ff0), retryAfter:1000000000} 2025/11/07 04:58:45 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:58:45 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:58:45 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:58:45 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/11/07 04:58:46 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/07 04:58:48 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:58:48 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00029d4a0), retryAfter:1000000000} 2025/11/07 04:58:48 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:58:48 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:58:48 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:58:49 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:58:49 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0016a6ab0), retryAfter:1000000000} 2025/11/07 04:58:49 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:58:49 DEBUG : pacer: low level retry 6/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:58:49 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:58:49 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/11/07 04:58:50 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/07 04:58:52 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:58:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00025d3e0), retryAfter:1000000000} 2025/11/07 04:58:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:58:52 DEBUG : pacer: low level retry 6/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:58:52 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:58:53 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:58:53 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00025d710), retryAfter:1000000000} 2025/11/07 04:58:53 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:58:53 DEBUG : pacer: low level retry 7/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:58:53 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:58:53 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/11/07 04:58:54 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/07 04:58:56 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:58:56 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000399ec0), retryAfter:1000000000} 2025/11/07 04:58:56 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:58:56 DEBUG : pacer: low level retry 7/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:58:56 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:58:57 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:58:57 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc001155980), retryAfter:1000000000} 2025/11/07 04:58:57 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:58:57 DEBUG : pacer: low level retry 8/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:58:57 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:58:57 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/11/07 04:58:58 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/07 04:59:00 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:59:00 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000352000), retryAfter:1000000000} 2025/11/07 04:59:00 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:59:00 DEBUG : pacer: low level retry 8/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:59:00 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:59:01 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:59:01 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00025c6c0), retryAfter:1000000000} 2025/11/07 04:59:01 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:59:01 DEBUG : pacer: low level retry 9/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:59:01 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:59:01 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/11/07 04:59:02 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/07 04:59:04 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:59:04 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0000e3020), retryAfter:1000000000} 2025/11/07 04:59:04 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:59:04 DEBUG : pacer: low level retry 9/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:59:04 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:59:05 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 04:59:05 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0011c6060), retryAfter:1000000000} 2025/11/07 04:59:05 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:59:05 DEBUG : pacer: low level retry 10/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 04:59:05 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 04:59:05 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Error sending chunk 0: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 04:59:05 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/11/07 04:59:06 DEBUG : chunked-6.667Mi-13.333Mi-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/07 04:59:06 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Cancelling large file upload 2025/11/07 04:59:08 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/07 04:59:10 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:59:10 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/07 04:59:10 DEBUG : pacer: Reducing sleep to 250ms 2025/11/07 04:59:10 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f21587366531465eb_d20251107_m045910_c001_v0001134_t0015_u01762491550470") 2025/11/07 04:59:10 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/11/07 04:59:10 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/07 04:59:11 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/11/07 04:59:11 DEBUG : pacer: Reducing sleep to 125ms 2025/11/07 04:59:11 DEBUG : pacer: Reducing sleep to 62.5ms 2025/11/07 04:59:13 DEBUG : pacer: Reducing sleep to 31.25ms 2025/11/07 04:59:13 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 0 2025/11/07 04:59:14 DEBUG : pacer: Reducing sleep to 15.625ms 2025/11/07 04:59:14 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 1 2025/11/07 04:59:14 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Finishing large file upload with 2 parts 2025/11/07 04:59:14 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013 2025/11/07 04:59:17 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/13.333Mi 2025/11/07 04:59:17 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2025/11/07 04:59:17 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 1 size 6.667Mi offset 6.667Mi/13.333Mi 2025/11/07 04:59:17 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2025/11/07 04:59:17 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 2 size 1 offset 13.333Mi/13.333Mi 2025/11/07 04:59:18 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 1 2025/11/07 04:59:19 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 2 2025/11/07 04:59:20 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 0 2025/11/07 04:59:20 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 1 2025/11/07 04:59:20 DEBUG : chunked-6.667Mi-13.333Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013/Streamed 2025/11/07 04:59:22 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:59:22 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/07 04:59:22 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f207ae72636de4bbe_d20251107_m045922_c001_v0001120_t0036_u01762491562432") 2025/11/07 04:59:22 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/11/07 04:59:22 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/07 04:59:22 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/11/07 04:59:22 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 2 length 1 2025/11/07 04:59:23 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 2 2025/11/07 04:59:25 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 0 2025/11/07 04:59:25 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 1 2025/11/07 04:59:25 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609 2025/11/07 04:59:27 DEBUG : chunked-8Mi-8.000Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/8.000Mi 2025/11/07 04:59:27 DEBUG : chunked-8Mi-8.000Mi.bin: Sending chunk 0 length 8388608 2025/11/07 04:59:28 DEBUG : chunked-8Mi-8.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 8Mi/8.000Mi 2025/11/07 04:59:28 DEBUG : chunked-8Mi-8.000Mi.bin: Sending chunk 1 length 1 2025/11/07 04:59:28 DEBUG : chunked-8Mi-8.000Mi.bin: Done sending chunk 1 2025/11/07 04:59:30 DEBUG : chunked-8Mi-8.000Mi.bin: Done sending chunk 0 2025/11/07 04:59:30 DEBUG : chunked-8Mi-8.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609/Streamed 2025/11/07 04:59:32 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:59:32 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/07 04:59:32 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f214136f5a5518e71_d20251107_m045932_c001_v0001130_t0002_u01762491572170") 2025/11/07 04:59:32 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/07 04:59:32 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Sending chunk 1 length 1 2025/11/07 04:59:32 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Sending chunk 0 length 8388608 2025/11/07 04:59:33 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Done sending chunk 1 2025/11/07 04:59:34 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Done sending chunk 0 2025/11/07 04:59:34 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216 2025/11/07 04:59:36 DEBUG : chunked-8Mi-16Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/16Mi 2025/11/07 04:59:36 DEBUG : chunked-8Mi-16Mi.bin: Sending chunk 0 length 8388608 2025/11/07 04:59:36 DEBUG : chunked-8Mi-16Mi.bin: multipart upload: starting chunk 1 size 8Mi offset 8Mi/16Mi 2025/11/07 04:59:36 DEBUG : chunked-8Mi-16Mi.bin: Sending chunk 1 length 8388608 2025/11/07 04:59:39 DEBUG : chunked-8Mi-16Mi.bin: Done sending chunk 0 2025/11/07 04:59:39 DEBUG : chunked-8Mi-16Mi.bin: Done sending chunk 1 2025/11/07 04:59:39 DEBUG : chunked-8Mi-16Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216/Streamed 2025/11/07 04:59:41 DEBUG : chunked-8Mi-16Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:59:41 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/07 04:59:41 DEBUG : chunked-8Mi-16Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f215873665314663c_d20251107_m045941_c001_v0001184_t0045_u01762491581488") 2025/11/07 04:59:41 DEBUG : chunked-8Mi-16Mi-streamed.bin: Sending chunk 0 length 8388608 2025/11/07 04:59:41 DEBUG : chunked-8Mi-16Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/11/07 04:59:41 DEBUG : chunked-8Mi-16Mi-streamed.bin: Sending chunk 1 length 8388608 2025/11/07 04:59:44 DEBUG : chunked-8Mi-16Mi-streamed.bin: Done sending chunk 0 2025/11/07 04:59:44 DEBUG : chunked-8Mi-16Mi-streamed.bin: Done sending chunk 1 2025/11/07 04:59:44 DEBUG : chunked-8Mi-16Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217 2025/11/07 04:59:46 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/16.000Mi 2025/11/07 04:59:46 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 0 length 8388608 2025/11/07 04:59:46 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 1 size 8Mi offset 8Mi/16.000Mi 2025/11/07 04:59:46 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 1 length 8388608 2025/11/07 04:59:46 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 16Mi/16.000Mi 2025/11/07 04:59:46 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 2 length 1 2025/11/07 04:59:48 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 2 2025/11/07 04:59:49 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 0 2025/11/07 04:59:49 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 1 2025/11/07 04:59:49 DEBUG : chunked-8Mi-16.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217/Streamed 2025/11/07 04:59:52 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 04:59:52 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/07 04:59:52 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f215873665314666a_d20251107_m045952_c001_v0001037_t0055_u01762491592355") 2025/11/07 04:59:52 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 0 length 8388608 2025/11/07 04:59:52 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/07 04:59:52 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 1 length 8388608 2025/11/07 04:59:52 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 2 length 1 2025/11/07 04:59:53 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 2 2025/11/07 04:59:55 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 0 2025/11/07 04:59:55 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 1 2025/11/07 04:59:55 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/07 04:59:57 DEBUG : chunked-10Mi-10.000Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/10.000Mi 2025/11/07 04:59:57 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/11/07 04:59:57 DEBUG : chunked-10Mi-10.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 10Mi/10.000Mi 2025/11/07 04:59:58 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/11/07 04:59:58 DEBUG : chunked-10Mi-10.000Mi.bin: Done sending chunk 1 2025/11/07 05:00:00 DEBUG : chunked-10Mi-10.000Mi.bin: Done sending chunk 0 2025/11/07 05:00:00 DEBUG : chunked-10Mi-10.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761/Streamed 2025/11/07 05:00:02 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 05:00:02 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/07 05:00:02 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f20108f13c6ec6bc9_d20251107_m050002_c001_v0001099_t0057_u01762491602736") 2025/11/07 05:00:02 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/07 05:00:02 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Sending chunk 1 length 1 2025/11/07 05:00:02 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Sending chunk 0 length 10485760 2025/11/07 05:00:03 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Done sending chunk 1 2025/11/07 05:00:05 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Done sending chunk 0 2025/11/07 05:00:05 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520 2025/11/07 05:00:07 DEBUG : chunked-10Mi-20Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/20Mi 2025/11/07 05:00:07 DEBUG : chunked-10Mi-20Mi.bin: Sending chunk 0 length 10485760 2025/11/07 05:00:08 DEBUG : chunked-10Mi-20Mi.bin: multipart upload: starting chunk 1 size 10Mi offset 10Mi/20Mi 2025/11/07 05:00:08 DEBUG : chunked-10Mi-20Mi.bin: Sending chunk 1 length 10485760 2025/11/07 05:00:10 DEBUG : chunked-10Mi-20Mi.bin: Done sending chunk 0 2025/11/07 05:00:10 DEBUG : chunked-10Mi-20Mi.bin: Done sending chunk 1 2025/11/07 05:00:10 DEBUG : chunked-10Mi-20Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520/Streamed 2025/11/07 05:00:13 DEBUG : chunked-10Mi-20Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 05:00:13 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/07 05:00:13 DEBUG : chunked-10Mi-20Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f2162881cf412bc9a_d20251107_m050013_c001_v0001115_t0020_u01762491613493") 2025/11/07 05:00:13 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 0 length 10485760 2025/11/07 05:00:14 DEBUG : chunked-10Mi-20Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/11/07 05:00:14 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 1 length 10485760 2025/11/07 05:00:16 DEBUG : chunked-10Mi-20Mi-streamed.bin: Done sending chunk 0 2025/11/07 05:00:17 DEBUG : chunked-10Mi-20Mi-streamed.bin: Done sending chunk 1 2025/11/07 05:00:17 DEBUG : chunked-10Mi-20Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521 2025/11/07 05:00:19 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/20.000Mi 2025/11/07 05:00:19 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 0 length 10485760 2025/11/07 05:00:20 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 1 size 10Mi offset 10Mi/20.000Mi 2025/11/07 05:00:20 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 1 length 10485760 2025/11/07 05:00:20 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 20Mi/20.000Mi 2025/11/07 05:00:20 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 2 length 1 2025/11/07 05:00:21 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 2 2025/11/07 05:00:22 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 0 2025/11/07 05:00:22 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 1 2025/11/07 05:00:22 DEBUG : chunked-10Mi-20.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521/Streamed 2025/11/07 05:00:25 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: File is big enough for chunked streaming 2025/11/07 05:00:25 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/07 05:00:25 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Starting streaming of large file (id "4_z5f522d23a0ad5bcb9fa80e1b_f2162881cf412bd10_d20251107_m050025_c001_v0001136_t0058_u01762491625409") 2025/11/07 05:00:25 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 0 length 10485760 2025/11/07 05:00:25 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/11/07 05:00:25 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 2 length 1 2025/11/07 05:00:25 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 1 length 10485760 2025/11/07 05:00:26 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 2 2025/11/07 05:00:28 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 1 2025/11/07 05:00:32 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 0 2025/11/07 05:00:32 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/07 05:00:42 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_z5f522d23a0ad5bcb9fa80e1b_f220ad3cb3292e04e_d20251107_m050042_c001_v0001120_t0022_u01762491642696") 2025/11/07 05:00:42 DEBUG : chunked-copy.copy: Copying chunk 1 length 1 2025/11/07 05:00:42 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/11/07 05:00:43 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/11/07 05:00:43 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/11/07 05:00:43 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485759 2025/11/07 05:00:45 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10.000Mi 2025/11/07 05:00:45 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/11/07 05:00:45 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4.000Mi offset 6Mi/10.000Mi 2025/11/07 05:00:45 DEBUG : chunked-copy: Sending chunk 1 length 4194303 2025/11/07 05:00:48 DEBUG : chunked-copy: Done sending chunk 0 2025/11/07 05:00:48 DEBUG : chunked-copy: Done sending chunk 1 2025/11/07 05:00:48 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2025/11/07 05:00:48 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_z5f522d23a0ad5bcb9fa80e1b_f220ad3cb3292e05b_d20251107_m050048_c001_v0001104_t0049_u01762491648785") 2025/11/07 05:00:48 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242879 2025/11/07 05:00:48 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/11/07 05:00:49 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/11/07 05:00:49 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/11/07 05:00:49 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485760 2025/11/07 05:00:51 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10Mi 2025/11/07 05:00:51 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/11/07 05:00:51 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4Mi offset 6Mi/10Mi 2025/11/07 05:00:51 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/11/07 05:00:52 DEBUG : chunked-copy: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000399560), retryAfter:1000000000} 2025/11/07 05:00:52 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:00:52 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:00:52 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:00:52 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/11/07 05:00:52 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00029c8d0), retryAfter:1000000000} 2025/11/07 05:00:52 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:00:52 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:00:53 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:00:54 DEBUG : chunked-copy: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00025c270), retryAfter:1000000000} 2025/11/07 05:00:54 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:00:54 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:00:54 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:00:54 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/11/07 05:00:55 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/11/07 05:00:56 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:00:57 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0011c7290), retryAfter:1000000000} 2025/11/07 05:00:57 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:00:57 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:00:57 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:00:57 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:00:58 DEBUG : chunked-copy: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0011c7980), retryAfter:1000000000} 2025/11/07 05:00:58 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:00:58 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:00:58 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:00:58 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/11/07 05:00:59 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/11/07 05:01:00 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:01:01 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0000dc750), retryAfter:1000000000} 2025/11/07 05:01:01 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:01:01 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:01:01 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:01:01 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:01:02 DEBUG : chunked-copy: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0000dcb70), retryAfter:1000000000} 2025/11/07 05:01:02 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:01:02 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:01:02 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:01:02 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/11/07 05:01:03 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/11/07 05:01:04 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:01:05 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00034a6f0), retryAfter:1000000000} 2025/11/07 05:01:05 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:01:05 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:01:05 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:01:05 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:01:06 DEBUG : chunked-copy: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00128f0e0), retryAfter:1000000000} 2025/11/07 05:01:06 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:01:06 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:01:06 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:01:06 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/11/07 05:01:07 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/11/07 05:01:08 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:01:09 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00034b3e0), retryAfter:1000000000} 2025/11/07 05:01:09 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:01:09 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:01:09 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:01:09 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:01:10 DEBUG : chunked-copy: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00128fe00), retryAfter:1000000000} 2025/11/07 05:01:10 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:01:10 DEBUG : pacer: low level retry 6/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:01:10 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:01:10 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/11/07 05:01:11 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/11/07 05:01:12 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:01:13 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc00034b8f0), retryAfter:1000000000} 2025/11/07 05:01:13 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:01:13 DEBUG : pacer: low level retry 6/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:01:13 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:01:13 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:01:14 DEBUG : chunked-copy: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc000352d50), retryAfter:1000000000} 2025/11/07 05:01:14 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:01:14 DEBUG : pacer: low level retry 7/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:01:14 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:01:14 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/11/07 05:01:15 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/11/07 05:01:16 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:01:17 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc001154000), retryAfter:1000000000} 2025/11/07 05:01:17 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:01:17 DEBUG : pacer: low level retry 7/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:01:17 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:01:17 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:01:18 DEBUG : chunked-copy: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0000e3140), retryAfter:1000000000} 2025/11/07 05:01:18 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:01:18 DEBUG : pacer: low level retry 8/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:01:18 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:01:18 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/11/07 05:01:19 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/11/07 05:01:20 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:01:21 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0000e3500), retryAfter:1000000000} 2025/11/07 05:01:21 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:01:21 DEBUG : pacer: low level retry 8/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:01:21 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:01:21 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:01:22 DEBUG : chunked-copy: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc0016a61e0), retryAfter:1000000000} 2025/11/07 05:01:22 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:01:22 DEBUG : pacer: low level retry 9/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:01:22 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:01:22 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/11/07 05:01:23 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/11/07 05:01:24 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:01:25 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc001155a40), retryAfter:1000000000} 2025/11/07 05:01:25 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:01:25 DEBUG : pacer: low level retry 9/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:01:25 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:01:25 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:01:26 DEBUG : chunked-copy: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): trying again in 1s: &pacer.retryAfterError{error:(*api.Error)(0xc001214c60), retryAfter:1000000000} 2025/11/07 05:01:26 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:01:26 DEBUG : pacer: low level retry 10/10 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:01:26 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/11/07 05:01:26 DEBUG : chunked-copy: Error sending chunk 0: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:01:26 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/11/07 05:01:27 DEBUG : chunked-copy: 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/07 05:01:27 DEBUG : chunked-copy: Cancelling multipart upload 2025/11/07 05:01:27 DEBUG : chunked-copy: Cancelling large file upload 2025/11/07 05:01:28 DEBUG : pacer: Reducing sleep to 500ms fstests.go:143: Put error: no tomes available (503 service_unavailable): trying again in 1s - low level retry 1/10 2025/11/07 05:01:30 DEBUG : pacer: Reducing sleep to 250ms 2025/11/07 05:01:31 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10Mi 2025/11/07 05:01:31 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/11/07 05:01:31 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4Mi offset 6Mi/10Mi 2025/11/07 05:01:31 DEBUG : pacer: Reducing sleep to 125ms 2025/11/07 05:01:31 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/11/07 05:01:32 DEBUG : pacer: Reducing sleep to 62.5ms 2025/11/07 05:01:33 DEBUG : pacer: Reducing sleep to 31.25ms 2025/11/07 05:01:33 DEBUG : chunked-copy: Done sending chunk 0 2025/11/07 05:01:34 DEBUG : pacer: Reducing sleep to 15.625ms 2025/11/07 05:01:34 DEBUG : chunked-copy: Done sending chunk 1 2025/11/07 05:01:34 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2025/11/07 05:01:34 DEBUG : pacer: Reducing sleep to 10ms 2025/11/07 05:01:35 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_z5f522d23a0ad5bcb9fa80e1b_f205f627b9f998e0d_d20251107_m050135_c001_v0001172_t0009_u01762491695532") 2025/11/07 05:01:35 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242880 2025/11/07 05:01:35 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/11/07 05:01:36 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/11/07 05:01:36 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/11/07 05:01:36 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485761 2025/11/07 05:01:39 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10.000Mi 2025/11/07 05:01:39 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/11/07 05:01:40 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4.000Mi offset 6Mi/10.000Mi 2025/11/07 05:01:40 DEBUG : chunked-copy: Sending chunk 1 length 4194305 2025/11/07 05:01:42 DEBUG : chunked-copy: Done sending chunk 0 2025/11/07 05:01:42 DEBUG : chunked-copy: Done sending chunk 1 2025/11/07 05:01:42 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2025/11/07 05:01:42 DEBUG : chunked-copy.copy: Starting copy of large file in 3 chunks (id "4_z5f522d23a0ad5bcb9fa80e1b_f205f627b9f998e43_d20251107_m050142_c001_v0001093_t0014_u01762491702888") 2025/11/07 05:01:42 DEBUG : chunked-copy.copy: Copying chunk 2 length 1 2025/11/07 05:01:42 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242880 2025/11/07 05:01:43 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/11/07 05:01:43 DEBUG : chunked-copy.copy: Done copying chunk 2 2025/11/07 05:01:43 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/11/07 05:01:43 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/11/07 05:01:43 DEBUG : chunked-copy.copy: Finishing large file copy with 3 parts === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2025/11/07 05:01:45 DEBUG : unknown-size-put.txt: File has 100 bytes, which makes only one chunk. Using direct upload. 2025/11/07 05:01:45 DEBUG : unknown-size-put.txt: Clearing upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s 2025/11/07 05:01:45 DEBUG : pacer: low level retry 1/1 (error no tomes available (503 service_unavailable): trying again in 1s) 2025/11/07 05:01:45 DEBUG : pacer: Rate limited, increasing sleep to 1s === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2025/11/07 05:01:45 DEBUG : pacer: Reducing sleep to 500ms 2025/11/07 05:01:47 DEBUG : pacer: Reducing sleep to 250ms 2025/11/07 05:01:47 DEBUG : pacer: Reducing sleep to 125ms 2025/11/07 05:01:47 DEBUG : pacer: Reducing sleep to 62.5ms 2025/11/07 05:01:47 DEBUG : unknown-size-update.txt: File has 200 bytes, which makes only one chunk. Using direct upload. 2025/11/07 05:01:47 DEBUG : pacer: Reducing sleep to 31.25ms 2025/11/07 05:01:48 DEBUG : pacer: Reducing sleep to 15.625ms === RUN TestIntegration/FsMkdir/FsRootCollapse 2025/11/07 05:01:48 DEBUG : Creating backend with remote "TestB2:rclone-test-qomives5tofe/deeper/nonexisting/directory" 2025/11/07 05:01:48 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/07 05:01:49 INFO : B2 bucket rclone-test-qomives5tofe: cleaning bucket "rclone-test-qomives5tofe" of all files 2025/11/07 05:01:49 DEBUG : pacer: Reducing sleep to 10ms 2025/11/07 05:01:49 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f4162881cf412bc84_d20251107_m050007_c001_v7007000_t0000_u01762491607041") 2025/11/07 05:01:49 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f20108f13c6ec6bc9_d20251107_m050002_c001_v0001099_t0057_u01762491602736") 2025/11/07 05:01:49 DEBUG : chunked-10Mi-10.000Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f40108f13c6ec6bc6_d20251107_m050002_c001_v7007000_t0000_u01762491602173") 2025/11/07 05:01:49 DEBUG : chunked-10Mi-10.000Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f2162881cf412bc5d_d20251107_m045957_c001_v0001183_t0051_u01762491597473") 2025/11/07 05:01:49 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f420ad3cb3292e03d_d20251107_m050035_c001_v7007000_t0000_u01762491635977") 2025/11/07 05:01:49 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f2162881cf412bd10_d20251107_m050025_c001_v0001136_t0058_u01762491625409") 2025/11/07 05:01:49 DEBUG : chunked-10Mi-20.000Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f4162881cf412bd09_d20251107_m050024_c001_v7007000_t0000_u01762491624853") 2025/11/07 05:01:49 DEBUG : chunked-10Mi-20.000Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f2162881cf412bcb6_d20251107_m050019_c001_v0001130_t0010_u01762491619146") 2025/11/07 05:01:49 DEBUG : chunked-10Mi-20Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f4162881cf412bcb4_d20251107_m050018_c001_v7007000_t0000_u01762491618964") 2025/11/07 05:01:50 DEBUG : chunked-10Mi-20Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f2162881cf412bc9a_d20251107_m050013_c001_v0001115_t0020_u01762491613493") 2025/11/07 05:01:50 DEBUG : chunked-10Mi-20Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f4162881cf412bc95_d20251107_m050012_c001_v7007000_t0000_u01762491612907") 2025/11/07 05:01:50 DEBUG : chunked-10Mi-20Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f2162881cf412bc85_d20251107_m050007_c001_v0001154_t0017_u01762491607222") 2025/11/07 05:01:50 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f408fa5ef1da64483_d20251107_m045751_c001_v7007000_t0000_u01762491471635") 2025/11/07 05:01:50 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f203872197e0d1573_d20251107_m045747_c001_v0001136_t0024_u01762491467751") 2025/11/07 05:01:50 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f408fa5ef1da64481_d20251107_m045743_c001_v7007000_t0000_u01762491463069") 2025/11/07 05:01:50 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f208fa5ef1da6447f_d20251107_m045739_c001_v0001146_t0010_u01762491459205") 2025/11/07 05:01:50 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f403872197e0d1572_d20251107_m045747_c001_v7007000_t0000_u01762491467376") 2025/11/07 05:01:50 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f208fa5ef1da64482_d20251107_m045743_c001_v0001131_t0013_u01762491463250") 2025/11/07 05:01:50 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f408fa5ef1da6447e_d20251107_m045738_c001_v7007000_t0000_u01762491458809") 2025/11/07 05:01:50 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f208fa5ef1da6447b_d20251107_m045734_c001_v0001146_t0022_u01762491454518") 2025/11/07 05:01:50 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f408fa5ef1da6447a_d20251107_m045734_c001_v7007000_t0000_u01762491454337") 2025/11/07 05:01:50 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f218bef918b7b9f3f_d20251107_m045730_c001_v0001150_t0038_u01762491450864") 2025/11/07 05:01:50 DEBUG : chunked-5.054Mi-5.054Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f418bef918b7b9f3d_d20251107_m045730_c001_v7007000_t0000_u01762491450492") 2025/11/07 05:01:50 DEBUG : chunked-5.054Mi-5.054Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f208fa5ef1da64478_d20251107_m045726_c001_v0001093_t0047_u01762491446942") 2025/11/07 05:01:51 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f41587366531465c5_d20251107_m045817_c001_v7007000_t0000_u01762491497476") 2025/11/07 05:01:51 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f208fa5ef1da6448c_d20251107_m045813_c001_v0001043_t0000_u01762491493476") 2025/11/07 05:01:51 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f4158736653146585_d20251107_m045808_c001_v7007000_t0000_u01762491488348") 2025/11/07 05:01:51 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f208fa5ef1da64489_d20251107_m045804_c001_v0001044_t0010_u01762491484202") 2025/11/07 05:01:51 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f408fa5ef1da6448b_d20251107_m045813_c001_v7007000_t0000_u01762491493099") 2025/11/07 05:01:51 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f2158736653146588_d20251107_m045808_c001_v0001116_t0053_u01762491488530") 2025/11/07 05:01:51 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f408fa5ef1da64488_d20251107_m045803_c001_v7007000_t0000_u01762491483834") 2025/11/07 05:01:51 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f208fa5ef1da64487_d20251107_m045759_c001_v0001041_t0032_u01762491479430") 2025/11/07 05:01:51 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f408fa5ef1da64486_d20251107_m045759_c001_v7007000_t0000_u01762491479249") 2025/11/07 05:01:51 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f203872197e0d1577_d20251107_m045755_c001_v0001090_t0005_u01762491475837") 2025/11/07 05:01:51 DEBUG : chunked-5.055Mi-5.055Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f403872197e0d1576_d20251107_m045755_c001_v7007000_t0000_u01762491475465") 2025/11/07 05:01:51 DEBUG : chunked-5.055Mi-5.055Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f208fa5ef1da64484_d20251107_m045751_c001_v0001042_t0003_u01762491471817") 2025/11/07 05:01:51 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f408fa5ef1da64477_d20251107_m045726_c001_v7007000_t0000_u01762491446761") 2025/11/07 05:01:51 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f208fa5ef1da64476_d20251107_m045722_c001_v0001113_t0025_u01762491442864") 2025/11/07 05:01:51 DEBUG : chunked-5Mi-10.000Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f408fa5ef1da64475_d20251107_m045722_c001_v7007000_t0000_u01762491442499") 2025/11/07 05:01:51 DEBUG : chunked-5Mi-10.000Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f218bef918b7b9efd_d20251107_m045718_c001_v0001038_t0001_u01762491438239") 2025/11/07 05:01:51 DEBUG : chunked-5Mi-10Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f418bef918b7b9efb_d20251107_m045718_c001_v7007000_t0000_u01762491438056") 2025/11/07 05:01:51 DEBUG : chunked-5Mi-10Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f218bef918b7b9eed_d20251107_m045713_c001_v0001116_t0036_u01762491433908") 2025/11/07 05:01:51 DEBUG : chunked-5Mi-10Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f418bef918b7b9eea_d20251107_m045713_c001_v7007000_t0000_u01762491433533") 2025/11/07 05:01:51 DEBUG : chunked-5Mi-10Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f202687500d788795_d20251107_m045657_c001_v0001150_t0036_u01762491417989") 2025/11/07 05:01:51 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f402687500d788794_d20251107_m045657_c001_v7007000_t0000_u01762491417805") 2025/11/07 05:01:51 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f202687500d788793_d20251107_m045651_c001_v0001115_t0057_u01762491411432") 2025/11/07 05:01:52 DEBUG : chunked-5Mi-5.000Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f4162881cf412b9dc_d20251107_m045611_c001_v7007000_t0000_u01762491371713") 2025/11/07 05:01:52 DEBUG : chunked-5Mi-5.000Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f217ea52e047008c8_d20251107_m045606_c001_v0001113_t0041_u01762491366396") 2025/11/07 05:01:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f41587366531465f4_d20251107_m045927_c001_v7007000_t0000_u01762491567363") 2025/11/07 05:01:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f207ae72636de4bbe_d20251107_m045922_c001_v0001120_t0036_u01762491562432") 2025/11/07 05:01:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f41587366531465f1_d20251107_m045917_c001_v7007000_t0000_u01762491557187") 2025/11/07 05:01:52 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f21587366531465eb_d20251107_m045910_c001_v0001134_t0015_u01762491550470") 2025/11/07 05:01:52 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f407ae72636de4bbd_d20251107_m045922_c001_v7007000_t0000_u01762491562003") 2025/11/07 05:01:52 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f21587366531465f2_d20251107_m045917_c001_v0001102_t0033_u01762491557371") 2025/11/07 05:01:52 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f41587366531465e0_d20251107_m045830_c001_v7007000_t0000_u01762491510595") 2025/11/07 05:01:52 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f21587366531465de_d20251107_m045825_c001_v0001157_t0016_u01762491505901") 2025/11/07 05:01:52 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f41587366531465dd_d20251107_m045825_c001_v7007000_t0000_u01762491505718") 2025/11/07 05:01:52 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f208fa5ef1da6448e_d20251107_m045821_c001_v0001037_t0005_u01762491501932") 2025/11/07 05:01:52 DEBUG : chunked-6.667Mi-6.667Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f408fa5ef1da6448d_d20251107_m045821_c001_v7007000_t0000_u01762491501473") 2025/11/07 05:01:52 DEBUG : chunked-6.667Mi-6.667Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f21587366531465c7_d20251107_m045817_c001_v0001179_t0033_u01762491497658") 2025/11/07 05:01:52 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f4162881cf412bc5b_d20251107_m045957_c001_v7007000_t0000_u01762491597292") 2025/11/07 05:01:52 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f215873665314666a_d20251107_m045952_c001_v0001037_t0055_u01762491592355") 2025/11/07 05:01:52 DEBUG : chunked-8Mi-16.000Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f4158736653146669_d20251107_m045951_c001_v7007000_t0000_u01762491591875") 2025/11/07 05:01:52 DEBUG : chunked-8Mi-16.000Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f215873665314665a_d20251107_m045946_c001_v0001029_t0010_u01762491586251") 2025/11/07 05:01:52 DEBUG : chunked-8Mi-16Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f4158736653146658_d20251107_m045946_c001_v7007000_t0000_u01762491586069") 2025/11/07 05:01:52 DEBUG : chunked-8Mi-16Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f215873665314663c_d20251107_m045941_c001_v0001184_t0045_u01762491581488") 2025/11/07 05:01:53 DEBUG : chunked-8Mi-16Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f4158736653146638_d20251107_m045941_c001_v7007000_t0000_u01762491581007") 2025/11/07 05:01:53 DEBUG : chunked-8Mi-16Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f215873665314661b_d20251107_m045936_c001_v0001145_t0048_u01762491576266") 2025/11/07 05:01:53 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f4158736653146619_d20251107_m045936_c001_v7007000_t0000_u01762491576084") 2025/11/07 05:01:53 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f214136f5a5518e71_d20251107_m045932_c001_v0001130_t0002_u01762491572170") 2025/11/07 05:01:53 DEBUG : chunked-8Mi-8.000Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f414136f5a5518e70_d20251107_m045931_c001_v7007000_t0000_u01762491571649") 2025/11/07 05:01:53 DEBUG : chunked-8Mi-8.000Mi.bin: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f21587366531465f5_d20251107_m045927_c001_v0001182_t0015_u01762491567546") 2025/11/07 05:01:53 DEBUG : chunked-copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f406602b19265ffd4_d20251107_m050145_c001_v7007000_t0000_u01762491705090") 2025/11/07 05:01:53 DEBUG : chunked-copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f202687500d788a10_d20251107_m050139_c001_v0001165_t0025_u01762491699633") 2025/11/07 05:01:53 DEBUG : chunked-copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f402687500d788a0f_d20251107_m050139_c001_v7007000_t0000_u01762491699022") 2025/11/07 05:01:53 DEBUG : chunked-copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f205f627b9f998e01_d20251107_m050130_c001_v0001036_t0031_u01762491690851") 2025/11/07 05:01:53 DEBUG : chunked-copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f420ad3cb3292e064_d20251107_m050050_c001_v7007000_t0000_u01762491650861") 2025/11/07 05:01:53 DEBUG : chunked-copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f205f627b9f998d6f_d20251107_m050045_c001_v0001176_t0016_u01762491645478") 2025/11/07 05:01:53 DEBUG : chunked-copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f405f627b9f998d6e_d20251107_m050044_c001_v7007000_t0000_u01762491644874") 2025/11/07 05:01:53 DEBUG : chunked-copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f1017043c92c147c7_d20251107_m050040_c001_v0001040_t0049_u01762491640751") 2025/11/07 05:01:53 DEBUG : chunked-copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f420ad3cb3292e047_d20251107_m050040_c001_v7007000_t0000_u01762491640336") 2025/11/07 05:01:53 DEBUG : chunked-copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f1017043c92c147bb_d20251107_m050036_c001_v0001040_t0024_u01762491636806") 2025/11/07 05:01:53 DEBUG : chunked-copy.copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f406602b19265ffd3_d20251107_m050144_c001_v7007000_t0000_u01762491704910") 2025/11/07 05:01:53 DEBUG : chunked-copy.copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f205f627b9f998e43_d20251107_m050142_c001_v0001093_t0014_u01762491702888") 2025/11/07 05:01:53 DEBUG : chunked-copy.copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f402687500d788a0e_d20251107_m050138_c001_v7007000_t0000_u01762491698840") 2025/11/07 05:01:53 DEBUG : chunked-copy.copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f205f627b9f998e0d_d20251107_m050135_c001_v0001172_t0009_u01762491695532") 2025/11/07 05:01:54 DEBUG : chunked-copy.copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f420ad3cb3292e063_d20251107_m050050_c001_v7007000_t0000_u01762491650679") 2025/11/07 05:01:54 DEBUG : chunked-copy.copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f220ad3cb3292e05b_d20251107_m050048_c001_v0001104_t0049_u01762491648785") 2025/11/07 05:01:54 DEBUG : chunked-copy.copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f405f627b9f998d6d_d20251107_m050044_c001_v7007000_t0000_u01762491644690") 2025/11/07 05:01:54 DEBUG : chunked-copy.copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f220ad3cb3292e04e_d20251107_m050042_c001_v0001120_t0022_u01762491642696") 2025/11/07 05:01:54 DEBUG : chunked-copy.copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f420ad3cb3292e046_d20251107_m050040_c001_v7007000_t0000_u01762491640152") 2025/11/07 05:01:54 DEBUG : chunked-copy.copy: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f109824fe1741a328_d20251107_m050039_c001_v0001177_t0037_u01762491639286") 2025/11/07 05:01:54 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f10512b66aa0f802e_d20251107_m045445_c001_v0001116_t0003_u01762491285112") 2025/11/07 05:01:54 DEBUG : unknown-size-update.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f406602b19265ffdb_d20251107_m050148_c001_v7007000_t0000_u01762491708041") 2025/11/07 05:01:54 DEBUG : unknown-size-update.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f114c732ca292723d_d20251107_m050147_c001_v0001177_t0054_u01762491707827") 2025/11/07 05:01:54 DEBUG : unknown-size-update.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f114c732ca292723b_d20251107_m050147_c001_v0001177_t0020_u01762491707189") 2025/11/07 05:01:54 DEBUG : versions/test-versions.txt: Deleting (id "4_z5f522d23a0ad5bcb9fa80e1b_f417ea52e047008c0_d20251107_m045602_c001_v7007000_t0000_u01762491362747") 2025/11/07 05:01:55 INFO : B2 bucket rclone-test-qomives5tofe: cleaning bucket "rclone-test-qomives5tofe" of all files === RUN TestIntegration/FsShutdown fstests.go:2831: Shutdown method not supported --- PASS: TestIntegration (473.09s) --- 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 (0.55s) --- PASS: TestIntegration/FsMkdir (470.72s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (0.54s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.18s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.18s) --- PASS: TestIntegration/FsMkdir/FsListRDirEmpty (0.18s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.18s) --- PASS: TestIntegration/FsMkdir/FsListRDirNotFound (0.18s) --- PASS: TestIntegration/FsMkdir/FsEncoding (26.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (2.26s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (1.20s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (1.19s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (1.68s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (1.25s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (1.40s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (1.44s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (1.48s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (1.22s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (1.18s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (1.29s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (1.57s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (1.18s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (1.27s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (1.36s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (1.33s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (1.74s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (1.29s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (1.42s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.36s) --- PASS: TestIntegration/FsMkdir/FsPutError (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (1.11s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- PASS: TestIntegration/FsMkdir/FsOpenChunkWriter (8.22s) --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (82.41s) --- 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.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.55s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (2.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (3.39s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir (4.69s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (2.26s) --- 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.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.18s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata (0.18s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (1.10s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.36s) --- 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.96s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (1.84s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (1.85s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (7.67s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (0.74s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (4.34s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (1.86s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/PublicLink (7.49s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Bucket (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (0.55s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (1.72s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (0.92s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (0.80s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (31.81s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata (7.08s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize (1.29s) --- 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 (5.79s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB/GzipEncoding (1.69s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB/GzipEncoding/NoDecompress (1.69s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions (21.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions (1.99s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/ReadCurrent (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/ReadVersion (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/NewObject (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/NewFs (1.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt (3.05s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before (1.13s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before/List (0.41s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before/NewObject (0.72s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne (0.83s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne/List (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne/NewObject (0.56s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete (0.63s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete/List (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete/NewObject (0.36s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo (0.46s) --- 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.33s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/DryRun (0.64s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/RealThing (3.69s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished (2.05s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished (2.05s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished/DryRun (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished/RealThing (0.75s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules (1.42s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/InitState (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/DryRun (0.56s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/RealThing (0.67s) --- PASS: TestIntegration/FsMkdir/FsPutChunked (269.76s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi (80.55s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881 (51.59s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881/Streamed (46.09s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760 (20.25s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760/Streamed (4.52s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761 (8.71s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761/Streamed (4.26s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi (24.87s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001 (7.58s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001/Streamed (3.84s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000 (8.73s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000/Streamed (4.26s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001 (8.57s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001/Streamed (4.26s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi (25.84s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054 (7.61s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054/Streamed (3.79s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106 (9.10s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106/Streamed (4.51s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107 (9.13s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107/Streamed (4.38s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi (69.89s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507 (8.24s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507/Streamed (4.24s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012 (51.47s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012/Streamed (46.59s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013 (10.17s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013/Streamed (5.36s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi (29.93s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609 (8.72s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609/Streamed (4.43s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216 (9.98s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216/Streamed (5.06s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217 (11.22s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217/Streamed (5.42s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi (38.69s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761 (9.75s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761/Streamed (4.86s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520 (11.92s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520/Streamed (6.06s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521 (17.01s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521/Streamed (11.12s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked (69.11s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/5242880 (4.36s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/5242881 (4.54s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485759 (5.99s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485760 (48.16s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485761 (6.07s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (2.95s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (0.55s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (2.40s) --- 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 473.133s "go test -v -timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 7m54.051327971s (try 1/5)