"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/06/19 05:16:11 DEBUG : Creating backend with remote "TestB2:rclone-test-toyozom1nupa" 2025/06/19 05:16:11 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/06/19 05:16:14 INFO : dir/subdir: Making directory fstest.go:247: Filtering empty directory "dir" fstest.go:247: Filtering empty directory "dir/subdir" 2025/06/19 05:16:14 INFO : dir/subdir: Removing directory fstest.go:247: Filtering empty directory "dir" 2025/06/19 05:16:14 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/06/19 05:16:45 DEBUG : writer-at-subdir/writer-at-file: Streaming upload with --b2-chunk-size 96Mi allows uploads of up to 937.500Gi and will fail only when that limit is reached. 2025/06/19 05:16:45 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 1 length 5242880 2025/06/19 05:16:48 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 1 2025/06/19 05:16:48 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 2 length 1048576 2025/06/19 05:16:49 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 2 2025/06/19 05:16:49 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 0 length 5242880 2025/06/19 05:16:50 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 0 2025/06/19 05:16:50 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/06/19 05:16:59 DEBUG : Creating backend with remote "TestB2:" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot 2025/06/19 05:17:00 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/06/19 05:17:05 INFO : B2 bucket rclone-test-toyozom1nupa: cleaning bucket "rclone-test-toyozom1nupa" of all files 2025/06/19 05:17:05 DEBUG : dirToPurge/fileToPurge.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d9a_d20250619_m051704_c001_v0001109_t0041_u01750310224542") === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2025/06/19 05:17:06 DEBUG : Creating backend with remote "TestB2:rclone-test-toyozom1nupa/dirToPurgeFromRoot" 2025/06/19 05:17:07 INFO : B2 bucket rclone-test-toyozom1nupa path dirToPurgeFromRoot: cleaning bucket "rclone-test-toyozom1nupa" of all files 2025/06/19 05:17:08 DEBUG : fileToPurgeFromRoot.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4da6_d20250619_m051706_c001_v0001109_t0025_u01750310226003") === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2025/06/19 05:17:08 DEBUG : Creating backend with remote "TestB2:rclone-test-toyozom1nupa/hello? sausage/êé" 2025/06/19 05:17:13 INFO : B2 bucket rclone-test-toyozom1nupa path hello? sausage/êé: cleaning bucket "rclone-test-toyozom1nupa" of all files 2025/06/19 05:17:13 DEBUG : a/b/c/d/e.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f116faf9a0c0f3ff8_d20250619_m051711_c001_v0001146_t0008_u01750310231533") === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2025/06/19 05:17:13 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_copy_file": EOF) 2025/06/19 05:17:13 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/06/19 05:17:14 DEBUG : pacer: Reducing sleep to 10ms === 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/06/19 05:17:22 DEBUG : Creating backend with remote "TestB2:rclone-test-toyozom1nupa/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2025/06/19 05:17:24 DEBUG : Creating backend with remote "TestB2:rclone-test-toyozom1nupa/not found.txt" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1911: Opening root remote "TestB2:" path "rclone-test-toyozom1nupa" from "TestB2:rclone-test-toyozom1nupa" 2025/06/19 05:17:26 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/06/19 05:17:37 DEBUG : Creating backend with remote "TestB2:rclone-test-roqowek0vavu" 2025/06/19 05:17:41 DEBUG : B2 bucket rclone-test-roqowek0vavu: Purge remote 2025/06/19 05:17:41 INFO : B2 bucket rclone-test-roqowek0vavu: cleaning bucket "rclone-test-roqowek0vavu" of all files 2025/06/19 05:17:41 DEBUG : somefile: Deleting (id "4_z2f020d9390fd8b6b9f780e1b_f4098fedbe3d452b5_d20250619_m051740_c001_v0001179_t0027_u01750310260154") === 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/06/19 05:17:42 DEBUG : piped data.txt: File has 0 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2025/06/19 05:17:43 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/06/19 05:17:46 DEBUG : test-metadata: multipart upload: starting chunk 0 size 6Mi offset 0/6.106Mi 2025/06/19 05:17:46 DEBUG : test-metadata: Sending chunk 0 length 6291456 2025/06/19 05:17:46 DEBUG : test-metadata: multipart upload: starting chunk 1 size 108.251Ki offset 6Mi/6.106Mi 2025/06/19 05:17:46 DEBUG : test-metadata: Sending chunk 1 length 110849 2025/06/19 05:17:48 DEBUG : test-metadata: Done sending chunk 1 2025/06/19 05:17:49 DEBUG : test-metadata: Done sending chunk 0 2025/06/19 05:17:49 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/06/19 05:18:00 DEBUG : versions/test-versions.txt: Clearing upload URL because of error: Post "https://pod-000-1109-03.backblaze.com/b2api/v1/b2_upload_file/7f62bdc390dd8b6b9f780e1b/c001_v0001109_t0018": EOF 2025/06/19 05:18:00 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1109-03.backblaze.com/b2api/v1/b2_upload_file/7f62bdc390dd8b6b9f780e1b/c001_v0001109_t0018": EOF) 2025/06/19 05:18:00 DEBUG : pacer: Rate limited, increasing sleep to 20ms fstests.go:143: Put error: Post "https://pod-000-1109-03.backblaze.com/b2api/v1/b2_upload_file/7f62bdc390dd8b6b9f780e1b/c001_v0001109_t0018": EOF - low level retry 1/10 2025/06/19 05:18:02 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-06-19-051754-044.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/06/19 05:18:04 DEBUG : oldPath = "TestB2:rclone-test-toyozom1nupa/versions/test-versions-v2025-06-19-051754-044.txt" 2025/06/19 05:18:04 DEBUG : newPath = "TestB2,versions:rclone-test-toyozom1nupa/versions/test-versions-v2025-06-19-051754-044.txt" 2025/06/19 05:18:04 DEBUG : Creating backend with remote "TestB2,versions:rclone-test-toyozom1nupa/versions/test-versions-v2025-06-19-051754-044.txt" 2025/06/19 05:18:04 DEBUG : TestB2: detected overridden config - adding "{pO73u}" suffix to name 2025/06/19 05:18:05 DEBUG : fs cache: renaming child cache item "TestB2,versions:rclone-test-toyozom1nupa/versions/test-versions-v2025-06-19-051754-044.txt" to be canonical for parent "TestB2{pO73u}:rclone-test-toyozom1nupa/versions" === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before/List === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne/List === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete/List === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo/List === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/DryRun 2025/06/19 05:18:07 INFO : B2 bucket rclone-test-toyozom1nupa: cleaning bucket "rclone-test-toyozom1nupa" of all hidden files 2025/06/19 05:18:07 DEBUG : leading space/ leading space: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4bf_d20250619_m051623_c001_v7007000_t0000_u01750310183883") as it is a hide marker 2025/06/19 05:18:07 NOTICE: leading space/ leading space: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : leading space/ leading space: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4cf8_d20250619_m051622_c001_v0001109_t0014_u01750310182880") 2025/06/19 05:18:07 NOTICE: leading space/ leading space: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4be_d20250619_m051622_c001_v7007000_t0000_u01750310182510") as it is a hide marker 2025/06/19 05:18:07 NOTICE: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4cf0_d20250619_m051621_c001_v0001109_t0047_u01750310181581") 2025/06/19 05:18:07 NOTICE: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : .leading dot/.leading dot: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4ca_d20250619_m051632_c001_v7007000_t0000_u01750310192155") as it is a hide marker 2025/06/19 05:18:07 NOTICE: .leading dot/.leading dot: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : .leading dot/.leading dot: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d1c_d20250619_m051631_c001_v0001109_t0030_u01750310191278") 2025/06/19 05:18:07 NOTICE: .leading dot/.leading dot: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : created from root.txt: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f4196f3fbe57975d4_d20250619_m051734_c001_v7007000_t0000_u01750310254047") as it is a hide marker 2025/06/19 05:18:07 NOTICE: created from root.txt: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : created from root.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f10876165a1ac1650_d20250619_m051732_c001_v0001150_t0042_u01750310252967") 2025/06/19 05:18:07 NOTICE: created from root.txt: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : file name.txt: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f40358b5d4b20949e_d20250619_m051742_c001_v7007000_t0000_u01750310262221") as it is a hide marker 2025/06/19 05:18:07 NOTICE: file name.txt: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : file name.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4dd8_d20250619_m051721_c001_v0001109_t0045_u01750310241570") 2025/06/19 05:18:07 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : file name.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103bcf9c109eb982_d20250619_m051717_c001_v0001036_t0029_u01750310237823") 2025/06/19 05:18:07 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : file name.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d78_d20250619_m051656_c001_v0001109_t0054_u01750310216346") 2025/06/19 05:18:07 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : file name.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d72_d20250619_m051654_c001_v0001109_t0035_u01750310214799") 2025/06/19 05:18:07 NOTICE: file name.txt: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d74_d20250619_m051655_c001_v0001109_t0001_u01750310215455") "upload" dated 2025-06-19 05:16:55.455 +0000 UTC (1m12.072237747s ago) 2025/06/19 05:18:07 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f418c3542662bd0ee_d20250619_m051715_c001_v7007000_t0000_u01750310235524") as it is a hide marker 2025/06/19 05:18:07 NOTICE: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f1056e5b3b08b2fbc_d20250619_m051715_c001_v0001134_t0040_u01750310235171") 2025/06/19 05:18:07 NOTICE: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f1053975427ad0c05_d20250619_m051714_c001_v0001180_t0058_u01750310234489") 2025/06/19 05:18:07 NOTICE: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : invalid utf-8/invalid utf-8: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4d5_d20250619_m051641_c001_v7007000_t0000_u01750310201557") as it is a hide marker 2025/06/19 05:18:07 NOTICE: invalid utf-8/invalid utf-8: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : invalid utf-8/invalid utf-8: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d44_d20250619_m051640_c001_v0001109_t0046_u01750310200664") 2025/06/19 05:18:07 NOTICE: invalid utf-8/invalid utf-8: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : piped data.txt: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f40358b5d4b2094ad_d20250619_m051744_c001_v7007000_t0000_u01750310264423") as it is a hide marker 2025/06/19 05:18:07 NOTICE: piped data.txt: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : piped data.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4e28_d20250619_m051743_c001_v0001109_t0039_u01750310263499") 2025/06/19 05:18:07 NOTICE: piped data.txt: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : piped data.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f40358b5d4b2094a6_d20250619_m051743_c001_v7007000_t0000_u01750310263315") 2025/06/19 05:18:07 NOTICE: piped data.txt: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : piped data.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f403566c8ae0e4e22_d20250619_m051742_c001_v0001109_t0018_u01750310262952") 2025/06/19 05:18:07 NOTICE: piped data.txt: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : test%46.txt/test%46.txt: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4d6_d20250619_m051643_c001_v7007000_t0000_u01750310203161") as it is a hide marker 2025/06/19 05:18:07 NOTICE: test%46.txt/test%46.txt: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : test%46.txt/test%46.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d4a_d20250619_m051641_c001_v0001109_t0016_u01750310201929") 2025/06/19 05:18:07 NOTICE: test%46.txt/test%46.txt: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : test-metadata: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f4176b9eeb99266e2_d20250619_m051751_c001_v7007000_t0000_u01750310271497") as it is a hide marker 2025/06/19 05:18:07 NOTICE: test-metadata: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : test-metadata: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f20358b5d4b2094bb_d20250619_m051746_c001_v0001183_t0057_u01750310266576") 2025/06/19 05:18:07 NOTICE: test-metadata: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : test-metadata: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f40358b5d4b2094b5_d20250619_m051745_c001_v7007000_t0000_u01750310265630") 2025/06/19 05:18:07 NOTICE: test-metadata: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : test-metadata: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4e2e_d20250619_m051744_c001_v0001109_t0024_u01750310264608") 2025/06/19 05:18:07 NOTICE: test-metadata: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : trailing CR␍/trailing CR␍: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4cd_d20250619_m051634_c001_v7007000_t0000_u01750310194866") as it is a hide marker 2025/06/19 05:18:07 NOTICE: trailing CR␍/trailing CR␍: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : trailing CR␍/trailing CR␍: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d28_d20250619_m051633_c001_v0001109_t0029_u01750310193966") 2025/06/19 05:18:07 NOTICE: trailing CR␍/trailing CR␍: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : trailing HT␉/trailing HT␉: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4d0_d20250619_m051637_c001_v7007000_t0000_u01750310197522") as it is a hide marker 2025/06/19 05:18:07 NOTICE: trailing HT␉/trailing HT␉: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : trailing HT␉/trailing HT␉: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d32_d20250619_m051636_c001_v0001109_t0000_u01750310196554") 2025/06/19 05:18:07 NOTICE: trailing HT␉/trailing HT␉: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : trailing LF␊/trailing LF␊: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4cf_d20250619_m051636_c001_v7007000_t0000_u01750310196174") as it is a hide marker 2025/06/19 05:18:07 NOTICE: trailing LF␊/trailing LF␊: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : trailing LF␊/trailing LF␊: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d2c_d20250619_m051635_c001_v0001109_t0031_u01750310195237") 2025/06/19 05:18:07 NOTICE: trailing LF␊/trailing LF␊: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : trailing VT␋/trailing VT␋: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4d1_d20250619_m051639_c001_v7007000_t0000_u01750310199031") as it is a hide marker 2025/06/19 05:18:07 NOTICE: trailing VT␋/trailing VT␋: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : trailing VT␋/trailing VT␋: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d38_d20250619_m051637_c001_v0001109_t0045_u01750310197899") 2025/06/19 05:18:07 NOTICE: trailing VT␋/trailing VT␋: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : trailing dot./trailing dot.: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4d2_d20250619_m051640_c001_v7007000_t0000_u01750310200293") as it is a hide marker 2025/06/19 05:18:07 NOTICE: trailing dot./trailing dot.: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : trailing dot./trailing dot.: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d3e_d20250619_m051639_c001_v0001109_t0000_u01750310199400") 2025/06/19 05:18:07 NOTICE: trailing dot./trailing dot.: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : trailing space /trailing space : Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4cb_d20250619_m051633_c001_v7007000_t0000_u01750310193589") as it is a hide marker 2025/06/19 05:18:07 NOTICE: trailing space /trailing space : Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : trailing space /trailing space : Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d20_d20250619_m051632_c001_v0001109_t0002_u01750310192527") 2025/06/19 05:18:07 NOTICE: trailing space /trailing space : Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f112de1709764cdfe_d20250619_m051802_c001_v0001181_t0052_u01750310282817") "upload" dated 2025-06-19 05:18:02.817 +0000 UTC (4.711424745s ago) 2025/06/19 05:18:07 DEBUG : versions/test-versions.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4196f3fbe5797649_d20250619_m051758_c001_v7007000_t0000_u01750310278059") 2025/06/19 05:18:07 NOTICE: versions/test-versions.txt: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : versions/test-versions.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4e48_d20250619_m051754_c001_v0001109_t0014_u01750310274044") 2025/06/19 05:18:07 NOTICE: versions/test-versions.txt: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : writer-at-subdir/writer-at-file: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f4086edb209d40ca1_d20250619_m051654_c001_v7007000_t0000_u01750310214245") as it is a hide marker 2025/06/19 05:18:07 NOTICE: writer-at-subdir/writer-at-file: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : writer-at-subdir/writer-at-file: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f202ff3cf68f6e4d9_d20250619_m051645_c001_v0001105_t0046_u01750310205376") 2025/06/19 05:18:07 NOTICE: writer-at-subdir/writer-at-file: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : zero-length-file: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4d7_d20250619_m051645_c001_v7007000_t0000_u01750310205034") as it is a hide marker 2025/06/19 05:18:07 NOTICE: zero-length-file: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : zero-length-file: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f403566c8ae0e4d52_d20250619_m051644_c001_v0001109_t0018_u01750310204486") 2025/06/19 05:18:07 NOTICE: zero-length-file: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : ~leading tilde/~leading tilde: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4c0_d20250619_m051625_c001_v7007000_t0000_u01750310185439") as it is a hide marker 2025/06/19 05:18:07 NOTICE: ~leading tilde/~leading tilde: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : ~leading tilde/~leading tilde: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4cfe_d20250619_m051624_c001_v0001109_t0051_u01750310184263") 2025/06/19 05:18:07 NOTICE: ~leading tilde/~leading tilde: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e48a_d20250619_m051618_c001_v7007000_t0000_u01750310178054") as it is a hide marker 2025/06/19 05:18:07 NOTICE: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4cdc_d20250619_m051616_c001_v0001109_t0037_u01750310176790") 2025/06/19 05:18:07 NOTICE: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : ␉leading HT/␉leading HT: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4c7_d20250619_m051629_c001_v7007000_t0000_u01750310189616") as it is a hide marker 2025/06/19 05:18:07 NOTICE: ␉leading HT/␉leading HT: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : ␉leading HT/␉leading HT: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d12_d20250619_m051628_c001_v0001109_t0058_u01750310188798") 2025/06/19 05:18:07 NOTICE: ␉leading HT/␉leading HT: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : ␊leading LF/␊leading LF: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4c3_d20250619_m051628_c001_v7007000_t0000_u01750310188431") as it is a hide marker 2025/06/19 05:18:07 NOTICE: ␊leading LF/␊leading LF: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : ␊leading LF/␊leading LF: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d0e_d20250619_m051627_c001_v0001109_t0052_u01750310187509") 2025/06/19 05:18:07 NOTICE: ␊leading LF/␊leading LF: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : ␋leading VT/␋leading VT: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4c8_d20250619_m051630_c001_v7007000_t0000_u01750310190903") as it is a hide marker 2025/06/19 05:18:07 NOTICE: ␋leading VT/␋leading VT: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : ␋leading VT/␋leading VT: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d16_d20250619_m051629_c001_v0001109_t0051_u01750310189986") 2025/06/19 05:18:07 NOTICE: ␋leading VT/␋leading VT: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : ␍leading CR/␍leading CR: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4c1_d20250619_m051627_c001_v7007000_t0000_u01750310187116") as it is a hide marker 2025/06/19 05:18:07 NOTICE: ␍leading CR/␍leading CR: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : ␍leading CR/␍leading CR: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d02_d20250619_m051625_c001_v0001109_t0007_u01750310185805") 2025/06/19 05:18:07 NOTICE: ␍leading CR/␍leading CR: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : ．/．: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4a3_d20250619_m051619_c001_v7007000_t0000_u01750310179638") as it is a hide marker 2025/06/19 05:18:07 NOTICE: ．/．: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : ．/．: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4ce4_d20250619_m051618_c001_v0001109_t0044_u01750310178432") 2025/06/19 05:18:07 NOTICE: ．/．: Skipped delete as --dry-run is set 2025/06/19 05:18:07 DEBUG : ．．/．．: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4bd_d20250619_m051621_c001_v7007000_t0000_u01750310181216") as it is a hide marker 2025/06/19 05:18:07 NOTICE: ．．/．．: Skipped remove hide marker as --dry-run is set 2025/06/19 05:18:07 DEBUG : ．．/．．: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4ce8_d20250619_m051620_c001_v0001109_t0056_u01750310180011") 2025/06/19 05:18:07 NOTICE: ．．/．．: Skipped delete as --dry-run is set === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/RealThing 2025/06/19 05:18:07 INFO : B2 bucket rclone-test-toyozom1nupa: cleaning bucket "rclone-test-toyozom1nupa" of all hidden files 2025/06/19 05:18:07 DEBUG : leading space/ leading space: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4bf_d20250619_m051623_c001_v7007000_t0000_u01750310183883") as it is a hide marker 2025/06/19 05:18:07 DEBUG : leading space/ leading space: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4cf8_d20250619_m051622_c001_v0001109_t0014_u01750310182880") 2025/06/19 05:18:07 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4be_d20250619_m051622_c001_v7007000_t0000_u01750310182510") as it is a hide marker 2025/06/19 05:18:07 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4cf0_d20250619_m051621_c001_v0001109_t0047_u01750310181581") 2025/06/19 05:18:07 DEBUG : .leading dot/.leading dot: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4ca_d20250619_m051632_c001_v7007000_t0000_u01750310192155") as it is a hide marker 2025/06/19 05:18:07 DEBUG : .leading dot/.leading dot: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d1c_d20250619_m051631_c001_v0001109_t0030_u01750310191278") 2025/06/19 05:18:07 DEBUG : created from root.txt: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f4196f3fbe57975d4_d20250619_m051734_c001_v7007000_t0000_u01750310254047") as it is a hide marker 2025/06/19 05:18:07 DEBUG : created from root.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f10876165a1ac1650_d20250619_m051732_c001_v0001150_t0042_u01750310252967") 2025/06/19 05:18:07 DEBUG : file name.txt: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f40358b5d4b20949e_d20250619_m051742_c001_v7007000_t0000_u01750310262221") as it is a hide marker 2025/06/19 05:18:08 DEBUG : file name.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4dd8_d20250619_m051721_c001_v0001109_t0045_u01750310241570") 2025/06/19 05:18:08 DEBUG : file name.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103bcf9c109eb982_d20250619_m051717_c001_v0001036_t0029_u01750310237823") 2025/06/19 05:18:08 DEBUG : file name.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d78_d20250619_m051656_c001_v0001109_t0054_u01750310216346") 2025/06/19 05:18:08 DEBUG : file name.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d72_d20250619_m051654_c001_v0001109_t0035_u01750310214799") 2025/06/19 05:18:08 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d74_d20250619_m051655_c001_v0001109_t0001_u01750310215455") "upload" dated 2025-06-19 05:16:55.455 +0000 UTC (1m13.120054042s ago) 2025/06/19 05:18:08 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f418c3542662bd0ee_d20250619_m051715_c001_v7007000_t0000_u01750310235524") as it is a hide marker 2025/06/19 05:18:08 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f1056e5b3b08b2fbc_d20250619_m051715_c001_v0001134_t0040_u01750310235171") 2025/06/19 05:18:08 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f1053975427ad0c05_d20250619_m051714_c001_v0001180_t0058_u01750310234489") 2025/06/19 05:18:08 DEBUG : invalid utf-8/invalid utf-8: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4d5_d20250619_m051641_c001_v7007000_t0000_u01750310201557") as it is a hide marker 2025/06/19 05:18:08 DEBUG : invalid utf-8/invalid utf-8: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d44_d20250619_m051640_c001_v0001109_t0046_u01750310200664") 2025/06/19 05:18:08 DEBUG : piped data.txt: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f40358b5d4b2094ad_d20250619_m051744_c001_v7007000_t0000_u01750310264423") as it is a hide marker 2025/06/19 05:18:08 DEBUG : piped data.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4e28_d20250619_m051743_c001_v0001109_t0039_u01750310263499") 2025/06/19 05:18:08 DEBUG : piped data.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f40358b5d4b2094a6_d20250619_m051743_c001_v7007000_t0000_u01750310263315") 2025/06/19 05:18:08 DEBUG : piped data.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f403566c8ae0e4e22_d20250619_m051742_c001_v0001109_t0018_u01750310262952") 2025/06/19 05:18:09 DEBUG : test%46.txt/test%46.txt: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4d6_d20250619_m051643_c001_v7007000_t0000_u01750310203161") as it is a hide marker 2025/06/19 05:18:09 DEBUG : test%46.txt/test%46.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d4a_d20250619_m051641_c001_v0001109_t0016_u01750310201929") 2025/06/19 05:18:09 DEBUG : test-metadata: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f4176b9eeb99266e2_d20250619_m051751_c001_v7007000_t0000_u01750310271497") as it is a hide marker 2025/06/19 05:18:09 DEBUG : test-metadata: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f20358b5d4b2094bb_d20250619_m051746_c001_v0001183_t0057_u01750310266576") 2025/06/19 05:18:09 DEBUG : test-metadata: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f40358b5d4b2094b5_d20250619_m051745_c001_v7007000_t0000_u01750310265630") 2025/06/19 05:18:09 DEBUG : test-metadata: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4e2e_d20250619_m051744_c001_v0001109_t0024_u01750310264608") 2025/06/19 05:18:09 DEBUG : trailing CR␍/trailing CR␍: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4cd_d20250619_m051634_c001_v7007000_t0000_u01750310194866") as it is a hide marker 2025/06/19 05:18:09 DEBUG : trailing CR␍/trailing CR␍: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d28_d20250619_m051633_c001_v0001109_t0029_u01750310193966") 2025/06/19 05:18:09 DEBUG : trailing HT␉/trailing HT␉: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4d0_d20250619_m051637_c001_v7007000_t0000_u01750310197522") as it is a hide marker 2025/06/19 05:18:09 DEBUG : trailing HT␉/trailing HT␉: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d32_d20250619_m051636_c001_v0001109_t0000_u01750310196554") 2025/06/19 05:18:09 DEBUG : trailing LF␊/trailing LF␊: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4cf_d20250619_m051636_c001_v7007000_t0000_u01750310196174") as it is a hide marker 2025/06/19 05:18:09 DEBUG : trailing LF␊/trailing LF␊: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d2c_d20250619_m051635_c001_v0001109_t0031_u01750310195237") 2025/06/19 05:18:09 DEBUG : trailing VT␋/trailing VT␋: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4d1_d20250619_m051639_c001_v7007000_t0000_u01750310199031") as it is a hide marker 2025/06/19 05:18:09 DEBUG : trailing VT␋/trailing VT␋: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d38_d20250619_m051637_c001_v0001109_t0045_u01750310197899") 2025/06/19 05:18:09 DEBUG : trailing dot./trailing dot.: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4d2_d20250619_m051640_c001_v7007000_t0000_u01750310200293") as it is a hide marker 2025/06/19 05:18:09 DEBUG : trailing dot./trailing dot.: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d3e_d20250619_m051639_c001_v0001109_t0000_u01750310199400") 2025/06/19 05:18:09 DEBUG : trailing space /trailing space : Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4cb_d20250619_m051633_c001_v7007000_t0000_u01750310193589") as it is a hide marker 2025/06/19 05:18:09 DEBUG : trailing space /trailing space : Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d20_d20250619_m051632_c001_v0001109_t0002_u01750310192527") 2025/06/19 05:18:09 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f112de1709764cdfe_d20250619_m051802_c001_v0001181_t0052_u01750310282817") "upload" dated 2025-06-19 05:18:02.817 +0000 UTC (7.104543855s ago) 2025/06/19 05:18:09 DEBUG : versions/test-versions.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4196f3fbe5797649_d20250619_m051758_c001_v7007000_t0000_u01750310278059") 2025/06/19 05:18:09 DEBUG : versions/test-versions.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4e48_d20250619_m051754_c001_v0001109_t0014_u01750310274044") 2025/06/19 05:18:10 DEBUG : writer-at-subdir/writer-at-file: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f4086edb209d40ca1_d20250619_m051654_c001_v7007000_t0000_u01750310214245") as it is a hide marker 2025/06/19 05:18:10 DEBUG : writer-at-subdir/writer-at-file: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f202ff3cf68f6e4d9_d20250619_m051645_c001_v0001105_t0046_u01750310205376") 2025/06/19 05:18:10 DEBUG : zero-length-file: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4d7_d20250619_m051645_c001_v7007000_t0000_u01750310205034") as it is a hide marker 2025/06/19 05:18:10 DEBUG : zero-length-file: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f403566c8ae0e4d52_d20250619_m051644_c001_v0001109_t0018_u01750310204486") 2025/06/19 05:18:10 DEBUG : ~leading tilde/~leading tilde: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4c0_d20250619_m051625_c001_v7007000_t0000_u01750310185439") as it is a hide marker 2025/06/19 05:18:10 DEBUG : ~leading tilde/~leading tilde: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4cfe_d20250619_m051624_c001_v0001109_t0051_u01750310184263") 2025/06/19 05:18:10 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e48a_d20250619_m051618_c001_v7007000_t0000_u01750310178054") as it is a hide marker 2025/06/19 05:18:10 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4cdc_d20250619_m051616_c001_v0001109_t0037_u01750310176790") 2025/06/19 05:18:10 DEBUG : ␉leading HT/␉leading HT: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4c7_d20250619_m051629_c001_v7007000_t0000_u01750310189616") as it is a hide marker 2025/06/19 05:18:10 DEBUG : ␉leading HT/␉leading HT: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d12_d20250619_m051628_c001_v0001109_t0058_u01750310188798") 2025/06/19 05:18:10 DEBUG : ␊leading LF/␊leading LF: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4c3_d20250619_m051628_c001_v7007000_t0000_u01750310188431") as it is a hide marker 2025/06/19 05:18:10 DEBUG : ␊leading LF/␊leading LF: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d0e_d20250619_m051627_c001_v0001109_t0052_u01750310187509") 2025/06/19 05:18:10 DEBUG : ␋leading VT/␋leading VT: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4c8_d20250619_m051630_c001_v7007000_t0000_u01750310190903") as it is a hide marker 2025/06/19 05:18:10 DEBUG : ␋leading VT/␋leading VT: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d16_d20250619_m051629_c001_v0001109_t0051_u01750310189986") 2025/06/19 05:18:10 DEBUG : ␍leading CR/␍leading CR: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4c1_d20250619_m051627_c001_v7007000_t0000_u01750310187116") as it is a hide marker 2025/06/19 05:18:10 DEBUG : ␍leading CR/␍leading CR: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d02_d20250619_m051625_c001_v0001109_t0007_u01750310185805") 2025/06/19 05:18:10 DEBUG : ．/．: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4a3_d20250619_m051619_c001_v7007000_t0000_u01750310179638") as it is a hide marker 2025/06/19 05:18:10 DEBUG : ．/．: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4ce4_d20250619_m051618_c001_v0001109_t0044_u01750310178432") 2025/06/19 05:18:10 DEBUG : ．．/．．: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f402ff3cf68f6e4bd_d20250619_m051621_c001_v7007000_t0000_u01750310181216") as it is a hide marker 2025/06/19 05:18:10 DEBUG : ．．/．．: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4ce8_d20250619_m051620_c001_v0001109_t0056_u01750310180011") === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished 2025/06/19 05:18:11 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/06/19 05:18:11 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/06/19 05:18:12 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/06/19 05:18:12 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/06/19 05:18:12 INFO : B2 bucket rclone-test-toyozom1nupa: cleaning bucket "rclone-test-toyozom1nupa" of pending multipart uploads older than 0s 2025/06/19 05:18:12 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d74_d20250619_m051655_c001_v0001109_t0001_u01750310215455") "upload" dated 2025-06-19 05:16:55.455 +0000 UTC (1m17.402335798s ago) 2025/06/19 05:18:12 DEBUG : unfinished/unfinished-1: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f204317e1319720e0_d20250619_m051811_c001_v0001105_t0051_u01750310291838") as it is a start marker (upload started at 2025-06-19 05:18:11.838 +0000 UTC) 2025/06/19 05:18:12 NOTICE: unfinished/unfinished-1: Skipped remove pending upload as --dry-run is set 2025/06/19 05:18:12 DEBUG : unfinished/unfinished-2: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f204317e1319720e1_d20250619_m051812_c001_v0001101_t0043_u01750310292022") as it is a start marker (upload started at 2025-06-19 05:18:12.022 +0000 UTC) 2025/06/19 05:18:12 NOTICE: unfinished/unfinished-2: Skipped remove pending upload as --dry-run is set 2025/06/19 05:18:12 DEBUG : unfinished/unfinished-3: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f204317e1319720e2_d20250619_m051812_c001_v0001177_t0054_u01750310292207") as it is a start marker (upload started at 2025-06-19 05:18:12.207 +0000 UTC) 2025/06/19 05:18:12 NOTICE: unfinished/unfinished-3: Skipped remove pending upload as --dry-run is set 2025/06/19 05:18:12 DEBUG : unfinished/unfinished-4: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f204317e1319720e3_d20250619_m051812_c001_v0001179_t0048_u01750310292392") as it is a start marker (upload started at 2025-06-19 05:18:12.392 +0000 UTC) 2025/06/19 05:18:12 NOTICE: unfinished/unfinished-4: Skipped remove pending upload as --dry-run is set 2025/06/19 05:18:12 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f404317e1319720df_d20250619_m051811_c001_v7007000_t0000_u01750310291655") "hide" dated 2025-06-19 05:18:11.655 +0000 UTC (1.202570328s ago) 2025/06/19 05:18:12 DEBUG : versions/test-versions.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f112de1709764cdfe_d20250619_m051802_c001_v0001181_t0052_u01750310282817") 2025/06/19 05:18:12 NOTICE: versions/test-versions.txt: Skipped delete as --dry-run is set === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished/RealThing 2025/06/19 05:18:13 INFO : B2 bucket rclone-test-toyozom1nupa: cleaning bucket "rclone-test-toyozom1nupa" of pending multipart uploads older than 0s 2025/06/19 05:18:13 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d74_d20250619_m051655_c001_v0001109_t0001_u01750310215455") "upload" dated 2025-06-19 05:16:55.455 +0000 UTC (1m17.772361394s ago) 2025/06/19 05:18:13 DEBUG : unfinished/unfinished-1: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f204317e1319720e0_d20250619_m051811_c001_v0001105_t0051_u01750310291838") as it is a start marker (upload started at 2025-06-19 05:18:11.838 +0000 UTC) 2025/06/19 05:18:13 DEBUG : unfinished/unfinished-2: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f204317e1319720e1_d20250619_m051812_c001_v0001101_t0043_u01750310292022") as it is a start marker (upload started at 2025-06-19 05:18:12.022 +0000 UTC) 2025/06/19 05:18:13 DEBUG : unfinished/unfinished-3: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f204317e1319720e2_d20250619_m051812_c001_v0001177_t0054_u01750310292207") as it is a start marker (upload started at 2025-06-19 05:18:12.207 +0000 UTC) 2025/06/19 05:18:13 DEBUG : unfinished/unfinished-4: Deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f204317e1319720e3_d20250619_m051812_c001_v0001179_t0048_u01750310292392") as it is a start marker (upload started at 2025-06-19 05:18:12.392 +0000 UTC) 2025/06/19 05:18:13 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z7f62bdc390dd8b6b9f780e1b_f404317e1319720df_d20250619_m051811_c001_v7007000_t0000_u01750310291655") "hide" dated 2025-06-19 05:18:11.655 +0000 UTC (1.572544108s ago) 2025/06/19 05:18:13 DEBUG : versions/test-versions.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f112de1709764cdfe_d20250619_m051802_c001_v0001181_t0052_u01750310282817") === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/InitState === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/DryRun 2025/06/19 05:18:13 NOTICE: lifecycle: Skipped update lifecycle rules as --dry-run is set 2025/06/19 05:18:14 NOTICE: lifecycle: Skipped update lifecycle rules as --dry-run is set 2025/06/19 05:18:14 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/06/19 05:18:15 DEBUG : chunked-5Mi-5.000Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/5.000Mi 2025/06/19 05:18:15 DEBUG : chunked-5Mi-5.000Mi.bin: Sending chunk 0 length 5242880 2025/06/19 05:18:15 DEBUG : chunked-5Mi-5.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 5Mi/5.000Mi 2025/06/19 05:18:15 DEBUG : chunked-5Mi-5.000Mi.bin: Sending chunk 1 length 1 2025/06/19 05:18:16 DEBUG : chunked-5Mi-5.000Mi.bin: Error sending chunk 0 (retry=true): CPU too busy (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0002b3da0), retryAfter:1000000000} 2025/06/19 05:18:16 DEBUG : chunked-5Mi-5.000Mi.bin: Clearing part upload URL because of error: CPU too busy (503 service_unavailable) 2025/06/19 05:18:16 DEBUG : pacer: low level retry 1/10 (error CPU too busy (503 service_unavailable)) 2025/06/19 05:18:16 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/06/19 05:18:16 DEBUG : chunked-5Mi-5.000Mi.bin: Sending chunk 0 length 5242880 2025/06/19 05:18:16 DEBUG : pacer: Reducing sleep to 500ms 2025/06/19 05:18:16 DEBUG : chunked-5Mi-5.000Mi.bin: Done sending chunk 1 2025/06/19 05:18:17 DEBUG : pacer: Reducing sleep to 250ms 2025/06/19 05:18:24 DEBUG : pacer: Reducing sleep to 125ms 2025/06/19 05:18:24 DEBUG : chunked-5Mi-5.000Mi.bin: Done sending chunk 0 2025/06/19 05:18:24 DEBUG : chunked-5Mi-5.000Mi.bin: Finishing large file upload with 2 parts 2025/06/19 05:18:24 DEBUG : pacer: Reducing sleep to 62.5ms 2025/06/19 05:18:24 DEBUG : pacer: Reducing sleep to 31.25ms 2025/06/19 05:18:25 DEBUG : pacer: Reducing sleep to 15.625ms 2025/06/19 05:18:26 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881/Streamed 2025/06/19 05:18:26 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:18:26 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/06/19 05:18:27 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f208fc7e03e35cec3_d20250619_m051827_c001_v0001171_t0006_u01750310307016") 2025/06/19 05:18:27 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/06/19 05:18:27 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/06/19 05:18:27 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 1 length 1 2025/06/19 05:18:28 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:18:29 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:18:29 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760 2025/06/19 05:18:31 DEBUG : chunked-5Mi-10Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/10Mi 2025/06/19 05:18:31 DEBUG : chunked-5Mi-10Mi.bin: Sending chunk 0 length 5242880 2025/06/19 05:18:31 DEBUG : chunked-5Mi-10Mi.bin: multipart upload: starting chunk 1 size 5Mi offset 5Mi/10Mi 2025/06/19 05:18:31 DEBUG : chunked-5Mi-10Mi.bin: Sending chunk 1 length 5242880 2025/06/19 05:18:33 DEBUG : chunked-5Mi-10Mi.bin: Done sending chunk 0 2025/06/19 05:18:33 DEBUG : chunked-5Mi-10Mi.bin: Done sending chunk 1 2025/06/19 05:18:33 DEBUG : chunked-5Mi-10Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760/Streamed 2025/06/19 05:18:35 DEBUG : chunked-5Mi-10Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:18:35 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/06/19 05:18:35 DEBUG : chunked-5Mi-10Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f213b977d3b1ff75d_d20250619_m051835_c001_v0001181_t0045_u01750310315548") 2025/06/19 05:18:35 DEBUG : chunked-5Mi-10Mi-streamed.bin: Sending chunk 0 length 5242880 2025/06/19 05:18:35 DEBUG : chunked-5Mi-10Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/06/19 05:18:35 DEBUG : chunked-5Mi-10Mi-streamed.bin: Sending chunk 1 length 5242880 2025/06/19 05:18:37 DEBUG : chunked-5Mi-10Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:18:38 DEBUG : chunked-5Mi-10Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:18:38 DEBUG : chunked-5Mi-10Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761 2025/06/19 05:18:39 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/10.000Mi 2025/06/19 05:18:39 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 0 length 5242880 2025/06/19 05:18:40 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 10Mi/10.000Mi 2025/06/19 05:18:40 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 2 length 1 2025/06/19 05:18:40 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 1 size 5Mi offset 5Mi/10.000Mi 2025/06/19 05:18:40 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 1 length 5242880 2025/06/19 05:18:40 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 2 2025/06/19 05:18:42 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 0 2025/06/19 05:18:42 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 1 2025/06/19 05:18:42 DEBUG : chunked-5Mi-10.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761/Streamed 2025/06/19 05:18:44 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:18:44 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/06/19 05:18:44 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f218c3542662bd20c_d20250619_m051844_c001_v0001182_t0020_u01750310324356") 2025/06/19 05:18:44 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 0 length 5242880 2025/06/19 05:18:44 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/06/19 05:18:44 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 2 length 1 2025/06/19 05:18:44 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 1 length 5242880 2025/06/19 05:18:45 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 2 2025/06/19 05:18:46 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:18:47 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:18:47 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/06/19 05:18:49 DEBUG : chunked-5.054Mi-5.054Mi.bin: multipart upload: starting chunk 1 size 1 offset 5.054Mi/5.054Mi 2025/06/19 05:18:49 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 1 length 1 2025/06/19 05:18:49 DEBUG : chunked-5.054Mi-5.054Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/5.054Mi 2025/06/19 05:18:49 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 0 length 5300000 2025/06/19 05:18:50 DEBUG : chunked-5.054Mi-5.054Mi.bin: Done sending chunk 1 2025/06/19 05:18:51 DEBUG : chunked-5.054Mi-5.054Mi.bin: Done sending chunk 0 2025/06/19 05:18:51 DEBUG : chunked-5.054Mi-5.054Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001/Streamed 2025/06/19 05:18:53 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:18:53 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/06/19 05:18:53 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f218c3542662bd214_d20250619_m051853_c001_v0001182_t0033_u01750310333261") 2025/06/19 05:18:53 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/06/19 05:18:53 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Sending chunk 1 length 1 2025/06/19 05:18:53 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Sending chunk 0 length 5300000 2025/06/19 05:18:54 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:18:55 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:18:55 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000 2025/06/19 05:18:57 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/10.109Mi 2025/06/19 05:18:57 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 0 length 5300000 2025/06/19 05:18:57 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.054Mi offset 5.054Mi/10.109Mi 2025/06/19 05:18:57 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 1 length 5300000 2025/06/19 05:18:59 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 0 2025/06/19 05:18:59 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 1 2025/06/19 05:18:59 DEBUG : chunked-5.054Mi-10.109Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000/Streamed 2025/06/19 05:19:01 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:19:01 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/06/19 05:19:01 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f213b977d3b1ff822_d20250619_m051901_c001_v0001177_t0035_u01750310341469") 2025/06/19 05:19:01 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300000 2025/06/19 05:19:01 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/06/19 05:19:01 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300000 2025/06/19 05:19:03 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:19:04 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:19:04 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001 2025/06/19 05:19:05 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/10.109Mi 2025/06/19 05:19:05 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 0 length 5300000 2025/06/19 05:19:06 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 2 size 1 offset 10.109Mi/10.109Mi 2025/06/19 05:19:06 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 2 length 1 2025/06/19 05:19:06 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.054Mi offset 5.054Mi/10.109Mi 2025/06/19 05:19:06 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 1 length 5300000 2025/06/19 05:19:06 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 2 2025/06/19 05:19:08 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 0 2025/06/19 05:19:08 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 1 2025/06/19 05:19:08 DEBUG : chunked-5.054Mi-10.109Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001/Streamed 2025/06/19 05:19:10 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:19:10 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/06/19 05:19:10 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f213b977d3b1ff849_d20250619_m051910_c001_v0001113_t0027_u01750310350132") 2025/06/19 05:19:10 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300000 2025/06/19 05:19:10 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/06/19 05:19:10 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 2 length 1 2025/06/19 05:19:10 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300000 2025/06/19 05:19:11 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 2 2025/06/19 05:19:12 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:19:13 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:19:13 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/06/19 05:19:15 DEBUG : chunked-5.055Mi-5.055Mi.bin: multipart upload: starting chunk 1 size 1 offset 5.055Mi/5.055Mi 2025/06/19 05:19:15 DEBUG : chunked-5.055Mi-5.055Mi.bin: Sending chunk 1 length 1 2025/06/19 05:19:15 DEBUG : chunked-5.055Mi-5.055Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/5.055Mi 2025/06/19 05:19:15 DEBUG : chunked-5.055Mi-5.055Mi.bin: Sending chunk 0 length 5300053 2025/06/19 05:19:16 DEBUG : chunked-5.055Mi-5.055Mi.bin: Done sending chunk 1 2025/06/19 05:19:18 DEBUG : chunked-5.055Mi-5.055Mi.bin: Done sending chunk 0 2025/06/19 05:19:18 DEBUG : chunked-5.055Mi-5.055Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054/Streamed 2025/06/19 05:19:20 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:19:20 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/06/19 05:19:20 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f2086edb209d40dc6_d20250619_m051920_c001_v0001177_t0041_u01750310360169") 2025/06/19 05:19:20 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/06/19 05:19:20 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 1 length 1 2025/06/19 05:19:20 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 0 length 5300053 2025/06/19 05:19:21 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:19:22 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:19:22 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106 2025/06/19 05:19:24 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/10.109Mi 2025/06/19 05:19:24 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 0 length 5300053 2025/06/19 05:19:24 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.055Mi offset 5.055Mi/10.109Mi 2025/06/19 05:19:24 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 1 length 5300053 2025/06/19 05:19:26 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 0 2025/06/19 05:19:27 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 1 2025/06/19 05:19:27 DEBUG : chunked-5.055Mi-10.109Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106/Streamed 2025/06/19 05:19:28 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:19:28 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/06/19 05:19:28 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f2086edb209d40dce_d20250619_m051928_c001_v0001171_t0001_u01750310368734") 2025/06/19 05:19:28 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/06/19 05:19:29 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/06/19 05:19:29 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/06/19 05:19:31 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:19:31 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:19:31 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107 2025/06/19 05:19:33 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/10.109Mi 2025/06/19 05:19:33 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 0 length 5300053 2025/06/19 05:19:33 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 2 size 1 offset 10.109Mi/10.109Mi 2025/06/19 05:19:33 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 2 length 1 2025/06/19 05:19:33 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.055Mi offset 5.055Mi/10.109Mi 2025/06/19 05:19:33 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 1 length 5300053 2025/06/19 05:19:34 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 2 2025/06/19 05:19:36 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 0 2025/06/19 05:19:36 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 1 2025/06/19 05:19:36 DEBUG : chunked-5.055Mi-10.109Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107/Streamed 2025/06/19 05:19:38 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:19:38 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/06/19 05:19:38 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f216ceea3fbaa8f6e_d20250619_m051938_c001_v0001131_t0032_u01750310378180") 2025/06/19 05:19:38 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2025/06/19 05:19:38 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/06/19 05:19:38 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 2 length 1 2025/06/19 05:19:38 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2025/06/19 05:19:39 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 2 2025/06/19 05:19:40 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:19:40 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:19:40 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/06/19 05:19:42 DEBUG : chunked-6.667Mi-6.667Mi.bin: multipart upload: starting chunk 1 size 1 offset 6.667Mi/6.667Mi 2025/06/19 05:19:42 DEBUG : chunked-6.667Mi-6.667Mi.bin: Sending chunk 1 length 1 2025/06/19 05:19:42 DEBUG : chunked-6.667Mi-6.667Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/6.667Mi 2025/06/19 05:19:42 DEBUG : chunked-6.667Mi-6.667Mi.bin: Sending chunk 0 length 6990506 2025/06/19 05:19:43 DEBUG : chunked-6.667Mi-6.667Mi.bin: Done sending chunk 1 2025/06/19 05:19:45 DEBUG : chunked-6.667Mi-6.667Mi.bin: Done sending chunk 0 2025/06/19 05:19:45 DEBUG : chunked-6.667Mi-6.667Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507/Streamed 2025/06/19 05:19:46 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:19:46 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/06/19 05:19:46 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f2086edb209d40ddd_d20250619_m051946_c001_v0001184_t0055_u01750310386831") 2025/06/19 05:19:46 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/06/19 05:19:46 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 1 length 1 2025/06/19 05:19:47 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 0 length 6990506 2025/06/19 05:19:47 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:19:49 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:19:49 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012 2025/06/19 05:19:51 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/13.333Mi 2025/06/19 05:19:51 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2025/06/19 05:19:51 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 1 size 6.667Mi offset 6.667Mi/13.333Mi 2025/06/19 05:19:51 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2025/06/19 05:19:53 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 0 2025/06/19 05:19:53 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 1 2025/06/19 05:19:53 DEBUG : chunked-6.667Mi-13.333Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012/Streamed 2025/06/19 05:19:55 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:19:55 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/06/19 05:19:55 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f216ceea3fbaa8f75_d20250619_m051955_c001_v0001038_t0019_u01750310395894") 2025/06/19 05:19:55 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/06/19 05:19:56 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/06/19 05:19:56 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/06/19 05:19:58 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:19:59 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:19:59 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013 2025/06/19 05:20:01 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/13.333Mi 2025/06/19 05:20:01 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2025/06/19 05:20:02 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 1 size 6.667Mi offset 6.667Mi/13.333Mi 2025/06/19 05:20:02 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2025/06/19 05:20:02 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 2 size 1 offset 13.333Mi/13.333Mi 2025/06/19 05:20:02 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 1 2025/06/19 05:20:04 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 2 2025/06/19 05:20:04 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 0 2025/06/19 05:20:04 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 1 2025/06/19 05:20:04 DEBUG : chunked-6.667Mi-13.333Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013/Streamed 2025/06/19 05:20:07 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:20:07 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/06/19 05:20:07 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f216ceea3fbaa8f7d_d20250619_m052007_c001_v0001144_t0002_u01750310407213") 2025/06/19 05:20:07 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2025/06/19 05:20:07 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/06/19 05:20:07 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 2 length 1 2025/06/19 05:20:07 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2025/06/19 05:20:09 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 2 2025/06/19 05:20:10 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:20:10 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:20:10 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/06/19 05:20:12 DEBUG : chunked-8Mi-8.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 8Mi/8.000Mi 2025/06/19 05:20:12 DEBUG : chunked-8Mi-8.000Mi.bin: Sending chunk 1 length 1 2025/06/19 05:20:12 DEBUG : chunked-8Mi-8.000Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/8.000Mi 2025/06/19 05:20:12 DEBUG : chunked-8Mi-8.000Mi.bin: Sending chunk 0 length 8388608 2025/06/19 05:20:13 DEBUG : chunked-8Mi-8.000Mi.bin: Done sending chunk 1 2025/06/19 05:20:15 DEBUG : chunked-8Mi-8.000Mi.bin: Done sending chunk 0 2025/06/19 05:20:15 DEBUG : chunked-8Mi-8.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609/Streamed 2025/06/19 05:20:16 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:20:16 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Streaming upload with --b2-chunk-size 8Mi allows uploads of up to 78.125Gi and will fail only when that limit is reached. 2025/06/19 05:20:17 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f2154f524e89d143d_d20250619_m052016_c001_v0001171_t0052_u01750310416991") 2025/06/19 05:20:17 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/06/19 05:20:17 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Sending chunk 1 length 1 2025/06/19 05:20:17 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Sending chunk 0 length 8388608 2025/06/19 05:20:18 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:20:19 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:20:19 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216 2025/06/19 05:20:21 DEBUG : chunked-8Mi-16Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/16Mi 2025/06/19 05:20:21 DEBUG : chunked-8Mi-16Mi.bin: Sending chunk 0 length 8388608 2025/06/19 05:20:21 DEBUG : chunked-8Mi-16Mi.bin: multipart upload: starting chunk 1 size 8Mi offset 8Mi/16Mi 2025/06/19 05:20:21 DEBUG : chunked-8Mi-16Mi.bin: Sending chunk 1 length 8388608 2025/06/19 05:20:24 DEBUG : chunked-8Mi-16Mi.bin: Done sending chunk 0 2025/06/19 05:20:24 DEBUG : chunked-8Mi-16Mi.bin: Done sending chunk 1 2025/06/19 05:20:24 DEBUG : chunked-8Mi-16Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216/Streamed 2025/06/19 05:20:26 DEBUG : chunked-8Mi-16Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:20:26 DEBUG : chunked-8Mi-16Mi-streamed.bin: Streaming upload with --b2-chunk-size 8Mi allows uploads of up to 78.125Gi and will fail only when that limit is reached. 2025/06/19 05:20:26 DEBUG : chunked-8Mi-16Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f2144cb410922eca7_d20250619_m052026_c001_v0001150_t0030_u01750310426720") 2025/06/19 05:20:26 DEBUG : chunked-8Mi-16Mi-streamed.bin: Sending chunk 0 length 8388608 2025/06/19 05:20:27 DEBUG : chunked-8Mi-16Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/06/19 05:20:27 DEBUG : chunked-8Mi-16Mi-streamed.bin: Sending chunk 1 length 8388608 2025/06/19 05:20:29 DEBUG : chunked-8Mi-16Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:20:29 DEBUG : chunked-8Mi-16Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:20:29 DEBUG : chunked-8Mi-16Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217 2025/06/19 05:20:32 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/16.000Mi 2025/06/19 05:20:32 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 0 length 8388608 2025/06/19 05:20:32 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 16Mi/16.000Mi 2025/06/19 05:20:32 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 2 length 1 2025/06/19 05:20:32 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 1 size 8Mi offset 8Mi/16.000Mi 2025/06/19 05:20:32 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 1 length 8388608 2025/06/19 05:20:33 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 2 2025/06/19 05:20:35 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 0 2025/06/19 05:20:35 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 1 2025/06/19 05:20:35 DEBUG : chunked-8Mi-16.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217/Streamed 2025/06/19 05:20:37 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:20:37 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Streaming upload with --b2-chunk-size 8Mi allows uploads of up to 78.125Gi and will fail only when that limit is reached. 2025/06/19 05:20:37 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f2187ea8124b04c44_d20250619_m052037_c001_v0001184_t0026_u01750310437779") 2025/06/19 05:20:37 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 0 length 8388608 2025/06/19 05:20:38 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/06/19 05:20:38 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 2 length 1 2025/06/19 05:20:38 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 1 length 8388608 2025/06/19 05:20:39 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 2 2025/06/19 05:20:40 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:20:40 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:20:40 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761 2025/06/19 05:20:43 DEBUG : chunked-10Mi-10.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 10Mi/10.000Mi 2025/06/19 05:20:43 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2025/06/19 05:20:43 DEBUG : chunked-10Mi-10.000Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/10.000Mi 2025/06/19 05:20:43 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2025/06/19 05:20:43 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_get_upload_part_url": EOF) 2025/06/19 05:20:43 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/06/19 05:20:43 DEBUG : pacer: Reducing sleep to 10ms 2025/06/19 05:20:44 DEBUG : chunked-10Mi-10.000Mi.bin: Done sending chunk 1 2025/06/19 05:20:46 DEBUG : chunked-10Mi-10.000Mi.bin: Done sending chunk 0 2025/06/19 05:20:46 DEBUG : chunked-10Mi-10.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761/Streamed 2025/06/19 05:20:48 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:20:48 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/06/19 05:20:48 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f216ceea3fbaa8fea_d20250619_m052048_c001_v0001182_t0021_u01750310448363") 2025/06/19 05:20:48 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/06/19 05:20:48 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Sending chunk 1 length 1 2025/06/19 05:20:48 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Sending chunk 0 length 10485760 2025/06/19 05:20:49 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:20:51 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:20:51 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520 2025/06/19 05:20:53 DEBUG : chunked-10Mi-20Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/20Mi 2025/06/19 05:20:53 DEBUG : chunked-10Mi-20Mi.bin: Sending chunk 0 length 10485760 2025/06/19 05:20:53 DEBUG : chunked-10Mi-20Mi.bin: multipart upload: starting chunk 1 size 10Mi offset 10Mi/20Mi 2025/06/19 05:20:53 DEBUG : chunked-10Mi-20Mi.bin: Sending chunk 1 length 10485760 2025/06/19 05:20:55 DEBUG : chunked-10Mi-20Mi.bin: Done sending chunk 0 2025/06/19 05:20:55 DEBUG : chunked-10Mi-20Mi.bin: Done sending chunk 1 2025/06/19 05:20:55 DEBUG : chunked-10Mi-20Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520/Streamed 2025/06/19 05:20:58 DEBUG : chunked-10Mi-20Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:20:58 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/06/19 05:20:58 DEBUG : chunked-10Mi-20Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f2032276360e46520_d20250619_m052058_c001_v0001165_t0025_u01750310458552") 2025/06/19 05:20:58 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 0 length 10485760 2025/06/19 05:20:59 DEBUG : chunked-10Mi-20Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2025/06/19 05:20:59 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 1 length 10485760 2025/06/19 05:21:01 DEBUG : chunked-10Mi-20Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:21:02 DEBUG : chunked-10Mi-20Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:21:02 DEBUG : chunked-10Mi-20Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521 2025/06/19 05:21:06 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/20.000Mi 2025/06/19 05:21:06 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 0 length 10485760 2025/06/19 05:21:07 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 1 size 10Mi offset 10Mi/20.000Mi 2025/06/19 05:21:07 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 1 length 10485760 2025/06/19 05:21:07 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 20Mi/20.000Mi 2025/06/19 05:21:07 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 2 length 1 2025/06/19 05:21:08 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 2 2025/06/19 05:21:09 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 0 2025/06/19 05:21:11 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 1 2025/06/19 05:21:11 DEBUG : chunked-10Mi-20.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521/Streamed 2025/06/19 05:21:15 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: File is big enough for chunked streaming 2025/06/19 05:21:15 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/06/19 05:21:15 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Starting streaming of large file (id "4_z7f62bdc390dd8b6b9f780e1b_f20730db71674efe3_d20250619_m052115_c001_v0001183_t0019_u01750310475864") 2025/06/19 05:21:15 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 0 length 10485760 2025/06/19 05:21:16 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2025/06/19 05:21:16 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 2 length 1 2025/06/19 05:21:16 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 1 length 10485760 2025/06/19 05:21:17 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 2 2025/06/19 05:21:18 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 0 2025/06/19 05:21:19 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 1 2025/06/19 05:21:19 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/06/19 05:21:28 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_z7f62bdc390dd8b6b9f780e1b_f2191d0f2f0e7a24a_d20250619_m052128_c001_v0001101_t0015_u01750310488055") 2025/06/19 05:21:28 DEBUG : chunked-copy.copy: Copying chunk 1 length 1 2025/06/19 05:21:28 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/06/19 05:21:28 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/06/19 05:21:29 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/06/19 05:21:29 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485759 2025/06/19 05:21:30 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10.000Mi 2025/06/19 05:21:30 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/06/19 05:21:31 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4.000Mi offset 6Mi/10.000Mi 2025/06/19 05:21:31 DEBUG : chunked-copy: Sending chunk 1 length 4194303 2025/06/19 05:21:33 DEBUG : chunked-copy: Done sending chunk 0 2025/06/19 05:21:33 DEBUG : chunked-copy: Done sending chunk 1 2025/06/19 05:21:33 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2025/06/19 05:21:34 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_z7f62bdc390dd8b6b9f780e1b_f2191d0f2f0e7a286_d20250619_m052134_c001_v0001134_t0005_u01750310494328") 2025/06/19 05:21:34 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242879 2025/06/19 05:21:34 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/06/19 05:21:35 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/06/19 05:21:35 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/06/19 05:21:35 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485760 2025/06/19 05:21:37 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10Mi 2025/06/19 05:21:37 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/06/19 05:21:37 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4Mi offset 6Mi/10Mi 2025/06/19 05:21:37 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/06/19 05:21:38 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): CPU too busy (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc000290780), retryAfter:1000000000} 2025/06/19 05:21:38 DEBUG : chunked-copy: Clearing part upload URL because of error: CPU too busy (503 service_unavailable) 2025/06/19 05:21:38 DEBUG : pacer: low level retry 1/10 (error CPU too busy (503 service_unavailable)) 2025/06/19 05:21:38 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/06/19 05:21:38 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/06/19 05:21:39 DEBUG : pacer: Reducing sleep to 500ms 2025/06/19 05:21:39 DEBUG : pacer: Reducing sleep to 250ms 2025/06/19 05:21:39 DEBUG : chunked-copy: Done sending chunk 0 2025/06/19 05:21:40 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc000337e00), retryAfter:1000000000} 2025/06/19 05:21:40 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/06/19 05:21:40 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable)) 2025/06/19 05:21:40 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/06/19 05:21:40 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/06/19 05:21:40 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0002dc090), retryAfter:1000000000} 2025/06/19 05:21:40 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/06/19 05:21:40 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable)) 2025/06/19 05:21:41 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/06/19 05:21:42 DEBUG : pacer: Reducing sleep to 500ms 2025/06/19 05:21:43 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0002dd950), retryAfter:1000000000} 2025/06/19 05:21:43 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/06/19 05:21:43 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable)) 2025/06/19 05:21:43 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/06/19 05:21:43 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/06/19 05:21:44 DEBUG : pacer: Reducing sleep to 500ms 2025/06/19 05:21:45 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0002fe2a0), retryAfter:1000000000} 2025/06/19 05:21:45 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/06/19 05:21:45 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable)) 2025/06/19 05:21:45 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/06/19 05:21:45 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/06/19 05:21:46 DEBUG : pacer: Reducing sleep to 500ms 2025/06/19 05:21:47 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc000272270), retryAfter:1000000000} 2025/06/19 05:21:47 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/06/19 05:21:47 DEBUG : pacer: low level retry 6/10 (error no tomes available (503 service_unavailable)) 2025/06/19 05:21:47 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/06/19 05:21:47 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/06/19 05:21:48 DEBUG : pacer: Reducing sleep to 500ms 2025/06/19 05:21:49 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc000272540), retryAfter:1000000000} 2025/06/19 05:21:49 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/06/19 05:21:49 DEBUG : pacer: low level retry 7/10 (error no tomes available (503 service_unavailable)) 2025/06/19 05:21:49 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/06/19 05:21:49 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/06/19 05:21:50 DEBUG : pacer: Reducing sleep to 500ms 2025/06/19 05:21:51 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00246f320), retryAfter:1000000000} 2025/06/19 05:21:51 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/06/19 05:21:51 DEBUG : pacer: low level retry 8/10 (error no tomes available (503 service_unavailable)) 2025/06/19 05:21:51 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/06/19 05:21:51 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/06/19 05:21:52 DEBUG : pacer: Reducing sleep to 500ms 2025/06/19 05:21:53 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0003e20c0), retryAfter:1000000000} 2025/06/19 05:21:53 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/06/19 05:21:53 DEBUG : pacer: low level retry 9/10 (error no tomes available (503 service_unavailable)) 2025/06/19 05:21:53 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/06/19 05:21:53 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/06/19 05:21:54 DEBUG : pacer: Reducing sleep to 500ms 2025/06/19 05:21:55 DEBUG : chunked-copy: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc000273dd0), retryAfter:1000000000} 2025/06/19 05:21:55 DEBUG : chunked-copy: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2025/06/19 05:21:55 DEBUG : pacer: low level retry 10/10 (error no tomes available (503 service_unavailable)) 2025/06/19 05:21:55 DEBUG : pacer: Rate limited, increasing sleep to 1s 2025/06/19 05:21:55 DEBUG : chunked-copy: Error sending chunk 1: no tomes available (503 service_unavailable) 2025/06/19 05:21:55 DEBUG : chunked-copy: Cancelling multipart upload 2025/06/19 05:21:55 DEBUG : chunked-copy: Cancelling large file upload 2025/06/19 05:21:55 DEBUG : pacer: Reducing sleep to 500ms fstests.go:143: Put error: no tomes available (503 service_unavailable) - low level retry 1/10 2025/06/19 05:21:57 DEBUG : pacer: Reducing sleep to 250ms 2025/06/19 05:21:58 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10Mi 2025/06/19 05:21:58 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/06/19 05:21:58 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4Mi offset 6Mi/10Mi 2025/06/19 05:21:58 DEBUG : pacer: Reducing sleep to 125ms 2025/06/19 05:21:58 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2025/06/19 05:21:59 DEBUG : pacer: Reducing sleep to 62.5ms 2025/06/19 05:22:01 DEBUG : pacer: Reducing sleep to 31.25ms 2025/06/19 05:22:01 DEBUG : chunked-copy: Done sending chunk 0 2025/06/19 05:22:01 DEBUG : pacer: Reducing sleep to 15.625ms 2025/06/19 05:22:01 DEBUG : chunked-copy: Done sending chunk 1 2025/06/19 05:22:01 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2025/06/19 05:22:01 DEBUG : pacer: Reducing sleep to 10ms 2025/06/19 05:22:02 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_z7f62bdc390dd8b6b9f780e1b_f2191d0f2f0e7a2b5_d20250619_m052202_c001_v0001090_t0047_u01750310522449") 2025/06/19 05:22:02 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242880 2025/06/19 05:22:02 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/06/19 05:22:03 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/06/19 05:22:03 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/06/19 05:22:03 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485761 2025/06/19 05:22:07 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10.000Mi 2025/06/19 05:22:07 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2025/06/19 05:22:07 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4.000Mi offset 6Mi/10.000Mi 2025/06/19 05:22:07 DEBUG : chunked-copy: Sending chunk 1 length 4194305 2025/06/19 05:22:09 DEBUG : chunked-copy: Done sending chunk 0 2025/06/19 05:22:10 DEBUG : chunked-copy: Done sending chunk 1 2025/06/19 05:22:10 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2025/06/19 05:22:10 DEBUG : chunked-copy.copy: Starting copy of large file in 3 chunks (id "4_z7f62bdc390dd8b6b9f780e1b_f2055f98f3aa64ec4_d20250619_m052210_c001_v0001157_t0007_u01750310530779") 2025/06/19 05:22:10 DEBUG : chunked-copy.copy: Copying chunk 2 length 1 2025/06/19 05:22:10 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2025/06/19 05:22:10 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242880 2025/06/19 05:22:11 DEBUG : chunked-copy.copy: Done copying chunk 2 2025/06/19 05:22:11 DEBUG : chunked-copy.copy: Done copying chunk 0 2025/06/19 05:22:11 DEBUG : chunked-copy.copy: Done copying chunk 1 2025/06/19 05:22:11 DEBUG : chunked-copy.copy: Finishing large file copy with 3 parts === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2025/06/19 05:22:13 DEBUG : unknown-size-put.txt: File has 100 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2025/06/19 05:22:14 DEBUG : unknown-size-update.txt: File has 200 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsRootCollapse 2025/06/19 05:22:15 DEBUG : Creating backend with remote "TestB2:rclone-test-toyozom1nupa/deeper/nonexisting/directory" 2025/06/19 05:22:15 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/06/19 05:22:16 INFO : B2 bucket rclone-test-toyozom1nupa: cleaning bucket "rclone-test-toyozom1nupa" of all files 2025/06/19 05:22:16 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4032276360e4651d_d20250619_m052052_c001_v7007000_t0000_u01750310452362") 2025/06/19 05:22:16 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f216ceea3fbaa8fea_d20250619_m052048_c001_v0001182_t0021_u01750310448363") 2025/06/19 05:22:16 DEBUG : chunked-10Mi-10.000Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f416ceea3fbaa8fe9_d20250619_m052047_c001_v7007000_t0000_u01750310447775") 2025/06/19 05:22:16 DEBUG : chunked-10Mi-10.000Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f216ceea3fbaa8fe8_d20250619_m052042_c001_v0001183_t0049_u01750310442963") 2025/06/19 05:22:16 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4191d0f2f0e7a21f_d20250619_m052122_c001_v7007000_t0000_u01750310482100") 2025/06/19 05:22:16 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f20730db71674efe3_d20250619_m052115_c001_v0001183_t0019_u01750310475864") 2025/06/19 05:22:16 DEBUG : chunked-10Mi-20.000Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f40730db71674efe2_d20250619_m052115_c001_v7007000_t0000_u01750310475285") 2025/06/19 05:22:16 DEBUG : chunked-10Mi-20.000Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2032276360e46523_d20250619_m052106_c001_v0001116_t0039_u01750310466264") 2025/06/19 05:22:16 DEBUG : chunked-10Mi-20Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4032276360e46522_d20250619_m052106_c001_v7007000_t0000_u01750310466076") 2025/06/19 05:22:17 DEBUG : chunked-10Mi-20Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2032276360e46520_d20250619_m052058_c001_v0001165_t0025_u01750310458552") 2025/06/19 05:22:17 DEBUG : chunked-10Mi-20Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4032276360e4651f_d20250619_m052057_c001_v7007000_t0000_u01750310457911") 2025/06/19 05:22:17 DEBUG : chunked-10Mi-20Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2032276360e4651e_d20250619_m052052_c001_v0001182_t0058_u01750310452550") 2025/06/19 05:22:17 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f418c3542662bd223_d20250619_m051915_c001_v7007000_t0000_u01750310355383") 2025/06/19 05:22:17 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f213b977d3b1ff849_d20250619_m051910_c001_v0001113_t0027_u01750310350132") 2025/06/19 05:22:17 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f413b977d3b1ff82e_d20250619_m051905_c001_v7007000_t0000_u01750310345344") 2025/06/19 05:22:17 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f213b977d3b1ff822_d20250619_m051901_c001_v0001177_t0035_u01750310341469") 2025/06/19 05:22:17 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f413b977d3b1ff846_d20250619_m051909_c001_v7007000_t0000_u01750310349729") 2025/06/19 05:22:17 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f213b977d3b1ff82f_d20250619_m051905_c001_v0001150_t0041_u01750310345530") 2025/06/19 05:22:17 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f413b977d3b1ff821_d20250619_m051901_c001_v7007000_t0000_u01750310341068") 2025/06/19 05:22:17 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f218c3542662bd21a_d20250619_m051856_c001_v0001146_t0052_u01750310336797") 2025/06/19 05:22:17 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f418c3542662bd219_d20250619_m051856_c001_v7007000_t0000_u01750310336565") 2025/06/19 05:22:17 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f218c3542662bd214_d20250619_m051853_c001_v0001182_t0033_u01750310333261") 2025/06/19 05:22:17 DEBUG : chunked-5.054Mi-5.054Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f418c3542662bd213_d20250619_m051852_c001_v7007000_t0000_u01750310332879") 2025/06/19 05:22:18 DEBUG : chunked-5.054Mi-5.054Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f213b977d3b1ff7fa_d20250619_m051848_c001_v0001117_t0030_u01750310328904") 2025/06/19 05:22:18 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4086edb209d40dd5_d20250619_m051942_c001_v7007000_t0000_u01750310382286") 2025/06/19 05:22:18 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f216ceea3fbaa8f6e_d20250619_m051938_c001_v0001131_t0032_u01750310378180") 2025/06/19 05:22:18 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4086edb209d40dcf_d20250619_m051932_c001_v7007000_t0000_u01750310372735") 2025/06/19 05:22:18 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2086edb209d40dce_d20250619_m051928_c001_v0001171_t0001_u01750310368734") 2025/06/19 05:22:18 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f416ceea3fbaa8f6d_d20250619_m051937_c001_v7007000_t0000_u01750310377773") 2025/06/19 05:22:18 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2086edb209d40dd0_d20250619_m051932_c001_v0001117_t0022_u01750310372920") 2025/06/19 05:22:18 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4086edb209d40dcd_d20250619_m051928_c001_v7007000_t0000_u01750310368349") 2025/06/19 05:22:18 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f218c3542662bd22a_d20250619_m051924_c001_v0001176_t0018_u01750310364290") 2025/06/19 05:22:18 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f418c3542662bd229_d20250619_m051923_c001_v7007000_t0000_u01750310363968") 2025/06/19 05:22:18 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2086edb209d40dc6_d20250619_m051920_c001_v0001177_t0041_u01750310360169") 2025/06/19 05:22:18 DEBUG : chunked-5.055Mi-5.055Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4086edb209d40dc4_d20250619_m051919_c001_v7007000_t0000_u01750310359760") 2025/06/19 05:22:18 DEBUG : chunked-5.055Mi-5.055Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f218c3542662bd224_d20250619_m051915_c001_v0001145_t0057_u01750310355575") 2025/06/19 05:22:18 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f413b977d3b1ff7f6_d20250619_m051848_c001_v7007000_t0000_u01750310328716") 2025/06/19 05:22:18 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f218c3542662bd20c_d20250619_m051844_c001_v0001182_t0020_u01750310324356") 2025/06/19 05:22:18 DEBUG : chunked-5Mi-10.000Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f418c3542662bd20b_d20250619_m051843_c001_v7007000_t0000_u01750310323923") 2025/06/19 05:22:18 DEBUG : chunked-5Mi-10.000Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f213b977d3b1ff788_d20250619_m051839_c001_v0001157_t0027_u01750310319542") 2025/06/19 05:22:18 DEBUG : chunked-5Mi-10Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f413b977d3b1ff785_d20250619_m051839_c001_v7007000_t0000_u01750310319358") 2025/06/19 05:22:18 DEBUG : chunked-5Mi-10Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f213b977d3b1ff75d_d20250619_m051835_c001_v0001181_t0045_u01750310315548") 2025/06/19 05:22:18 DEBUG : chunked-5Mi-10Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f413b977d3b1ff758_d20250619_m051835_c001_v7007000_t0000_u01750310315146") 2025/06/19 05:22:19 DEBUG : chunked-5Mi-10Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f213b977d3b1ff730_d20250619_m051831_c001_v0001182_t0016_u01750310311114") 2025/06/19 05:22:19 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f413b977d3b1ff72e_d20250619_m051830_c001_v7007000_t0000_u01750310310930") 2025/06/19 05:22:19 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f208fc7e03e35cec3_d20250619_m051827_c001_v0001171_t0006_u01750310307016") 2025/06/19 05:22:19 DEBUG : chunked-5Mi-5.000Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f408fc7e03e35cec2_d20250619_m051826_c001_v7007000_t0000_u01750310306620") 2025/06/19 05:22:19 DEBUG : chunked-5Mi-5.000Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f204317e1319720e6_d20250619_m051815_c001_v0001165_t0057_u01750310295607") 2025/06/19 05:22:19 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4144cb410922ec9e_d20250619_m052012_c001_v7007000_t0000_u01750310412170") 2025/06/19 05:22:19 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f216ceea3fbaa8f7d_d20250619_m052007_c001_v0001144_t0002_u01750310407213") 2025/06/19 05:22:19 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f416ceea3fbaa8f76_d20250619_m052001_c001_v7007000_t0000_u01750310401084") 2025/06/19 05:22:19 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f216ceea3fbaa8f75_d20250619_m051955_c001_v0001038_t0019_u01750310395894") 2025/06/19 05:22:19 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f416ceea3fbaa8f7b_d20250619_m052006_c001_v7007000_t0000_u01750310406746") 2025/06/19 05:22:19 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f216ceea3fbaa8f77_d20250619_m052001_c001_v0001109_t0028_u01750310401502") 2025/06/19 05:22:19 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f416ceea3fbaa8f74_d20250619_m051955_c001_v7007000_t0000_u01750310395407") 2025/06/19 05:22:19 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f216ceea3fbaa8f73_d20250619_m051950_c001_v0001131_t0048_u01750310390695") 2025/06/19 05:22:19 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f416ceea3fbaa8f72_d20250619_m051950_c001_v7007000_t0000_u01750310390504") 2025/06/19 05:22:19 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2086edb209d40ddd_d20250619_m051946_c001_v0001184_t0055_u01750310386831") 2025/06/19 05:22:19 DEBUG : chunked-6.667Mi-6.667Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4086edb209d40ddc_d20250619_m051946_c001_v7007000_t0000_u01750310386375") 2025/06/19 05:22:19 DEBUG : chunked-6.667Mi-6.667Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2086edb209d40dd6_d20250619_m051942_c001_v0001184_t0026_u01750310382486") 2025/06/19 05:22:19 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f416ceea3fbaa8fe7_d20250619_m052042_c001_v7007000_t0000_u01750310442770") 2025/06/19 05:22:19 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2187ea8124b04c44_d20250619_m052037_c001_v0001184_t0026_u01750310437779") 2025/06/19 05:22:19 DEBUG : chunked-8Mi-16.000Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4187ea8124b04c3e_d20250619_m052037_c001_v7007000_t0000_u01750310437278") 2025/06/19 05:22:19 DEBUG : chunked-8Mi-16.000Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2144cb410922ecdc_d20250619_m052032_c001_v0001113_t0007_u01750310432078") 2025/06/19 05:22:20 DEBUG : chunked-8Mi-16Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4144cb410922ecd9_d20250619_m052031_c001_v7007000_t0000_u01750310431891") 2025/06/19 05:22:20 DEBUG : chunked-8Mi-16Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2144cb410922eca7_d20250619_m052026_c001_v0001150_t0030_u01750310426720") 2025/06/19 05:22:20 DEBUG : chunked-8Mi-16Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4144cb410922eca6_d20250619_m052026_c001_v7007000_t0000_u01750310426215") 2025/06/19 05:22:20 DEBUG : chunked-8Mi-16Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2144cb410922eca4_d20250619_m052021_c001_v0001101_t0051_u01750310421215") 2025/06/19 05:22:20 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4144cb410922eca3_d20250619_m052021_c001_v7007000_t0000_u01750310421029") 2025/06/19 05:22:20 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2154f524e89d143d_d20250619_m052016_c001_v0001171_t0052_u01750310416991") 2025/06/19 05:22:20 DEBUG : chunked-8Mi-8.000Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4154f524e89d143c_d20250619_m052016_c001_v7007000_t0000_u01750310416481") 2025/06/19 05:22:20 DEBUG : chunked-8Mi-8.000Mi.bin: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2144cb410922ec9f_d20250619_m052012_c001_v0001180_t0010_u01750310412352") 2025/06/19 05:22:20 DEBUG : chunked-copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f41078181dba8354e_d20250619_m052213_c001_v7007000_t0000_u01750310533183") 2025/06/19 05:22:20 DEBUG : chunked-copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2191d0f2f0e7a2cf_d20250619_m052206_c001_v0001036_t0033_u01750310526811") 2025/06/19 05:22:20 DEBUG : chunked-copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4191d0f2f0e7a2ca_d20250619_m052206_c001_v7007000_t0000_u01750310526171") 2025/06/19 05:22:20 DEBUG : chunked-copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2191d0f2f0e7a2a7_d20250619_m052157_c001_v0001172_t0023_u01750310517877") 2025/06/19 05:22:20 DEBUG : chunked-copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f40730db71674f031_d20250619_m052136_c001_v7007000_t0000_u01750310496673") 2025/06/19 05:22:20 DEBUG : chunked-copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f20730db71674f005_d20250619_m052130_c001_v0001044_t0011_u01750310490659") 2025/06/19 05:22:20 DEBUG : chunked-copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f40730db71674f001_d20250619_m052130_c001_v7007000_t0000_u01750310490047") 2025/06/19 05:22:20 DEBUG : chunked-copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f112de1709764d3c9_d20250619_m052126_c001_v0001181_t0036_u01750310486516") 2025/06/19 05:22:20 DEBUG : chunked-copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4191d0f2f0e7a231_d20250619_m052126_c001_v7007000_t0000_u01750310486029") 2025/06/19 05:22:20 DEBUG : chunked-copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f112de1709764d3a3_d20250619_m052122_c001_v0001181_t0003_u01750310482939") 2025/06/19 05:22:20 DEBUG : chunked-copy.copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f41078181dba8354d_d20250619_m052213_c001_v7007000_t0000_u01750310533000") 2025/06/19 05:22:21 DEBUG : chunked-copy.copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2055f98f3aa64ec4_d20250619_m052210_c001_v0001157_t0007_u01750310530779") 2025/06/19 05:22:21 DEBUG : chunked-copy.copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4191d0f2f0e7a2c7_d20250619_m052205_c001_v7007000_t0000_u01750310525986") 2025/06/19 05:22:21 DEBUG : chunked-copy.copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2191d0f2f0e7a2b5_d20250619_m052202_c001_v0001090_t0047_u01750310522449") 2025/06/19 05:22:21 DEBUG : chunked-copy.copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f40730db71674f02e_d20250619_m052136_c001_v7007000_t0000_u01750310496475") 2025/06/19 05:22:21 DEBUG : chunked-copy.copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2191d0f2f0e7a286_d20250619_m052134_c001_v0001134_t0005_u01750310494328") 2025/06/19 05:22:21 DEBUG : chunked-copy.copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f40730db71674f000_d20250619_m052129_c001_v7007000_t0000_u01750310489862") 2025/06/19 05:22:21 DEBUG : chunked-copy.copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f2191d0f2f0e7a24a_d20250619_m052128_c001_v0001101_t0015_u01750310488055") 2025/06/19 05:22:21 DEBUG : chunked-copy.copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f4191d0f2f0e7a230_d20250619_m052125_c001_v7007000_t0000_u01750310485805") 2025/06/19 05:22:21 DEBUG : chunked-copy.copy: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f10628f28c4dc5366_d20250619_m052124_c001_v0001105_t0036_u01750310484894") 2025/06/19 05:22:21 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f103566c8ae0e4d74_d20250619_m051655_c001_v0001109_t0001_u01750310215455") 2025/06/19 05:22:21 DEBUG : unknown-size-put.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f41078181dba8354f_d20250619_m052213_c001_v7007000_t0000_u01750310533938") 2025/06/19 05:22:21 DEBUG : unknown-size-put.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f112de1709764d4e1_d20250619_m052213_c001_v0001181_t0028_u01750310533729") 2025/06/19 05:22:21 DEBUG : unknown-size-update.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f41078181dba83550_d20250619_m052215_c001_v7007000_t0000_u01750310535038") 2025/06/19 05:22:21 DEBUG : unknown-size-update.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f112de1709764d4ed_d20250619_m052214_c001_v0001181_t0056_u01750310534834") 2025/06/19 05:22:21 DEBUG : unknown-size-update.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f112de1709764d4e5_d20250619_m052214_c001_v0001181_t0059_u01750310534133") 2025/06/19 05:22:21 DEBUG : versions/test-versions.txt: Deleting (id "4_z7f62bdc390dd8b6b9f780e1b_f404317e1319720df_d20250619_m051811_c001_v7007000_t0000_u01750310291655") 2025/06/19 05:22:22 INFO : B2 bucket rclone-test-toyozom1nupa: cleaning bucket "rclone-test-toyozom1nupa" of all files === RUN TestIntegration/FsShutdown fstests.go:2831: Shutdown method not supported --- PASS: TestIntegration (371.73s) --- 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.57s) --- PASS: TestIntegration/FsMkdir (369.32s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (0.55s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.18s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.18s) --- PASS: TestIntegration/FsMkdir/FsListRDirEmpty (0.19s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.18s) --- PASS: TestIntegration/FsMkdir/FsListRDirNotFound (0.18s) --- PASS: TestIntegration/FsMkdir/FsEncoding (27.50s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (2.20s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (1.58s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (1.57s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (1.30s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (1.38s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (1.54s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (1.70s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (1.29s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (1.19s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (1.29s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (1.24s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (1.44s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (1.27s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (1.32s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (1.34s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (1.50s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (1.26s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (1.27s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (1.63s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutError (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (1.10s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- PASS: TestIntegration/FsMkdir/FsOpenChunkWriter (9.21s) --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (81.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (0.92s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.94s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (1.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (1.16s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.39s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.45s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (0.20s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.57s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (1.82s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (3.08s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir (4.44s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (2.19s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.19s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata (0.18s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (1.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.38s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (0.40s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (0.91s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.36s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (1.98s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (1.83s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (1.87s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (7.91s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (0.74s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (4.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (2.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/PublicLink (7.62s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Bucket (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (0.54s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (2.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (0.91s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (1.11s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (31.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata (7.07s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize (1.21s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize/GzipEncoding (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize/GzipEncoding/NoDecompress (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB (5.86s) --- 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 (20.16s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions (2.05s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/ReadCurrent (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/ReadVersion (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/NewObject (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/NewFs (1.31s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt (1.28s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before (0.45s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before/List (0.45s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne (0.28s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne/List (0.28s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete/List (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo (0.28s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo/List (0.28s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup (4.50s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/DryRun (0.64s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup/RealThing (3.85s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished (2.04s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/CleanupUnfinished/CleanupUnfinished (2.04s) --- 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.72s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/InitState (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/DryRun (0.56s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/LifecycleRules/RealThing (0.97s) --- PASS: TestIntegration/FsMkdir/FsPutChunked (186.67s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi (33.29s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881 (15.50s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881/Streamed (4.31s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760 (8.43s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760/Streamed (4.21s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761 (9.36s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761/Streamed (4.79s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi (26.68s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001 (7.90s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001/Streamed (3.73s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000 (8.73s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000/Streamed (4.28s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001 (10.04s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001/Streamed (5.66s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi (26.92s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054 (8.65s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054/Streamed (4.28s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106 (8.70s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106/Streamed (4.39s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107 (9.57s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107/Streamed (4.52s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi (29.86s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507 (8.21s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507/Streamed (4.13s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012 (10.58s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012/Streamed (5.68s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013 (11.08s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013/Streamed (5.41s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi (30.61s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609 (8.86s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609/Streamed (4.54s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216 (10.86s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216/Streamed (5.67s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217 (10.89s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217/Streamed (5.50s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi (39.32s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761 (9.59s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761/Streamed (4.57s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520 (13.71s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520/Streamed (8.16s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521 (16.02s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521/Streamed (6.81s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked (51.08s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/5242880 (3.94s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/5242881 (4.01s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485759 (6.63s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485760 (29.49s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485761 (7.01s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (1.86s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (0.77s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (1.09s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (1.30s) --- 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 371.769s "go test -v -timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 6m13.008747934s (try 1/5)