"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.96s)
=== RUN   TestEncryptDecrypt1
--- PASS: TestEncryptDecrypt1 (0.61s)
=== RUN   TestEncryptDecrypt32
--- PASS: TestEncryptDecrypt32 (1.77s)
=== RUN   TestEncryptDecrypt4096
--- PASS: TestEncryptDecrypt4096 (1.19s)
=== RUN   TestEncryptDecrypt65536
--- PASS: TestEncryptDecrypt65536 (1.52s)
=== RUN   TestEncryptDecrypt65537
--- PASS: TestEncryptDecrypt65537 (2.36s)
=== RUN   TestEncryptData
--- PASS: TestEncryptData (0.01s)
=== 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.70s)
=== 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.62s)
=== RUN   TestIntegration
    fstests.go:357: Using remote "TestCryptDrive:"
2020/11/03 05:14:07 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-yenihaz4xoyalum9dusoniv4"
2020/11/03 05:14:08 DEBUG : Creating backend with remote "TestDrive:crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6"
2020/11/03 05:14:08 DEBUG : TestDrive: Loaded invalid token from config file - ignoring
2020/11/03 05:14:08 DEBUG : TestDrive: Saved new token in config file
=== 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
2020/11/03 05:14:14 DEBUG : dir/subdir: Making directory
2020/11/03 05:14:18 DEBUG : dir/subdir: Removing directory
2020/11/03 05:14:19 DEBUG : dir: Removing directory
2020/11/03 05:14:20 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "i24n0ln37pa2hv8pctddeuh73o"
=== RUN   TestIntegration/FsMkdir/FsListEmpty
=== RUN   TestIntegration/FsMkdir/FsListDirEmpty
=== RUN   TestIntegration/FsMkdir/FsListRDirEmpty
=== RUN   TestIntegration/FsMkdir/FsListDirNotFound
2020/11/03 05:14:22 ERROR : does not exist: error listing: directory not found
=== RUN   TestIntegration/FsMkdir/FsListRDirNotFound
2020/11/03 05:14:22 ERROR : does not exist: error listing: directory not found
=== RUN   TestIntegration/FsMkdir/FsEncoding
=== RUN   TestIntegration/FsMkdir/FsEncoding/control_chars
    fstests.go:605: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡"
2020/11/03 05:14:27 DEBUG : f5e9c92esko9m26sej116anh5babi4i0al2vne0nmg9mvm0d1b9nq1ttlhvs75nh03n25mqg1vfrohsj7ua930ba4p3c4g405vd56tmte6ccvf24guul4t5gib4nm25g5l7cglg1lpefefuvubu655p01tsluhjl8jduu4dtjlour146bad0: Rmdir: contains trashed file: "f5e9c92esko9m26sej116anh5babi4i0al2vne0nmg9mvm0d1b9nq1ttlhvs75nh03n25mqg1vfrohsj7ua930ba4p3c4g405vd56tmte6ccvf24guul4t5gib4nm25g5l7cglg1lpefefuvubu655p01tsluhjl8jduu4dtjlour146bad0"
=== RUN   TestIntegration/FsMkdir/FsEncoding/dot
    fstests.go:605: testing "．"
2020/11/03 05:14:33 DEBUG : do1eshp7fq66o1l1ei9pomlig8: Rmdir: contains trashed file: "do1eshp7fq66o1l1ei9pomlig8"
=== RUN   TestIntegration/FsMkdir/FsEncoding/dot_dot
    fstests.go:605: testing "．．"
2020/11/03 05:14:38 DEBUG : msnn42iarlnl4kchlpfbl49rq0: Rmdir: contains trashed file: "msnn42iarlnl4kchlpfbl49rq0"
=== RUN   TestIntegration/FsMkdir/FsEncoding/punctuation
    fstests.go:605: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~"
2020/11/03 05:14:44 DEBUG : hassubu97i2ift2pggdbt1cfp876l53bceemu9btjavl43jthqj7tasdbcmte4busfs27dmocs8na: Rmdir: contains trashed file: "hassubu97i2ift2pggdbt1cfp876l53bceemu9btjavl43jthqj7tasdbcmte4busfs27dmocs8na"
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_space
    fstests.go:605: testing " leading space"
