"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:181: potato: want error strconv.ParseInt: parsing "potato": invalid syntax got error --- PASS: TestParseTimeString (0.00s) === RUN TestIntegration fstests.go:442: Using remote "TestB2:" 2024/11/05 05:02:36 DEBUG : Creating backend with remote "TestB2:rclone-test-hasuqur9xiza" 2024/11/05 05:02:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" === RUN TestIntegration/FsCheckWrap fstests.go:483: 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 2024/11/05 05:02:40 DEBUG : dir/subdir: Making directory fstest.go:244: Filtering empty directory "dir" fstest.go:244: Filtering empty directory "dir/subdir" 2024/11/05 05:02:40 INFO : dir/subdir: Removing directory fstest.go:244: Filtering empty directory "dir" 2024/11/05 05:02:40 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:711: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:711: testing "．" === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:711: testing "．．" === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:711: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:711: testing " leading space" === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:711: testing "~leading tilde" === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:711: testing "␍leading CR" === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:711: testing "␊leading LF" === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:711: testing "␉leading HT" === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:711: testing "␋leading VT" === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:711: testing ".leading dot" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:711: testing "trailing space " === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:711: testing "trailing CR␍" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:711: testing "trailing LF␊" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:711: testing "trailing HT␉" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:711: testing "trailing VT␋" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:711: testing "trailing dot." === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:711: testing "invalid utf-8\xfe" === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:711: testing "test%46.txt" === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError === RUN TestIntegration/FsMkdir/FsPutZeroLength === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:790: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsOpenChunkWriter 2024/11/05 05:03:02 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. 2024/11/05 05:03:02 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 1 length 5242880 2024/11/05 05:03:05 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 1 2024/11/05 05:03:05 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 2 length 1048576 2024/11/05 05:03:06 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 2 2024/11/05 05:03:06 DEBUG : writer-at-subdir/writer-at-file: Sending chunk 0 length 5242880 2024/11/05 05:03:07 DEBUG : writer-at-subdir/writer-at-file: Done sending chunk 0 2024/11/05 05:03:07 DEBUG : writer-at-subdir/writer-at-file: Finishing large file upload with 0 parts === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:872: 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 2024/11/05 05:03:13 DEBUG : Creating backend with remote "TestB2:" 2024/11/05 05:03:13 DEBUG : Config file has changed externally - reloading === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot 2024/11/05 05:03:14 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:1125: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2024/11/05 05:03:19 INFO : B2 bucket rclone-test-hasuqur9xiza: cleaning bucket "rclone-test-hasuqur9xiza" of all files 2024/11/05 05:03:20 DEBUG : dirToPurge/fileToPurge.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde1c8_d20241105_m050319_c001_v0001178_t0001_u01730782999148") === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2024/11/05 05:03:21 DEBUG : Creating backend with remote "TestB2:rclone-test-hasuqur9xiza/dirToPurgeFromRoot" 2024/11/05 05:03:22 INFO : B2 bucket rclone-test-hasuqur9xiza path dirToPurgeFromRoot: cleaning bucket "rclone-test-hasuqur9xiza" of all files 2024/11/05 05:03:23 DEBUG : fileToPurgeFromRoot.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde1d4_d20241105_m050320_c001_v0001178_t0052_u01730783000665") === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2024/11/05 05:03:24 DEBUG : Creating backend with remote "TestB2:rclone-test-hasuqur9xiza/hello? sausage/êé" 2024/11/05 05:03:28 INFO : B2 bucket rclone-test-hasuqur9xiza path hello? sausage/êé: cleaning bucket "rclone-test-hasuqur9xiza" of all files 2024/11/05 05:03:28 DEBUG : a/b/c/d/e.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f1095de1affd43d4d_d20241105_m050326_c001_v0001182_t0019_u01730783006647") === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1293: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove fstests.go:1346: FS has no Mover interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove fstests.go:1463: 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:1627: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1698: 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 2024/11/05 05:03:37 DEBUG : Creating backend with remote "TestB2:rclone-test-hasuqur9xiza/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2024/11/05 05:03:37 DEBUG : Config file has changed externally - reloading === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2024/11/05 05:03:39 DEBUG : Creating backend with remote "TestB2:rclone-test-hasuqur9xiza/not found.txt" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1915: Opening root remote "TestB2:" path "rclone-test-hasuqur9xiza" from "TestB2:rclone-test-hasuqur9xiza" 2024/11/05 05:03:41 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 2024/11/05 05:03:54 DEBUG : Creating backend with remote "TestB2:rclone-test-bilimut2toze" 2024/11/05 05:03:54 DEBUG : Config file has changed externally - reloading 2024/11/05 05:03:57 DEBUG : B2 bucket rclone-test-bilimut2toze: Purge remote 2024/11/05 05:03:57 INFO : B2 bucket rclone-test-bilimut2toze: cleaning bucket "rclone-test-bilimut2toze" of all files 2024/11/05 05:03:57 DEBUG : somefile: Deleting (id "4_zcfc23de3b05dfbab9f280e1b_f4180bcc7eb236d20_d20241105_m050356_c001_v0001115_t0008_u01730783036080") === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:416: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:2113: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout fstests.go:2147: FS does not support About === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 2024/11/05 05:03:58 DEBUG : piped data.txt: File has 0 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2024/11/05 05:03:59 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 2024/11/05 05:04:02 DEBUG : test-metadata: multipart upload: starting chunk 0 size 6Mi offset 0/6.106Mi 2024/11/05 05:04:02 DEBUG : test-metadata: Sending chunk 0 length 6291456 2024/11/05 05:04:02 DEBUG : test-metadata: multipart upload: starting chunk 1 size 108.063Ki offset 6Mi/6.106Mi 2024/11/05 05:04:02 DEBUG : test-metadata: Sending chunk 1 length 110657 2024/11/05 05:04:03 DEBUG : test-metadata: Done sending chunk 1 2024/11/05 05:04:04 DEBUG : test-metadata: Done sending chunk 0 2024/11/05 05:04:04 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 2024/11/05 05:04:12 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_hide_file": EOF) 2024/11/05 05:04:12 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/11/05 05:04:12 DEBUG : pacer: Reducing sleep to 10ms 2024/11/05 05:04:14 DEBUG : versions/test-versions.txt: Clearing upload URL because of error: Post "https://pod-000-1178-03.backblaze.com/b2api/v1/b2_upload_file/3f221d73b04dfbab9f280e1b/c001_v0001178_t0035": EOF 2024/11/05 05:04:14 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1178-03.backblaze.com/b2api/v1/b2_upload_file/3f221d73b04dfbab9f280e1b/c001_v0001178_t0035": EOF) 2024/11/05 05:04:14 DEBUG : pacer: Rate limited, increasing sleep to 20ms fstests.go:142: Put error: Post "https://pod-000-1178-03.backblaze.com/b2api/v1/b2_upload_file/3f221d73b04dfbab9f280e1b/c001_v0001178_t0035": EOF - low level retry 1/10 2024/11/05 05:04:16 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions b2_internal_test.go:361: versions/test-versions.txt === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/ReadCurrent === NAME TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions b2_internal_test.go:361: versions/test-versions-v2024-11-05-050409-630.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 2024/11/05 05:04:19 DEBUG : oldPath = "TestB2:rclone-test-hasuqur9xiza/versions/test-versions-v2024-11-05-050409-630.txt" 2024/11/05 05:04:19 DEBUG : newPath = "TestB2,versions:rclone-test-hasuqur9xiza/versions/test-versions-v2024-11-05-050409-630.txt" 2024/11/05 05:04:19 DEBUG : Creating backend with remote "TestB2,versions:rclone-test-hasuqur9xiza/versions/test-versions-v2024-11-05-050409-630.txt" 2024/11/05 05:04:19 DEBUG : Config file has changed externally - reloading 2024/11/05 05:04:19 DEBUG : TestB2: detected overridden config - adding "{pO73u}" suffix to name 2024/11/05 05:04:20 DEBUG : fs cache: renaming child cache item "TestB2,versions:rclone-test-hasuqur9xiza/versions/test-versions-v2024-11-05-050409-630.txt" to be canonical for parent "TestB2{pO73u}:rclone-test-hasuqur9xiza/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 2024/11/05 05:04:21 INFO : B2 bucket rclone-test-hasuqur9xiza: cleaning bucket "rclone-test-hasuqur9xiza" of all hidden files 2024/11/05 05:04:22 DEBUG : leading space/ leading space: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3d0_d20241105_m050247_c001_v7007000_t0000_u01730782967214") as it is a hide marker 2024/11/05 05:04:22 DEBUG : leading space/ leading space: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde13c_d20241105_m050246_c001_v0001178_t0052_u01730782966641") 2024/11/05 05:04:22 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3cf_d20241105_m050246_c001_v7007000_t0000_u01730782966275") as it is a hide marker 2024/11/05 05:04:22 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde13a_d20241105_m050245_c001_v0001178_t0040_u01730782965695") 2024/11/05 05:04:22 DEBUG : .leading dot/.leading dot: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3d8_d20241105_m050252_c001_v7007000_t0000_u01730782972927") as it is a hide marker 2024/11/05 05:04:22 DEBUG : .leading dot/.leading dot: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde162_d20241105_m050252_c001_v0001178_t0051_u01730782972354") 2024/11/05 05:04:22 DEBUG : created from root.txt: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f418b60c3d0ffde0a_d20241105_m050350_c001_v7007000_t0000_u01730783030076") as it is a hide marker 2024/11/05 05:04:22 DEBUG : created from root.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f118f425fb260e9b3_d20241105_m050348_c001_v0001117_t0047_u01730783028376") 2024/11/05 05:04:22 DEBUG : file name.txt: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4153c5e7a7ac1566_d20241105_m050358_c001_v7007000_t0000_u01730783038313") as it is a hide marker 2024/11/05 05:04:22 DEBUG : file name.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde214_d20241105_m050336_c001_v0001178_t0030_u01730783016810") 2024/11/05 05:04:22 DEBUG : file name.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f117136f589a69c15_d20241105_m050333_c001_v0001178_t0039_u01730783013262") 2024/11/05 05:04:22 DEBUG : file name.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde1a2_d20241105_m050310_c001_v0001178_t0058_u01730782990859") 2024/11/05 05:04:22 DEBUG : file name.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde19e_d20241105_m050310_c001_v0001178_t0013_u01730782990049") 2024/11/05 05:04:22 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Not deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde1a0_d20241105_m050310_c001_v0001178_t0012_u01730782990454") "upload" dated 2024-11-05 05:03:10.454 +0000 UTC (1m12.387352501s ago) 2024/11/05 05:04:22 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f40428dd177640f41_d20241105_m050330_c001_v7007000_t0000_u01730783010971") as it is a hide marker 2024/11/05 05:04:22 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f101c9eca0a75ee52_d20241105_m050330_c001_v0001098_t0049_u01730783010658") 2024/11/05 05:04:23 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f1065a05fd755473b_d20241105_m050329_c001_v0001182_t0033_u01730783009817") 2024/11/05 05:04:23 DEBUG : invalid utf-8/invalid utf-8: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3e0_d20241105_m050259_c001_v7007000_t0000_u01730782979711") as it is a hide marker 2024/11/05 05:04:23 DEBUG : invalid utf-8/invalid utf-8: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde180_d20241105_m050259_c001_v0001178_t0002_u01730782979122") 2024/11/05 05:04:23 DEBUG : piped data.txt: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4153c5e7a7ac156a_d20241105_m050400_c001_v7007000_t0000_u01730783040013") as it is a hide marker 2024/11/05 05:04:23 DEBUG : piped data.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde23e_d20241105_m050359_c001_v0001178_t0016_u01730783039601") 2024/11/05 05:04:23 DEBUG : piped data.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4153c5e7a7ac1568_d20241105_m050359_c001_v7007000_t0000_u01730783039417") 2024/11/05 05:04:23 DEBUG : piped data.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f403e9ab060bde23c_d20241105_m050359_c001_v0001178_t0035_u01730783039047") 2024/11/05 05:04:23 DEBUG : test%46.txt/test%46.txt: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3e1_d20241105_m050300_c001_v7007000_t0000_u01730782980668") as it is a hide marker 2024/11/05 05:04:23 DEBUG : test%46.txt/test%46.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde184_d20241105_m050300_c001_v0001178_t0019_u01730782980077") 2024/11/05 05:04:23 DEBUG : test-metadata: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f416ca964b9828794_d20241105_m050407_c001_v7007000_t0000_u01730783047081") as it is a hide marker 2024/11/05 05:04:23 DEBUG : test-metadata: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2153c5e7a7ac156f_d20241105_m050401_c001_v0001038_t0058_u01730783041787") 2024/11/05 05:04:23 DEBUG : test-metadata: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4153c5e7a7ac156c_d20241105_m050401_c001_v7007000_t0000_u01730783041020") 2024/11/05 05:04:23 DEBUG : test-metadata: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde246_d20241105_m050400_c001_v0001178_t0025_u01730783040203") 2024/11/05 05:04:23 DEBUG : trailing CR␍/trailing CR␍: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3db_d20241105_m050254_c001_v7007000_t0000_u01730782974958") as it is a hide marker 2024/11/05 05:04:23 DEBUG : trailing CR␍/trailing CR␍: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde168_d20241105_m050254_c001_v0001178_t0052_u01730782974303") 2024/11/05 05:04:23 DEBUG : trailing HT␉/trailing HT␉: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3dd_d20241105_m050256_c001_v7007000_t0000_u01730782976871") as it is a hide marker 2024/11/05 05:04:23 DEBUG : trailing HT␉/trailing HT␉: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde170_d20241105_m050256_c001_v0001178_t0036_u01730782976295") 2024/11/05 05:04:23 DEBUG : trailing LF␊/trailing LF␊: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3dc_d20241105_m050255_c001_v7007000_t0000_u01730782975928") as it is a hide marker 2024/11/05 05:04:23 DEBUG : trailing LF␊/trailing LF␊: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde16e_d20241105_m050255_c001_v0001178_t0041_u01730782975324") 2024/11/05 05:04:23 DEBUG : trailing VT␋/trailing VT␋: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3de_d20241105_m050257_c001_v7007000_t0000_u01730782977814") as it is a hide marker 2024/11/05 05:04:24 DEBUG : trailing VT␋/trailing VT␋: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde178_d20241105_m050257_c001_v0001178_t0005_u01730782977242") 2024/11/05 05:04:24 DEBUG : trailing dot./trailing dot.: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3df_d20241105_m050258_c001_v7007000_t0000_u01730782978753") as it is a hide marker 2024/11/05 05:04:24 DEBUG : trailing dot./trailing dot.: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde17c_d20241105_m050258_c001_v0001178_t0034_u01730782978179") 2024/11/05 05:04:24 DEBUG : trailing space /trailing space : Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3d9_d20241105_m050253_c001_v7007000_t0000_u01730782973935") as it is a hide marker 2024/11/05 05:04:24 DEBUG : trailing space /trailing space : Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde164_d20241105_m050253_c001_v0001178_t0057_u01730782973304") 2024/11/05 05:04:24 DEBUG : versions/test-versions.txt: Not deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f1179dd16dae88b92_d20241105_m050417_c001_v0001182_t0040_u01730783057392") "upload" dated 2024-11-05 05:04:17.392 +0000 UTC (6.833323332s ago) 2024/11/05 05:04:24 DEBUG : versions/test-versions.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f405dedf207c37105_d20241105_m050412_c001_v7007000_t0000_u01730783052620") 2024/11/05 05:04:24 DEBUG : versions/test-versions.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde25e_d20241105_m050409_c001_v0001178_t0037_u01730783049630") 2024/11/05 05:04:24 DEBUG : writer-at-subdir/writer-at-file: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3e6_d20241105_m050309_c001_v7007000_t0000_u01730782989507") as it is a hide marker 2024/11/05 05:04:24 DEBUG : writer-at-subdir/writer-at-file: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2100c3bc6213d3e3_d20241105_m050302_c001_v0001104_t0020_u01730782982756") 2024/11/05 05:04:24 DEBUG : zero-length-file: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3e2_d20241105_m050302_c001_v7007000_t0000_u01730782982510") as it is a hide marker 2024/11/05 05:04:24 DEBUG : zero-length-file: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f403e9ab060bde18a_d20241105_m050301_c001_v0001178_t0035_u01730782981956") 2024/11/05 05:04:24 DEBUG : ~leading tilde/~leading tilde: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3d2_d20241105_m050248_c001_v7007000_t0000_u01730782968201") as it is a hide marker 2024/11/05 05:04:24 DEBUG : ~leading tilde/~leading tilde: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde140_d20241105_m050247_c001_v0001178_t0052_u01730782967579") 2024/11/05 05:04:24 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3cc_d20241105_m050243_c001_v7007000_t0000_u01730782963419") as it is a hide marker 2024/11/05 05:04:24 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde12e_d20241105_m050242_c001_v0001178_t0002_u01730782962478") 2024/11/05 05:04:24 DEBUG : ␉leading HT/␉leading HT: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3d6_d20241105_m050251_c001_v7007000_t0000_u01730782971041") as it is a hide marker 2024/11/05 05:04:24 DEBUG : ␉leading HT/␉leading HT: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde156_d20241105_m050250_c001_v0001178_t0029_u01730782970461") 2024/11/05 05:04:24 DEBUG : ␊leading LF/␊leading LF: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3d5_d20241105_m050250_c001_v7007000_t0000_u01730782970092") as it is a hide marker 2024/11/05 05:04:24 DEBUG : ␊leading LF/␊leading LF: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde14a_d20241105_m050249_c001_v0001178_t0054_u01730782969507") 2024/11/05 05:04:25 DEBUG : ␋leading VT/␋leading VT: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3d7_d20241105_m050251_c001_v7007000_t0000_u01730782971987") as it is a hide marker 2024/11/05 05:04:25 DEBUG : ␋leading VT/␋leading VT: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde15e_d20241105_m050251_c001_v0001178_t0023_u01730782971406") 2024/11/05 05:04:25 DEBUG : ␍leading CR/␍leading CR: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3d4_d20241105_m050249_c001_v7007000_t0000_u01730782969142") as it is a hide marker 2024/11/05 05:04:25 DEBUG : ␍leading CR/␍leading CR: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde144_d20241105_m050248_c001_v0001178_t0041_u01730782968565") 2024/11/05 05:04:25 DEBUG : ．/．: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3cd_d20241105_m050244_c001_v7007000_t0000_u01730782964356") as it is a hide marker 2024/11/05 05:04:25 DEBUG : ．/．: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde132_d20241105_m050243_c001_v0001178_t0050_u01730782963784") 2024/11/05 05:04:25 DEBUG : ．．/．．: Deleting current version (id "4_z3f221d73b04dfbab9f280e1b_f4100c3bc6213d3ce_d20241105_m050245_c001_v7007000_t0000_u01730782965315") as it is a hide marker 2024/11/05 05:04:25 DEBUG : ．．/．．: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde134_d20241105_m050244_c001_v0001178_t0045_u01730782964745") === RUN TestIntegration/FsMkdir/FsPutChunked === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881 2024/11/05 05:04:26 DEBUG : chunked-5Mi-5.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 5Mi/5.000Mi 2024/11/05 05:04:26 DEBUG : chunked-5Mi-5.000Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/5.000Mi 2024/11/05 05:04:26 DEBUG : chunked-5Mi-5.000Mi.bin: Sending chunk 1 length 1 2024/11/05 05:04:26 DEBUG : chunked-5Mi-5.000Mi.bin: Sending chunk 0 length 5242880 2024/11/05 05:04:27 DEBUG : chunked-5Mi-5.000Mi.bin: Done sending chunk 1 2024/11/05 05:04:29 DEBUG : chunked-5Mi-5.000Mi.bin: Done sending chunk 0 2024/11/05 05:04:29 DEBUG : chunked-5Mi-5.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881/Streamed 2024/11/05 05:04:31 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:04:31 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. 2024/11/05 05:04:32 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f202d19b89b6e6ad4_d20241105_m050431_c001_v0001144_t0049_u01730783071983") 2024/11/05 05:04:32 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2024/11/05 05:04:32 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 1 length 1 2024/11/05 05:04:32 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Sending chunk 0 length 5242880 2024/11/05 05:04:33 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:04:35 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:04:35 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760 2024/11/05 05:04:36 DEBUG : chunked-5Mi-10Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/10Mi 2024/11/05 05:04:36 DEBUG : chunked-5Mi-10Mi.bin: Sending chunk 0 length 5242880 2024/11/05 05:04:36 DEBUG : chunked-5Mi-10Mi.bin: multipart upload: starting chunk 1 size 5Mi offset 5Mi/10Mi 2024/11/05 05:04:36 DEBUG : chunked-5Mi-10Mi.bin: Sending chunk 1 length 5242880 2024/11/05 05:04:39 DEBUG : chunked-5Mi-10Mi.bin: Done sending chunk 0 2024/11/05 05:04:39 DEBUG : chunked-5Mi-10Mi.bin: Done sending chunk 1 2024/11/05 05:04:39 DEBUG : chunked-5Mi-10Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760/Streamed 2024/11/05 05:04:40 DEBUG : chunked-5Mi-10Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:04:40 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. 2024/11/05 05:04:41 DEBUG : chunked-5Mi-10Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f202d19b89b6e6aed_d20241105_m050440_c001_v0001097_t0048_u01730783080920") 2024/11/05 05:04:41 DEBUG : chunked-5Mi-10Mi-streamed.bin: Sending chunk 0 length 5242880 2024/11/05 05:04:41 DEBUG : chunked-5Mi-10Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2024/11/05 05:04:41 DEBUG : chunked-5Mi-10Mi-streamed.bin: Sending chunk 1 length 5242880 2024/11/05 05:04:43 DEBUG : chunked-5Mi-10Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:04:43 DEBUG : chunked-5Mi-10Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:04:43 DEBUG : chunked-5Mi-10Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761 2024/11/05 05:04:45 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 0 size 5Mi offset 0/10.000Mi 2024/11/05 05:04:45 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 0 length 5242880 2024/11/05 05:04:45 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 10Mi/10.000Mi 2024/11/05 05:04:45 DEBUG : chunked-5Mi-10.000Mi.bin: multipart upload: starting chunk 1 size 5Mi offset 5Mi/10.000Mi 2024/11/05 05:04:45 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 2 length 1 2024/11/05 05:04:45 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 1 length 5242880 2024/11/05 05:04:46 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 2 2024/11/05 05:04:48 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 0 2024/11/05 05:04:49 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 1 2024/11/05 05:04:49 DEBUG : chunked-5Mi-10.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761/Streamed 2024/11/05 05:04:50 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:04:50 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. 2024/11/05 05:04:50 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f20585b838fe05415_d20241105_m050450_c001_v0001104_t0051_u01730783090729") 2024/11/05 05:04:50 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 0 length 5242880 2024/11/05 05:04:51 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2024/11/05 05:04:51 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 1 length 5242880 2024/11/05 05:04:51 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Sending chunk 2 length 1 2024/11/05 05:04:52 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 2 2024/11/05 05:04:53 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:04:53 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:04:53 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 2024/11/05 05:04:55 DEBUG : chunked-5.054Mi-5.054Mi.bin: multipart upload: starting chunk 1 size 1 offset 5.054Mi/5.054Mi 2024/11/05 05:04:55 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 1 length 1 2024/11/05 05:04:55 DEBUG : chunked-5.054Mi-5.054Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/5.054Mi 2024/11/05 05:04:55 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 0 length 5300000 2024/11/05 05:04:56 DEBUG : chunked-5.054Mi-5.054Mi.bin: Done sending chunk 1 2024/11/05 05:04:58 DEBUG : chunked-5.054Mi-5.054Mi.bin: Done sending chunk 0 2024/11/05 05:04:58 DEBUG : chunked-5.054Mi-5.054Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001/Streamed 2024/11/05 05:05:00 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:05:00 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. 2024/11/05 05:05:00 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f2199817b6e639864_d20241105_m050500_c001_v0001145_t0042_u01730783100307") 2024/11/05 05:05:00 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2024/11/05 05:05:00 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Sending chunk 1 length 1 2024/11/05 05:05:00 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Sending chunk 0 length 5300000 2024/11/05 05:05:01 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:05:02 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:05:02 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000 2024/11/05 05:05:04 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/10.109Mi 2024/11/05 05:05:04 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 0 length 5300000 2024/11/05 05:05:04 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.054Mi offset 5.054Mi/10.109Mi 2024/11/05 05:05:04 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 1 length 5300000 2024/11/05 05:05:06 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 0 2024/11/05 05:05:06 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 1 2024/11/05 05:05:06 DEBUG : chunked-5.054Mi-10.109Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000/Streamed 2024/11/05 05:05:08 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:05:08 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Streaming upload with --b2-chunk-size 5.054Mi allows uploads of up to 49.360Gi and will fail only when that limit is reached. 2024/11/05 05:05:08 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f2199817b6e63989f_d20241105_m050508_c001_v0001157_t0040_u01730783108225") 2024/11/05 05:05:08 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300000 2024/11/05 05:05:08 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2024/11/05 05:05:08 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300000 2024/11/05 05:05:10 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:05:10 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:05:10 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001 2024/11/05 05:05:12 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.054Mi offset 0/10.109Mi 2024/11/05 05:05:12 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 0 length 5300000 2024/11/05 05:05:12 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 2 size 1 offset 10.109Mi/10.109Mi 2024/11/05 05:05:12 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 2 length 1 2024/11/05 05:05:12 DEBUG : chunked-5.054Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.054Mi offset 5.054Mi/10.109Mi 2024/11/05 05:05:12 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 1 length 5300000 2024/11/05 05:05:13 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 2 2024/11/05 05:05:15 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 0 2024/11/05 05:05:16 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 1 2024/11/05 05:05:16 DEBUG : chunked-5.054Mi-10.109Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001/Streamed 2024/11/05 05:05:17 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:05:17 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. 2024/11/05 05:05:18 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f2153c5e7a7ac15ef_d20241105_m050518_c001_v0001109_t0018_u01730783118050") 2024/11/05 05:05:18 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300000 2024/11/05 05:05:18 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2024/11/05 05:05:18 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 2 length 1 2024/11/05 05:05:18 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300000 2024/11/05 05:05:19 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 2 2024/11/05 05:05:21 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:05:22 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:05:22 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 2024/11/05 05:05:23 DEBUG : chunked-5.055Mi-5.055Mi.bin: multipart upload: starting chunk 1 size 1 offset 5.055Mi/5.055Mi 2024/11/05 05:05:23 DEBUG : chunked-5.055Mi-5.055Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/5.055Mi 2024/11/05 05:05:23 DEBUG : chunked-5.055Mi-5.055Mi.bin: Sending chunk 1 length 1 2024/11/05 05:05:23 DEBUG : chunked-5.055Mi-5.055Mi.bin: Sending chunk 0 length 5300053 2024/11/05 05:05:25 DEBUG : chunked-5.055Mi-5.055Mi.bin: Done sending chunk 1 2024/11/05 05:05:27 DEBUG : chunked-5.055Mi-5.055Mi.bin: Done sending chunk 0 2024/11/05 05:05:27 DEBUG : chunked-5.055Mi-5.055Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054/Streamed 2024/11/05 05:05:29 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:05:29 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. 2024/11/05 05:05:29 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f202d19b89b6e6bde_d20241105_m050529_c001_v0001182_t0037_u01730783129553") 2024/11/05 05:05:29 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2024/11/05 05:05:29 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 1 length 1 2024/11/05 05:05:29 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Sending chunk 0 length 5300053 2024/11/05 05:05:30 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:05:33 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:05:33 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106 2024/11/05 05:05:34 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/10.109Mi 2024/11/05 05:05:34 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 0 length 5300053 2024/11/05 05:05:35 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.055Mi offset 5.055Mi/10.109Mi 2024/11/05 05:05:35 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 1 length 5300053 2024/11/05 05:05:37 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 0 2024/11/05 05:05:37 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 1 2024/11/05 05:05:37 DEBUG : chunked-5.055Mi-10.109Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106/Streamed 2024/11/05 05:05:39 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:05:39 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. 2024/11/05 05:05:39 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f2153c5e7a7ac1690_d20241105_m050539_c001_v0001104_t0009_u01730783139285") 2024/11/05 05:05:39 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2024/11/05 05:05:39 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2024/11/05 05:05:39 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2024/11/05 05:05:41 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:05:42 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:05:42 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107 2024/11/05 05:05:44 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 0 size 5.055Mi offset 0/10.109Mi 2024/11/05 05:05:44 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 0 length 5300053 2024/11/05 05:05:44 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 2 size 1 offset 10.109Mi/10.109Mi 2024/11/05 05:05:44 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 2 length 1 2024/11/05 05:05:44 DEBUG : chunked-5.055Mi-10.109Mi.bin: multipart upload: starting chunk 1 size 5.055Mi offset 5.055Mi/10.109Mi 2024/11/05 05:05:44 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 1 length 5300053 2024/11/05 05:05:45 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 2 2024/11/05 05:05:46 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 0 2024/11/05 05:05:47 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 1 2024/11/05 05:05:47 DEBUG : chunked-5.055Mi-10.109Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107/Streamed 2024/11/05 05:05:48 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:05:48 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. 2024/11/05 05:05:48 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f2153c5e7a7ac16e3_d20241105_m050548_c001_v0001150_t0035_u01730783148734") 2024/11/05 05:05:48 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 0 length 5300053 2024/11/05 05:05:49 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2024/11/05 05:05:49 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 2 length 1 2024/11/05 05:05:49 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Sending chunk 1 length 5300053 2024/11/05 05:05:49 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 2 2024/11/05 05:05:51 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:05:51 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:05:51 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 2024/11/05 05:05:53 DEBUG : chunked-6.667Mi-6.667Mi.bin: multipart upload: starting chunk 1 size 1 offset 6.667Mi/6.667Mi 2024/11/05 05:05:53 DEBUG : chunked-6.667Mi-6.667Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/6.667Mi 2024/11/05 05:05:53 DEBUG : chunked-6.667Mi-6.667Mi.bin: Sending chunk 1 length 1 2024/11/05 05:05:53 DEBUG : chunked-6.667Mi-6.667Mi.bin: Sending chunk 0 length 6990506 2024/11/05 05:05:54 DEBUG : chunked-6.667Mi-6.667Mi.bin: Done sending chunk 1 2024/11/05 05:05:57 DEBUG : chunked-6.667Mi-6.667Mi.bin: Done sending chunk 0 2024/11/05 05:05:57 DEBUG : chunked-6.667Mi-6.667Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507/Streamed 2024/11/05 05:06:00 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:06:00 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. 2024/11/05 05:06:00 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f207cf25182a2b1e4_d20241105_m050600_c001_v0001104_t0043_u01730783160339") 2024/11/05 05:06:00 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2024/11/05 05:06:00 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 1 length 1 2024/11/05 05:06:00 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Sending chunk 0 length 6990506 2024/11/05 05:06:01 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:06:03 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:06:03 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012 2024/11/05 05:06:05 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/13.333Mi 2024/11/05 05:06:05 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2024/11/05 05:06:05 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 1 size 6.667Mi offset 6.667Mi/13.333Mi 2024/11/05 05:06:05 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2024/11/05 05:06:07 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 0 2024/11/05 05:06:07 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 1 2024/11/05 05:06:07 DEBUG : chunked-6.667Mi-13.333Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012/Streamed 2024/11/05 05:06:09 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:06:09 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. 2024/11/05 05:06:09 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f20585b838fe056b1_d20241105_m050609_c001_v0001113_t0014_u01730783169514") 2024/11/05 05:06:09 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2024/11/05 05:06:09 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2024/11/05 05:06:09 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2024/11/05 05:06:12 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:06:12 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:06:12 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013 2024/11/05 05:06:14 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/13.333Mi 2024/11/05 05:06:14 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2024/11/05 05:06:14 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 2 size 1 offset 13.333Mi/13.333Mi 2024/11/05 05:06:14 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 1 2024/11/05 05:06:14 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 1 size 6.667Mi offset 6.667Mi/13.333Mi 2024/11/05 05:06:14 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2024/11/05 05:06:15 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00058c6c0), retryAfter:1000000000} 2024/11/05 05:06:15 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:15 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:15 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:15 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2024/11/05 05:06:15 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 2 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc000231650), retryAfter:1000000000} 2024/11/05 05:06:15 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:15 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:15 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00058c720), retryAfter:1000000000} 2024/11/05 05:06:15 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:15 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:16 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:17 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc001213050), retryAfter:1000000000} 2024/11/05 05:06:17 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:17 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:17 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:17 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 1 2024/11/05 05:06:18 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2024/11/05 05:06:19 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2024/11/05 05:06:20 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:21 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 2 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00058cc30), retryAfter:1000000000} 2024/11/05 05:06:21 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:21 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:21 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:21 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:22 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0012135c0), retryAfter:1000000000} 2024/11/05 05:06:22 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:22 DEBUG : pacer: low level retry 2/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:22 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:22 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:22 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc001179140), retryAfter:1000000000} 2024/11/05 05:06:22 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:22 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:22 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:23 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 1 2024/11/05 05:06:24 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2024/11/05 05:06:25 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2024/11/05 05:06:26 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:27 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 2 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0011795c0), retryAfter:1000000000} 2024/11/05 05:06:27 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:27 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:27 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:27 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:28 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc001179d40), retryAfter:1000000000} 2024/11/05 05:06:28 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:28 DEBUG : pacer: low level retry 3/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:28 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:28 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:29 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc001213f20), retryAfter:1000000000} 2024/11/05 05:06:29 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:29 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:29 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:29 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 1 2024/11/05 05:06:30 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2024/11/05 05:06:31 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2024/11/05 05:06:32 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:33 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 2 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00058da40), retryAfter:1000000000} 2024/11/05 05:06:33 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:33 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:33 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:33 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:34 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00058dbc0), retryAfter:1000000000} 2024/11/05 05:06:34 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:34 DEBUG : pacer: low level retry 4/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:34 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:34 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:35 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00058def0), retryAfter:1000000000} 2024/11/05 05:06:35 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:35 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:35 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:35 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 1 2024/11/05 05:06:36 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2024/11/05 05:06:37 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2024/11/05 05:06:38 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:39 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 2 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00023a030), retryAfter:1000000000} 2024/11/05 05:06:39 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:39 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:39 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:39 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:40 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0010d21b0), retryAfter:1000000000} 2024/11/05 05:06:40 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:40 DEBUG : pacer: low level retry 5/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:40 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:40 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:41 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0010d2210), retryAfter:1000000000} 2024/11/05 05:06:41 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:41 DEBUG : pacer: low level retry 6/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:41 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:41 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 1 2024/11/05 05:06:42 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2024/11/05 05:06:43 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2024/11/05 05:06:44 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:45 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 2 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0010d22d0), retryAfter:1000000000} 2024/11/05 05:06:45 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:45 DEBUG : pacer: low level retry 6/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:45 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:45 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:46 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00023b140), retryAfter:1000000000} 2024/11/05 05:06:46 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:46 DEBUG : pacer: low level retry 6/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:46 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:46 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:47 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00023b860), retryAfter:1000000000} 2024/11/05 05:06:47 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:47 DEBUG : pacer: low level retry 7/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:47 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:47 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 1 2024/11/05 05:06:48 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2024/11/05 05:06:49 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2024/11/05 05:06:50 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:51 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 2 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc000298210), retryAfter:1000000000} 2024/11/05 05:06:51 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:51 DEBUG : pacer: low level retry 7/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:51 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:51 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:52 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc000298570), retryAfter:1000000000} 2024/11/05 05:06:52 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:52 DEBUG : pacer: low level retry 7/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:52 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:52 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:53 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00023b920), retryAfter:1000000000} 2024/11/05 05:06:53 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:53 DEBUG : pacer: low level retry 8/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:53 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:53 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 1 2024/11/05 05:06:54 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2024/11/05 05:06:55 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2024/11/05 05:06:56 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:57 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 2 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc000299140), retryAfter:1000000000} 2024/11/05 05:06:57 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:57 DEBUG : pacer: low level retry 8/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:57 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:57 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:57 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0003a05d0), retryAfter:1000000000} 2024/11/05 05:06:57 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:57 DEBUG : pacer: low level retry 8/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:57 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:58 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:06:59 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc000b80900), retryAfter:1000000000} 2024/11/05 05:06:59 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:06:59 DEBUG : pacer: low level retry 9/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:06:59 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:06:59 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 1 2024/11/05 05:07:00 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2024/11/05 05:07:01 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2024/11/05 05:07:02 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:07:03 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 2 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0003a0de0), retryAfter:1000000000} 2024/11/05 05:07:03 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:07:03 DEBUG : pacer: low level retry 9/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:07:03 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:07:03 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:07:04 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc000148660), retryAfter:1000000000} 2024/11/05 05:07:04 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:07:04 DEBUG : pacer: low level retry 9/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:07:04 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:07:04 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:07:05 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 0 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc000b81410), retryAfter:1000000000} 2024/11/05 05:07:05 DEBUG : chunked-6.667Mi-13.333Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2024/11/05 05:07:05 DEBUG : pacer: low level retry 10/10 (error no tomes available (503 service_unavailable)) 2024/11/05 05:07:05 DEBUG : pacer: Rate limited, increasing sleep to 1s 2024/11/05 05:07:05 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 0: no tomes available (503 service_unavailable) 2024/11/05 05:07:05 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 1 2024/11/05 05:07:06 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2024/11/05 05:07:07 DEBUG : pacer: Reducing sleep to 500ms 2024/11/05 05:07:07 DEBUG : pacer: Reducing sleep to 250ms 2024/11/05 05:07:07 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 2: failed to get upload URL: Post "https://api001.backblazeb2.com/b2api/v1/b2_get_upload_part_url": context canceled 2024/11/05 05:07:08 DEBUG : pacer: Reducing sleep to 125ms 2024/11/05 05:07:08 DEBUG : pacer: Reducing sleep to 62.5ms 2024/11/05 05:07:08 DEBUG : chunked-6.667Mi-13.333Mi.bin: Error sending chunk 1: failed to get upload URL: Post "https://api001.backblazeb2.com/b2api/v1/b2_get_upload_part_url": context canceled 2024/11/05 05:07:08 DEBUG : chunked-6.667Mi-13.333Mi.bin: Cancelling multipart upload 2024/11/05 05:07:08 DEBUG : chunked-6.667Mi-13.333Mi.bin: Cancelling large file upload 2024/11/05 05:07:08 DEBUG : pacer: Reducing sleep to 31.25ms fstests.go:142: PutLarge error: no tomes available (503 service_unavailable) - low level retry 1/10 2024/11/05 05:07:11 DEBUG : pacer: Reducing sleep to 15.625ms 2024/11/05 05:07:11 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 0 size 6.667Mi offset 0/13.333Mi 2024/11/05 05:07:11 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 0 length 6990506 2024/11/05 05:07:11 DEBUG : pacer: Reducing sleep to 10ms 2024/11/05 05:07:11 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 2 size 1 offset 13.333Mi/13.333Mi 2024/11/05 05:07:11 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 1 2024/11/05 05:07:11 DEBUG : chunked-6.667Mi-13.333Mi.bin: multipart upload: starting chunk 1 size 6.667Mi offset 6.667Mi/13.333Mi 2024/11/05 05:07:11 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2024/11/05 05:07:12 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 2 2024/11/05 05:07:13 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 0 2024/11/05 05:07:14 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 1 2024/11/05 05:07:14 DEBUG : chunked-6.667Mi-13.333Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013/Streamed 2024/11/05 05:07:17 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:07:17 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. 2024/11/05 05:07:17 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f202d19b89b6e7206_d20241105_m050717_c001_v0001137_t0015_u01730783237580") 2024/11/05 05:07:17 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 0 length 6990506 2024/11/05 05:07:17 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2024/11/05 05:07:17 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 2 length 1 2024/11/05 05:07:17 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Sending chunk 1 length 6990506 2024/11/05 05:07:18 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 2 2024/11/05 05:07:20 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:07:20 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:07:20 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 2024/11/05 05:07:22 DEBUG : chunked-8Mi-8.000Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/8.000Mi 2024/11/05 05:07:22 DEBUG : chunked-8Mi-8.000Mi.bin: Sending chunk 0 length 8388608 2024/11/05 05:07:22 DEBUG : chunked-8Mi-8.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 8Mi/8.000Mi 2024/11/05 05:07:22 DEBUG : chunked-8Mi-8.000Mi.bin: Sending chunk 1 length 1 2024/11/05 05:07:23 DEBUG : chunked-8Mi-8.000Mi.bin: Done sending chunk 1 2024/11/05 05:07:26 DEBUG : chunked-8Mi-8.000Mi.bin: Done sending chunk 0 2024/11/05 05:07:26 DEBUG : chunked-8Mi-8.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609/Streamed 2024/11/05 05:07:30 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:07:30 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. 2024/11/05 05:07:30 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f202d19b89b6e725e_d20241105_m050730_c001_v0001157_t0007_u01730783250803") 2024/11/05 05:07:30 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2024/11/05 05:07:30 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Sending chunk 0 length 8388608 2024/11/05 05:07:30 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Sending chunk 1 length 1 2024/11/05 05:07:31 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:07:33 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:07:33 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216 2024/11/05 05:07:35 DEBUG : chunked-8Mi-16Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/16Mi 2024/11/05 05:07:35 DEBUG : chunked-8Mi-16Mi.bin: Sending chunk 0 length 8388608 2024/11/05 05:07:35 DEBUG : chunked-8Mi-16Mi.bin: multipart upload: starting chunk 1 size 8Mi offset 8Mi/16Mi 2024/11/05 05:07:35 DEBUG : chunked-8Mi-16Mi.bin: Sending chunk 1 length 8388608 2024/11/05 05:07:37 DEBUG : chunked-8Mi-16Mi.bin: Done sending chunk 0 2024/11/05 05:07:40 DEBUG : chunked-8Mi-16Mi.bin: Done sending chunk 1 2024/11/05 05:07:40 DEBUG : chunked-8Mi-16Mi.bin: Finishing large file upload with 2 parts 2024/11/05 05:07:40 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_finish_large_file": EOF) 2024/11/05 05:07:40 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/11/05 05:07:41 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216/Streamed 2024/11/05 05:07:44 DEBUG : chunked-8Mi-16Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:07:44 DEBUG : chunked-8Mi-16Mi-streamed.bin: Streaming upload with --b2-chunk-size 8Mi allows uploads of up to 78.125Gi and will fail only when that limit is reached. 2024/11/05 05:07:44 DEBUG : chunked-8Mi-16Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f2067bd8c2eaa6d83_d20241105_m050744_c001_v0001182_t0013_u01730783264771") 2024/11/05 05:07:44 DEBUG : chunked-8Mi-16Mi-streamed.bin: Sending chunk 0 length 8388608 2024/11/05 05:07:45 DEBUG : chunked-8Mi-16Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2024/11/05 05:07:45 DEBUG : chunked-8Mi-16Mi-streamed.bin: Sending chunk 1 length 8388608 2024/11/05 05:07:49 DEBUG : chunked-8Mi-16Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:07:49 DEBUG : chunked-8Mi-16Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:07:49 DEBUG : chunked-8Mi-16Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217 2024/11/05 05:07:53 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 0 size 8Mi offset 0/16.000Mi 2024/11/05 05:07:53 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 0 length 8388608 2024/11/05 05:07:53 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 1 size 8Mi offset 8Mi/16.000Mi 2024/11/05 05:07:53 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 1 length 8388608 2024/11/05 05:07:53 DEBUG : chunked-8Mi-16.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 16Mi/16.000Mi 2024/11/05 05:07:53 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 2 length 1 2024/11/05 05:07:54 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 2 2024/11/05 05:07:56 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 0 2024/11/05 05:07:56 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 1 2024/11/05 05:07:56 DEBUG : chunked-8Mi-16.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217/Streamed 2024/11/05 05:07:58 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:07:58 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. 2024/11/05 05:07:58 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f212ef77d7ba3013d_d20241105_m050758_c001_v0001161_t0006_u01730783278233") 2024/11/05 05:07:58 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 0 length 8388608 2024/11/05 05:07:58 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2024/11/05 05:07:58 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 2 length 1 2024/11/05 05:07:58 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Sending chunk 1 length 8388608 2024/11/05 05:07:59 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 2 2024/11/05 05:08:00 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:08:01 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:08:01 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 2024/11/05 05:08:03 DEBUG : chunked-10Mi-10.000Mi.bin: multipart upload: starting chunk 1 size 1 offset 10Mi/10.000Mi 2024/11/05 05:08:03 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 1 2024/11/05 05:08:03 DEBUG : chunked-10Mi-10.000Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/10.000Mi 2024/11/05 05:08:03 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 0 length 10485760 2024/11/05 05:08:05 DEBUG : chunked-10Mi-10.000Mi.bin: Done sending chunk 1 2024/11/05 05:08:06 DEBUG : chunked-10Mi-10.000Mi.bin: Done sending chunk 0 2024/11/05 05:08:06 DEBUG : chunked-10Mi-10.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761/Streamed 2024/11/05 05:08:08 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:08:08 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. 2024/11/05 05:08:08 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f2067bd8c2eaa6eb8_d20241105_m050808_c001_v0001182_t0020_u01730783288697") 2024/11/05 05:08:08 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2024/11/05 05:08:08 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Sending chunk 1 length 1 2024/11/05 05:08:08 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Sending chunk 0 length 10485760 2024/11/05 05:08:09 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:08:13 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:08:13 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520 2024/11/05 05:08:16 DEBUG : chunked-10Mi-20Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/20Mi 2024/11/05 05:08:16 DEBUG : chunked-10Mi-20Mi.bin: Sending chunk 0 length 10485760 2024/11/05 05:08:17 DEBUG : chunked-10Mi-20Mi.bin: multipart upload: starting chunk 1 size 10Mi offset 10Mi/20Mi 2024/11/05 05:08:17 DEBUG : chunked-10Mi-20Mi.bin: Sending chunk 1 length 10485760 2024/11/05 05:08:19 DEBUG : chunked-10Mi-20Mi.bin: Done sending chunk 0 2024/11/05 05:08:21 DEBUG : chunked-10Mi-20Mi.bin: Done sending chunk 1 2024/11/05 05:08:21 DEBUG : chunked-10Mi-20Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520/Streamed 2024/11/05 05:08:25 DEBUG : chunked-10Mi-20Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:08:25 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. 2024/11/05 05:08:26 DEBUG : chunked-10Mi-20Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f218b60c3d0ffe74e_d20241105_m050826_c001_v0001182_t0059_u01730783306019") 2024/11/05 05:08:26 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 0 length 10485760 2024/11/05 05:08:26 DEBUG : chunked-10Mi-20Mi-streamed.bin: Not sending empty chunk after EOF - ending. 2024/11/05 05:08:26 DEBUG : chunked-10Mi-20Mi-streamed.bin: Sending chunk 1 length 10485760 2024/11/05 05:08:31 DEBUG : chunked-10Mi-20Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:08:31 DEBUG : chunked-10Mi-20Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:08:31 DEBUG : chunked-10Mi-20Mi-streamed.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521 2024/11/05 05:08:36 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 0 size 10Mi offset 0/20.000Mi 2024/11/05 05:08:36 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 0 length 10485760 2024/11/05 05:08:36 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 2 size 1 offset 20Mi/20.000Mi 2024/11/05 05:08:36 DEBUG : chunked-10Mi-20.000Mi.bin: multipart upload: starting chunk 1 size 10Mi offset 10Mi/20.000Mi 2024/11/05 05:08:36 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 2 length 1 2024/11/05 05:08:36 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 1 length 10485760 2024/11/05 05:08:37 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 2 2024/11/05 05:08:39 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 0 2024/11/05 05:08:39 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 1 2024/11/05 05:08:39 DEBUG : chunked-10Mi-20.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521/Streamed 2024/11/05 05:08:41 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: File is big enough for chunked streaming 2024/11/05 05:08:41 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. 2024/11/05 05:08:42 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Starting streaming of large file (id "4_z3f221d73b04dfbab9f280e1b_f2119bf2f51b7a2f1_d20241105_m050842_c001_v0001097_t0050_u01730783322037") 2024/11/05 05:08:42 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 0 length 10485760 2024/11/05 05:08:42 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Read less than a full chunk 1, making this the last one. 2024/11/05 05:08:42 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 2 length 1 2024/11/05 05:08:42 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Sending chunk 1 length 10485760 2024/11/05 05:08:43 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 2 2024/11/05 05:08:45 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 1 2024/11/05 05:08:46 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Done sending chunk 0 2024/11/05 05:08:46 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 2024/11/05 05:09:03 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_z3f221d73b04dfbab9f280e1b_f20162a7bd8764fe0_d20241105_m050903_c001_v0001172_t0039_u01730783343047") 2024/11/05 05:09:03 DEBUG : chunked-copy.copy: Copying chunk 1 length 1 2024/11/05 05:09:03 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2024/11/05 05:09:03 DEBUG : chunked-copy.copy: Done copying chunk 1 2024/11/05 05:09:04 DEBUG : chunked-copy.copy: Done copying chunk 0 2024/11/05 05:09:04 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485759 2024/11/05 05:09:05 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10.000Mi 2024/11/05 05:09:05 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2024/11/05 05:09:06 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4.000Mi offset 6Mi/10.000Mi 2024/11/05 05:09:06 DEBUG : chunked-copy: Sending chunk 1 length 4194303 2024/11/05 05:09:08 DEBUG : chunked-copy: Done sending chunk 0 2024/11/05 05:09:08 DEBUG : chunked-copy: Done sending chunk 1 2024/11/05 05:09:08 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2024/11/05 05:09:09 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_z3f221d73b04dfbab9f280e1b_f20162a7bd876503f_d20241105_m050908_c001_v0001090_t0054_u01730783348963") 2024/11/05 05:09:09 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242879 2024/11/05 05:09:09 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2024/11/05 05:09:09 DEBUG : chunked-copy.copy: Done copying chunk 0 2024/11/05 05:09:09 DEBUG : chunked-copy.copy: Done copying chunk 1 2024/11/05 05:09:09 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485760 2024/11/05 05:09:11 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10Mi 2024/11/05 05:09:11 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2024/11/05 05:09:11 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4Mi offset 6Mi/10Mi 2024/11/05 05:09:11 DEBUG : chunked-copy: Sending chunk 1 length 4194304 2024/11/05 05:09:14 DEBUG : chunked-copy: Done sending chunk 1 2024/11/05 05:09:14 DEBUG : chunked-copy: Done sending chunk 0 2024/11/05 05:09:14 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2024/11/05 05:09:15 DEBUG : chunked-copy.copy: Starting copy of large file in 2 chunks (id "4_z3f221d73b04dfbab9f280e1b_f2086d03dd05a3d53_d20241105_m050915_c001_v0001182_t0040_u01730783355284") 2024/11/05 05:09:15 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242880 2024/11/05 05:09:15 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2024/11/05 05:09:17 DEBUG : chunked-copy.copy: Done copying chunk 0 2024/11/05 05:09:17 DEBUG : chunked-copy.copy: Done copying chunk 1 2024/11/05 05:09:17 DEBUG : chunked-copy.copy: Finishing large file copy with 2 parts === RUN TestIntegration/FsMkdir/FsCopyChunked/10485761 2024/11/05 05:09:19 DEBUG : chunked-copy: multipart upload: starting chunk 0 size 6Mi offset 0/10.000Mi 2024/11/05 05:09:19 DEBUG : chunked-copy: Sending chunk 0 length 6291456 2024/11/05 05:09:19 DEBUG : chunked-copy: multipart upload: starting chunk 1 size 4.000Mi offset 6Mi/10.000Mi 2024/11/05 05:09:19 DEBUG : chunked-copy: Sending chunk 1 length 4194305 2024/11/05 05:09:21 DEBUG : chunked-copy: Done sending chunk 0 2024/11/05 05:09:21 DEBUG : chunked-copy: Done sending chunk 1 2024/11/05 05:09:21 DEBUG : chunked-copy: Finishing large file upload with 2 parts 2024/11/05 05:09:22 DEBUG : chunked-copy.copy: Starting copy of large file in 3 chunks (id "4_z3f221d73b04dfbab9f280e1b_f20162a7bd87650f0_d20241105_m050922_c001_v0001182_t0044_u01730783362657") 2024/11/05 05:09:22 DEBUG : chunked-copy.copy: Copying chunk 2 length 1 2024/11/05 05:09:22 DEBUG : chunked-copy.copy: Copying chunk 0 length 5242880 2024/11/05 05:09:22 DEBUG : chunked-copy.copy: Copying chunk 1 length 5242880 2024/11/05 05:09:23 DEBUG : chunked-copy.copy: Done copying chunk 2 2024/11/05 05:09:24 DEBUG : chunked-copy.copy: Done copying chunk 0 2024/11/05 05:09:25 DEBUG : chunked-copy.copy: Done copying chunk 1 2024/11/05 05:09:25 DEBUG : chunked-copy.copy: Finishing large file copy with 3 parts === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2024/11/05 05:09:27 DEBUG : unknown-size-put.txt: File has 100 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2024/11/05 05:09:29 DEBUG : unknown-size-update.txt: File has 200 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsRootCollapse 2024/11/05 05:09:29 DEBUG : Creating backend with remote "TestB2:rclone-test-hasuqur9xiza/deeper/nonexisting/directory" 2024/11/05 05:09:29 DEBUG : Config file has changed externally - reloading === RUN TestIntegration/FsMkdir/FsDirSetModTime fstests.go:2500: FS has no DirSetModTime interface === RUN TestIntegration/FsMkdir/FsMkdirMetadata fstests.go:2543: FS has no MkdirMetadata interface === RUN TestIntegration/FsMkdir/FsDirectory fstests.go:2592: Can't test if can't have empty directories 2024/11/05 05:09:31 INFO : B2 bucket rclone-test-hasuqur9xiza: cleaning bucket "rclone-test-hasuqur9xiza" of all files 2024/11/05 05:09:31 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f418b60c3d0ffe69a_d20241105_m050816_c001_v7007000_t0000_u01730783296148") 2024/11/05 05:09:31 DEBUG : chunked-10Mi-10.000Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2067bd8c2eaa6eb8_d20241105_m050808_c001_v0001182_t0020_u01730783288697") 2024/11/05 05:09:31 DEBUG : chunked-10Mi-10.000Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4067bd8c2eaa6ead_d20241105_m050808_c001_v7007000_t0000_u01730783288174") 2024/11/05 05:09:31 DEBUG : chunked-10Mi-10.000Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2067bd8c2eaa6e4c_d20241105_m050803_c001_v0001098_t0053_u01730783283497") 2024/11/05 05:09:31 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f40162a7bd8764fa7_d20241105_m050850_c001_v7007000_t0000_u01730783330244") 2024/11/05 05:09:31 DEBUG : chunked-10Mi-20.000Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2119bf2f51b7a2f1_d20241105_m050842_c001_v0001097_t0050_u01730783322037") 2024/11/05 05:09:31 DEBUG : chunked-10Mi-20.000Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4119bf2f51b7a2f0_d20241105_m050841_c001_v7007000_t0000_u01730783321528") 2024/11/05 05:09:31 DEBUG : chunked-10Mi-20.000Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f20428dd177641590_d20241105_m050835_c001_v0001154_t0012_u01730783315978") 2024/11/05 05:09:31 DEBUG : chunked-10Mi-20Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f40428dd17764158f_d20241105_m050835_c001_v7007000_t0000_u01730783315797") 2024/11/05 05:09:32 DEBUG : chunked-10Mi-20Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f218b60c3d0ffe74e_d20241105_m050826_c001_v0001182_t0059_u01730783306019") 2024/11/05 05:09:32 DEBUG : chunked-10Mi-20Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f418b60c3d0ffe74a_d20241105_m050825_c001_v7007000_t0000_u01730783305476") 2024/11/05 05:09:32 DEBUG : chunked-10Mi-20Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f218b60c3d0ffe69d_d20241105_m050816_c001_v0001136_t0015_u01730783296334") 2024/11/05 05:09:32 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f416ca964b982897e_d20241105_m050523_c001_v7007000_t0000_u01730783123486") 2024/11/05 05:09:32 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2153c5e7a7ac15ef_d20241105_m050518_c001_v0001109_t0018_u01730783118050") 2024/11/05 05:09:32 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f40585b838fe05483_d20241105_m050512_c001_v7007000_t0000_u01730783112195") 2024/11/05 05:09:32 DEBUG : chunked-5.054Mi-10.109Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2199817b6e63989f_d20241105_m050508_c001_v0001157_t0040_u01730783108225") 2024/11/05 05:09:32 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4153c5e7a7ac15ec_d20241105_m050517_c001_v7007000_t0000_u01730783117684") 2024/11/05 05:09:32 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f20585b838fe05486_d20241105_m050512_c001_v0001182_t0030_u01730783112380") 2024/11/05 05:09:32 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4199817b6e63989c_d20241105_m050507_c001_v7007000_t0000_u01730783107876") 2024/11/05 05:09:32 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f20585b838fe0545a_d20241105_m050503_c001_v0001105_t0000_u01730783103854") 2024/11/05 05:09:32 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f40585b838fe05458_d20241105_m050503_c001_v7007000_t0000_u01730783103644") 2024/11/05 05:09:32 DEBUG : chunked-5.054Mi-5.054Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2199817b6e639864_d20241105_m050500_c001_v0001145_t0042_u01730783100307") 2024/11/05 05:09:33 DEBUG : chunked-5.054Mi-5.054Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4199817b6e639863_d20241105_m050459_c001_v7007000_t0000_u01730783099959") 2024/11/05 05:09:33 DEBUG : chunked-5.054Mi-5.054Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f20585b838fe0542e_d20241105_m050455_c001_v0001182_t0016_u01730783095314") 2024/11/05 05:09:33 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4153c5e7a7ac16eb_d20241105_m050552_c001_v7007000_t0000_u01730783152666") 2024/11/05 05:09:33 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2153c5e7a7ac16e3_d20241105_m050548_c001_v0001150_t0035_u01730783148734") 2024/11/05 05:09:33 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4153c5e7a7ac16cf_d20241105_m050543_c001_v7007000_t0000_u01730783143965") 2024/11/05 05:09:33 DEBUG : chunked-5.055Mi-10.109Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2153c5e7a7ac1690_d20241105_m050539_c001_v0001104_t0009_u01730783139285") 2024/11/05 05:09:33 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4153c5e7a7ac16e1_d20241105_m050548_c001_v7007000_t0000_u01730783148391") 2024/11/05 05:09:33 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2153c5e7a7ac16d3_d20241105_m050544_c001_v0001145_t0017_u01730783144148") 2024/11/05 05:09:33 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4153c5e7a7ac168e_d20241105_m050538_c001_v7007000_t0000_u01730783138934") 2024/11/05 05:09:33 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f202d19b89b6e6c2e_d20241105_m050534_c001_v0001113_t0045_u01730783134684") 2024/11/05 05:09:33 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f402d19b89b6e6c2c_d20241105_m050534_c001_v7007000_t0000_u01730783134502") 2024/11/05 05:09:33 DEBUG : chunked-5.055Mi-5.055Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f202d19b89b6e6bde_d20241105_m050529_c001_v0001182_t0037_u01730783129553") 2024/11/05 05:09:33 DEBUG : chunked-5.055Mi-5.055Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f402d19b89b6e6bd5_d20241105_m050529_c001_v7007000_t0000_u01730783129201") 2024/11/05 05:09:33 DEBUG : chunked-5.055Mi-5.055Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f216ca964b982897f_d20241105_m050523_c001_v0001182_t0040_u01730783123672") 2024/11/05 05:09:33 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f40585b838fe0542c_d20241105_m050455_c001_v7007000_t0000_u01730783095123") 2024/11/05 05:09:33 DEBUG : chunked-5Mi-10.000Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f20585b838fe05415_d20241105_m050450_c001_v0001104_t0051_u01730783090729") 2024/11/05 05:09:33 DEBUG : chunked-5Mi-10.000Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f40585b838fe05412_d20241105_m050450_c001_v7007000_t0000_u01730783090369") 2024/11/05 05:09:33 DEBUG : chunked-5Mi-10.000Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f202d19b89b6e6afb_d20241105_m050445_c001_v0001115_t0059_u01730783085221") 2024/11/05 05:09:34 DEBUG : chunked-5Mi-10Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f402d19b89b6e6af9_d20241105_m050445_c001_v7007000_t0000_u01730783085032") 2024/11/05 05:09:34 DEBUG : chunked-5Mi-10Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f202d19b89b6e6aed_d20241105_m050440_c001_v0001097_t0048_u01730783080920") 2024/11/05 05:09:34 DEBUG : chunked-5Mi-10Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f402d19b89b6e6aec_d20241105_m050440_c001_v7007000_t0000_u01730783080586") 2024/11/05 05:09:34 DEBUG : chunked-5Mi-10Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f202d19b89b6e6adf_d20241105_m050436_c001_v0001120_t0056_u01730783076511") 2024/11/05 05:09:34 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f402d19b89b6e6add_d20241105_m050436_c001_v7007000_t0000_u01730783076325") 2024/11/05 05:09:34 DEBUG : chunked-5Mi-5.000Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f202d19b89b6e6ad4_d20241105_m050431_c001_v0001144_t0049_u01730783071983") 2024/11/05 05:09:34 DEBUG : chunked-5Mi-5.000Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f402d19b89b6e6ad1_d20241105_m050431_c001_v7007000_t0000_u01730783071614") 2024/11/05 05:09:34 DEBUG : chunked-5Mi-5.000Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f205dedf207c37128_d20241105_m050426_c001_v0001145_t0018_u01730783066513") 2024/11/05 05:09:34 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4076ed8f9e866ec3_d20241105_m050722_c001_v7007000_t0000_u01730783242266") 2024/11/05 05:09:34 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f202d19b89b6e7206_d20241105_m050717_c001_v0001137_t0015_u01730783237580") 2024/11/05 05:09:34 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f40585b838fe056bc_d20241105_m050614_c001_v7007000_t0000_u01730783174120") 2024/11/05 05:09:34 DEBUG : chunked-6.667Mi-13.333Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f20585b838fe056b1_d20241105_m050609_c001_v0001113_t0014_u01730783169514") 2024/11/05 05:09:34 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f402d19b89b6e7203_d20241105_m050717_c001_v7007000_t0000_u01730783237161") 2024/11/05 05:09:34 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f20585b838fe059b3_d20241105_m050711_c001_v0001097_t0045_u01730783231028") 2024/11/05 05:09:34 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f40585b838fe056b0_d20241105_m050609_c001_v7007000_t0000_u01730783169109") 2024/11/05 05:09:34 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f20585b838fe056a2_d20241105_m050604_c001_v0001179_t0035_u01730783164675") 2024/11/05 05:09:35 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f40585b838fe056a1_d20241105_m050604_c001_v7007000_t0000_u01730783164485") 2024/11/05 05:09:35 DEBUG : chunked-6.667Mi-6.667Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f207cf25182a2b1e4_d20241105_m050600_c001_v0001104_t0043_u01730783160339") 2024/11/05 05:09:35 DEBUG : chunked-6.667Mi-6.667Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f407cf25182a2b1e1_d20241105_m050559_c001_v7007000_t0000_u01730783159935") 2024/11/05 05:09:35 DEBUG : chunked-6.667Mi-6.667Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2153c5e7a7ac16ee_d20241105_m050552_c001_v0001144_t0028_u01730783152851") 2024/11/05 05:09:35 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4067bd8c2eaa6e47_d20241105_m050803_c001_v7007000_t0000_u01730783283307") 2024/11/05 05:09:35 DEBUG : chunked-8Mi-16.000Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f212ef77d7ba3013d_d20241105_m050758_c001_v0001161_t0006_u01730783278233") 2024/11/05 05:09:35 DEBUG : chunked-8Mi-16.000Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f412ef77d7ba3013c_d20241105_m050757_c001_v7007000_t0000_u01730783277811") 2024/11/05 05:09:35 DEBUG : chunked-8Mi-16.000Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2067bd8c2eaa6dab_d20241105_m050752_c001_v0001172_t0033_u01730783272917") 2024/11/05 05:09:35 DEBUG : chunked-8Mi-16Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4067bd8c2eaa6da7_d20241105_m050752_c001_v7007000_t0000_u01730783272727") 2024/11/05 05:09:35 DEBUG : chunked-8Mi-16Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2067bd8c2eaa6d83_d20241105_m050744_c001_v0001182_t0013_u01730783264771") 2024/11/05 05:09:35 DEBUG : chunked-8Mi-16Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4067bd8c2eaa6d81_d20241105_m050744_c001_v7007000_t0000_u01730783264307") 2024/11/05 05:09:35 DEBUG : chunked-8Mi-16Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f20585b838fe05f0e_d20241105_m050734_c001_v0001130_t0054_u01730783254837") 2024/11/05 05:09:35 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f40585b838fe05f09_d20241105_m050734_c001_v7007000_t0000_u01730783254646") 2024/11/05 05:09:35 DEBUG : chunked-8Mi-8.000Mi-streamed.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f202d19b89b6e725e_d20241105_m050730_c001_v0001157_t0007_u01730783250803") 2024/11/05 05:09:35 DEBUG : chunked-8Mi-8.000Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f402d19b89b6e725b_d20241105_m050730_c001_v7007000_t0000_u01730783250355") 2024/11/05 05:09:35 DEBUG : chunked-8Mi-8.000Mi.bin: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2076ed8f9e866ec5_d20241105_m050722_c001_v0001182_t0008_u01730783242449") 2024/11/05 05:09:35 DEBUG : chunked-copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4199817b6e63a276_d20241105_m050926_c001_v7007000_t0000_u01730783366969") 2024/11/05 05:09:35 DEBUG : chunked-copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2086d03dd05a3d59_d20241105_m050919_c001_v0001134_t0018_u01730783359359") 2024/11/05 05:09:36 DEBUG : chunked-copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4086d03dd05a3d58_d20241105_m050918_c001_v7007000_t0000_u01730783358866") 2024/11/05 05:09:36 DEBUG : chunked-copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f20162a7bd876506a_d20241105_m050911_c001_v0001032_t0059_u01730783351484") 2024/11/05 05:09:36 DEBUG : chunked-copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f40162a7bd8765065_d20241105_m050910_c001_v7007000_t0000_u01730783350871") 2024/11/05 05:09:36 DEBUG : chunked-copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2086d03dd05a3d4b_d20241105_m050905_c001_v0001179_t0014_u01730783345718") 2024/11/05 05:09:36 DEBUG : chunked-copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4086d03dd05a3d4a_d20241105_m050905_c001_v7007000_t0000_u01730783345184") 2024/11/05 05:09:36 DEBUG : chunked-copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f1179dd16dae8d29c_d20241105_m050859_c001_v0001182_t0047_u01730783339167") 2024/11/05 05:09:36 DEBUG : chunked-copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f40162a7bd8764fc1_d20241105_m050858_c001_v7007000_t0000_u01730783338821") 2024/11/05 05:09:36 DEBUG : chunked-copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f1179dd16dae8d049_d20241105_m050850_c001_v0001182_t0045_u01730783330966") 2024/11/05 05:09:36 DEBUG : chunked-copy.copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4199817b6e63a272_d20241105_m050926_c001_v7007000_t0000_u01730783366783") 2024/11/05 05:09:36 DEBUG : chunked-copy.copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f20162a7bd87650f0_d20241105_m050922_c001_v0001182_t0044_u01730783362657") 2024/11/05 05:09:36 DEBUG : chunked-copy.copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4086d03dd05a3d57_d20241105_m050918_c001_v7007000_t0000_u01730783358683") 2024/11/05 05:09:36 DEBUG : chunked-copy.copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f2086d03dd05a3d53_d20241105_m050915_c001_v0001182_t0040_u01730783355284") 2024/11/05 05:09:36 DEBUG : chunked-copy.copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f40162a7bd8765063_d20241105_m050910_c001_v7007000_t0000_u01730783350689") 2024/11/05 05:09:36 DEBUG : chunked-copy.copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f20162a7bd876503f_d20241105_m050908_c001_v0001090_t0054_u01730783348963") 2024/11/05 05:09:36 DEBUG : chunked-copy.copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4086d03dd05a3d49_d20241105_m050905_c001_v7007000_t0000_u01730783345003") 2024/11/05 05:09:36 DEBUG : chunked-copy.copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f20162a7bd8764fe0_d20241105_m050903_c001_v0001172_t0039_u01730783343047") 2024/11/05 05:09:36 DEBUG : chunked-copy.copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f40162a7bd8764fc0_d20241105_m050858_c001_v7007000_t0000_u01730783338640") 2024/11/05 05:09:36 DEBUG : chunked-copy.copy: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f114aa8449643f0b9_d20241105_m050854_c001_v0001182_t0038_u01730783334911") 2024/11/05 05:09:37 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f103e9ab060bde1a0_d20241105_m050310_c001_v0001178_t0012_u01730782990454") 2024/11/05 05:09:37 DEBUG : unknown-size-put.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4199817b6e63a285_d20241105_m050928_c001_v7007000_t0000_u01730783368243") 2024/11/05 05:09:37 DEBUG : unknown-size-put.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f1179dd16dae8d92c_d20241105_m050927_c001_v0001182_t0005_u01730783367513") 2024/11/05 05:09:37 DEBUG : unknown-size-update.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f4199817b6e63a28e_d20241105_m050929_c001_v7007000_t0000_u01730783369778") 2024/11/05 05:09:37 DEBUG : unknown-size-update.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f1179dd16dae8d99d_d20241105_m050929_c001_v0001182_t0043_u01730783369316") 2024/11/05 05:09:37 DEBUG : unknown-size-update.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f1179dd16dae8d95d_d20241105_m050928_c001_v0001182_t0041_u01730783368436") 2024/11/05 05:09:37 DEBUG : versions/test-versions.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f405dedf207c37127_d20241105_m050426_c001_v7007000_t0000_u01730783066331") 2024/11/05 05:09:37 DEBUG : versions/test-versions.txt: Deleting (id "4_z3f221d73b04dfbab9f280e1b_f1179dd16dae88b92_d20241105_m050417_c001_v0001182_t0040_u01730783057392") 2024/11/05 05:09:38 INFO : B2 bucket rclone-test-hasuqur9xiza: cleaning bucket "rclone-test-hasuqur9xiza" of all files === RUN TestIntegration/FsShutdown fstests.go:2706: Shutdown method not supported --- PASS: TestIntegration (422.06s) --- SKIP: TestIntegration/FsCheckWrap (0.00s) --- PASS: TestIntegration/FsCommand (0.00s) --- PASS: TestIntegration/FsRmdirNotFound (0.59s) --- PASS: TestIntegration/FsString (0.00s) --- PASS: TestIntegration/FsName (0.00s) --- PASS: TestIntegration/FsRoot (0.00s) --- PASS: TestIntegration/FsRmdirEmpty (0.57s) --- PASS: TestIntegration/FsMkdir (419.61s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (0.54s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.18s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.18s) --- PASS: TestIntegration/FsMkdir/FsListRDirEmpty (0.18s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.18s) --- PASS: TestIntegration/FsMkdir/FsListRDirNotFound (0.19s) --- PASS: TestIntegration/FsMkdir/FsEncoding (19.29s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (1.85s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (0.96s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (0.95s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (0.95s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (0.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (0.99s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (0.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (0.95s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (0.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (0.95s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (0.95s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (1.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (1.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (0.97s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (0.95s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (0.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (0.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (0.96s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (0.96s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.36s) --- PASS: TestIntegration/FsMkdir/FsPutError (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (1.10s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- PASS: TestIntegration/FsMkdir/FsOpenChunkWriter (7.00s) --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (76.82s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (0.94s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.94s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (1.24s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (1.56s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.58s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (0.24s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.20s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (1.88s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (3.44s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir (4.77s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (2.29s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.21s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.19s) --- 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 (0.82s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.38s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (0.91s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.39s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (1.53s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (1.92s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (1.85s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (9.08s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (0.97s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.23s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (4.68s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (2.65s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (0.40s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/PublicLink (7.65s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (0.56s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (1.52s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (0.92s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (0.60s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (26.31s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata (7.06s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize (1.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize/GzipEncoding (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size1kiB/UploadCutoff/ChunkSize/GzipEncoding/NoDecompress (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB (6.06s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB/GzipEncoding (1.83s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Metadata/Size10MiB/UploadCutoff6MiB/ChunkSize6MiB/GzipEncoding/NoDecompress (1.83s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions (19.25s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions (2.13s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/ReadCurrent (0.21s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Versions/ReadVersion (0.25s) --- 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.22s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before (0.41s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/Before/List (0.41s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterOne/List (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterDelete/List (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/VersionAt/AfterTwo/List (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/Versions/Cleanup (4.28s) --- PASS: TestIntegration/FsMkdir/FsPutChunked (263.91s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi (28.79s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881 (10.00s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881/Streamed (4.71s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760 (8.70s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760/Streamed (4.45s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761 (10.09s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761/Streamed (4.75s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi (28.36s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001 (8.54s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001/Streamed (3.69s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000 (8.53s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000/Streamed (4.32s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001 (11.29s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001/Streamed (5.80s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi (29.19s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054 (11.02s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054/Streamed (5.30s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106 (9.47s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106/Streamed (5.03s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107 (8.70s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107/Streamed (4.28s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi (89.60s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507 (11.81s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507/Streamed (4.54s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012 (9.63s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012/Streamed (5.01s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013 (68.15s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013/Streamed (5.11s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi (41.04s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609 (12.38s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609/Streamed (4.29s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216 (18.08s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216/Streamed (8.42s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217 (10.59s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217/Streamed (5.50s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi (46.93s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761 (12.84s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761/Streamed (7.95s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520 (19.65s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520/Streamed (10.32s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521 (14.45s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521/Streamed (8.72s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked (36.72s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/5242880 (8.57s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/5242881 (6.36s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485759 (5.69s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485760 (7.99s) --- PASS: TestIntegration/FsMkdir/FsCopyChunked/10485761 (8.11s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (2.84s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (1.28s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (1.56s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (1.51s) --- 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 422.073s "go test -v -timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 7m2.719145793s (try 1/5)