"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:166: potato: want error strconv.ParseInt: parsing "potato": invalid syntax got error --- PASS: TestParseTimeString (0.00s) === RUN TestIntegration fstests.go:418: Using remote "TestB2:" 2022/03/24 05:28:57 DEBUG : Creating backend with remote "TestB2:rclone-test-cowajiy3wirojuw5lajuvud4" 2022/03/24 05:28:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" === RUN TestIntegration/FsCheckWrap fstests.go:459: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:487: No commands in this remote === RUN TestIntegration/FsRmdirNotFound === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2022/03/24 05:29:01 DEBUG : dir/subdir: Making directory fstest.go:251: Filtering empty directory "dir" fstest.go:251: Filtering empty directory "dir/subdir" 2022/03/24 05:29:01 INFO : dir/subdir: Removing directory fstest.go:251: Filtering empty directory "dir" 2022/03/24 05:29:01 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:674: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:674: testing "．" === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:674: testing "．．" === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:674: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:674: testing " leading space" === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:674: testing "~leading tilde" === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:674: testing "␍leading CR" === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:674: testing "␊leading LF" === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:674: testing "␉leading HT" === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:674: testing "␋leading VT" === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:674: testing ".leading dot" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:674: testing "trailing space " === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:674: testing "trailing CR␍" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:674: testing "trailing LF␊" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:674: testing "trailing HT␉" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:674: testing "trailing VT␋" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:674: testing "trailing dot." === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:674: testing "invalid utf-8\xfe" === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:674: testing "test%46.txt" === RUN TestIntegration/FsMkdir/FsNewObjectNotFound 2022/03/24 05:30:12 DEBUG : Couldn't decode error response: EOF 2022/03/24 05:30:12 DEBUG : Couldn't decode error response: EOF === RUN TestIntegration/FsMkdir/FsPutError 2022/03/24 05:30:12 DEBUG : Couldn't decode error response: EOF === RUN TestIntegration/FsMkdir/FsPutZeroLength === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:747: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:783: 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 2022/03/24 05:30:20 DEBUG : Creating backend with remote "TestB2:" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot 2022/03/24 05:30:21 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:1036: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2022/03/24 05:30:23 DEBUG : Couldn't decode error response: EOF === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2022/03/24 05:30:25 DEBUG : dirToPurge/fileToPurge.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c9b48_d20220324_m053024_c001_v0001115_t0008_u01648099824153") === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove fstests.go:1139: FS has no Mover interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove fstests.go:1210: 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/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 2022/03/24 05:30:36 DEBUG : Creating backend with remote "TestB2:rclone-test-cowajiy3wirojuw5lajuvud4/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2022/03/24 05:30:37 DEBUG : Creating backend with remote "TestB2:rclone-test-cowajiy3wirojuw5lajuvud4/not found.txt" 2022/03/24 05:30:38 DEBUG : Couldn't decode error response: EOF === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1503: Opening root remote "TestB2:" path "rclone-test-cowajiy3wirojuw5lajuvud4" from "TestB2:rclone-test-cowajiy3wirojuw5lajuvud4" 2022/03/24 05:30:38 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 2022/03/24 05:30:50 DEBUG : Couldn't decode error response: EOF 2022/03/24 05:30:51 DEBUG : Couldn't decode error response: EOF 2022/03/24 05:30:52 DEBUG : Couldn't decode error response: EOF 2022/03/24 05:30:52 DEBUG : Creating backend with remote "TestB2:rclone-test-yufekas5mofidez2rukayaf4" 2022/03/24 05:30:55 DEBUG : Couldn't decode error response: EOF 2022/03/24 05:30:55 DEBUG : B2 bucket rclone-test-yufekas5mofidez2rukayaf4: Purge remote 2022/03/24 05:30:55 DEBUG : somefile: Deleting (id "4_zcf020d43608dbbab7ff80e1b_f1197c44a71b6bd63_d20220324_m053054_c001_v0001137_t0032_u01648099854724") === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:392: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:1699: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout fstests.go:1728: FS does not support About === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 2022/03/24 05:30:58 DEBUG : piped data.txt: File has 0 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2022/03/24 05:31:01 DEBUG : piped data.txt: File has 100 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsPutFiles/Internal fstests.go:1790: *b2.Fs does not implement InternalTester === RUN TestIntegration/FsMkdir/FsPutChunked === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881 2022/03/24 05:31:02 DEBUG : chunked-5Mi-5.000Mi.bin: Starting upload of large file in 2 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f2027a3abbe737332_d20220324_m053102_c001_v0001134_t0058_u01648099862610") 2022/03/24 05:31:02 DEBUG : chunked-5Mi-5.000Mi.bin: Sending chunk 1 length 5242880 2022/03/24 05:31:02 DEBUG : chunked-5Mi-5.000Mi.bin: Sending chunk 2 length 1 2022/03/24 05:31:04 DEBUG : chunked-5Mi-5.000Mi.bin: Done sending chunk 2 2022/03/24 05:31:06 DEBUG : chunked-5Mi-5.000Mi.bin: Done sending chunk 1 2022/03/24 05:31:06 DEBUG : chunked-5Mi-5.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760 2022/03/24 05:31:10 DEBUG : chunked-5Mi-10Mi.bin: Starting upload of large file in 2 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f2027a3abbe737334_d20220324_m053110_c001_v0001115_t0034_u01648099870194") 2022/03/24 05:31:10 DEBUG : chunked-5Mi-10Mi.bin: Sending chunk 1 length 5242880 2022/03/24 05:31:10 DEBUG : chunked-5Mi-10Mi.bin: Sending chunk 2 length 5242880 2022/03/24 05:31:11 DEBUG : chunked-5Mi-10Mi.bin: Error sending chunk 1 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc00030e300), retryAfter:1000000000} 2022/03/24 05:31:11 DEBUG : chunked-5Mi-10Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2022/03/24 05:31:11 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable)) 2022/03/24 05:31:11 DEBUG : pacer: Rate limited, increasing sleep to 1s 2022/03/24 05:31:11 DEBUG : chunked-5Mi-10Mi.bin: Sending chunk 1 length 5242880 2022/03/24 05:31:11 DEBUG : chunked-5Mi-10Mi.bin: Error sending chunk 2 (retry=true): no tomes available (503 service_unavailable): &pacer.retryAfterError{error:(*api.Error)(0xc0002f9920), retryAfter:1000000000} 2022/03/24 05:31:11 DEBUG : chunked-5Mi-10Mi.bin: Clearing part upload URL because of error: no tomes available (503 service_unavailable) 2022/03/24 05:31:11 DEBUG : pacer: low level retry 1/10 (error no tomes available (503 service_unavailable)) 2022/03/24 05:31:12 DEBUG : pacer: Reducing sleep to 500ms 2022/03/24 05:31:13 DEBUG : chunked-5Mi-10Mi.bin: Sending chunk 2 length 5242880 2022/03/24 05:31:14 DEBUG : pacer: Reducing sleep to 250ms 2022/03/24 05:31:15 DEBUG : pacer: Reducing sleep to 125ms 2022/03/24 05:31:15 DEBUG : chunked-5Mi-10Mi.bin: Done sending chunk 1 2022/03/24 05:31:17 DEBUG : pacer: Reducing sleep to 62.5ms 2022/03/24 05:31:17 DEBUG : chunked-5Mi-10Mi.bin: Done sending chunk 2 2022/03/24 05:31:17 DEBUG : chunked-5Mi-10Mi.bin: Finishing large file upload with 2 parts 2022/03/24 05:31:18 DEBUG : pacer: Reducing sleep to 31.25ms 2022/03/24 05:31:19 DEBUG : pacer: Reducing sleep to 15.625ms 2022/03/24 05:31:19 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761 2022/03/24 05:31:22 DEBUG : chunked-5Mi-10.000Mi.bin: Starting upload of large file in 3 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f2027a3abbe73733b_d20220324_m053122_c001_v0001116_t0045_u01648099882140") 2022/03/24 05:31:22 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 1 length 5242880 2022/03/24 05:31:22 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 2 length 5242880 2022/03/24 05:31:22 DEBUG : chunked-5Mi-10.000Mi.bin: Sending chunk 3 length 1 2022/03/24 05:31:24 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 3 2022/03/24 05:31:25 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 1 2022/03/24 05:31:28 DEBUG : chunked-5Mi-10.000Mi.bin: Done sending chunk 2 2022/03/24 05:31:28 DEBUG : chunked-5Mi-10.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001 2022/03/24 05:31:33 DEBUG : chunked-5.054Mi-5.054Mi.bin: Starting upload of large file in 2 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f213258e9966b1078_d20220324_m053133_c001_v0001022_t0036_u01648099893638") 2022/03/24 05:31:33 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 2 length 1 2022/03/24 05:31:33 DEBUG : chunked-5.054Mi-5.054Mi.bin: Sending chunk 1 length 5300000 2022/03/24 05:31:35 DEBUG : chunked-5.054Mi-5.054Mi.bin: Done sending chunk 2 2022/03/24 05:31:37 DEBUG : chunked-5.054Mi-5.054Mi.bin: Done sending chunk 1 2022/03/24 05:31:37 DEBUG : chunked-5.054Mi-5.054Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000 2022/03/24 05:31:40 DEBUG : chunked-5.054Mi-10.109Mi.bin: Starting upload of large file in 2 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f213258e9966b107a_d20220324_m053140_c001_v0001161_t0039_u01648099900536") 2022/03/24 05:31:40 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 1 length 5300000 2022/03/24 05:31:41 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 2 length 5300000 2022/03/24 05:31:43 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 1 2022/03/24 05:31:43 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 2 2022/03/24 05:31:43 DEBUG : chunked-5.054Mi-10.109Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001 2022/03/24 05:31:46 DEBUG : chunked-5.054Mi-10.109Mi.bin: Starting upload of large file in 3 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f213258e9966b107c_d20220324_m053146_c001_v0001117_t0056_u01648099906524") 2022/03/24 05:31:46 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 1 length 5300000 2022/03/24 05:31:47 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 3 length 1 2022/03/24 05:31:47 DEBUG : chunked-5.054Mi-10.109Mi.bin: Sending chunk 2 length 5300000 2022/03/24 05:31:48 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 3 2022/03/24 05:31:50 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 2 2022/03/24 05:31:52 DEBUG : chunked-5.054Mi-10.109Mi.bin: Done sending chunk 1 2022/03/24 05:31:52 DEBUG : chunked-5.054Mi-10.109Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054 2022/03/24 05:32:03 DEBUG : chunked-5.055Mi-5.055Mi.bin: Starting upload of large file in 2 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f21033cabc9a97635_d20220324_m053202_c001_v0001144_t0014_u01648099922921") 2022/03/24 05:32:03 DEBUG : chunked-5.055Mi-5.055Mi.bin: Sending chunk 2 length 1 2022/03/24 05:32:03 DEBUG : chunked-5.055Mi-5.055Mi.bin: Sending chunk 1 length 5300053 2022/03/24 05:32:04 DEBUG : chunked-5.055Mi-5.055Mi.bin: Done sending chunk 2 2022/03/24 05:32:06 DEBUG : chunked-5.055Mi-5.055Mi.bin: Done sending chunk 1 2022/03/24 05:32:06 DEBUG : chunked-5.055Mi-5.055Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106 2022/03/24 05:32:10 DEBUG : chunked-5.055Mi-10.109Mi.bin: Starting upload of large file in 2 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f21033cabc9a97636_d20220324_m053210_c001_v0001117_t0016_u01648099930186") 2022/03/24 05:32:10 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 1 length 5300053 2022/03/24 05:32:10 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 2 length 5300053 2022/03/24 05:32:16 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 1 2022/03/24 05:32:16 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 2 2022/03/24 05:32:16 DEBUG : chunked-5.055Mi-10.109Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107 2022/03/24 05:32:22 DEBUG : chunked-5.055Mi-10.109Mi.bin: Starting upload of large file in 3 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f2193ebf0a60bf3d2_d20220324_m053221_c001_v0001146_t0027_u01648099941980") 2022/03/24 05:32:22 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 1 length 5300053 2022/03/24 05:32:22 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 3 length 1 2022/03/24 05:32:22 DEBUG : chunked-5.055Mi-10.109Mi.bin: Sending chunk 2 length 5300053 2022/03/24 05:32:23 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 3 2022/03/24 05:32:24 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 1 2022/03/24 05:32:25 DEBUG : chunked-5.055Mi-10.109Mi.bin: Done sending chunk 2 2022/03/24 05:32:25 DEBUG : chunked-5.055Mi-10.109Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507 2022/03/24 05:32:31 DEBUG : chunked-6.667Mi-6.667Mi.bin: Starting upload of large file in 2 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f2193ebf0a60bf3dc_d20220324_m053231_c001_v0001116_t0005_u01648099951365") 2022/03/24 05:32:31 DEBUG : chunked-6.667Mi-6.667Mi.bin: Sending chunk 2 length 1 2022/03/24 05:32:31 DEBUG : chunked-6.667Mi-6.667Mi.bin: Sending chunk 1 length 6990506 2022/03/24 05:32:33 DEBUG : chunked-6.667Mi-6.667Mi.bin: Done sending chunk 2 2022/03/24 05:32:35 DEBUG : chunked-6.667Mi-6.667Mi.bin: Done sending chunk 1 2022/03/24 05:32:35 DEBUG : chunked-6.667Mi-6.667Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012 2022/03/24 05:32:39 DEBUG : chunked-6.667Mi-13.333Mi.bin: Starting upload of large file in 2 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f2193ebf0a60bf3e2_d20220324_m053239_c001_v0001117_t0043_u01648099959061") 2022/03/24 05:32:39 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2022/03/24 05:32:39 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 6990506 2022/03/24 05:32:43 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 1 2022/03/24 05:32:43 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 2 2022/03/24 05:32:43 DEBUG : chunked-6.667Mi-13.333Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013 2022/03/24 05:32:50 DEBUG : chunked-6.667Mi-13.333Mi.bin: Starting upload of large file in 3 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f2193ebf0a60bf3ee_d20220324_m053249_c001_v0001120_t0048_u01648099969958") 2022/03/24 05:32:50 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 1 length 6990506 2022/03/24 05:32:50 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 3 length 1 2022/03/24 05:32:50 DEBUG : chunked-6.667Mi-13.333Mi.bin: Sending chunk 2 length 6990506 2022/03/24 05:32:51 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 3 2022/03/24 05:32:53 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 1 2022/03/24 05:32:53 DEBUG : chunked-6.667Mi-13.333Mi.bin: Done sending chunk 2 2022/03/24 05:32:53 DEBUG : chunked-6.667Mi-13.333Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609 2022/03/24 05:32:58 DEBUG : chunked-8Mi-8.000Mi.bin: Starting upload of large file in 2 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f2193ebf0a60bf3f9_d20220324_m053258_c001_v0001130_t0029_u01648099978482") 2022/03/24 05:32:58 DEBUG : chunked-8Mi-8.000Mi.bin: Sending chunk 2 length 1 2022/03/24 05:32:58 DEBUG : chunked-8Mi-8.000Mi.bin: Sending chunk 1 length 8388608 2022/03/24 05:32:59 DEBUG : chunked-8Mi-8.000Mi.bin: Done sending chunk 2 2022/03/24 05:33:01 DEBUG : chunked-8Mi-8.000Mi.bin: Done sending chunk 1 2022/03/24 05:33:01 DEBUG : chunked-8Mi-8.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216 2022/03/24 05:33:04 DEBUG : chunked-8Mi-16Mi.bin: Starting upload of large file in 2 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f2193ebf0a60bf3fe_d20220324_m053304_c001_v0001113_t0054_u01648099984117") 2022/03/24 05:33:04 DEBUG : chunked-8Mi-16Mi.bin: Sending chunk 1 length 8388608 2022/03/24 05:33:04 DEBUG : chunked-8Mi-16Mi.bin: Sending chunk 2 length 8388608 2022/03/24 05:33:07 DEBUG : chunked-8Mi-16Mi.bin: Done sending chunk 1 2022/03/24 05:33:08 DEBUG : chunked-8Mi-16Mi.bin: Done sending chunk 2 2022/03/24 05:33:08 DEBUG : chunked-8Mi-16Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217 2022/03/24 05:33:14 DEBUG : chunked-8Mi-16.000Mi.bin: Starting upload of large file in 3 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f2193ebf0a60bf40c_d20220324_m053313_c001_v0001116_t0008_u01648099993920") 2022/03/24 05:33:14 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 1 length 8388608 2022/03/24 05:33:14 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 3 length 1 2022/03/24 05:33:14 DEBUG : chunked-8Mi-16.000Mi.bin: Sending chunk 2 length 8388608 2022/03/24 05:33:16 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 3 2022/03/24 05:33:18 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 2 2022/03/24 05:33:19 DEBUG : chunked-8Mi-16.000Mi.bin: Done sending chunk 1 2022/03/24 05:33:19 DEBUG : chunked-8Mi-16.000Mi.bin: Finishing large file upload with 3 parts 2022/03/24 05:33:20 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_finish_large_file": EOF) 2022/03/24 05:33:20 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/03/24 05:33:21 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761 2022/03/24 05:33:25 DEBUG : chunked-10Mi-10.000Mi.bin: Starting upload of large file in 2 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f209570085c041691_d20220324_m053325_c001_v0001105_t0035_u01648100005137") 2022/03/24 05:33:25 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 2 length 1 2022/03/24 05:33:25 DEBUG : chunked-10Mi-10.000Mi.bin: Sending chunk 1 length 10485760 2022/03/24 05:33:26 DEBUG : chunked-10Mi-10.000Mi.bin: Done sending chunk 2 2022/03/24 05:33:31 DEBUG : chunked-10Mi-10.000Mi.bin: Done sending chunk 1 2022/03/24 05:33:31 DEBUG : chunked-10Mi-10.000Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520 2022/03/24 05:33:37 DEBUG : chunked-10Mi-20Mi.bin: Starting upload of large file in 2 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f208cf5c145967be1_d20220324_m053337_c001_v0001092_t0052_u01648100017047") 2022/03/24 05:33:37 DEBUG : chunked-10Mi-20Mi.bin: Sending chunk 1 length 10485760 2022/03/24 05:33:37 DEBUG : chunked-10Mi-20Mi.bin: Sending chunk 2 length 10485760 2022/03/24 05:33:40 DEBUG : chunked-10Mi-20Mi.bin: Done sending chunk 1 2022/03/24 05:33:40 DEBUG : chunked-10Mi-20Mi.bin: Done sending chunk 2 2022/03/24 05:33:40 DEBUG : chunked-10Mi-20Mi.bin: Finishing large file upload with 2 parts === RUN TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521 2022/03/24 05:33:47 DEBUG : chunked-10Mi-20.000Mi.bin: Starting upload of large file in 3 chunks (id "4_z8f524dc3606dbbab7ff80e1b_f208cf5c145967be6_d20220324_m053347_c001_v0001116_t0055_u01648100027659") 2022/03/24 05:33:48 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 1 length 10485760 2022/03/24 05:33:48 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 3 length 1 2022/03/24 05:33:48 DEBUG : chunked-10Mi-20.000Mi.bin: Sending chunk 2 length 10485760 2022/03/24 05:33:49 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 3 2022/03/24 05:33:51 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 1 2022/03/24 05:33:53 DEBUG : chunked-10Mi-20.000Mi.bin: Done sending chunk 2 2022/03/24 05:33:53 DEBUG : chunked-10Mi-20.000Mi.bin: Finishing large file upload with 3 parts === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2022/03/24 05:33:58 DEBUG : unknown-size-put.txt: File has 100 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2022/03/24 05:34:03 DEBUG : unknown-size-update.txt: File has 200 bytes, which makes only one chunk. Using direct upload. === RUN TestIntegration/FsMkdir/FsRootCollapse 2022/03/24 05:34:05 DEBUG : Creating backend with remote "TestB2:rclone-test-cowajiy3wirojuw5lajuvud4/deeper/nonexisting/directory" 2022/03/24 05:34:06 DEBUG : Couldn't decode error response: EOF 2022/03/24 05:34:06 DEBUG : Couldn't decode error response: EOF 2022/03/24 05:34:06 DEBUG : leading space/ leading space: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1151cdc1d3337196_d20220324_m052914_c001_v0001029_t0028_u01648099754199") 2022/03/24 05:34:06 DEBUG : leading space/ leading space: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c9206_d20220324_m052912_c001_v0001115_t0018_u01648099752507") 2022/03/24 05:34:06 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f100284e66f7c50c5_d20220324_m052911_c001_v0001042_t0042_u01648099751953") 2022/03/24 05:34:06 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c91c0_d20220324_m052910_c001_v0001115_t0002_u01648099750510") 2022/03/24 05:34:06 DEBUG : .leading dot/.leading dot: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f103de26c7df5b62d_d20220324_m052937_c001_v0001116_t0051_u01648099777111") 2022/03/24 05:34:06 DEBUG : .leading dot/.leading dot: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c9528_d20220324_m052936_c001_v0001115_t0016_u01648099776181") 2022/03/24 05:34:06 DEBUG : chunked-10Mi-10.000Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f105d334e35cd976f_d20220324_m053336_c001_v0001134_t0050_u01648100016713") 2022/03/24 05:34:06 DEBUG : chunked-10Mi-10.000Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f209570085c041691_d20220324_m053325_c001_v0001105_t0035_u01648100005137") 2022/03/24 05:34:06 DEBUG : chunked-10Mi-20.000Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f102cb1ae8e0f66f8_d20220324_m053357_c001_v0001145_t0043_u01648100037593") 2022/03/24 05:34:06 DEBUG : chunked-10Mi-20.000Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f208cf5c145967be6_d20220324_m053347_c001_v0001116_t0055_u01648100027659") 2022/03/24 05:34:06 DEBUG : chunked-10Mi-20Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f111ca6a669c4913e_d20220324_m053345_c001_v0001104_t0026_u01648100025210") 2022/03/24 05:34:07 DEBUG : chunked-10Mi-20Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f208cf5c145967be1_d20220324_m053337_c001_v0001092_t0052_u01648100017047") 2022/03/24 05:34:07 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1119cd4cb520c4ad_d20220324_m053200_c001_v0001106_t0022_u01648099920325") 2022/03/24 05:34:07 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f213258e9966b107c_d20220324_m053146_c001_v0001117_t0056_u01648099906524") 2022/03/24 05:34:07 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1112022d035842cf_d20220324_m053145_c001_v0001042_t0008_u01648099905306") 2022/03/24 05:34:07 DEBUG : chunked-5.054Mi-10.109Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f213258e9966b107a_d20220324_m053140_c001_v0001161_t0039_u01648099900536") 2022/03/24 05:34:07 DEBUG : chunked-5.054Mi-5.054Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f104db1d3735043c7_d20220324_m053140_c001_v0001103_t0035_u01648099900205") 2022/03/24 05:34:07 DEBUG : chunked-5.054Mi-5.054Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f213258e9966b1078_d20220324_m053133_c001_v0001022_t0036_u01648099893638") 2022/03/24 05:34:07 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f103b56bae490af8a_d20220324_m053227_c001_v0001117_t0032_u01648099947234") 2022/03/24 05:34:07 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f2193ebf0a60bf3d2_d20220324_m053221_c001_v0001146_t0027_u01648099941980") 2022/03/24 05:34:07 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1022d782cf0514c9_d20220324_m053221_c001_v0001104_t0051_u01648099941323") 2022/03/24 05:34:07 DEBUG : chunked-5.055Mi-10.109Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f21033cabc9a97636_d20220324_m053210_c001_v0001117_t0016_u01648099930186") 2022/03/24 05:34:07 DEBUG : chunked-5.055Mi-5.055Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f118c0fe16ff98762_d20220324_m053209_c001_v0001130_t0029_u01648099929844") 2022/03/24 05:34:07 DEBUG : chunked-5.055Mi-5.055Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f21033cabc9a97635_d20220324_m053202_c001_v0001144_t0014_u01648099922921") 2022/03/24 05:34:07 DEBUG : chunked-5Mi-10.000Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1078dced7b98e203_d20220324_m053132_c001_v0001106_t0024_u01648099892308") 2022/03/24 05:34:07 DEBUG : chunked-5Mi-10.000Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f2027a3abbe73733b_d20220324_m053122_c001_v0001116_t0045_u01648099882140") 2022/03/24 05:34:08 DEBUG : chunked-5Mi-10Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1180a9c458957659_d20220324_m053121_c001_v0001031_t0020_u01648099881697") 2022/03/24 05:34:08 DEBUG : chunked-5Mi-10Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f2027a3abbe737334_d20220324_m053110_c001_v0001115_t0034_u01648099870194") 2022/03/24 05:34:08 DEBUG : chunked-5Mi-5.000Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f104307c46ecaea4d_d20220324_m053109_c001_v0001013_t0000_u01648099869754") 2022/03/24 05:34:08 DEBUG : chunked-5Mi-5.000Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f2027a3abbe737332_d20220324_m053102_c001_v0001134_t0058_u01648099862610") 2022/03/24 05:34:08 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f116cc5f65d2aefc9_d20220324_m053257_c001_v0001109_t0040_u01648099977717") 2022/03/24 05:34:08 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f2193ebf0a60bf3ee_d20220324_m053249_c001_v0001120_t0048_u01648099969958") 2022/03/24 05:34:08 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1119b36c4b346a47_d20220324_m053247_c001_v0001097_t0043_u01648099967170") 2022/03/24 05:34:08 DEBUG : chunked-6.667Mi-13.333Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f2193ebf0a60bf3e2_d20220324_m053239_c001_v0001117_t0043_u01648099959061") 2022/03/24 05:34:08 DEBUG : chunked-6.667Mi-6.667Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f118c88b1aaf86c10_d20220324_m053238_c001_v0001115_t0018_u01648099958279") 2022/03/24 05:34:08 DEBUG : chunked-6.667Mi-6.667Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f2193ebf0a60bf3dc_d20220324_m053231_c001_v0001116_t0005_u01648099951365") 2022/03/24 05:34:08 DEBUG : chunked-8Mi-16.000Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f10130000ff5ca7f4_d20220324_m053324_c001_v0001019_t0019_u01648100004721") 2022/03/24 05:34:08 DEBUG : chunked-8Mi-16.000Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f2193ebf0a60bf40c_d20220324_m053313_c001_v0001116_t0008_u01648099993920") 2022/03/24 05:34:08 DEBUG : chunked-8Mi-16Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1193947e9d971b0d_d20220324_m053312_c001_v0001116_t0030_u01648099992849") 2022/03/24 05:34:08 DEBUG : chunked-8Mi-16Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f2193ebf0a60bf3fe_d20220324_m053304_c001_v0001113_t0054_u01648099984117") 2022/03/24 05:34:08 DEBUG : chunked-8Mi-8.000Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f105a1eec84995886_d20220324_m053303_c001_v0001090_t0000_u01648099983614") 2022/03/24 05:34:08 DEBUG : chunked-8Mi-8.000Mi.bin: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f2193ebf0a60bf3f9_d20220324_m053258_c001_v0001130_t0029_u01648099978482") 2022/03/24 05:34:08 DEBUG : created from root.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f109111faa166e875_d20220324_m053049_c001_v0001165_t0038_u01648099849583") 2022/03/24 05:34:08 DEBUG : created from root.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1051eec0f755aaab_d20220324_m053046_c001_v0001116_t0057_u01648099846702") 2022/03/24 05:34:09 DEBUG : file name.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1060fdae686bf7cb_d20220324_m053057_c001_v0001100_t0017_u01648099857496") 2022/03/24 05:34:09 DEBUG : file name.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c9cd9_d20220324_m053034_c001_v0001115_t0037_u01648099834680") 2022/03/24 05:34:09 DEBUG : file name.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f10034bc2a915543f_d20220324_m053030_c001_v0001134_t0056_u01648099830697") 2022/03/24 05:34:09 DEBUG : file name.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c9a6c_d20220324_m053017_c001_v0001115_t0005_u01648099817598") 2022/03/24 05:34:09 DEBUG : file name.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c9a1f_d20220324_m053015_c001_v0001115_t0017_u01648099815701") 2022/03/24 05:34:09 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c9a47_d20220324_m053016_c001_v0001115_t0029_u01648099816645") 2022/03/24 05:34:09 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f101828ec9b92aeeb_d20220324_m053026_c001_v0001106_t0017_u01648099826911") 2022/03/24 05:34:09 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt-copy: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f118a8c5c728be7b4_d20220324_m053026_c001_v0001041_t0007_u01648099826397") 2022/03/24 05:34:09 DEBUG : invalid utf-8/invalid utf-8: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f111876e2d72b303d_d20220324_m053009_c001_v0001150_t0018_u01648099809132") 2022/03/24 05:34:09 DEBUG : invalid utf-8/invalid utf-8: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c98b1_d20220324_m053006_c001_v0001115_t0053_u01648099802695") 2022/03/24 05:34:09 DEBUG : piped data.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11522c2bb822ce37_d20220324_m053102_c001_v0001165_t0019_u01648099862361") 2022/03/24 05:34:09 DEBUG : piped data.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37ca078_d20220324_m053101_c001_v0001115_t0029_u01648099861438") 2022/03/24 05:34:09 DEBUG : piped data.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f108ff0977d54f0e9_d20220324_m053100_c001_v0001115_t0027_u01648099860291") 2022/03/24 05:34:09 DEBUG : piped data.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37ca031_d20220324_m053059_c001_v0001115_t0005_u01648099859441") 2022/03/24 05:34:09 DEBUG : test%46.txt/test%46.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1089390c05794eaa_d20220324_m053011_c001_v0001131_t0028_u01648099811275") 2022/03/24 05:34:09 DEBUG : test%46.txt/test%46.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c9941_d20220324_m053009_c001_v0001115_t0033_u01648099809903") 2022/03/24 05:34:09 DEBUG : trailing CR␍/trailing CR␍: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f114bf4ca3789c322_d20220324_m052941_c001_v0001036_t0022_u01648099781110") 2022/03/24 05:34:09 DEBUG : trailing CR␍/trailing CR␍: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c95b9_d20220324_m052940_c001_v0001115_t0012_u01648099780062") 2022/03/24 05:34:09 DEBUG : trailing HT␉/trailing HT␉: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f108aa218d6897828_d20220324_m052950_c001_v0001120_t0048_u01648099789362") 2022/03/24 05:34:09 DEBUG : trailing HT␉/trailing HT␉: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c9658_d20220324_m052945_c001_v0001115_t0039_u01648099785333") 2022/03/24 05:34:10 DEBUG : trailing LF␊/trailing LF␊: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f107e255712d3db94_d20220324_m052944_c001_v0001105_t0044_u01648099784687") 2022/03/24 05:34:10 DEBUG : trailing LF␊/trailing LF␊: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c9605_d20220324_m052942_c001_v0001115_t0012_u01648099782541") 2022/03/24 05:34:10 DEBUG : trailing VT␋/trailing VT␋: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1146a9c0169afb2f_d20220324_m052953_c001_v0001099_t0000_u01648099793650") 2022/03/24 05:34:10 DEBUG : trailing VT␋/trailing VT␋: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c972a_d20220324_m052952_c001_v0001115_t0043_u01648099792462") 2022/03/24 05:34:10 DEBUG : trailing dot./trailing dot.: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1191537d9019ae74_d20220324_m053001_c001_v0001099_t0033_u01648099801939") 2022/03/24 05:34:10 DEBUG : trailing dot./trailing dot.: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c978a_d20220324_m052954_c001_v0001115_t0030_u01648099794991") 2022/03/24 05:34:10 DEBUG : trailing space /trailing space : Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f119de790f9ed7ecc_d20220324_m052939_c001_v0001106_t0038_u01648099779090") 2022/03/24 05:34:10 DEBUG : trailing space /trailing space : Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c9570_d20220324_m052937_c001_v0001115_t0007_u01648099777998") 2022/03/24 05:34:10 DEBUG : unknown-size-put.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f103ad2ed431b4981_d20220324_m053359_c001_v0001093_t0026_u01648100039500") 2022/03/24 05:34:10 DEBUG : unknown-size-put.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37cb969_d20220324_m053358_c001_v0001115_t0000_u01648100038723") 2022/03/24 05:34:10 DEBUG : unknown-size-update.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f111ed3eb0de2c5e2_d20220324_m053404_c001_v0001036_t0006_u01648100044685") 2022/03/24 05:34:10 DEBUG : unknown-size-update.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37cba1e_d20220324_m053403_c001_v0001115_t0041_u01648100043729") 2022/03/24 05:34:10 DEBUG : unknown-size-update.txt: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37cb999_d20220324_m053359_c001_v0001115_t0031_u01648100039871") 2022/03/24 05:34:10 DEBUG : zero-length-file: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11311a5acfd7fc77_d20220324_m053014_c001_v0001036_t0058_u01648099814583") 2022/03/24 05:34:10 DEBUG : zero-length-file: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c99c3_d20220324_m053013_c001_v0001115_t0047_u01648099813256") 2022/03/24 05:34:10 DEBUG : ~leading tilde/~leading tilde: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f112a49cfd85cb8ce_d20220324_m052918_c001_v0001040_t0052_u01648099758636") 2022/03/24 05:34:10 DEBUG : ~leading tilde/~leading tilde: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c9256_d20220324_m052914_c001_v0001115_t0043_u01648099754853") 2022/03/24 05:34:10 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f10534af0257feda8_d20220324_m052905_c001_v0001144_t0000_u01648099745454") 2022/03/24 05:34:10 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c90d4_d20220324_m052903_c001_v0001115_t0002_u01648099743635") 2022/03/24 05:34:10 DEBUG : ␉leading HT/␉leading HT: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1142337603939744_d20220324_m052933_c001_v0001146_t0057_u01648099773634") 2022/03/24 05:34:11 DEBUG : ␉leading HT/␉leading HT: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c94b5_d20220324_m052932_c001_v0001115_t0007_u01648099772369") 2022/03/24 05:34:11 DEBUG : ␊leading LF/␊leading LF: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f10029f9d230f9b84_d20220324_m052931_c001_v0001090_t0039_u01648099771802") 2022/03/24 05:34:11 DEBUG : ␊leading LF/␊leading LF: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c946a_d20220324_m052930_c001_v0001115_t0008_u01648099770353") 2022/03/24 05:34:11 DEBUG : ␋leading VT/␋leading VT: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1129ad8552d86275_d20220324_m052935_c001_v0001128_t0022_u01648099775711") 2022/03/24 05:34:11 DEBUG : ␋leading VT/␋leading VT: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c94ed_d20220324_m052934_c001_v0001115_t0040_u01648099774235") 2022/03/24 05:34:11 DEBUG : ␍leading CR/␍leading CR: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1083869dd201552d_d20220324_m052929_c001_v0001030_t0003_u01648099769621") 2022/03/24 05:34:11 DEBUG : ␍leading CR/␍leading CR: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c9405_d20220324_m052927_c001_v0001115_t0044_u01648099767735") 2022/03/24 05:34:11 DEBUG : ．/．: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f1170db11c1b9112d_d20220324_m052907_c001_v0001100_t0045_u01648099747479") 2022/03/24 05:34:11 DEBUG : ．/．: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c912a_d20220324_m052906_c001_v0001115_t0014_u01648099746147") 2022/03/24 05:34:11 DEBUG : ．．/．．: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f10027495fbab78d8_d20220324_m052909_c001_v0001113_t0024_u01648099749217") 2022/03/24 05:34:11 DEBUG : ．．/．．: Deleting (id "4_z8f524dc3606dbbab7ff80e1b_f11952728e37c9165_d20220324_m052907_c001_v0001115_t0047_u01648099747955") === RUN TestIntegration/FsShutdown fstests.go:2003: Shutdown method not supported --- PASS: TestIntegration (314.75s) --- SKIP: TestIntegration/FsCheckWrap (0.00s) --- SKIP: TestIntegration/FsCommand (0.00s) --- PASS: TestIntegration/FsRmdirNotFound (0.68s) --- PASS: TestIntegration/FsString (0.00s) --- PASS: TestIntegration/FsName (0.00s) --- PASS: TestIntegration/FsRoot (0.00s) --- PASS: TestIntegration/FsRmdirEmpty (0.54s) --- PASS: TestIntegration/FsMkdir (311.95s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (0.50s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.17s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.17s) --- PASS: TestIntegration/FsMkdir/FsListRDirEmpty (0.25s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.17s) --- PASS: TestIntegration/FsMkdir/FsListRDirNotFound (0.17s) --- PASS: TestIntegration/FsMkdir/FsEncoding (69.43s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (3.19s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (1.81s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (2.56s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (2.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (2.35s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (12.34s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (3.16s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (2.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (1.87s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (1.95s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (1.82s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (2.06s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (2.48s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (2.79s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (7.13s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (2.53s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (7.71s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (7.21s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (2.32s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.34s) --- PASS: TestIntegration/FsMkdir/FsPutError (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (2.95s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (46.91s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (0.86s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.86s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (0.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (0.87s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.38s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.34s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.34s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (0.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.17s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (0.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (2.55s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (2.25s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.20s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (1.65s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.39s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (0.34s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (0.91s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.34s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (2.51s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (1.81s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (0.74s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (11.20s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (0.35s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (3.64s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (6.76s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (0.36s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/PublicLink (6.89s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (2.08s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (3.71s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (2.53s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (1.18s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Internal (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutChunked (175.61s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi (31.03s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/5242881 (7.58s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485760 (11.95s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5Mi/10485761 (11.50s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi (29.29s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/5300001 (6.90s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600000 (5.99s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.054Mi/10600001 (16.40s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi (28.44s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/5300054 (7.26s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600106 (11.79s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/5.055Mi/10600107 (9.38s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi (27.12s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/6990507 (7.70s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981012 (10.90s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/6.667Mi/13981013 (8.52s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi (26.66s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/8388609 (5.63s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777216 (9.80s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/8Mi/16777217 (11.22s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi (33.08s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/10485761 (11.91s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971520 (10.61s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/10Mi/20971521 (10.56s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (6.95s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (1.65s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (5.30s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (1.12s) --- SKIP: TestIntegration/FsShutdown (0.00s) PASS ok github.com/rclone/rclone/backend/b2 314.777s "go test -v -timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose" - Finished OK in 5m15.660111669s (try 1/5)