2020/11/03 05:14:49 DEBUG : igstt54k5qp9e549r6pqm35hl8: Rmdir: contains trashed file: "igstt54k5qp9e549r6pqm35hl8"
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_tilde
    fstests.go:605: testing "~leading tilde"
2020/11/03 05:14:54 DEBUG : nevsnko0v27og2qt2iomj9cbi4: Rmdir: contains trashed file: "nevsnko0v27og2qt2iomj9cbi4"
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_CR
    fstests.go:605: testing "␍leading CR"
2020/11/03 05:14:59 DEBUG : a2h0eu6un2623343gk1tqj6ut4: Rmdir: contains trashed file: "a2h0eu6un2623343gk1tqj6ut4"
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_LF
    fstests.go:605: testing "␊leading LF"
2020/11/03 05:15:05 DEBUG : u529vt6ubothc7lvqh76rsb288: Rmdir: contains trashed file: "u529vt6ubothc7lvqh76rsb288"
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_HT
    fstests.go:605: testing "␉leading HT"
2020/11/03 05:15:10 DEBUG : uhsh7ikeumar00etd5nibokc00: Rmdir: contains trashed file: "uhsh7ikeumar00etd5nibokc00"
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_VT
    fstests.go:605: testing "␋leading VT"
2020/11/03 05:15:15 DEBUG : 5k8glf8j137rtplh8pf777kc6k: Rmdir: contains trashed file: "5k8glf8j137rtplh8pf777kc6k"
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_dot
    fstests.go:605: testing ".leading dot"
2020/11/03 05:15:22 DEBUG : hc8mhlb9r1e05buvff53j7ps94: Rmdir: contains trashed file: "hc8mhlb9r1e05buvff53j7ps94"
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_space
    fstests.go:605: testing "trailing space "
2020/11/03 05:15:27 DEBUG : 85pi11ntdsjcdjq56s37obj3k8: Rmdir: contains trashed file: "85pi11ntdsjcdjq56s37obj3k8"
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_CR
    fstests.go:605: testing "trailing CR␍"
2020/11/03 05:15:32 DEBUG : 0e6sh7hqlktdhvg6jk5u8d06u0: Rmdir: contains trashed file: "0e6sh7hqlktdhvg6jk5u8d06u0"
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_LF
    fstests.go:605: testing "trailing LF␊"
2020/11/03 05:15:38 DEBUG : 90oud4k3ofajhl43rm1o73dvd8: Rmdir: contains trashed file: "90oud4k3ofajhl43rm1o73dvd8"
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_HT
    fstests.go:605: testing "trailing HT␉"
2020/11/03 05:15:43 DEBUG : dfr970d3hh9t7tp17e6s5eukfk: Rmdir: contains trashed file: "dfr970d3hh9t7tp17e6s5eukfk"
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_VT
    fstests.go:605: testing "trailing VT␋"
2020/11/03 05:15:49 DEBUG : pd7ls3vh3ccrv2ib1dd61189cc: Rmdir: contains trashed file: "pd7ls3vh3ccrv2ib1dd61189cc"
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_dot
    fstests.go:605: testing "trailing dot."
2020/11/03 05:15:54 DEBUG : fmlmh67m08l9reo4o1doaon9i4: Rmdir: contains trashed file: "fmlmh67m08l9reo4o1doaon9i4"
=== RUN   TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8
    fstests.go:605: testing "invalid utf-8\xfe"
