"go test -v -timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5) === RUN TestNewNameEncryptionMode --- PASS: TestNewNameEncryptionMode (0.00s) === RUN TestNewNameEncryptionModeString --- PASS: TestNewNameEncryptionModeString (0.00s) === RUN TestEncodeFileName --- PASS: TestEncodeFileName (0.00s) === RUN TestDecodeFileName --- PASS: TestDecodeFileName (0.00s) === RUN TestEncryptSegment --- PASS: TestEncryptSegment (0.00s) === RUN TestDecryptSegment --- PASS: TestDecryptSegment (0.00s) === RUN TestEncryptFileName --- PASS: TestEncryptFileName (0.00s) === RUN TestDecryptFileName --- PASS: TestDecryptFileName (0.00s) === RUN TestEncDecMatches --- PASS: TestEncDecMatches (0.00s) === RUN TestEncryptDirName --- PASS: TestEncryptDirName (0.00s) === RUN TestDecryptDirName --- PASS: TestDecryptDirName (0.00s) === RUN TestEncryptedSize --- PASS: TestEncryptedSize (0.00s) === RUN TestDecryptedSize --- PASS: TestDecryptedSize (0.00s) === RUN TestNoncePointer --- PASS: TestNoncePointer (0.00s) === RUN TestNonceFromReader --- PASS: TestNonceFromReader (0.00s) === RUN TestNonceFromBuf --- PASS: TestNonceFromBuf (0.00s) === RUN TestNonceIncrement --- PASS: TestNonceIncrement (0.00s) === RUN TestNonceAdd --- PASS: TestNonceAdd (0.00s) === RUN TestRandomSource --- PASS: TestRandomSource (0.61s) === RUN TestEncryptDecrypt1 --- PASS: TestEncryptDecrypt1 (0.48s) === RUN TestEncryptDecrypt32 --- PASS: TestEncryptDecrypt32 (1.14s) === RUN TestEncryptDecrypt4096 --- PASS: TestEncryptDecrypt4096 (1.01s) === RUN TestEncryptDecrypt65536 --- PASS: TestEncryptDecrypt65536 (0.99s) === RUN TestEncryptDecrypt65537 --- PASS: TestEncryptDecrypt65537 (1.00s) === RUN TestEncryptData --- PASS: TestEncryptData (0.00s) === RUN TestNewEncrypter --- PASS: TestNewEncrypter (0.00s) === RUN TestNewEncrypterErrUnexpectedEOF --- PASS: TestNewEncrypterErrUnexpectedEOF (0.00s) === RUN TestNewDecrypter --- PASS: TestNewDecrypter (0.00s) === RUN TestNewDecrypterErrUnexpectedEOF --- PASS: TestNewDecrypterErrUnexpectedEOF (0.00s) === RUN TestNewDecrypterSeekLimit --- PASS: TestNewDecrypterSeekLimit (0.23s) === RUN TestDecrypterCalculateUnderlying --- PASS: TestDecrypterCalculateUnderlying (0.00s) === RUN TestDecrypterRead --- PASS: TestDecrypterRead (0.00s) === RUN TestDecrypterClose --- PASS: TestDecrypterClose (0.00s) === RUN TestPutGetBlock --- PASS: TestPutGetBlock (0.00s) === RUN TestKey --- PASS: TestKey (0.51s) === RUN TestIntegration fstests.go:418: Using remote "TestCryptDrive:" 2021/07/25 06:20:23 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-suzepiy4hujegow3ruqoqin6" 2021/07/25 06:20:23 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/07/25 06:20:23 DEBUG : Creating backend with remote "TestDrive:crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse" === RUN TestIntegration/FsCheckWrap === RUN TestIntegration/FsCommand === RUN TestIntegration/FsRmdirNotFound === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2021/07/25 06:20:27 DEBUG : dir/subdir: Making directory 2021/07/25 06:20:29 INFO : dir/subdir: Removing directory 2021/07/25 06:20:30 INFO : dir: Removing directory 2021/07/25 06:20:31 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "i24n0ln37pa2hv8pctddeuh73o" === RUN TestIntegration/FsMkdir/FsListEmpty === RUN TestIntegration/FsMkdir/FsListDirEmpty === RUN TestIntegration/FsMkdir/FsListRDirEmpty === RUN TestIntegration/FsMkdir/FsListDirNotFound 2021/07/25 06:20:32 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound 2021/07/25 06:20:32 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:669: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2021/07/25 06:20:34 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: md5 = 32273594766dd5d04d70f00eaf109362 OK 2021/07/25 06:20:36 DEBUG : f5e9c92esko9m26sej116anh5babi4i0al2vne0nmg9mvm0d1b9nq1ttlhvs75nh03n25mqg1vfrohsj7ua930ba4p3c4g405vd56tmte6ccvf24guul4t5gib4nm25g5l7cglg1lpefefuvubu655p01tsluhjl8jduu4dtjlour146bad0: Rmdir: contains trashed file: "f5e9c92esko9m26sej116anh5babi4i0al2vne0nmg9mvm0d1b9nq1ttlhvs75nh03n25mqg1vfrohsj7ua930ba4p3c4g405vd56tmte6ccvf24guul4t5gib4nm25g5l7cglg1lpefefuvubu655p01tsluhjl8jduu4dtjlour146bad0" === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:669: testing "." 2021/07/25 06:20:38 DEBUG : ./.: md5 = d7bdd248394854f0ec3c6403a37f763e OK 2021/07/25 06:20:40 DEBUG : do1eshp7fq66o1l1ei9pomlig8: Rmdir: contains trashed file: "do1eshp7fq66o1l1ei9pomlig8" === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:669: testing ".." 2021/07/25 06:20:42 DEBUG : ../..: md5 = ee9401f34a1c976b1cf8ec9fcf905bb2 OK 2021/07/25 06:20:44 DEBUG : msnn42iarlnl4kchlpfbl49rq0: Rmdir: contains trashed file: "msnn42iarlnl4kchlpfbl49rq0" === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:669: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2021/07/25 06:20:46 DEBUG : !"#$%&'()*+,-./:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-./:;<=>?@[\]^_`{|}~: md5 = 47972aa04053f631391c8f64a6a78099 OK 2021/07/25 06:20:47 DEBUG : hassubu97i2ift2pggdbt1cfp876l53bceemu9btjavl43jthqj7tasdbcmte4busfs27dmocs8na: Rmdir: contains trashed file: "hassubu97i2ift2pggdbt1cfp876l53bceemu9btjavl43jthqj7tasdbcmte4busfs27dmocs8na" === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:669: testing " leading space" 2021/07/25 06:20:50 DEBUG : leading space/ leading space: md5 = ab9b2d59fb9b08fbc6b2c934d98982aa OK 2021/07/25 06:20:52 DEBUG : igstt54k5qp9e549r6pqm35hl8: Rmdir: contains trashed file: "igstt54k5qp9e549r6pqm35hl8" === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:669: testing "~leading tilde" 2021/07/25 06:20:54 DEBUG : ~leading tilde/~leading tilde: md5 = 4faa181d34c6cd461e491af9b72b0842 OK 2021/07/25 06:20:56 DEBUG : nevsnko0v27og2qt2iomj9cbi4: Rmdir: contains trashed file: "nevsnko0v27og2qt2iomj9cbi4" === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:669: testing "␍leading CR" 2021/07/25 06:20:58 DEBUG : ␍leading CR/␍leading CR: md5 = a7b4fd23d45bd25a879ad42f2369c944 OK 2021/07/25 06:21:00 DEBUG : a2h0eu6un2623343gk1tqj6ut4: Rmdir: contains trashed file: "a2h0eu6un2623343gk1tqj6ut4" === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:669: testing "␊leading LF" 2021/07/25 06:21:02 DEBUG : ␊leading LF/␊leading LF: md5 = 5b4155f6c72d823c8736ae2a9043c303 OK 2021/07/25 06:21:04 DEBUG : u529vt6ubothc7lvqh76rsb288: Rmdir: contains trashed file: "u529vt6ubothc7lvqh76rsb288" === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:669: testing "␉leading HT" 2021/07/25 06:21:06 DEBUG : ␉leading HT/␉leading HT: md5 = f78a1366fe6e0e634ea5ebbcf9760f6c OK 2021/07/25 06:21:08 DEBUG : uhsh7ikeumar00etd5nibokc00: Rmdir: contains trashed file: "uhsh7ikeumar00etd5nibokc00" === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:669: testing "␋leading VT" 2021/07/25 06:21:10 DEBUG : ␋leading VT/␋leading VT: md5 = 0289bb04147f25336c8dea37f5655d92 OK 2021/07/25 06:21:12 DEBUG : 5k8glf8j137rtplh8pf777kc6k: Rmdir: contains trashed file: "5k8glf8j137rtplh8pf777kc6k" === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:669: testing ".leading dot" 2021/07/25 06:21:14 DEBUG : .leading dot/.leading dot: md5 = 8d86861b60aea8bdc5b951200ca4c3c9 OK 2021/07/25 06:21:16 DEBUG : hc8mhlb9r1e05buvff53j7ps94: Rmdir: contains trashed file: "hc8mhlb9r1e05buvff53j7ps94" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:669: testing "trailing space " 2021/07/25 06:21:18 DEBUG : trailing space /trailing space : md5 = 1cfbe02ea50528eec780e683f821effe OK 2021/07/25 06:21:19 DEBUG : 85pi11ntdsjcdjq56s37obj3k8: Rmdir: contains trashed file: "85pi11ntdsjcdjq56s37obj3k8" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:669: testing "trailing CR␍" 2021/07/25 06:21:22 DEBUG : trailing CR␍/trailing CR␍: md5 = 0686383e14dc9f520408aee3c38f6ab7 OK 2021/07/25 06:21:23 DEBUG : 0e6sh7hqlktdhvg6jk5u8d06u0: Rmdir: contains trashed file: "0e6sh7hqlktdhvg6jk5u8d06u0" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:669: testing "trailing LF␊" 2021/07/25 06:21:26 DEBUG : trailing LF␊/trailing LF␊: md5 = d653d5d613cb9cd61822b8d0f88be4e5 OK 2021/07/25 06:21:27 DEBUG : 90oud4k3ofajhl43rm1o73dvd8: Rmdir: contains trashed file: "90oud4k3ofajhl43rm1o73dvd8" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:669: testing "trailing HT␉" 2021/07/25 06:21:30 DEBUG : trailing HT␉/trailing HT␉: md5 = 0171d5dd7cf5d79b5b9c5a0dc5380e9a OK 2021/07/25 06:21:31 DEBUG : dfr970d3hh9t7tp17e6s5eukfk: Rmdir: contains trashed file: "dfr970d3hh9t7tp17e6s5eukfk" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:669: testing "trailing VT␋" 2021/07/25 06:21:34 DEBUG : trailing VT␋/trailing VT␋: md5 = 7b59bad9fccf97d44826fe23d481e636 OK 2021/07/25 06:21:35 DEBUG : pd7ls3vh3ccrv2ib1dd61189cc: Rmdir: contains trashed file: "pd7ls3vh3ccrv2ib1dd61189cc" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:669: testing "trailing dot." 2021/07/25 06:21:38 DEBUG : trailing dot./trailing dot.: md5 = 51ddf1f0b4245cc57dacad0b128fc9d3 OK 2021/07/25 06:21:39 DEBUG : fmlmh67m08l9reo4o1doaon9i4: Rmdir: contains trashed file: "fmlmh67m08l9reo4o1doaon9i4" === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:669: testing "invalid utf-8\xfe" 2021/07/25 06:21:42 DEBUG : invalid utf-8/invalid utf-8: md5 = 8d6e160665aa49ca4222b4c07b073809 OK 2021/07/25 06:21:43 DEBUG : toetsandc7pe3o6afhhq1cvja4: Rmdir: contains trashed file: "toetsandc7pe3o6afhhq1cvja4" === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError === RUN TestIntegration/FsMkdir/FsPutZeroLength 2021/07/25 06:21:49 DEBUG : zero-length-file: md5 = 7795d570d915fd98dfe828e593a804e6 OK === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:742: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsChangeNotify 2021/07/25 06:21:51 DEBUG : dir: Making directory 2021/07/25 06:21:52 DEBUG : dir/subdir1: Making directory 2021/07/25 06:21:52 DEBUG : dir/subdir3: Making directory 2021/07/25 06:21:53 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': Checking for changes on remote 2021/07/25 06:21:53 DEBUG : doChangeNotify("dir/subdir1", 0) 2021/07/25 06:21:53 DEBUG : dir/subdir2: Making directory 2021/07/25 06:21:54 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': Checking for changes on remote 2021/07/25 06:21:54 DEBUG : doChangeNotify("dir/subdir3", 0) 2021/07/25 06:21:55 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': Checking for changes on remote 2021/07/25 06:21:55 DEBUG : doChangeNotify("dir/subdir2", 0) 2021/07/25 06:21:55 DEBUG : dir/file2: md5 = 48ce2c381f477e25611c6acd9a7bd194 OK 2021/07/25 06:21:56 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': Checking for changes on remote 2021/07/25 06:21:56 DEBUG : doChangeNotify("dir/file2", 1) 2021/07/25 06:21:56 DEBUG : dir/file4: md5 = 033a8121d34cd2bb691d11b31bdcecfc OK 2021/07/25 06:21:57 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': Checking for changes on remote 2021/07/25 06:21:57 DEBUG : doChangeNotify("dir/file4", 1) 2021/07/25 06:21:58 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': Checking for changes on remote 2021/07/25 06:21:58 DEBUG : dir/file3: md5 = 3b0d4cc79279e275fd6a2874bbc87e74 OK fstests.go:841: Try 1/10 waiting for dirChanges and objChanges 2021/07/25 06:21:59 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': Checking for changes on remote 2021/07/25 06:21:59 DEBUG : doChangeNotify("dir/file3", 1) 2021/07/25 06:22:00 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': Checking for changes on remote 2021/07/25 06:22:01 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': Checking for changes on remote 2021/07/25 06:22:02 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': Checking for changes on remote 2021/07/25 06:22:02 DEBUG : doChangeNotify("dir/file2", 1) 2021/07/25 06:22:03 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': Checking for changes on remote 2021/07/25 06:22:03 DEBUG : doChangeNotify("dir/file4", 1) 2021/07/25 06:22:03 DEBUG : doChangeNotify("dir/file3", 1) 2021/07/25 06:22:04 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': Checking for changes on remote 2021/07/25 06:22:04 DEBUG : doChangeNotify("dir/subdir1", 0) 2021/07/25 06:22:05 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': Checking for changes on remote 2021/07/25 06:22:05 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "633fmaa0qic3kv3cladnu11pto" 2021/07/25 06:22:05 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "amq3bkuqh8mg4ohnbs2i3185uk" 2021/07/25 06:22:05 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "d26uovf0miqhd1mu3c9jbseq00" 2021/07/25 06:22:05 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "9ttr6ktchp8s75c384iri950b4" 2021/07/25 06:22:05 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "6lcfeqjd564osl9cuhasslpeto" 2021/07/25 06:22:05 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "hpv9vbsl47o6lm8alr8q8b1m60" 2021/07/25 06:22:05 DEBUG : doChangeNotify("dir/subdir3", 0) 2021/07/25 06:22:05 DEBUG : doChangeNotify("dir/subdir2", 0) === RUN TestIntegration/FsMkdir/FsPutFiles 2021/07/25 06:22:07 DEBUG : file name.txt: md5 = 8954f09df2d49bd73215ae9b9724dae5 OK 2021/07/25 06:22:08 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: md5 = e8fc44f7813239f4ebc05dbf25b4f659 OK 2021/07/25 06:22:09 DEBUG : file name.txt: md5 = ad6e0e5bd1f567a71fb8364066c6be97 OK === 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 2021/07/25 06:22:14 DEBUG : Creating backend with remote "TestCryptDrive:" 2021/07/25 06:22:14 DEBUG : Creating backend with remote "TestDrive:crypt" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot 2021/07/25 06:22:14 DEBUG : Creating backend with remote "TestCryptDrive:" === 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:1031: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2021/07/25 06:22:22 DEBUG : dirToPurge/fileToPurge.txt: md5 = 6f1b1df04f5dc98a9321bf6359cf5cbc OK === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2021/07/25 06:22:37 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-xuhevof8getusic2mavolav8" 2021/07/25 06:22:37 DEBUG : Config file has changed externaly - reloading 2021/07/25 06:22:38 DEBUG : Creating backend with remote "TestDrive:crypt/616dfl725hjhk2tl04v5qmupj95kt4sqor1q03tskua2drha2cf7rt2h2n2n0c0f1skvg14ghv6m8" 2021/07/25 06:22:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-xuhevof8getusic2mavolav8': Purge remote === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2021/07/25 06:22:46 DEBUG : : Rmdir: contains trashed file: "jvrdogrnrblgdk6svqeu791rdk" 2021/07/25 06:22:46 DEBUG : : Rmdir: contains trashed file: "ruuqrs7tbf1d4t5glb6bcbp5rk" 2021/07/25 06:22:46 DEBUG : : Rmdir: contains trashed file: "gbh5ioi619nhbt6teh71jdbef8" 2021/07/25 06:22:46 DEBUG : : Rmdir: contains file: "96gujs4dkgufj2s2d5t6mkrddg" === 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 fstests.go:1338: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2021/07/25 06:22:56 DEBUG : file name.txt: md5 = 4805c4325b5beca7e0602f4f95290789 OK === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2021/07/25 06:22:57 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-suzepiy4hujegow3ruqoqin6/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2021/07/25 06:22:57 DEBUG : Creating backend with remote "TestDrive:crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse/96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0/u4ipppammbskorbmr2jnsktems" 2021/07/25 06:23:00 DEBUG : fs cache: adding new entry for parent of "TestDrive:crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse/96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0/u4ipppammbskorbmr2jnsktems", "TestDrive:crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse/96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0" === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2021/07/25 06:23:00 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-suzepiy4hujegow3ruqoqin6/not found.txt" 2021/07/25 06:23:00 DEBUG : Creating backend with remote "TestDrive:crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse/jgn01lqi50h78b6hoirrg20eko" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1496: Opening root remote "TestCryptDrive:" path "rclone-test-suzepiy4hujegow3ruqoqin6" from "TestCryptDrive:rclone-test-suzepiy4hujegow3ruqoqin6" 2021/07/25 06:23:02 DEBUG : Creating backend with remote "TestCryptDrive:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR 2021/07/25 06:23:04 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: User Rate Limit Exceeded. Rate of requests for user exceed configured project quota. You may consider re-evaluating expected per-user traffic to the API and adjust project quota limits accordingly. You may monitor aggregate quota usage and adjust limits in the API Console: https://console.developers.google.com/apis/api/drive.googleapis.com/quotas?project=847840889997, userRateLimitExceeded) 2021/07/25 06:23:04 DEBUG : pacer: Rate limited, increasing sleep to 1.270873772s 2021/07/25 06:23:04 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2021/07/25 06:23:07 DEBUG : rclone-test-suzepiy4hujegow3ruqoqin6/created from root.txt: md5 = 315222caaf44138b80c695dd586a8596 OK === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink 2021/07/25 06:23:10 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': attempting to share single file '80abn8ft86uthvulkui23octdeuodrrspkhsk4hav33t59ipblug' 2021/07/25 06:23:11 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': attempting to share single file 'nolduc6vng08379n9fboa9fqe4' 2021/07/25 06:23:12 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': attempting to share single file '96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0/u4ipppammbskorbmr2jnsktems' 2021/07/25 06:23:13 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': attempting to share single file 'nolduc6vng08379n9fboa9fqe4' 2021/07/25 06:23:14 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': attempting to share directory '96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0' 2021/07/25 06:23:15 DEBUG : Google drive root 'crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse': attempting to share directory '96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0' 2021/07/25 06:23:16 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-jegahas2lekugub1fipipoq7" 2021/07/25 06:23:16 DEBUG : Creating backend with remote "TestDrive:crypt/1qnefbmjb8uvs0aavq47g1siif1299cm44eu15vamseqr107oo79uf0abg5v932dkc6bpnk8qac32" 2021/07/25 06:23:18 DEBUG : somefile: md5 = 512ff26bad138afb6b8ba59c7a9885a6 OK 2021/07/25 06:23:19 DEBUG : Google drive root 'crypt/1qnefbmjb8uvs0aavq47g1siif1299cm44eu15vamseqr107oo79uf0abg5v932dkc6bpnk8qac32': attempting to share directory '' 2021/07/25 06:23:19 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-jegahas2lekugub1fipipoq7': Purge remote === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:392: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 2021/07/25 06:23:22 DEBUG : cqiebell711479onsrbud2b5lc: Sending chunk 0 length 32 2021/07/25 06:23:23 DEBUG : piped data.txt: md5 = cef05046bcd214f80075bb461750dba0 OK === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2021/07/25 06:23:24 DEBUG : cqiebell711479onsrbud2b5lc: Sending chunk 0 length 148 2021/07/25 06:23:25 DEBUG : piped data.txt: md5 = f1c4017492bc95a808825b998b0d93ff OK === RUN TestIntegration/FsMkdir/FsPutFiles/Internal === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/ObjectInfo 2021/07/25 06:23:26 DEBUG : Creating backend with remote "/tmp/rclone-spool354456154" 2021/07/25 06:23:26 DEBUG : hash_test_object: Computing md5 hash of encrypted source === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/ObjectInfoWrap 2021/07/25 06:23:26 DEBUG : Creating backend with remote "/tmp/rclone-spool694724081" 2021/07/25 06:23:26 DEBUG : _wrap: Computing md5 hash of encrypted source === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/ComputeHash 2021/07/25 06:23:26 DEBUG : Creating backend with remote "/tmp/rclone-spool238655644" 2021/07/25 06:23:27 DEBUG : compute_hash_test: md5 = 0e1d497d56b27fe44c67c426725cc135 OK === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:1782: *crypt.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2021/07/25 06:23:29 DEBUG : 5t6fllu4sbjea82tuvhkr8g5779c8gu1qrjbjcj5lupfmsp501n0: Sending chunk 0 length 148 2021/07/25 06:23:29 DEBUG : unknown-size-put.txt: md5 = 551536c9ba43b4a043305f30a2836a98 OK === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2021/07/25 06:23:31 DEBUG : unknown-size-update.txt: md5 = 8f2c74f9c8ca0e0add1425a4d8a8336f OK 2021/07/25 06:23:32 DEBUG : i60g7a9vietemeugj4vko3pcqb41or1fldle4lnp7rp5c64fh8ng: Sending chunk 0 length 248 2021/07/25 06:23:33 DEBUG : unknown-size-update.txt: md5 = c938679bbd8035b43fea92f62915efa3 OK === RUN TestIntegration/FsMkdir/FsRootCollapse 2021/07/25 06:23:33 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-suzepiy4hujegow3ruqoqin6/deeper/nonexisting/directory" 2021/07/25 06:23:33 DEBUG : Creating backend with remote "TestDrive:crypt/cl1ak28i0ah5l4o1abebvk2mq5mlcroon7gn46uhkrior9f4ts0cbpc79ethktchiu8l89fmkprse/a8sv4gs52nbgdd9dahlimee280/fbehe2orn9mdrpoteiv7ibtqvo/486oifeu6kfc5kp8j3to8ir2s4" === RUN TestIntegration/FsShutdown fstests.go:1978: Shutdown method not supported --- PASS: TestIntegration (192.70s) --- PASS: TestIntegration/FsCheckWrap (0.00s) --- PASS: TestIntegration/FsCommand (0.00s) --- PASS: TestIntegration/FsRmdirNotFound (0.20s) --- PASS: TestIntegration/FsString (0.00s) --- PASS: TestIntegration/FsName (0.00s) --- PASS: TestIntegration/FsRoot (0.00s) --- PASS: TestIntegration/FsRmdirEmpty (0.69s) --- PASS: TestIntegration/FsMkdir (189.63s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (4.13s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.20s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.21s) --- PASS: TestIntegration/FsMkdir/FsListRDirEmpty (0.20s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.20s) --- PASS: TestIntegration/FsMkdir/FsListRDirNotFound (0.20s) --- PASS: TestIntegration/FsMkdir/FsEncoding (71.76s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (3.97s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (3.92s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (3.85s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (3.92s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (4.12s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (4.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (4.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (4.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (4.11s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (3.84s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (3.89s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (3.93s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (3.90s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (3.92s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (4.04s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (4.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (3.98s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (4.07s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.47s) --- PASS: TestIntegration/FsMkdir/FsPutError (3.57s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (2.70s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- PASS: TestIntegration/FsMkdir/FsChangeNotify (14.64s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (82.89s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (1.11s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (1.11s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (1.09s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.88s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (0.57s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.30s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.50s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.42s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.44s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (1.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (1.16s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.23s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (1.16s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (4.87s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (3.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (9.49s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (8.26s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.23s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.25s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.23s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.21s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.23s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.24s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (0.93s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.22s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.78s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (1.29s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (3.83s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.78s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (1.84s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.21s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (3.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (1.53s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (7.56s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (1.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (2.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (3.91s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (1.48s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/PublicLink (10.22s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.23s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (1.87s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.20s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (4.13s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (2.11s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (2.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (2.35s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ObjectInfo (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ObjectInfoWrap (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ComputeHash (2.35s) --- SKIP: TestIntegration/FsMkdir/FsPutChunked (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (4.72s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (1.69s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (3.03s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (1.43s) --- SKIP: TestIntegration/FsShutdown (0.00s) === RUN TestStandard crypt_test.go:34: Skipping as -remote set --- SKIP: TestStandard (0.00s) === RUN TestOff crypt_test.go:55: Skipping as -remote set --- SKIP: TestOff (0.00s) === RUN TestObfuscate crypt_test.go:76: Skipping as -remote set --- SKIP: TestObfuscate (0.00s) === RUN TestNoDataObfuscate crypt_test.go:98: Skipping as -remote set --- SKIP: TestNoDataObfuscate (0.00s) PASS ok github.com/rclone/rclone/backend/crypt 198.708s "go test -v -timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 3m21.187271054s (try 1/5)