"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 TestEncodeFileNameBase32 --- PASS: TestEncodeFileNameBase32 (0.00s) === RUN TestEncodeFileNameBase64 --- PASS: TestEncodeFileNameBase64 (0.00s) === RUN TestEncodeFileNameBase32768 --- PASS: TestEncodeFileNameBase32768 (0.00s) === RUN TestDecodeFileNameBase32 --- PASS: TestDecodeFileNameBase32 (0.00s) === RUN TestDecodeFileNameBase64 --- PASS: TestDecodeFileNameBase64 (0.00s) === RUN TestDecodeFileNameBase32768 --- PASS: TestDecodeFileNameBase32768 (0.00s) === RUN TestEncryptSegmentBase32 --- PASS: TestEncryptSegmentBase32 (0.00s) === RUN TestEncryptSegmentBase64 --- PASS: TestEncryptSegmentBase64 (0.00s) === RUN TestEncryptSegmentBase32768 --- PASS: TestEncryptSegmentBase32768 (0.00s) === RUN TestDecryptSegmentBase32 --- PASS: TestDecryptSegmentBase32 (0.00s) === RUN TestDecryptSegmentBase64 --- PASS: TestDecryptSegmentBase64 (0.00s) === RUN TestDecryptSegmentBase32768 --- PASS: TestDecryptSegmentBase32768 (0.00s) === RUN TestStandardEncryptFileNameBase32 --- PASS: TestStandardEncryptFileNameBase32 (0.01s) === RUN TestStandardEncryptFileNameBase64 --- PASS: TestStandardEncryptFileNameBase64 (0.00s) === RUN TestStandardEncryptFileNameBase32768 --- PASS: TestStandardEncryptFileNameBase32768 (0.00s) === RUN TestNonStandardEncryptFileName --- PASS: TestNonStandardEncryptFileName (0.00s) === RUN TestStandardDecryptFileNameBase32 --- PASS: TestStandardDecryptFileNameBase32 (0.00s) === RUN TestStandardDecryptFileNameBase64 --- PASS: TestStandardDecryptFileNameBase64 (0.00s) === RUN TestStandardDecryptFileNameBase32768 --- PASS: TestStandardDecryptFileNameBase32768 (0.00s) === RUN TestNonStandardDecryptFileName --- PASS: TestNonStandardDecryptFileName (0.00s) === RUN TestEncDecMatches --- PASS: TestEncDecMatches (0.00s) === RUN TestStandardEncryptDirNameBase32 --- PASS: TestStandardEncryptDirNameBase32 (0.00s) === RUN TestStandardEncryptDirNameBase64 --- PASS: TestStandardEncryptDirNameBase64 (0.00s) === RUN TestStandardEncryptDirNameBase32768 --- PASS: TestStandardEncryptDirNameBase32768 (0.00s) === RUN TestNonStandardEncryptDirName --- PASS: TestNonStandardEncryptDirName (0.00s) === RUN TestStandardDecryptDirNameBase32 --- PASS: TestStandardDecryptDirNameBase32 (0.00s) === RUN TestStandardDecryptDirNameBase64 --- PASS: TestStandardDecryptDirNameBase64 (0.00s) === RUN TestStandardDecryptDirNameBase32768 --- PASS: TestStandardDecryptDirNameBase32768 (0.00s) === RUN TestNonStandardDecryptDirName --- PASS: TestNonStandardDecryptDirName (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.50s) === RUN TestEncryptDecrypt1 --- PASS: TestEncryptDecrypt1 (0.26s) === RUN TestEncryptDecrypt32 --- PASS: TestEncryptDecrypt32 (0.82s) === RUN TestEncryptDecrypt4096 --- PASS: TestEncryptDecrypt4096 (0.75s) === RUN TestEncryptDecrypt65536 --- PASS: TestEncryptDecrypt65536 (0.76s) === RUN TestEncryptDecrypt65537 --- PASS: TestEncryptDecrypt65537 (0.87s) === 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.39s) === 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.54s) === RUN TestIntegration fstests.go:418: Using remote "TestCryptDrive:" 2022/01/09 05:02:29 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-witexoh0kedejoc2masoqoq2" 2022/01/09 05:02:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/01/09 05:02:29 DEBUG : Creating backend with remote "TestDrive:crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta" === 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 2022/01/09 05:02:35 DEBUG : dir/subdir: Making directory 2022/01/09 05:02:38 INFO : dir/subdir: Removing directory 2022/01/09 05:02:40 INFO : dir: Removing directory 2022/01/09 05:02:40 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "i24n0ln37pa2hv8pctddeuh73o" === RUN TestIntegration/FsMkdir/FsListEmpty === RUN TestIntegration/FsMkdir/FsListDirEmpty === RUN TestIntegration/FsMkdir/FsListRDirEmpty === RUN TestIntegration/FsMkdir/FsListDirNotFound 2022/01/09 05:02:43 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound 2022/01/09 05:02:43 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:674: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2022/01/09 05:02:46 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: md5 = f101ba3b67cec1dc5e3719d05a6a386e OK 2022/01/09 05:02:48 DEBUG : f5e9c92esko9m26sej116anh5babi4i0al2vne0nmg9mvm0d1b9nq1ttlhvs75nh03n25mqg1vfrohsj7ua930ba4p3c4g405vd56tmte6ccvf24guul4t5gib4nm25g5l7cglg1lpefefuvubu655p01tsluhjl8jduu4dtjlour146bad0: Rmdir: contains trashed file: "f5e9c92esko9m26sej116anh5babi4i0al2vne0nmg9mvm0d1b9nq1ttlhvs75nh03n25mqg1vfrohsj7ua930ba4p3c4g405vd56tmte6ccvf24guul4t5gib4nm25g5l7cglg1lpefefuvubu655p01tsluhjl8jduu4dtjlour146bad0" === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:674: testing "．" 2022/01/09 05:02:52 DEBUG : ．/．: md5 = cf8af2432246472358d82a1bea1e15f3 OK 2022/01/09 05:02:54 DEBUG : do1eshp7fq66o1l1ei9pomlig8: Rmdir: contains trashed file: "do1eshp7fq66o1l1ei9pomlig8" === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:674: testing "．．" 2022/01/09 05:02:58 DEBUG : ．．/．．: md5 = 32ce3a052e0e358487269b979612cc3a OK 2022/01/09 05:02:59 DEBUG : msnn42iarlnl4kchlpfbl49rq0: Rmdir: contains trashed file: "msnn42iarlnl4kchlpfbl49rq0" === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:674: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2022/01/09 05:03:03 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: md5 = f9b03c6b71d5f5ca786d5922771f6ab0 OK 2022/01/09 05:03:05 DEBUG : hassubu97i2ift2pggdbt1cfp876l53bceemu9btjavl43jthqj7tasdbcmte4busfs27dmocs8na: Rmdir: contains trashed file: "hassubu97i2ift2pggdbt1cfp876l53bceemu9btjavl43jthqj7tasdbcmte4busfs27dmocs8na" === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:674: testing " leading space" 2022/01/09 05:03:09 DEBUG : leading space/ leading space: md5 = ddd11a73a1956dedf9dab690bdf1692d OK 2022/01/09 05:03:11 DEBUG : igstt54k5qp9e549r6pqm35hl8: Rmdir: contains trashed file: "igstt54k5qp9e549r6pqm35hl8" === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:674: testing "~leading tilde" 2022/01/09 05:03:15 DEBUG : ~leading tilde/~leading tilde: md5 = 5f383922acd650b209366a4727e10285 OK 2022/01/09 05:03:17 DEBUG : nevsnko0v27og2qt2iomj9cbi4: Rmdir: contains trashed file: "nevsnko0v27og2qt2iomj9cbi4" === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:674: testing "␍leading CR" 2022/01/09 05:03:21 DEBUG : ␍leading CR/␍leading CR: md5 = 7416435839773a95eba863f324aeb459 OK 2022/01/09 05:03:23 DEBUG : a2h0eu6un2623343gk1tqj6ut4: Rmdir: contains trashed file: "a2h0eu6un2623343gk1tqj6ut4" === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:674: testing "␊leading LF" 2022/01/09 05:03:27 DEBUG : ␊leading LF/␊leading LF: md5 = d55b21a481b5e8924d8e8ab231423132 OK 2022/01/09 05:03:29 DEBUG : u529vt6ubothc7lvqh76rsb288: Rmdir: contains trashed file: "u529vt6ubothc7lvqh76rsb288" === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:674: testing "␉leading HT" 2022/01/09 05:03:33 DEBUG : ␉leading HT/␉leading HT: md5 = e99c28d4b0e3a7d3e73517c7032d2cf1 OK 2022/01/09 05:03:35 DEBUG : uhsh7ikeumar00etd5nibokc00: Rmdir: contains trashed file: "uhsh7ikeumar00etd5nibokc00" === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:674: testing "␋leading VT" 2022/01/09 05:03:38 DEBUG : ␋leading VT/␋leading VT: md5 = a8941f2d184485cecfb402a471c59f75 OK 2022/01/09 05:03:40 DEBUG : 5k8glf8j137rtplh8pf777kc6k: Rmdir: contains trashed file: "5k8glf8j137rtplh8pf777kc6k" === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:674: testing ".leading dot" 2022/01/09 05:03:44 DEBUG : .leading dot/.leading dot: md5 = 94bb0fa23a91a0e56daeda53fbf6daa9 OK 2022/01/09 05:03:46 DEBUG : hc8mhlb9r1e05buvff53j7ps94: Rmdir: contains trashed file: "hc8mhlb9r1e05buvff53j7ps94" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:674: testing "trailing space " 2022/01/09 05:03:50 DEBUG : trailing space /trailing space : md5 = 773e935834dfc8d0524ca76cdf36de06 OK 2022/01/09 05:03:52 DEBUG : 85pi11ntdsjcdjq56s37obj3k8: Rmdir: contains trashed file: "85pi11ntdsjcdjq56s37obj3k8" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:674: testing "trailing CR␍" 2022/01/09 05:03:55 DEBUG : trailing CR␍/trailing CR␍: md5 = ec7ce723085d58e700aaf8a69d4d3363 OK 2022/01/09 05:03:57 DEBUG : 0e6sh7hqlktdhvg6jk5u8d06u0: Rmdir: contains trashed file: "0e6sh7hqlktdhvg6jk5u8d06u0" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:674: testing "trailing LF␊" 2022/01/09 05:04:01 DEBUG : trailing LF␊/trailing LF␊: md5 = 179abde10f468877cfc7b3930c38d922 OK 2022/01/09 05:04:03 DEBUG : 90oud4k3ofajhl43rm1o73dvd8: Rmdir: contains trashed file: "90oud4k3ofajhl43rm1o73dvd8" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:674: testing "trailing HT␉" 2022/01/09 05:04:07 DEBUG : trailing HT␉/trailing HT␉: md5 = 084d668a97b2a596ae1cf634b6e5ec95 OK 2022/01/09 05:04:09 DEBUG : dfr970d3hh9t7tp17e6s5eukfk: Rmdir: contains trashed file: "dfr970d3hh9t7tp17e6s5eukfk" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:674: testing "trailing VT␋" 2022/01/09 05:04:13 DEBUG : trailing VT␋/trailing VT␋: md5 = 271d5cff2ddc604d364f3791485b1d99 OK 2022/01/09 05:04:15 DEBUG : pd7ls3vh3ccrv2ib1dd61189cc: Rmdir: contains trashed file: "pd7ls3vh3ccrv2ib1dd61189cc" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:674: testing "trailing dot." 2022/01/09 05:04:18 DEBUG : trailing dot./trailing dot.: md5 = 952bea866728fc738853058533955681 OK 2022/01/09 05:04:20 DEBUG : fmlmh67m08l9reo4o1doaon9i4: Rmdir: contains trashed file: "fmlmh67m08l9reo4o1doaon9i4" === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:674: testing "invalid utf-8\xfe" 2022/01/09 05:04:24 DEBUG : invalid utf-8/invalid utf-8: md5 = db4e634748cb45279185a23035a1f329 OK 2022/01/09 05:04:26 DEBUG : toetsandc7pe3o6afhhq1cvja4: Rmdir: contains trashed file: "toetsandc7pe3o6afhhq1cvja4" === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:674: testing "test%46.txt" 2022/01/09 05:04:27 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) 2022/01/09 05:04:27 DEBUG : pacer: Rate limited, increasing sleep to 1.151910073s 2022/01/09 05:04:28 DEBUG : pacer: Reducing sleep to 0s 2022/01/09 05:04:31 DEBUG : test%46.txt/test%46.txt: md5 = 7cbfc19cba364364c04c2366a205e195 OK 2022/01/09 05:04:33 DEBUG : eleoftpogh4o5ffkg9917cnfq4: Rmdir: contains trashed file: "eleoftpogh4o5ffkg9917cnfq4" === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError === RUN TestIntegration/FsMkdir/FsPutZeroLength 2022/01/09 05:04:41 DEBUG : zero-length-file: md5 = cd6725eb57c525b8ac4d8d8359fb5cdc OK === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:747: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsChangeNotify 2022/01/09 05:04:43 DEBUG : dir: Making directory 2022/01/09 05:04:45 DEBUG : dir/subdir1: Making directory 2022/01/09 05:04:46 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:04:46 DEBUG : dir/subdir3: Making directory 2022/01/09 05:04:46 DEBUG : doChangeNotify("dir/subdir1", 0) 2022/01/09 05:04:47 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:04:47 DEBUG : dir/subdir2: Making directory 2022/01/09 05:04:48 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:04:48 DEBUG : doChangeNotify("dir/subdir3", 0) 2022/01/09 05:04:48 DEBUG : doChangeNotify("dir/subdir2", 0) 2022/01/09 05:04:49 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:04:49 DEBUG : dir/file2: md5 = 478753cb9077e3f7c1381ab1659b3997 OK 2022/01/09 05:04:50 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:04:50 DEBUG : doChangeNotify("dir/file2", 1) 2022/01/09 05:04:51 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:04:52 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:04:52 DEBUG : dir/file4: md5 = c6ff3d4ac990d8e7b3b717dfba591159 OK 2022/01/09 05:04:53 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:04:53 DEBUG : doChangeNotify("dir/file4", 1) 2022/01/09 05:04:54 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:04:54 DEBUG : dir/file3: md5 = 390c5dd5adba354be77681d1bbffb337 OK fstests.go:846: Try 1/10 waiting for dirChanges and objChanges 2022/01/09 05:04:55 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:04:55 DEBUG : doChangeNotify("dir/file3", 1) 2022/01/09 05:04:56 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:04:57 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:04:58 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:04:59 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:04:59 DEBUG : doChangeNotify("dir/file2", 1) 2022/01/09 05:04:59 DEBUG : doChangeNotify("dir/file4", 1) 2022/01/09 05:05:00 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:05:00 DEBUG : doChangeNotify("dir/file3", 1) 2022/01/09 05:05:01 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:05:01 DEBUG : doChangeNotify("dir/subdir1", 0) 2022/01/09 05:05:02 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:05:02 DEBUG : doChangeNotify("dir/subdir3", 0) 2022/01/09 05:05:02 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "633fmaa0qic3kv3cladnu11pto" 2022/01/09 05:05:02 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "amq3bkuqh8mg4ohnbs2i3185uk" 2022/01/09 05:05:02 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "d26uovf0miqhd1mu3c9jbseq00" 2022/01/09 05:05:02 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "9ttr6ktchp8s75c384iri950b4" 2022/01/09 05:05:02 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "6lcfeqjd564osl9cuhasslpeto" 2022/01/09 05:05:02 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "hpv9vbsl47o6lm8alr8q8b1m60" 2022/01/09 05:05:03 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': Checking for changes on remote 2022/01/09 05:05:03 DEBUG : doChangeNotify("dir/subdir2", 0) === RUN TestIntegration/FsMkdir/FsPutFiles 2022/01/09 05:05:05 DEBUG : file name.txt: md5 = a0ba882a168a1ef4c0ff1fc071b5492f OK 2022/01/09 05:05:07 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: md5 = 41e9bdbe292dd66d36c055686c1fa775 OK 2022/01/09 05:05:09 DEBUG : file name.txt: md5 = 33ef431279c38c9f0bb344148a475e84 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 2022/01/09 05:05:15 DEBUG : Creating backend with remote "TestCryptDrive:" 2022/01/09 05:05:15 DEBUG : Creating backend with remote "TestDrive:crypt" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot 2022/01/09 05:05:16 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:1036: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2022/01/09 05:05:25 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) 2022/01/09 05:05:25 DEBUG : pacer: Rate limited, increasing sleep to 1.639074444s 2022/01/09 05:05:26 DEBUG : pacer: Reducing sleep to 0s 2022/01/09 05:05:28 DEBUG : dirToPurge/fileToPurge.txt: md5 = beafe33d7422fb40e3378d3f147b401c OK === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2022/01/09 05:05:43 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) 2022/01/09 05:05:43 DEBUG : pacer: Rate limited, increasing sleep to 1.617977147s 2022/01/09 05:05:43 DEBUG : pacer: Reducing sleep to 0s 2022/01/09 05:05:49 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) 2022/01/09 05:05:49 DEBUG : pacer: Rate limited, increasing sleep to 1.251925585s 2022/01/09 05:05:50 DEBUG : pacer: Reducing sleep to 0s 2022/01/09 05:05:51 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) 2022/01/09 05:05:51 DEBUG : pacer: Rate limited, increasing sleep to 1.643625836s 2022/01/09 05:05:52 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2022/01/09 05:05:52 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-wimalim7debaxal4hogokat0" 2022/01/09 05:05:52 DEBUG : Config file has changed externaly - reloading 2022/01/09 05:05:52 DEBUG : Creating backend with remote "TestDrive:crypt/jv3c0ophd5tn66qkct11p4k6mjg3g48jc0n017chehee2d48m7apmhqshshp2c2q8lglabv21gjbo" 2022/01/09 05:05:58 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) 2022/01/09 05:05:58 DEBUG : pacer: Rate limited, increasing sleep to 1.216018549s 2022/01/09 05:05:59 DEBUG : pacer: Reducing sleep to 0s 2022/01/09 05:06:05 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-wimalim7debaxal4hogokat0': Purge remote === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2022/01/09 05:06:06 DEBUG : : Rmdir: contains trashed file: "jvrdogrnrblgdk6svqeu791rdk" 2022/01/09 05:06:06 DEBUG : : Rmdir: contains trashed file: "ruuqrs7tbf1d4t5glb6bcbp5rk" 2022/01/09 05:06:06 DEBUG : : Rmdir: contains trashed file: "gbh5ioi619nhbt6teh71jdbef8" 2022/01/09 05:06:06 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:1343: 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 2022/01/09 05:06:19 DEBUG : file name.txt: md5 = 789446e13eb9c9187a79aa91349f81a5 OK === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2022/01/09 05:06:21 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-witexoh0kedejoc2masoqoq2/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2022/01/09 05:06:21 DEBUG : Creating backend with remote "TestDrive:crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta/96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0/u4ipppammbskorbmr2jnsktems" 2022/01/09 05:06:25 DEBUG : fs cache: adding new entry for parent of "TestDrive:crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta/96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0/u4ipppammbskorbmr2jnsktems", "TestDrive:crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta/96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0" === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2022/01/09 05:06:26 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-witexoh0kedejoc2masoqoq2/not found.txt" 2022/01/09 05:06:26 DEBUG : Creating backend with remote "TestDrive:crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta/jgn01lqi50h78b6hoirrg20eko" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1503: Opening root remote "TestCryptDrive:" path "rclone-test-witexoh0kedejoc2masoqoq2" from "TestCryptDrive:rclone-test-witexoh0kedejoc2masoqoq2" 2022/01/09 05:06:28 DEBUG : Creating backend with remote "TestCryptDrive:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR 2022/01/09 05:06:30 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) 2022/01/09 05:06:30 DEBUG : pacer: Rate limited, increasing sleep to 1.640858664s 2022/01/09 05:06:30 DEBUG : pacer: Reducing sleep to 0s 2022/01/09 05:06:30 DEBUG : pacer: low level retry 2/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) 2022/01/09 05:06:30 DEBUG : pacer: Rate limited, increasing sleep to 1.635651484s 2022/01/09 05:06:31 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2022/01/09 05:06:35 DEBUG : rclone-test-witexoh0kedejoc2masoqoq2/created from root.txt: md5 = 1634dcc88760241909cce8c76244fdf2 OK 2022/01/09 05:06:35 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) 2022/01/09 05:06:35 DEBUG : pacer: Rate limited, increasing sleep to 1.10821604s 2022/01/09 05:06:36 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink 2022/01/09 05:06:41 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': attempting to share single file '80abn8ft86uthvulkui23octdeuodrrspkhsk4hav33t59ipblug' 2022/01/09 05:06:42 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': attempting to share single file 'nolduc6vng08379n9fboa9fqe4' 2022/01/09 05:06:44 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': attempting to share single file '96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0/u4ipppammbskorbmr2jnsktems' 2022/01/09 05:06:46 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': attempting to share single file 'nolduc6vng08379n9fboa9fqe4' 2022/01/09 05:06:47 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': attempting to share directory '96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0' 2022/01/09 05:06:48 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': attempting to share directory '96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0' 2022/01/09 05:06:49 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-yijidef4celofos0qequrer4" 2022/01/09 05:06:49 DEBUG : Creating backend with remote "TestDrive:crypt/1v7mtdc5sdutrtcvh9ea7mji50nm5vj19et26kq0i1p45au4sg4nqr49o4u272ibblgdn9h9ho7qk" 2022/01/09 05:06:50 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) 2022/01/09 05:06:50 DEBUG : pacer: Rate limited, increasing sleep to 1.189915159s 2022/01/09 05:06:50 DEBUG : pacer: Reducing sleep to 0s 2022/01/09 05:06:54 DEBUG : somefile: md5 = 49e9e6742a9e1d44e8c6a30df1d2935e OK 2022/01/09 05:06:55 DEBUG : Google drive root 'crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta': attempting to share directory '' 2022/01/09 05:06:56 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-yijidef4celofos0qequrer4': 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 2022/01/09 05:07:00 DEBUG : cqiebell711479onsrbud2b5lc: Sending chunk 0 length 32 2022/01/09 05:07:01 DEBUG : piped data.txt: md5 = 202801afa07cd0eaefa655e4b0e534df OK === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2022/01/09 05:07:03 DEBUG : cqiebell711479onsrbud2b5lc: Sending chunk 0 length 148 2022/01/09 05:07:04 DEBUG : piped data.txt: md5 = 39a62f843d5123167dbb66db75d84ad7 OK 2022/01/09 05:07: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) 2022/01/09 05:07:04 DEBUG : pacer: Rate limited, increasing sleep to 1.234020987s 2022/01/09 05:07:05 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/Internal === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/ObjectInfo 2022/01/09 05:07:05 DEBUG : Creating backend with remote "/tmp/rclone-spool1750112876" 2022/01/09 05:07:05 DEBUG : hash_test_object: Computing md5 hash of encrypted source === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/ObjectInfoWrap 2022/01/09 05:07:05 DEBUG : Creating backend with remote "/tmp/rclone-spool1616383270" 2022/01/09 05:07:05 DEBUG : _wrap: Computing md5 hash of encrypted source === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/ComputeHash 2022/01/09 05:07:05 DEBUG : Creating backend with remote "/tmp/rclone-spool1823853924" 2022/01/09 05:07:08 DEBUG : compute_hash_test: md5 = f6d44ad276a04be9aaa080607bec96f1 OK === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:1807: *crypt.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2022/01/09 05:07:10 DEBUG : 5t6fllu4sbjea82tuvhkr8g5779c8gu1qrjbjcj5lupfmsp501n0: Sending chunk 0 length 148 2022/01/09 05:07:11 DEBUG : unknown-size-put.txt: md5 = 05b51602268c717a03f1069a75cb367a OK 2022/01/09 05:07:12 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) 2022/01/09 05:07:12 DEBUG : pacer: Rate limited, increasing sleep to 1.123647328s 2022/01/09 05:07:12 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2022/01/09 05:07:14 DEBUG : unknown-size-update.txt: md5 = c5a43b3069bb0f6e37dd07432e0de866 OK 2022/01/09 05:07:15 DEBUG : i60g7a9vietemeugj4vko3pcqb41or1fldle4lnp7rp5c64fh8ng: Sending chunk 0 length 248 2022/01/09 05:07:16 DEBUG : unknown-size-update.txt: md5 = befe74c60f735f33d961486b0f0e4338 OK === RUN TestIntegration/FsMkdir/FsRootCollapse 2022/01/09 05:07:17 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-witexoh0kedejoc2masoqoq2/deeper/nonexisting/directory" 2022/01/09 05:07:17 DEBUG : Creating backend with remote "TestDrive:crypt/vreoota96fp39ap3kad8gkfhkibsua9rbs8mp65p9ieo0vj7a9g7o75tn3amka6qifbv33j5rg8ta/a8sv4gs52nbgdd9dahlimee280/fbehe2orn9mdrpoteiv7ibtqvo/486oifeu6kfc5kp8j3to8ir2s4" 2022/01/09 05:07:18 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) 2022/01/09 05:07:18 DEBUG : pacer: Rate limited, increasing sleep to 1.183359276s 2022/01/09 05:07:18 DEBUG : pacer: low level retry 2/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) 2022/01/09 05:07:18 DEBUG : pacer: Rate limited, increasing sleep to 2.12434326s 2022/01/09 05:07:20 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsShutdown fstests.go:2003: Shutdown method not supported --- PASS: TestIntegration (295.04s) --- PASS: TestIntegration/FsCheckWrap (0.00s) --- PASS: TestIntegration/FsCommand (0.00s) --- PASS: TestIntegration/FsRmdirNotFound (0.27s) --- PASS: TestIntegration/FsString (0.00s) --- PASS: TestIntegration/FsName (0.00s) --- PASS: TestIntegration/FsRoot (0.00s) --- PASS: TestIntegration/FsRmdirEmpty (1.01s) --- PASS: TestIntegration/FsMkdir (290.53s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (6.21s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.31s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.27s) --- PASS: TestIntegration/FsMkdir/FsListRDirEmpty (0.27s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.26s) --- PASS: TestIntegration/FsMkdir/FsListRDirNotFound (0.38s) --- PASS: TestIntegration/FsMkdir/FsEncoding (111.21s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (5.90s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (5.68s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (5.88s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (5.56s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (5.69s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (5.95s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (6.04s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (5.77s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (5.84s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (5.80s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (5.59s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (5.90s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (5.58s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (5.91s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (5.72s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (5.51s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (5.87s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (5.96s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (6.80s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.57s) --- PASS: TestIntegration/FsMkdir/FsPutError (4.90s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (3.47s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- PASS: TestIntegration/FsMkdir/FsChangeNotify (20.08s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (126.45s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (1.66s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (1.55s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (1.78s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (1.41s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (1.07s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.46s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.67s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.64s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.78s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (1.63s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (1.66s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.38s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (1.63s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.29s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (8.11s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (3.80s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (15.67s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (13.32s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.38s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.28s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.29s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.29s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.29s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.29s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.29s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (1.32s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.28s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (1.05s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (1.47s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (4.88s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.87s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (3.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.34s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (4.74s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (2.24s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (12.69s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (1.54s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.33s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (3.97s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (6.80s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (2.13s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/PublicLink (15.82s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.31s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (2.59s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.25s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (5.66s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (2.74s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (2.92s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (4.49s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ObjectInfo (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ObjectInfoWrap (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ComputeHash (4.49s) --- SKIP: TestIntegration/FsMkdir/FsPutChunked (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (7.54s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (2.65s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (4.89s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (5.37s) --- SKIP: TestIntegration/FsShutdown (0.00s) === RUN TestStandardBase32 crypt_test.go:34: Skipping as -remote set --- SKIP: TestStandardBase32 (0.00s) === RUN TestStandardBase64 crypt_test.go:54: Skipping as -remote set --- SKIP: TestStandardBase64 (0.00s) === RUN TestStandardBase32768 crypt_test.go:75: Skipping as -remote set --- SKIP: TestStandardBase32768 (0.00s) === RUN TestOff crypt_test.go:97: Skipping as -remote set --- SKIP: TestOff (0.00s) === RUN TestObfuscate crypt_test.go:118: Skipping as -remote set --- SKIP: TestObfuscate (0.00s) === RUN TestNoDataObfuscate crypt_test.go:140: Skipping as -remote set --- SKIP: TestNoDataObfuscate (0.00s) PASS ok github.com/rclone/rclone/backend/crypt 299.963s "go test -v -timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 5m2.834732812s (try 1/5)