2020/11/03 05:16:00 DEBUG : toetsandc7pe3o6afhhq1cvja4: Rmdir: contains trashed file: "toetsandc7pe3o6afhhq1cvja4"
=== RUN   TestIntegration/FsMkdir/FsNewObjectNotFound
=== RUN   TestIntegration/FsMkdir/FsPutError
=== RUN   TestIntegration/FsMkdir/FsPutZeroLength
=== RUN   TestIntegration/FsMkdir/FsOpenWriterAt
    fstests.go:678: FS has no OpenWriterAt interface
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2020/11/03 05:16:09 DEBUG : dir: Making directory
2020/11/03 05:16:11 DEBUG : dir/subdir1: Making directory
2020/11/03 05:16:12 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
2020/11/03 05:16:12 DEBUG : dir/subdir3: Making directory
2020/11/03 05:16:12 DEBUG : doChangeNotify("dir/subdir1", 2)
2020/11/03 05:16:13 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
2020/11/03 05:16:13 DEBUG : dir/subdir2: Making directory
2020/11/03 05:16:14 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
2020/11/03 05:16:14 DEBUG : doChangeNotify("dir/subdir3", 2)
2020/11/03 05:16:15 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
2020/11/03 05:16:15 DEBUG : doChangeNotify("dir/subdir2", 2)
2020/11/03 05:16:16 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
2020/11/03 05:16:16 DEBUG : doChangeNotify("dir/file2", 3)
2020/11/03 05:16:17 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
2020/11/03 05:16:18 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
2020/11/03 05:16:18 DEBUG : doChangeNotify("dir/file4", 3)
2020/11/03 05:16:19 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
    fstests.go:777: Try 1/10 waiting for dirChanges and objChanges
2020/11/03 05:16:20 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
2020/11/03 05:16:20 DEBUG : doChangeNotify("dir/file3", 3)
2020/11/03 05:16:21 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
2020/11/03 05:16:22 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
2020/11/03 05:16:23 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
2020/11/03 05:16:23 DEBUG : doChangeNotify("dir/file2", 3)
2020/11/03 05:16:24 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
2020/11/03 05:16:24 DEBUG : doChangeNotify("dir/file4", 3)
2020/11/03 05:16:25 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
2020/11/03 05:16:25 DEBUG : doChangeNotify("dir/file3", 3)
2020/11/03 05:16:26 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
2020/11/03 05:16:26 DEBUG : doChangeNotify("dir/subdir1", 2)
2020/11/03 05:16:26 DEBUG : doChangeNotify("dir/subdir3", 2)
2020/11/03 05:16:27 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
2020/11/03 05:16:27 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "633fmaa0qic3kv3cladnu11pto"
2020/11/03 05:16:27 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "amq3bkuqh8mg4ohnbs2i3185uk"
2020/11/03 05:16:27 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "d26uovf0miqhd1mu3c9jbseq00"
2020/11/03 05:16:27 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "9ttr6ktchp8s75c384iri950b4"
2020/11/03 05:16:27 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "6lcfeqjd564osl9cuhasslpeto"
2020/11/03 05:16:27 DEBUG : gbh5ioi619nhbt6teh71jdbef8: Rmdir: contains trashed file: "hpv9vbsl47o6lm8alr8q8b1m60"
2020/11/03 05:16:28 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': Checking for changes on remote
=== RUN   TestIntegration/FsMkdir/FsPutFiles
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListR
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot
2020/11/03 05:16:39 DEBUG : Creating backend with remote "TestCryptDrive:"
2020/11/03 05:16:39 DEBUG : Creating backend with remote "TestDrive:crypt"
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot
2020/11/03 05:16:40 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/FsListFile1and2
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsPurge
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsCopy
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsMove
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsDirMove
2020/11/03 05:17:08 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-vuhifir1zucamuz5lemitin2"
2020/11/03 05:17:08 DEBUG : Creating backend with remote "TestDrive:crypt/a8np9t77kg2amu588bl7rfbp6ih7pq9r5dmruimbh10565oqi85o3756qsnaeopv2b25o921073am"
2020/11/03 05:17:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-vuhifir1zucamuz5lemitin2': Purge remote
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull
2020/11/03 05:17:19 DEBUG : : Rmdir: contains trashed file: "jvrdogrnrblgdk6svqeu791rdk"
2020/11/03 05:17:19 DEBUG : : Rmdir: contains trashed file: "ruuqrs7tbf1d4t5glb6bcbp5rk"
2020/11/03 05:17:19 DEBUG : : Rmdir: contains trashed file: "gbh5ioi619nhbt6teh71jdbef8"
2020/11/03 05:17:19 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:1258: 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
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectStorable
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsIsFile
2020/11/03 05:17:31 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-yenihaz4xoyalum9dusoniv4/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt"
2020/11/03 05:17:31 DEBUG : Creating backend with remote "TestDrive:crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6/96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0/u4ipppammbskorbmr2jnsktems"
2020/11/03 05:17:34 DEBUG : fs cache: adding new entry for parent of "TestDrive:crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6/96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0/u4ipppammbskorbmr2jnsktems", "TestDrive:crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6/96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0"
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound
2020/11/03 05:17:35 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-yenihaz4xoyalum9dusoniv4/not found.txt"
2020/11/03 05:17:35 DEBUG : Creating backend with remote "TestDrive:crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6/jgn01lqi50h78b6hoirrg20eko"
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot
    fstests.go:1411: Opening root remote "TestCryptDrive:" path "rclone-test-yenihaz4xoyalum9dusoniv4" from "TestCryptDrive:rclone-test-yenihaz4xoyalum9dusoniv4"
