"go test -v -timeout 1h0m0s -remote TestQingStor: -verbose" - Starting (try 4/5) === RUN TestIntegration fstests.go:418: Using remote "TestQingStor:" 2022/04/15 08:32:40 DEBUG : Creating backend with remote "TestQingStor:rclone-test-kaguqac0jagimof4qekawic7" 2022/04/15 08:32:40 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 2022/04/15 08:32:47 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: QingStor bucket lease not ready, retries: 0 === RUN TestIntegration/FsMkdir 2022/04/15 08:32:49 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 0 2022/04/15 08:32:51 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 1 2022/04/15 08:32:52 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 2 2022/04/15 08:32:54 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 3 2022/04/15 08:32:56 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 4 2022/04/15 08:32:58 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 5 2022/04/15 08:33:00 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 6 2022/04/15 08:33:01 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 7 2022/04/15 08:33:03 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 8 2022/04/15 08:33:04 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 9 2022/04/15 08:33:05 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 10 2022/04/15 08:33:06 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 11 2022/04/15 08:33:08 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 12 2022/04/15 08:33:09 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 13 2022/04/15 08:33:11 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 14 2022/04/15 08:33:12 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 15 2022/04/15 08:33:13 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 16 2022/04/15 08:33:15 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 17 2022/04/15 08:33:16 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 18 2022/04/15 08:33:18 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 19 2022/04/15 08:33:19 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 20 2022/04/15 08:33:20 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 21 2022/04/15 08:33:21 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 22 2022/04/15 08:33:23 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 23 2022/04/15 08:33:25 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 24 2022/04/15 08:33:26 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 25 2022/04/15 08:33:27 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 26 2022/04/15 08:33:30 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 27 2022/04/15 08:33:31 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 28 2022/04/15 08:33:32 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 29 2022/04/15 08:33:34 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 30 2022/04/15 08:33:35 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 31 2022/04/15 08:33:36 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 32 2022/04/15 08:33:37 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 33 2022/04/15 08:33:39 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 34 2022/04/15 08:33:40 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 35 2022/04/15 08:33:41 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 36 2022/04/15 08:33:43 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 37 2022/04/15 08:33:45 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 38 2022/04/15 08:33:46 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 39 2022/04/15 08:33:48 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be deleted, retries: 40 2022/04/15 08:33:59 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be creatable, retries: 0 2022/04/15 08:34:05 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7: Wait for qingstor bucket to be creatable, retries: 1 === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2022/04/15 08:34:08 DEBUG : dir/subdir: Making directory fstest.go:251: Filtering empty directory "dir" fstest.go:251: Filtering empty directory "dir/subdir" 2022/04/15 08:34:09 INFO : dir/subdir: Removing directory fstest.go:251: Filtering empty directory "dir" 2022/04/15 08:34:09 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 "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2022/04/15 08:34:12 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:674: testing "．" 2022/04/15 08:34:17 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key ./.: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:674: testing "．．" 2022/04/15 08:34:22 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key ../..: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:674: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2022/04/15 08:34:26 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:674: testing " leading space" 2022/04/15 08:34:28 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key leading space/ leading space: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:674: testing "~leading tilde" 2022/04/15 08:34:33 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key ~leading tilde/~leading tilde: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:674: testing "␍leading CR" 2022/04/15 08:34:36 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key ␍leading CR/␍leading CR: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:674: testing "␊leading LF" 2022/04/15 08:34:39 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key ␊leading LF/␊leading LF: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:674: testing "␉leading HT" 2022/04/15 08:34:42 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key ␉leading HT/␉leading HT: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:674: testing "␋leading VT" 2022/04/15 08:34:47 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key ␋leading VT/␋leading VT: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:674: testing ".leading dot" 2022/04/15 08:34:51 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key .leading dot/.leading dot: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:674: testing "trailing space " 2022/04/15 08:34:53 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key trailing space /trailing space : Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:674: testing "trailing CR␍" 2022/04/15 08:34:56 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key trailing CR␍/trailing CR␍: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:674: testing "trailing LF␊" 2022/04/15 08:34:59 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key trailing LF␊/trailing LF␊: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:674: testing "trailing HT␉" 2022/04/15 08:35:03 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key trailing HT␉/trailing HT␉: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:674: testing "trailing VT␋" 2022/04/15 08:35:06 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key trailing VT␋/trailing VT␋: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:674: testing "trailing dot." 2022/04/15 08:35:12 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key trailing dot./trailing dot.: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:674: testing "invalid utf-8\xfe" 2022/04/15 08:35:15 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key invalid utf-8‛FE/invalid utf-8‛FE: Upload single object finished === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:674: testing "test%46.txt" 2022/04/15 08:35:19 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key test%46.txt/test%46.txt: Upload single object finished === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError === RUN TestIntegration/FsMkdir/FsPutZeroLength 2022/04/15 08:35:25 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key zero-length-file: Upload single object finished === 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 2022/04/15 08:35:28 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key file name.txt: Upload single object finished 2022/04/15 08:35:29 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Upload single object finished 2022/04/15 08:35:31 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 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 2022/04/15 08:35:36 DEBUG : Creating backend with remote "TestQingStor:" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot 2022/04/15 08:35:38 DEBUG : Creating backend with remote "TestQingStor:" === 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 === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge fstests.go:1068: FS has no Purge interface === 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 2022/04/15 08:36:00 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key file name.txt: Upload single object finished === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2022/04/15 08:36:04 DEBUG : Creating backend with remote "TestQingStor:rclone-test-kaguqac0jagimof4qekawic7/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2022/04/15 08:36:05 DEBUG : Creating backend with remote "TestQingStor:rclone-test-kaguqac0jagimof4qekawic7/not found.txt" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1503: Opening root remote "TestQingStor:" path "rclone-test-kaguqac0jagimof4qekawic7" from "TestQingStor:rclone-test-kaguqac0jagimof4qekawic7" 2022/04/15 08:36:07 DEBUG : Creating backend with remote "TestQingStor:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:1562: Error Trace: fstests.go:1562 Error: Received unexpected error: directory not found Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:1565: Error Trace: fstests.go:1565 Error: Should be true Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR Messages: file1Root not found fstests.go:1566: Error Trace: fstests.go:1566 Error: Should be true Test: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR Messages: file2Root not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2022/04/15 08:36:12 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key created from root.txt: Upload single object finished === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink fstests.go:1596: FS has no PublicLinker interface === 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 fstests.go:1746: FS has no PutStream interface === RUN TestIntegration/FsMkdir/FsPutFiles/Internal fstests.go:1790: *qingstor.Fs does not implement InternalTester === RUN TestIntegration/FsMkdir/FsPutChunked === RUN TestIntegration/FsMkdir/FsPutChunked/4Mi === RUN TestIntegration/FsMkdir/FsPutChunked/4Mi/4194303 2022/04/15 08:41:27 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-4.000Mi.bin: Upload single object finished === RUN TestIntegration/FsMkdir/FsPutChunked/4Mi/4194304 2022/04/15 08:44:00 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-4Mi.bin: Uploading as multi-part object to QingStor 2022/04/15 08:44:00 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-4Mi.bin uploadID : Initiating a multi-part upload 2022/04/15 08:44:01 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-4Mi.bin uploadID 915e3df19005fee: Uploading a part to QingStor with partNumber 0 and partSize 4194304 2022/04/15 08:50:16 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-4Mi.bin uploadID 915e3df19005fee: Done uploading part partNumber 0 and partSize 4194304 2022/04/15 08:50:16 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-4Mi.bin uploadID 915e3df19005fee: Completing multi-part object 2022/04/15 08:50:17 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-4Mi.bin uploadID 915e3df19005fee: Complete multi-part finished 2022/04/15 08:50:17 DEBUG : chunked-4Mi-4Mi.bin: Invalid md5sum (probably multipart uploaded) - ignoring: "aad8b8e4d120d0df7a7fda991d5dab03-1" 2022/04/15 08:50:18 DEBUG : chunked-4Mi-4Mi.bin: Invalid md5sum (probably multipart uploaded) - ignoring: "aad8b8e4d120d0df7a7fda991d5dab03-1" === RUN TestIntegration/FsMkdir/FsPutChunked/4Mi/8388609 2022/04/15 08:51:20 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-8.000Mi.bin: Uploading as multi-part object to QingStor 2022/04/15 08:51:20 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-8.000Mi.bin uploadID : Initiating a multi-part upload 2022/04/15 08:51:20 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-8.000Mi.bin uploadID 915e58c214050b3: Uploading a part to QingStor with partNumber 0 and partSize 4194304 2022/04/15 08:58:36 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-8.000Mi.bin uploadID 915e58c214050b3: Done uploading part partNumber 0 and partSize 4194304 2022/04/15 08:58:36 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-8.000Mi.bin uploadID 915e58c214050b3: Uploading a part to QingStor with partNumber 1 and partSize 4194304 2022/04/15 09:04:47 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-8.000Mi.bin uploadID 915e58c214050b3: Done uploading part partNumber 1 and partSize 4194304 2022/04/15 09:04:47 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-8.000Mi.bin uploadID 915e58c214050b3: Uploading a part to QingStor with partNumber 2 and partSize 1 2022/04/15 09:04:47 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-8.000Mi.bin uploadID 915e58c214050b3: Done uploading part partNumber 2 and partSize 1 2022/04/15 09:04:47 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-8.000Mi.bin uploadID 915e58c214050b3: Completing multi-part object 2022/04/15 09:04:48 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4Mi-8.000Mi.bin uploadID 915e58c214050b3: Complete multi-part finished 2022/04/15 09:04:48 DEBUG : chunked-4Mi-8.000Mi.bin: Invalid md5sum (probably multipart uploaded) - ignoring: "551614bf9fa16f183f9d4e9bdcca0bf3-3" 2022/04/15 09:04:49 DEBUG : chunked-4Mi-8.000Mi.bin: Invalid md5sum (probably multipart uploaded) - ignoring: "551614bf9fa16f183f9d4e9bdcca0bf3-3" === RUN TestIntegration/FsMkdir/FsPutChunked/4.005Mi === RUN TestIntegration/FsMkdir/FsPutChunked/4.005Mi/4199999 2022/04/15 09:12:18 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4.005Mi-4.005Mi.bin: Upload single object finished === RUN TestIntegration/FsMkdir/FsPutChunked/4.005Mi/4200000 2022/04/15 09:12:30 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4.005Mi-4.005Mi.bin: Uploading as multi-part object to QingStor 2022/04/15 09:12:30 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4.005Mi-4.005Mi.bin uploadID : Initiating a multi-part upload 2022/04/15 09:12:30 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4.005Mi-4.005Mi.bin uploadID 915ea64b60052e4: Uploading a part to QingStor with partNumber 0 and partSize 4200000 2022/04/15 09:20:14 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4.005Mi-4.005Mi.bin uploadID 915ea64b60052e4: Cancelling multipart upload: Put "https://pek3a.qingstor.com:443/rclone-test-kaguqac0jagimof4qekawic7/chunked-4.005Mi-4.005Mi.bin?part_number=0&upload_id=915ea64b60052e4": write tcp 65.108.87.141:33716->36.110.197.137:443: use of closed network connection 2022/04/15 09:20:14 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4.005Mi-4.005Mi.bin uploadID 915ea64b60052e4: Aborting multi-part object "915ea64b60052e4" fstests.go:153: Error Trace: fstests.go:153 fstests.go:232 Error: Received unexpected error: Put "https://pek3a.qingstor.com:443/rclone-test-kaguqac0jagimof4qekawic7/chunked-4.005Mi-4.005Mi.bin?part_number=0&upload_id=915ea64b60052e4": write tcp 65.108.87.141:33716->36.110.197.137:443: use of closed network connection Test: TestIntegration/FsMkdir/FsPutChunked/4.005Mi/4200000 Messages: PutLarge === RUN TestIntegration/FsMkdir/FsPutChunked/4.005Mi/8400001 2022/04/15 09:20:16 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4.005Mi-8.011Mi.bin: Uploading as multi-part object to QingStor 2022/04/15 09:20:16 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4.005Mi-8.011Mi.bin uploadID : Initiating a multi-part upload 2022/04/15 09:20:17 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4.005Mi-8.011Mi.bin uploadID 915ec2c10008344: Uploading a part to QingStor with partNumber 0 and partSize 4200000 2022/04/15 09:25:17 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4.005Mi-8.011Mi.bin uploadID 915ec2c10008344: Done uploading part partNumber 0 and partSize 4200000 2022/04/15 09:25:17 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4.005Mi-8.011Mi.bin uploadID 915ec2c10008344: Uploading a part to QingStor with partNumber 1 and partSize 4200000 2022/04/15 09:30:14 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4.005Mi-8.011Mi.bin uploadID 915ec2c10008344: Done uploading part partNumber 1 and partSize 4200000 2022/04/15 09:30:15 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4.005Mi-8.011Mi.bin uploadID 915ec2c10008344: Uploading a part to QingStor with partNumber 2 and partSize 1 2022/04/15 09:30:15 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4.005Mi-8.011Mi.bin uploadID 915ec2c10008344: Done uploading part partNumber 2 and partSize 1 2022/04/15 09:30:15 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4.005Mi-8.011Mi.bin uploadID 915ec2c10008344: Completing multi-part object 2022/04/15 09:30:15 DEBUG : QingStor bucket rclone-test-kaguqac0jagimof4qekawic7 key chunked-4.005Mi-8.011Mi.bin uploadID 915ec2c10008344: Complete multi-part finished 2022/04/15 09:30:15 DEBUG : chunked-4.005Mi-8.011Mi.bin: Invalid md5sum (probably multipart uploaded) - ignoring: "f507c223019a48d80c993ce9f4e8ee9c-3" 2022/04/15 09:30:16 DEBUG : chunked-4.005Mi-8.011Mi.bin: Invalid md5sum (probably multipart uploaded) - ignoring: "f507c223019a48d80c993ce9f4e8ee9c-3" === RUN TestIntegration/FsMkdir/FsPutChunked/4.005Mi#01 === RUN TestIntegration/FsMkdir/FsPutChunked/4.005Mi#01/4200041 panic: test timed out after 1h0m0s goroutine 325 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:1788 +0x8e created by time.goFunc /usr/local/go/src/time/sleep.go:180 +0x31 goroutine 1 [chan receive, 59 minutes]: testing.(*T).Run(0xc000305040, {0xa25b87, 0x46f3b3}, 0xa5f838) /usr/local/go/src/testing/testing.go:1307 +0x375 testing.runTests.func1(0xc000321050) /usr/local/go/src/testing/testing.go:1598 +0x6e testing.tRunner(0xc000305040, 0xc000225d18) /usr/local/go/src/testing/testing.go:1259 +0x102 testing.runTests(0xc000346380, {0xebdfa0, 0x1, 0x1}, {0x48d1ed, 0xa256ea, 0xed3020}) /usr/local/go/src/testing/testing.go:1596 +0x43f testing.(*M).Run(0xc000346380) /usr/local/go/src/testing/testing.go:1504 +0x51d main.main() _testmain.go:43 +0x14b goroutine 34 [chan receive, 59 minutes]: testing.(*T).Run(0xc0003051e0, {0xa20ffe, 0xc0003d00e0}, 0xc000125080) /usr/local/go/src/testing/testing.go:1307 +0x375 github.com/rclone/rclone/fstest/fstests.Run(0xc0003051e0, 0xc000390000) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:554 +0x1109 github.com/rclone/rclone/backend/qingstor.TestIntegration(0x407b99) /home/rclone/go/src/github.com/rclone/rclone/backend/qingstor/qingstor_test.go:17 +0x5f testing.tRunner(0xc0003051e0, 0xa5f838) /usr/local/go/src/testing/testing.go:1259 +0x102 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1306 +0x35a goroutine 11 [chan receive, 56 minutes]: testing.(*T).Run(0xc0002ccb60, {0xa23e89, 0x0}, 0xc0004a91a0) /usr/local/go/src/testing/testing.go:1307 +0x375 github.com/rclone/rclone/fstest/fstests.Run.func13(0xc0002ccb60) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1799 +0xd34 testing.tRunner(0xc0002ccb60, 0xc000125080) /usr/local/go/src/testing/testing.go:1259 +0x102 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1306 +0x35a goroutine 289 [chan receive, 4 minutes]: testing.(*T).Run(0xc0003eaea0, {0xc0004a0c50, 0x0}, 0xc0004d05a0) /usr/local/go/src/testing/testing.go:1307 +0x375 github.com/rclone/rclone/fstest/fstests.Run.func13.14(0xc0003eaea0) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1876 +0x673 testing.tRunner(0xc0003eaea0, 0xc0004a91a0) /usr/local/go/src/testing/testing.go:1259 +0x102 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1306 +0x35a goroutine 306 [chan receive, 48 minutes]: github.com/rclone/rclone/lib/atexit.Register.func1.1() /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x2f created by github.com/rclone/rclone/lib/atexit.Register.func1 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x6d goroutine 338 [chan receive, 4 minutes]: testing.(*T).Run(0xc0003ea340, {0xc0004a0c80, 0xc0004c2f50}, 0xc0004a8c90) /usr/local/go/src/testing/testing.go:1307 +0x375 github.com/rclone/rclone/fstest/fstests.Run.func13.14.3(0xc00062e120) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1893 +0x1ce testing.tRunner(0xc0003ea340, 0xc0004d05a0) /usr/local/go/src/testing/testing.go:1259 +0x102 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1306 +0x35a goroutine 312 [IO wait, 2 minutes]: internal/poll.runtime_pollWait(0x7f2fe2a5a018, 0x77) /usr/local/go/src/runtime/netpoll.go:234 +0x89 internal/poll.(*pollDesc).wait(0xc000346200, 0xc00058968e, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x32 internal/poll.(*pollDesc).waitWrite(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:93 internal/poll.(*FD).Write(0xc000346200, {0xc000587000, 0x401d, 0x401d}) /usr/local/go/src/internal/poll/fd_unix.go:283 +0x2c5 net.(*netFD).Write(0xc000346200, {0xc000587000, 0x401d, 0x10a90bb030082304}) /usr/local/go/src/net/fd_posix.go:74 +0x29 net.(*conn).Write(0xc0000cc008, {0xc000587000, 0xc00006d550, 0x0}) /usr/local/go/src/net/net.go:195 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Write(0xc000478000, {0xc000587000, 0xc000587005, 0x401d}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:115 +0x5e crypto/tls.(*Conn).write(0xc0000d2a80, {0xc000587000, 0x5, 0x401d}) /usr/local/go/src/crypto/tls/conn.go:912 +0x108 crypto/tls.(*Conn).writeRecordLocked(0xc0000d2a80, 0x17, {0xc000280000, 0x8000, 0x8000}) /usr/local/go/src/crypto/tls/conn.go:980 +0x351 crypto/tls.(*Conn).Write(0xc00043dba8, {0xc000280000, 0x8000, 0x8000}) /usr/local/go/src/crypto/tls/conn.go:1151 +0x405 io.copyBuffer({0x7f2fcbfd0ef8, 0xc0000d2a80}, {0xaed280, 0xc00043dba8}, {0x0, 0x0, 0x0}) /usr/local/go/src/io/io.go:425 +0x204 io.Copy(...) /usr/local/go/src/io/io.go:382 net/http.persistConnWriter.ReadFrom({0xc0000d2a80}, {0xaed280, 0xc00043dba8}) /usr/local/go/src/net/http/transport.go:1772 +0x54 bufio.(*Writer).ReadFrom(0xc0000ca640, {0xaed280, 0xc00043dba8}) /usr/local/go/src/bufio/bufio.go:731 +0xa6 io.copyBuffer({0xaec520, 0xc0000ca640}, {0xaed280, 0xc00043dba8}, {0x0, 0x0, 0x0}) /usr/local/go/src/io/io.go:409 +0x14b io.Copy(...) /usr/local/go/src/io/io.go:382 net/http.(*transferWriter).doBodyCopy(0xc0002c2d20, {0xaec520, 0xc0000ca640}, {0xaed280, 0xc00043dba8}) /usr/local/go/src/net/http/transfer.go:410 +0x4d net/http.(*transferWriter).writeBody(0xc0002c2d20, {0xaec520, 0xc0000ca640}) /usr/local/go/src/net/http/transfer.go:368 +0x3d8 net/http.(*Request).write(0xc00020e800, {0xaec520, 0xc0000ca640}, 0x0, 0xc0004a8f60, 0x0) /usr/local/go/src/net/http/request.go:698 +0xb4e net/http.(*persistConn).writeLoop(0xc0003da240) /usr/local/go/src/net/http/transport.go:2389 +0x189 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1748 +0x1e65 goroutine 339 [select, 4 minutes]: net/http.(*persistConn).roundTrip(0xc0003da240, 0xc0002b2580) /usr/local/go/src/net/http/transport.go:2614 +0x97d net/http.(*Transport).roundTrip(0xc000339180, 0xc00020e700) /usr/local/go/src/net/http/transport.go:594 +0x7d1 net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:18 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc000324d20, 0xc00020e700) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:274 +0x4c8 net/http.send(0xc00020e700, {0xaecac0, 0xc000324d20}, {0xa0cc00, 0xc00008e101, 0x0}) /usr/local/go/src/net/http/client.go:252 +0x5d8 net/http.(*Client).send(0xc0003ca630, 0xc00020e700, {0xc0000e3700, 0x85f3b8, 0x0}) /usr/local/go/src/net/http/client.go:176 +0x9b net/http.(*Client).do(0xc0003ca630, 0xc00020e700) /usr/local/go/src/net/http/client.go:725 +0x908 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:593 github.com/yunify/qingstor-sdk-go/v3/request.(*Request).send(0xc0004a8cf0) /home/rclone/go/pkg/mod/github.com/yunify/qingstor-sdk-go/v3@v3.2.0/request/request.go:222 +0x15d github.com/yunify/qingstor-sdk-go/v3/request.(*Request).Do(0xc0004a8cf0) /home/rclone/go/pkg/mod/github.com/yunify/qingstor-sdk-go/v3@v3.2.0/request/request.go:105 +0x1e github.com/yunify/qingstor-sdk-go/v3/request.(*Request).Send(0xc000504c30) /home/rclone/go/pkg/mod/github.com/yunify/qingstor-sdk-go/v3@v3.2.0/request/request.go:78 +0x48 github.com/yunify/qingstor-sdk-go/v3/service.(*Bucket).PutObject(0xc0003160a0, {0xc0004b87e5, 0x24}, 0xc000333af7) /home/rclone/go/pkg/mod/github.com/yunify/qingstor-sdk-go/v3@v3.2.0/service/object.go:937 +0x3d github.com/rclone/rclone/backend/qingstor.(*uploader).singlePartUpload(0xc000608240, {0xaed320, 0xc000608220}, 0x401669) /home/rclone/go/src/github.com/rclone/rclone/backend/qingstor/upload.go:166 +0x188 github.com/rclone/rclone/backend/qingstor.(*Object).Update(0xc00021c5b0, {0xafaad0, 0xc000024058}, {0xaed320, 0xc000608220}, {0xb01cd0, 0xc0004d06e0}, {0x0, 0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/backend/qingstor/qingstor.go:1088 +0x2b9 github.com/rclone/rclone/backend/qingstor.(*Fs).Put(0xc0003d00e0, {0xafaad0, 0xc000024058}, {0xaed320, 0xc000608220}, {0xb01cd0, 0xc0004d06e0}, {0x0, 0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/backend/qingstor/qingstor.go:429 +0x125 github.com/rclone/rclone/fstest/fstests.TestPutLarge.func1() /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:238 +0x1ea github.com/rclone/rclone/fstest/fstests.retry(0xc0003ea680, {0xa21933, 0x8}, 0xc0000e3e10) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:145 +0x119 github.com/rclone/rclone/fstest/fstests.TestPutLarge({0xafaad0, 0xc000024058}, 0xc0003ea680, {0xb06f58, 0xc0003d00e0}, 0xc0000e3f28) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:232 +0x13b github.com/rclone/rclone/fstest/fstests.Run.func13.14.3.1(0xc00062e120) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1894 +0x20c testing.tRunner(0xc0003ea680, 0xc0004a8c90) /usr/local/go/src/testing/testing.go:1259 +0x102 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1306 +0x35a goroutine 294 [syscall, 48 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:169 +0x98 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:24 +0x19 created by os/signal.Notify.func1.1 /usr/local/go/src/os/signal/signal.go:151 +0x2c goroutine 311 [IO wait, 4 minutes]: internal/poll.runtime_pollWait(0x7f2fe2a5a018, 0x72) /usr/local/go/src/runtime/netpoll.go:234 +0x89 internal/poll.(*pollDesc).wait(0xc000346200, 0xc000294000, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x32 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000346200, {0xc000294000, 0xe817, 0xe817}) /usr/local/go/src/internal/poll/fd_unix.go:167 +0x25a net.(*netFD).Read(0xc000346200, {0xc000294000, 0xc000144640, 0xc00029400d}) /usr/local/go/src/net/fd_posix.go:56 +0x29 net.(*conn).Read(0xc0000cc008, {0xc000294000, 0xc00029400d, 0xc5}) /usr/local/go/src/net/net.go:183 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000478000, {0xc000294000, 0xc000540540, 0x100000000000002}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:104 +0x32 crypto/tls.(*atLeastReader).Read(0xc00043d818, {0xc000294000, 0x0, 0x40bacd}) /usr/local/go/src/crypto/tls/conn.go:777 +0x3d bytes.(*Buffer).ReadFrom(0xc0000d2cf8, {0xaec680, 0xc00043d818}) /usr/local/go/src/bytes/buffer.go:204 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0000d2a80, {0x7f2fe1a10100, 0xc000478000}, 0xe817) /usr/local/go/src/crypto/tls/conn.go:799 +0xe5 crypto/tls.(*Conn).readRecordOrCCS(0xc0000d2a80, 0x0) /usr/local/go/src/crypto/tls/conn.go:606 +0x112 crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:574 crypto/tls.(*Conn).Read(0xc0000d2a80, {0xc0003df000, 0x1000, 0x0}) /usr/local/go/src/crypto/tls/conn.go:1277 +0x16f net/http.(*persistConn).Read(0xc0003da240, {0xc0003df000, 0x44c1e0, 0xc000051ec8}) /usr/local/go/src/net/http/transport.go:1926 +0x4e bufio.(*Reader).fill(0xc000541320) /usr/local/go/src/bufio/bufio.go:101 +0x103 bufio.(*Reader).Peek(0xc000541320, 0x1) /usr/local/go/src/bufio/bufio.go:139 +0x5d net/http.(*persistConn).readLoop(0xc0003da240) /usr/local/go/src/net/http/transport.go:2087 +0x1ac created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1747 +0x1e05 exit status 2 FAIL github.com/rclone/rclone/backend/qingstor 3600.015s "go test -v -timeout 1h0m0s -remote TestQingStor: -verbose" - Finished ERROR in 1h0m0.605915103s (try 4/5): exit status 1: Failed []