"go test -v -timeout 1h0m0s -remote TestQingStor: -verbose" - Starting (try 3/5) === RUN TestIntegration === RUN TestIntegration/FsCheckWrap === RUN TestIntegration/FsCommand === RUN TestIntegration/FsRmdirNotFound === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir 2020/05/15 07:15:23 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 0 2020/05/15 07:15:25 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 1 2020/05/15 07:15:27 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 2 2020/05/15 07:15:28 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 3 2020/05/15 07:15:29 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 4 2020/05/15 07:15:31 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 5 2020/05/15 07:15:32 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 6 2020/05/15 07:15:33 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 7 2020/05/15 07:15:35 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 8 2020/05/15 07:15:36 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 9 2020/05/15 07:15:38 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 10 2020/05/15 07:15:39 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 11 2020/05/15 07:15:41 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 12 2020/05/15 07:15:42 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 13 2020/05/15 07:15:43 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 14 2020/05/15 07:15:45 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 15 2020/05/15 07:15:46 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 16 2020/05/15 07:15:47 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 17 2020/05/15 07:15:49 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 18 2020/05/15 07:15:50 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 19 2020/05/15 07:15:51 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 20 2020/05/15 07:15:53 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 21 2020/05/15 07:15:54 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 22 2020/05/15 07:15:56 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 23 2020/05/15 07:15:57 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 24 2020/05/15 07:15:58 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 25 2020/05/15 07:16:00 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 26 2020/05/15 07:16:01 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 27 2020/05/15 07:16:02 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 28 2020/05/15 07:16:04 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 29 2020/05/15 07:16:05 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 30 2020/05/15 07:16:06 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 31 2020/05/15 07:16:08 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 32 2020/05/15 07:16:09 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 33 2020/05/15 07:16:10 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 34 2020/05/15 07:16:12 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 35 2020/05/15 07:16:13 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 36 2020/05/15 07:16:14 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 37 2020/05/15 07:16:16 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 38 2020/05/15 07:16:17 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 39 2020/05/15 07:16:19 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 40 2020/05/15 07:16:20 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 41 2020/05/15 07:16:21 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be deleted, retries: 42 2020/05/15 07:16:24 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0: Wait for qingstor bucket to be creatable, retries: 0 === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2020/05/15 07:16:28 DEBUG : dir/subdir: Making directory 2020/05/15 07:16:28 DEBUG : dir/subdir: Removing directory 2020/05/15 07:16:29 DEBUG : 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 2020/05/15 07:16:31 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/dot 2020/05/15 07:16:36 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key ./.: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot 2020/05/15 07:16:40 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key ../..: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/punctuation 2020/05/15 07:16:43 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/leading_space 2020/05/15 07:16:48 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key leading space/ leading space: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde 2020/05/15 07:16:52 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key ~leading tilde/~leading tilde: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR 2020/05/15 07:16:56 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key ␍leading CR/␍leading CR: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF 2020/05/15 07:17:00 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key ␊leading LF/␊leading LF: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT 2020/05/15 07:17:03 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key ␉leading HT/␉leading HT: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT 2020/05/15 07:17:07 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key ␋leading VT/␋leading VT: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot 2020/05/15 07:17:11 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key .leading dot/.leading dot: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space 2020/05/15 07:17:14 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key trailing space /trailing space : Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR 2020/05/15 07:17:18 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key trailing CR␍/trailing CR␍: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF 2020/05/15 07:17:21 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key trailing LF␊/trailing LF␊: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT 2020/05/15 07:17:25 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key trailing HT␉/trailing HT␉: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT 2020/05/15 07:17:28 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key trailing VT␋/trailing VT␋: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot 2020/05/15 07:17:32 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key trailing dot./trailing dot.: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 2020/05/15 07:17:36 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key invalid utf-8‛FE/invalid utf-8‛FE: Upload single object finished === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError === RUN TestIntegration/FsMkdir/FsPutZeroLength 2020/05/15 07:17:42 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key zero-length-file: Upload single object finished === RUN TestIntegration/FsMkdir/FsOpenWriterAt === RUN TestIntegration/FsMkdir/FsChangeNotify === RUN TestIntegration/FsMkdir/FsPutFiles 2020/05/15 07:17:44 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key file name.txt: Upload single object finished 2020/05/15 07:17:47 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Upload single object finished 2020/05/15 07:17:49 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key file name.txt: Upload single object finished === 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 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot === 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/FsListFile1and2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove === 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 2020/05/15 07:18:20 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key file name.txt: Upload single object finished === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2020/05/15 07:18:30 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key created from root.txt: Upload single object finished === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream === RUN TestIntegration/FsMkdir/FsPutFiles/Internal === RUN TestIntegration/FsMkdir/FsPutChunked === RUN TestIntegration/FsMkdir/FsPutChunked/4M === RUN TestIntegration/FsMkdir/FsPutChunked/4M/4194303 2020/05/15 07:23:59 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-4.000M.bin: Upload single object finished === RUN TestIntegration/FsMkdir/FsPutChunked/4M/4194304 2020/05/15 07:24:14 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-4M.bin: Uploading as multi-part object to QingStor 2020/05/15 07:24:14 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-4M.bin uploadID : Initiating a multi-part upload 2020/05/15 07:24:14 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-4M.bin uploadID 59098b921c0ac8b: Uploading a part to QingStor with partNumber 0 and partSize 4194304 2020/05/15 07:28:25 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-4M.bin uploadID 59098b921c0ac8b: Done uploading part partNumber 0 and partSize 4194304 2020/05/15 07:28:25 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-4M.bin uploadID 59098b921c0ac8b: Completing multi-part object 2020/05/15 07:28:25 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-4M.bin uploadID 59098b921c0ac8b: Complete multi-part finished === RUN TestIntegration/FsMkdir/FsPutChunked/4M/8388609 2020/05/15 07:28:31 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-8.000M.bin: Uploading as multi-part object to QingStor 2020/05/15 07:28:31 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-8.000M.bin uploadID : Initiating a multi-part upload 2020/05/15 07:28:31 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-8.000M.bin uploadID 59099b3d980acb8: Uploading a part to QingStor with partNumber 0 and partSize 4194304 2020/05/15 07:32:42 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-8.000M.bin uploadID 59099b3d980acb8: Done uploading part partNumber 0 and partSize 4194304 2020/05/15 07:32:42 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-8.000M.bin uploadID 59099b3d980acb8: Uploading a part to QingStor with partNumber 1 and partSize 4194304 2020/05/15 07:36:40 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-8.000M.bin uploadID 59099b3d980acb8: Done uploading part partNumber 1 and partSize 4194304 2020/05/15 07:36:40 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-8.000M.bin uploadID 59099b3d980acb8: Uploading a part to QingStor with partNumber 2 and partSize 1 2020/05/15 07:36:40 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-8.000M.bin uploadID 59099b3d980acb8: Done uploading part partNumber 2 and partSize 1 2020/05/15 07:36:40 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-8.000M.bin uploadID 59099b3d980acb8: Completing multi-part object 2020/05/15 07:36:41 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4M-8.000M.bin uploadID 59099b3d980acb8: Complete multi-part finished === RUN TestIntegration/FsMkdir/FsPutChunked/4.005M === RUN TestIntegration/FsMkdir/FsPutChunked/4.005M/4199999 2020/05/15 07:41:51 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-4.005M.bin: Upload single object finished === RUN TestIntegration/FsMkdir/FsPutChunked/4.005M/4200000 2020/05/15 07:41:57 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-4.005M.bin: Uploading as multi-part object to QingStor 2020/05/15 07:41:57 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-4.005M.bin uploadID : Initiating a multi-part upload 2020/05/15 07:41:57 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-4.005M.bin uploadID 5909cc6b280ad88: Uploading a part to QingStor with partNumber 0 and partSize 4200000 2020/05/15 07:47:00 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-4.005M.bin uploadID 5909cc6b280ad88: Done uploading part partNumber 0 and partSize 4200000 2020/05/15 07:47:00 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-4.005M.bin uploadID 5909cc6b280ad88: Completing multi-part object 2020/05/15 07:47:01 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-4.005M.bin uploadID 5909cc6b280ad88: Complete multi-part finished === RUN TestIntegration/FsMkdir/FsPutChunked/4.005M/8400001 2020/05/15 07:47:16 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-8.011M.bin: Uploading as multi-part object to QingStor 2020/05/15 07:47:16 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-8.011M.bin uploadID : Initiating a multi-part upload 2020/05/15 07:47:17 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-8.011M.bin uploadID 5909dff1b80adc1: Uploading a part to QingStor with partNumber 0 and partSize 4200000 2020/05/15 07:52:06 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-8.011M.bin uploadID 5909dff1b80adc1: Done uploading part partNumber 0 and partSize 4200000 2020/05/15 07:52:06 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-8.011M.bin uploadID 5909dff1b80adc1: Uploading a part to QingStor with partNumber 1 and partSize 4200000 2020/05/15 07:57:07 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-8.011M.bin uploadID 5909dff1b80adc1: Done uploading part partNumber 1 and partSize 4200000 2020/05/15 07:57:07 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-8.011M.bin uploadID 5909dff1b80adc1: Uploading a part to QingStor with partNumber 2 and partSize 1 2020/05/15 07:57:07 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-8.011M.bin uploadID 5909dff1b80adc1: Done uploading part partNumber 2 and partSize 1 2020/05/15 07:57:07 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-8.011M.bin uploadID 5909dff1b80adc1: Completing multi-part object 2020/05/15 07:57:08 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-8.011M.bin uploadID 5909dff1b80adc1: Complete multi-part finished === RUN TestIntegration/FsMkdir/FsPutChunked/4.005M#01 === RUN TestIntegration/FsMkdir/FsPutChunked/4.005M#01/4200041 2020/05/15 08:02:55 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-4.005M.bin: Upload single object finished === RUN TestIntegration/FsMkdir/FsPutChunked/4.005M#01/4200042 2020/05/15 08:03:09 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-4.005M.bin: Uploading as multi-part object to QingStor 2020/05/15 08:03:09 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-4.005M.bin uploadID : Initiating a multi-part upload 2020/05/15 08:03:09 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-4.005M.bin uploadID 590a1a10b40113e: Uploading a part to QingStor with partNumber 0 and partSize 4200042 2020/05/15 08:07:17 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-4.005M.bin uploadID 590a1a10b40113e: Done uploading part partNumber 0 and partSize 4200042 2020/05/15 08:07:17 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-4.005M.bin uploadID 590a1a10b40113e: Completing multi-part object 2020/05/15 08:07:17 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-4.005M.bin uploadID 590a1a10b40113e: Complete multi-part finished === RUN TestIntegration/FsMkdir/FsPutChunked/4.005M#01/8400085 2020/05/15 08:07:28 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-8.011M.bin: Uploading as multi-part object to QingStor 2020/05/15 08:07:28 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-8.011M.bin uploadID : Initiating a multi-part upload 2020/05/15 08:07:28 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-8.011M.bin uploadID 590a29df20011d5: Uploading a part to QingStor with partNumber 0 and partSize 4200042 2020/05/15 08:12:21 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-8.011M.bin uploadID 590a29df20011d5: Done uploading part partNumber 0 and partSize 4200042 2020/05/15 08:12:21 DEBUG : QingStor bucket rclone-test-jelidap7zulujej2moqepix0 key chunked-4.005M-8.011M.bin uploadID 590a29df20011d5: Uploading a part to QingStor with partNumber 1 and partSize 4200042 panic: test timed out after 1h0m0s goroutine 386 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:1377 +0xdf created by time.goFunc /usr/local/go/src/time/sleep.go:168 +0x44 goroutine 1 [chan receive, 59 minutes]: testing.(*T).Run(0xc0001ba300, 0xabc1fa, 0xf, 0xaf0100, 0x48bef6) /usr/local/go/src/testing/testing.go:961 +0x377 testing.runTests.func1(0xc0001ba200) /usr/local/go/src/testing/testing.go:1202 +0x78 testing.tRunner(0xc0001ba200, 0xc0000f3dc0) /usr/local/go/src/testing/testing.go:909 +0xc9 testing.runTests(0xc000153a80, 0xfa7d60, 0x1, 0x1, 0x0) /usr/local/go/src/testing/testing.go:1200 +0x2a7 testing.(*M).Run(0xc000160200, 0x0) /usr/local/go/src/testing/testing.go:1117 +0x176 main.main() _testmain.go:44 +0x135 goroutine 19 [syscall, 59 minutes]: os/signal.signal_recv(0x0) /usr/local/go/src/runtime/sigqueue.go:147 +0x9c os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x22 created by os/signal.init.0 /usr/local/go/src/os/signal/signal_unix.go:29 +0x41 goroutine 36 [chan receive, 59 minutes]: testing.(*T).Run(0xc00010c300, 0xab5f2b, 0x7, 0xc00025a6c0, 0x1) /usr/local/go/src/testing/testing.go:961 +0x377 github.com/rclone/rclone/fstest/fstests.Run(0xc0001ba300, 0xc0001d4000) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:488 +0xed6 github.com/rclone/rclone/backend/qingstor.TestIntegration(0xc0001ba300) /home/rclone/go/src/github.com/rclone/rclone/backend/qingstor/qingstor_test.go:16 +0x88 testing.tRunner(0xc0001ba300, 0xaf0100) /usr/local/go/src/testing/testing.go:909 +0xc9 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:960 +0x350 goroutine 52 [chan receive, 56 minutes]: testing.(*T).Run(0xc000298e00, 0xab9ee4, 0xc, 0xc00021b7d0, 0x1) /usr/local/go/src/testing/testing.go:961 +0x377 github.com/rclone/rclone/fstest/fstests.Run.func13(0xc00010c300) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1649 +0xa7e testing.tRunner(0xc00010c300, 0xc00025a6c0) /usr/local/go/src/testing/testing.go:909 +0xc9 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:960 +0x350 goroutine 338 [chan receive, 17 minutes]: testing.(*T).Run(0xc00010c200, 0xc0002002c6, 0x6, 0xc0000c64b0, 0x1) /usr/local/go/src/testing/testing.go:961 +0x377 github.com/rclone/rclone/fstest/fstests.Run.func13.14(0xc000298e00) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1726 +0x552 testing.tRunner(0xc000298e00, 0xc00021b7d0) /usr/local/go/src/testing/testing.go:909 +0xc9 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:960 +0x350 goroutine 205 [chan receive, 7 minutes]: testing.(*T).Run(0xc0001ba400, 0xc000347a90, 0x7, 0xc00021ab10, 0x1) /usr/local/go/src/testing/testing.go:961 +0x377 github.com/rclone/rclone/fstest/fstests.Run.func13.14.3(0xc00010c200) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1743 +0x196 testing.tRunner(0xc00010c200, 0xc0000c64b0) /usr/local/go/src/testing/testing.go:909 +0xc9 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:960 +0x350 goroutine 208 [semacquire, 3 minutes]: sync.runtime_Semacquire(0xc00016b2d0) /usr/local/go/src/runtime/sema.go:56 +0x42 sync.(*WaitGroup).Wait(0xc00016b2c8) /usr/local/go/src/sync/waitgroup.go:130 +0x64 github.com/rclone/rclone/backend/qingstor.(*multiUploader).multiPartUpload(0xc00016b2c0, 0xb9cea0, 0xc00021ab70, 0xb97060, 0xc000092030) /home/rclone/go/src/github.com/rclone/rclone/backend/qingstor/upload.go:406 +0x33e github.com/rclone/rclone/backend/qingstor.(*uploader).upload(0xc0002fc9c0, 0x802cd5, 0xba9040) /home/rclone/go/src/github.com/rclone/rclone/backend/qingstor/upload.go:190 +0x175 github.com/rclone/rclone/backend/qingstor.(*Object).Update(0xc00019c380, 0xba4bc0, 0xc00009e010, 0xb97780, 0xc0002fc9a0, 0xba9040, 0xc0000c6690, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/qingstor/qingstor.go:1083 +0x294 github.com/rclone/rclone/backend/qingstor.(*Fs).Put(0xc0002120e0, 0xba4bc0, 0xc00009e010, 0xb97780, 0xc0002fc9a0, 0xba9040, 0xc0000c6690, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/qingstor/qingstor.go:427 +0x11e github.com/rclone/rclone/fstest/fstests.TestPutLarge.func1(0x10000c000000073, 0x4e4046) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:201 +0x21a github.com/rclone/rclone/fstest/fstests.retry(0xc0001ba400, 0xab72b4, 0x8, 0xc0000efe08) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:145 +0x152 github.com/rclone/rclone/fstest/fstests.TestPutLarge(0xba4bc0, 0xc00009e010, 0xc0001ba400, 0xbabc00, 0xc0002120e0, 0xc0000eff28) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:195 +0x131 github.com/rclone/rclone/fstest/fstests.Run.func13.14.3.1(0xc0001ba400) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1744 +0x2ac testing.tRunner(0xc0001ba400, 0xc00021ab10) /usr/local/go/src/testing/testing.go:909 +0xc9 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:960 +0x350 goroutine 358 [IO wait, 3 minutes]: internal/poll.runtime_pollWait(0x7f3fbc0d6f38, 0x72, 0xffffffffffffffff) /usr/local/go/src/runtime/netpoll.go:184 +0x55 internal/poll.(*pollDesc).wait(0xc00012ee98, 0x72, 0xd500, 0xd53e, 0xffffffffffffffff) /usr/local/go/src/internal/poll/fd_poll_runtime.go:87 +0x45 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:92 internal/poll.(*FD).Read(0xc00012ee80, 0xc0004be000, 0xd53e, 0xd53e, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x1cf net.(*netFD).Read(0xc00012ee80, 0xc0004be000, 0xd53e, 0xd53e, 0xa4d8ab417b23132b, 0xee35686fa318abd2, 0x100000032b95ad3) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc000010018, 0xc0004be000, 0xd53e, 0xd53e, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:184 +0x68 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc000328040, 0xc0001d67b8, 0xc0004be000, 0xd53e, 0xd53e, 0x203000, 0x0, 0xd531) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:75 +0x48 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000328040, 0xc0004be000, 0xd53e, 0xd53e, 0xc0001d6848, 0x4a87a1, 0x10) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:87 +0x8a crypto/tls.(*atLeastReader).Read(0xc0002d8020, 0xc0004be000, 0xd53e, 0xd53e, 0xc0001d68c0, 0x41956e, 0xc0001d68a0) /usr/local/go/src/crypto/tls/conn.go:780 +0x60 bytes.(*Buffer).ReadFrom(0xc00007b3d8, 0xb96f00, 0xc0002d8020, 0x40bf65, 0xa06d40, 0xa65340) /usr/local/go/src/bytes/buffer.go:204 +0xb4 crypto/tls.(*Conn).readFromUntil(0xc00007b180, 0x7f3fbc0d7618, 0xc000328040, 0x5, 0xc000328040, 0x45dae0) /usr/local/go/src/crypto/tls/conn.go:802 +0xec crypto/tls.(*Conn).readRecordOrCCS(0xc00007b180, 0x0, 0x0, 0xc0001d6dd0) /usr/local/go/src/crypto/tls/conn.go:609 +0x124 crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:577 crypto/tls.(*Conn).Read(0xc00007b180, 0xc000243000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1255 +0x161 net/http.(*persistConn).Read(0xc0000ca480, 0xc000243000, 0x1000, 0x1000, 0xc0001d6ed8, 0x45e410, 0xc0001d6ed8) /usr/local/go/src/net/http/transport.go:1752 +0x75 bufio.(*Reader).fill(0xc000342d20) /usr/local/go/src/bufio/bufio.go:100 +0x103 bufio.(*Reader).Peek(0xc000342d20, 0x1, 0x2, 0x0, 0x0, 0xc0001b6e00, 0x0) /usr/local/go/src/bufio/bufio.go:138 +0x4f net/http.(*persistConn).readLoop(0xc0000ca480) /usr/local/go/src/net/http/transport.go:1905 +0x1d6 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1574 +0xafe goroutine 359 [IO wait]: internal/poll.runtime_pollWait(0x7f3fbc0d6f38, 0x77, 0xffffffffffffffff) /usr/local/go/src/runtime/netpoll.go:184 +0x55 internal/poll.(*pollDesc).wait(0xc00012ee98, 0x77, 0x700, 0x7e0, 0xffffffffffffffff) /usr/local/go/src/internal/poll/fd_poll_runtime.go:87 +0x45 internal/poll.(*pollDesc).waitWrite(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:96 internal/poll.(*FD).Write(0xc00012ee80, 0xc00024c000, 0x101d, 0x101d, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:276 +0x2a9 net.(*netFD).Write(0xc00012ee80, 0xc00024c000, 0x101d, 0x101d, 0x8, 0x1018, 0xc000292000) /usr/local/go/src/net/fd_unix.go:220 +0x4f net.(*conn).Write(0xc000010018, 0xc00024c000, 0x101d, 0x101d, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:196 +0x68 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc000328040, 0xc00005b850, 0xc00024c000, 0x101d, 0x101d, 0x101d, 0x1018, 0x0) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:75 +0x48 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Write(0xc000328040, 0xc00024c000, 0x101d, 0x101d, 0xc0002d8ac0, 0xc0002d8ac0, 0xaf1838) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:92 +0x8a crypto/tls.(*Conn).write(0xc00007b180, 0xc00024c000, 0x101d, 0x101d, 0xc000292000, 0x1000, 0x1000) /usr/local/go/src/crypto/tls/conn.go:915 +0x160 crypto/tls.(*Conn).writeRecordLocked(0xc00007b180, 0x17, 0xc000292000, 0x1000, 0x1000, 0x1000, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:964 +0x29f crypto/tls.(*Conn).Write(0xc00007b180, 0xc000292000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1133 +0x1b7 net/http.persistConnWriter.Write(0xc0000ca480, 0xc000292000, 0x1000, 0x1000, 0xc000292000, 0x1000, 0x1000) /usr/local/go/src/net/http/transport.go:1590 +0x52 bufio.(*Writer).Flush(0xc000181880, 0xc000292000, 0x1000) /usr/local/go/src/bufio/bufio.go:593 +0x75 bufio.(*Writer).ReadFrom(0xc000181880, 0xb976e0, 0xc0002d8420, 0x7f3fbc0471a8, 0xc000181880, 0x1) /usr/local/go/src/bufio/bufio.go:719 +0x27f io.copyBuffer(0xb96da0, 0xc000181880, 0xb976e0, 0xc0002d8420, 0x0, 0x0, 0x0, 0xc00005bc38, 0x40e7f8, 0x20) /usr/local/go/src/io/io.go:388 +0x2ed io.Copy(...) /usr/local/go/src/io/io.go:364 net/http.(*transferWriter).doBodyCopy(0xc0000c0d20, 0xb96da0, 0xc000181880, 0xb976e0, 0xc0002d8420, 0xa06fc0, 0xa64d40, 0x7f3fbc093dd8) /usr/local/go/src/net/http/transfer.go:400 +0x6a net/http.(*transferWriter).writeBody(0xc0000c0d20, 0xb96da0, 0xc000181880, 0x2, 0x2) /usr/local/go/src/net/http/transfer.go:359 +0x6da net/http.(*Request).write(0xc000298100, 0xb96da0, 0xc000181880, 0x0, 0xc000032600, 0x0, 0x0, 0x0) /usr/local/go/src/net/http/request.go:682 +0x6d3 net/http.(*persistConn).writeLoop(0xc0000ca480) /usr/local/go/src/net/http/transport.go:2207 +0x1c8 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1575 +0xb23 goroutine 209 [select, 3 minutes]: net/http.(*persistConn).roundTrip(0xc0000ca480, 0xc0000325d0, 0x0, 0x0, 0x0) /usr/local/go/src/net/http/transport.go:2425 +0x770 net/http.(*Transport).roundTrip(0xc00018b040, 0xc000298100, 0xab8ca4, 0xa, 0xc0000d8378) /usr/local/go/src/net/http/transport.go:535 +0x9af net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:17 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0001f44c0, 0xc000298100, 0xc0001f44c0, 0x0, 0x0) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:356 +0x385 net/http.send(0xc000298100, 0xb971c0, 0xc0001f44c0, 0x0, 0x0, 0x0, 0xc000010050, 0x2, 0x1, 0x0) /usr/local/go/src/net/http/client.go:250 +0x443 net/http.(*Client).send(0xc00015dd40, 0xc000298100, 0x0, 0x0, 0x0, 0xc000010050, 0x0, 0x1, 0xc0000f5b98) /usr/local/go/src/net/http/client.go:174 +0xfa net/http.(*Client).do(0xc00015dd40, 0xc000298100, 0x0, 0x0, 0x0) /usr/local/go/src/net/http/client.go:641 +0x3ce net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:509 github.com/rclone/rclone/vendor/github.com/yunify/qingstor-sdk-go/v3/request.(*Request).send(0xc0000322d0, 0xc000188794, 0x28) /home/rclone/go/src/github.com/rclone/rclone/vendor/github.com/yunify/qingstor-sdk-go/v3/request/request.go:222 +0x1f4 github.com/rclone/rclone/vendor/github.com/yunify/qingstor-sdk-go/v3/request.(*Request).Do(0xc0000322d0, 0x0, 0x0) /home/rclone/go/src/github.com/rclone/rclone/vendor/github.com/yunify/qingstor-sdk-go/v3/request/request.go:105 +0x2f github.com/rclone/rclone/vendor/github.com/yunify/qingstor-sdk-go/v3/request.(*Request).Send(0xc0000322d0, 0xc0000f89e5, 0x19) /home/rclone/go/src/github.com/rclone/rclone/vendor/github.com/yunify/qingstor-sdk-go/v3/request/request.go:78 +0x6e github.com/rclone/rclone/vendor/github.com/yunify/qingstor-sdk-go/v3/service.(*Bucket).UploadMultipart(0xc0004507d0, 0xc0000f89e5, 0x19, 0xc0001aa360, 0x3f, 0xc0000f5e50, 0x2) /home/rclone/go/src/github.com/rclone/rclone/vendor/github.com/yunify/qingstor-sdk-go/v3/service/object.go:1085 +0x89 github.com/rclone/rclone/backend/qingstor.(*multiUploader).send(0xc00016b2c0, 0xb9cea0, 0xc000032120, 0x1, 0x40166a, 0x0, 0x0) /home/rclone/go/src/github.com/rclone/rclone/backend/qingstor/upload.go:283 +0x2a3 github.com/rclone/rclone/backend/qingstor.(*multiUploader).readChunk(0xc00016b2c0, 0xc00016b5c0) /home/rclone/go/src/github.com/rclone/rclone/backend/qingstor/upload.go:251 +0x13b created by github.com/rclone/rclone/backend/qingstor.(*multiUploader).multiPartUpload /home/rclone/go/src/github.com/rclone/rclone/backend/qingstor/upload.go:359 +0x142 exit status 2 FAIL github.com/rclone/rclone/backend/qingstor 3600.019s "go test -v -timeout 1h0m0s -remote TestQingStor: -verbose" - Finished ERROR in 1h0m1.492650529s (try 3/5): exit status 1: Failed []