2020/11/03 05:17:36 DEBUG : Creating backend with remote "TestCryptDrive:"
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/List
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove
=== RUN   TestIntegration/FsMkdir/FsPutFiles/PublicLink
2020/11/03 05:17:46 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': attempting to share single file '80abn8ft86uthvulkui23octdeuodrrspkhsk4hav33t59ipblug'
2020/11/03 05:17:46 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': attempting to share single file 'nolduc6vng08379n9fboa9fqe4'
2020/11/03 05:17:48 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': attempting to share single file '96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0/u4ipppammbskorbmr2jnsktems'
2020/11/03 05:17:49 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': attempting to share single file 'nolduc6vng08379n9fboa9fqe4'
2020/11/03 05:17:51 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': attempting to share directory '96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0'
2020/11/03 05:17:52 DEBUG : Google drive root 'crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6': attempting to share directory '96gujs4dkgufj2s2d5t6mkrddg/elopgm497i0kjatjjfeqjhvg4k/ua43qrl1hugkmo9sgaaa6fljgg/m9faeqldhkv5r8aeijp7g9s9kil7gn2l3rsj9l43nb06fnhu09r0'
2020/11/03 05:17:52 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-darozub0vowufew1jekofom3"
2020/11/03 05:17:52 DEBUG : Creating backend with remote "TestDrive:crypt/oi99t2mprthsiuvmlff0cleksceanebi8u7i4jnait7ephs6u8u44jeiafojvpgdsdn6vn6i94nmc"
2020/11/03 05:17:57 DEBUG : Google drive root 'crypt/oi99t2mprthsiuvmlff0cleksceanebi8u7i4jnait7ephs6u8u44jeiafojvpgdsdn6vn6i94nmc': attempting to share directory ''
2020/11/03 05:17:57 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-darozub0vowufew1jekofom3': Purge remote
=== RUN   TestIntegration/FsMkdir/FsPutFiles/SetTier
    fstests.go:331: 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
2020/11/03 05:18:01 DEBUG : cqiebell711479onsrbud2b5lc: Sending chunk 0 length 32
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100
2020/11/03 05:18:04 DEBUG : cqiebell711479onsrbud2b5lc: Sending chunk 0 length 148
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal/ObjectInfo
2020/11/03 05:18:06 DEBUG : Creating backend with remote "/tmp/rclone-spool079546633"
2020/11/03 05:18:06 DEBUG : hash_test_object: Computing MD5 hash of encrypted source
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal/ObjectInfoWrap
2020/11/03 05:18:06 DEBUG : Creating backend with remote "/tmp/rclone-spool660930772"
2020/11/03 05:18:06 DEBUG : _wrap: Computing MD5 hash of encrypted source
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal/ComputeHash
2020/11/03 05:18:06 DEBUG : Creating backend with remote "/tmp/rclone-spool409133603"
=== RUN   TestIntegration/FsMkdir/FsPutChunked
    fstests.go:1697: *crypt.Fs does not implement SetUploadChunkSizer
=== RUN   TestIntegration/FsMkdir/FsUploadUnknownSize
=== RUN   TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize
2020/11/03 05:18:09 DEBUG : 5t6fllu4sbjea82tuvhkr8g5779c8gu1qrjbjcj5lupfmsp501n0: Sending chunk 0 length 148
=== RUN   TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize
2020/11/03 05:18:14 DEBUG : i60g7a9vietemeugj4vko3pcqb41or1fldle4lnp7rp5c64fh8ng: Sending chunk 0 length 248
=== RUN   TestIntegration/FsMkdir/FsRootCollapse
2020/11/03 05:18:15 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-yenihaz4xoyalum9dusoniv4/deeper/nonexisting/directory"
2020/11/03 05:18:16 DEBUG : Creating backend with remote "TestDrive:crypt/2t0hiiac21dtbid48339kaquv2agrtpnvfqs7dstpvalkh9t2ij4l8f7usqufi1q0bb2scpdtiqc6/a8sv4gs52nbgdd9dahlimee280/fbehe2orn9mdrpoteiv7ibtqvo/486oifeu6kfc5kp8j3to8ir2s4"
--- PASS: TestIntegration (251.08s)
    --- PASS: TestIntegration/FsCheckWrap (0.00s)
    --- PASS: TestIntegration/FsCommand (0.00s)
    --- PASS: TestIntegration/FsRmdirNotFound (0.21s)
    --- PASS: TestIntegration/FsString (0.00s)
    --- PASS: TestIntegration/FsName (0.00s)
    --- PASS: TestIntegration/FsRoot (0.00s)
    --- PASS: TestIntegration/FsRmdirEmpty (0.97s)
    --- PASS: TestIntegration/FsMkdir (245.70s)
        --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (6.15s)
        --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.21s)
        --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.21s)
        --- PASS: TestIntegration/FsMkdir/FsListRDirEmpty (0.22s)
        --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.22s)
        --- PASS: TestIntegration/FsMkdir/FsListRDirNotFound (0.23s)
        --- PASS: TestIntegration/FsMkdir/FsEncoding (98.79s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (5.75s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (5.66s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (5.52s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (5.45s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (5.43s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (5.22s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (5.19s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (5.28s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (5.38s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (5.33s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (6.34s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (5.42s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (4.85s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (5.82s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (5.27s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (5.53s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (6.01s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (5.17s)
        --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.41s)
        --- PASS: TestIntegration/FsMkdir/FsPutError (5.21s)
        --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (3.24s)
        --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s)
        --- PASS: TestIntegration/FsMkdir/FsChangeNotify (18.67s)
        --- PASS: TestIntegration/FsMkdir/FsPutFiles (100.79s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (1.39s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (1.40s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (1.21s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.95s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (1.22s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.42s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.48s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.50s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.51s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (1.17s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (1.18s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.25s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (1.22s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.26s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (5.85s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (3.53s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (12.68s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (10.74s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.41s)
            --- 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.22s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.22s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.24s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.22s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (1.15s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.23s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.70s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (1.16s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (3.91s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.73s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (2.39s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.25s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (3.72s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (1.66s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (8.76s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (1.15s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.24s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (1.91s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (5.39s)
                    --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (2.06s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/PublicLink (12.79s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.24s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (2.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.38s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (5.38s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (2.94s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (2.43s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (2.92s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ObjectInfo (0.00s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ObjectInfoWrap (0.00s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ComputeHash (2.91s)
        --- SKIP: TestIntegration/FsMkdir/FsPutChunked (0.00s)
        --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (6.49s)
            --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (2.55s)
            --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (3.93s)
        --- PASS: TestIntegration/FsMkdir/FsRootCollapse (1.89s)
=== 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)
PASS
ok  	github.com/rclone/rclone/backend/crypt	260.883s
"go test -v -timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 4m25.290860643s (try 1/5)