"go test -v -timeout 1h0m0s -remote TestOneDriveBusiness: -verbose" - Starting (try 1/5) === RUN TestIntegration fstests.go:418: Using remote "TestOneDriveBusiness:" 2022/01/17 05:54:14 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-kuqewir6muvadif0tadimob0" 2022/01/17 05:54:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" === RUN TestIntegration/FsCheckWrap fstests.go:459: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:487: No commands in this remote === RUN TestIntegration/FsRmdirNotFound === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2022/01/17 05:54:20 DEBUG : dir/subdir: Making directory 2022/01/17 05:54:24 INFO : dir/subdir: Removing directory 2022/01/17 05:54:25 INFO : dir: Removing directory === RUN TestIntegration/FsMkdir/FsListEmpty === RUN TestIntegration/FsMkdir/FsListDirEmpty === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:378: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2022/01/17 05:54:28 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:378: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:674: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2022/01/17 05:54:29 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Starting multipart upload 2022/01/17 05:54:30 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:674: testing "." 2022/01/17 05:54:34 DEBUG : ./.: Starting multipart upload 2022/01/17 05:54:35 DEBUG : ./.: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:674: testing ".." 2022/01/17 05:54:38 DEBUG : ../..: Starting multipart upload 2022/01/17 05:54:39 DEBUG : ../..: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:674: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2022/01/17 05:54:43 DEBUG : !"#$%&'()*+,-./:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-./:;<=>?@[\]^_`{|}~: Starting multipart upload 2022/01/17 05:54:43 DEBUG : !"#$%&'()*+,-./:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-./:;<=>?@[\]^_`{|}~: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:674: testing " leading space" 2022/01/17 05:54:46 DEBUG : leading space/ leading space: Starting multipart upload 2022/01/17 05:54:47 DEBUG : leading space/ leading space: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:674: testing "~leading tilde" 2022/01/17 05:54:50 DEBUG : ~leading tilde/~leading tilde: Starting multipart upload 2022/01/17 05:54:51 DEBUG : ~leading tilde/~leading tilde: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:674: testing "␍leading CR" 2022/01/17 05:54:54 DEBUG : ␍leading CR/␍leading CR: Starting multipart upload 2022/01/17 05:54:54 DEBUG : ␍leading CR/␍leading CR: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:674: testing "␊leading LF" 2022/01/17 05:54:57 DEBUG : ␊leading LF/␊leading LF: Starting multipart upload 2022/01/17 05:54:58 DEBUG : ␊leading LF/␊leading LF: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:674: testing "␉leading HT" 2022/01/17 05:55:02 DEBUG : ␉leading HT/␉leading HT: Starting multipart upload 2022/01/17 05:55:02 DEBUG : ␉leading HT/␉leading HT: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:674: testing "␋leading VT" 2022/01/17 05:55:05 DEBUG : ␋leading VT/␋leading VT: Starting multipart upload 2022/01/17 05:55:06 DEBUG : ␋leading VT/␋leading VT: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:674: testing ".leading dot" 2022/01/17 05:55:09 DEBUG : .leading dot/.leading dot: Starting multipart upload 2022/01/17 05:55:10 DEBUG : .leading dot/.leading dot: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:674: testing "trailing space " 2022/01/17 05:55:13 DEBUG : trailing space /trailing space : Starting multipart upload 2022/01/17 05:55:13 DEBUG : trailing space /trailing space : Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:674: testing "trailing CR␍" 2022/01/17 05:55:17 DEBUG : trailing CR␍/trailing CR␍: Starting multipart upload 2022/01/17 05:55:17 DEBUG : trailing CR␍/trailing CR␍: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:674: testing "trailing LF␊" 2022/01/17 05:55:20 DEBUG : trailing LF␊/trailing LF␊: Starting multipart upload 2022/01/17 05:55:21 DEBUG : trailing LF␊/trailing LF␊: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:674: testing "trailing HT␉" 2022/01/17 05:55:24 DEBUG : trailing HT␉/trailing HT␉: Starting multipart upload 2022/01/17 05:55:25 DEBUG : trailing HT␉/trailing HT␉: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:674: testing "trailing VT␋" 2022/01/17 05:55:28 DEBUG : trailing VT␋/trailing VT␋: Starting multipart upload 2022/01/17 05:55:29 DEBUG : trailing VT␋/trailing VT␋: Uploading segment 0/100 size 100 2022/01/17 05:55:29 DEBUG : Too many requests. Trying again in 7 seconds. 2022/01/17 05:55:29 DEBUG : pacer: low level retry 1/10 (error activityLimitReached: throttledRequest: The request has been throttled) 2022/01/17 05:55:29 DEBUG : pacer: Rate limited, increasing sleep to 7s 2022/01/17 05:55:29 DEBUG : Too many requests. Trying again in 7 seconds. 2022/01/17 05:55:29 DEBUG : pacer: low level retry 2/10 (error activityLimitReached: throttledRequest: The request has been throttled) 2022/01/17 05:55:36 DEBUG : pacer: Reducing sleep to 5.25s 2022/01/17 05:55:43 DEBUG : pacer: Reducing sleep to 3.9375s 2022/01/17 05:55:48 DEBUG : pacer: Reducing sleep to 2.953125s 2022/01/17 05:55:52 DEBUG : pacer: Reducing sleep to 2.21484375s 2022/01/17 05:55:55 DEBUG : pacer: Reducing sleep to 1.661132812s 2022/01/17 05:55:57 DEBUG : pacer: Reducing sleep to 1.245849609s 2022/01/17 05:55:59 DEBUG : pacer: Reducing sleep to 934.387206ms 2022/01/17 05:56:00 DEBUG : pacer: Reducing sleep to 700.790404ms 2022/01/17 05:56:01 DEBUG : pacer: Reducing sleep to 525.592803ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:674: testing "trailing dot." 2022/01/17 05:56:02 DEBUG : pacer: Reducing sleep to 394.194602ms 2022/01/17 05:56:03 DEBUG : pacer: Reducing sleep to 295.645951ms 2022/01/17 05:56:03 DEBUG : trailing dot./trailing dot.: Starting multipart upload 2022/01/17 05:56:03 DEBUG : pacer: Reducing sleep to 221.734463ms 2022/01/17 05:56:03 DEBUG : trailing dot./trailing dot.: Uploading segment 0/100 size 100 2022/01/17 05:56:04 DEBUG : pacer: Reducing sleep to 166.300847ms 2022/01/17 05:56:04 DEBUG : pacer: Reducing sleep to 124.725635ms 2022/01/17 05:56:04 DEBUG : pacer: Reducing sleep to 93.544226ms 2022/01/17 05:56:04 DEBUG : pacer: Reducing sleep to 70.158169ms 2022/01/17 05:56:05 DEBUG : pacer: Reducing sleep to 52.618626ms 2022/01/17 05:56:05 DEBUG : pacer: Reducing sleep to 39.463969ms 2022/01/17 05:56:05 DEBUG : pacer: Reducing sleep to 29.597976ms 2022/01/17 05:56:06 DEBUG : pacer: Reducing sleep to 22.198482ms 2022/01/17 05:56:06 DEBUG : pacer: Reducing sleep to 16.648861ms === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:674: testing "invalid utf-8\xfe" 2022/01/17 05:56:06 DEBUG : pacer: Reducing sleep to 12.486645ms 2022/01/17 05:56:07 DEBUG : pacer: Reducing sleep to 10ms 2022/01/17 05:56:07 DEBUG : invalid utf-8/invalid utf-8: Starting multipart upload 2022/01/17 05:56:07 DEBUG : invalid utf-8/invalid utf-8: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:674: testing "test%46.txt" 2022/01/17 05:56:11 DEBUG : test%46.txt/test%46.txt: Starting multipart upload 2022/01/17 05:56:11 DEBUG : test%46.txt/test%46.txt: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2022/01/17 05:56:17 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Starting multipart upload 2022/01/17 05:56:18 DEBUG : Too many requests. Trying again in 62 seconds. 2022/01/17 05:56:18 DEBUG : pacer: low level retry 1/10 (error activityLimitReached: throttledRequest: The request has been throttled) 2022/01/17 05:56:18 DEBUG : pacer: Rate limited, increasing sleep to 1m2s 2022/01/17 05:56:18 DEBUG : pacer: Reducing sleep to 46.5s 2022/01/17 05:56:18 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Uploading segment 0/10240 size 10240 2022/01/17 05:57:20 DEBUG : pacer: Reducing sleep to 34.875s 2022/01/17 05:57:20 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Cancelling multipart upload: Put "https://whitecell-my.sharepoint.com/personal/nick_craig-wood_whitecell_online/_api/v2.0/drives/b!tPczmrJTCE2ShO96VLAAn_DGv2_4eZJGl83N0OQe_t9LuHcGPileQ7YByS0_6_xp/items/01W3O4PF2KLPJU5P6AQVEJOVVFAD3U526W/uploadSession?guid='f5189ab5-a82c-43c9-8fa8-f3f680106908'&overwrite=True&rename=False&dc=0&tempauth=eyJ0eXAiOiJKV1QiLCJhbGciOiJub25lIn0.eyJhdWQiOiIwMDAwMDAwMy0wMDAwLTBmZjEtY2UwMC0wMDAwMDAwMDAwMDAvd2hpdGVjZWxsLW15LnNoYXJlcG9pbnQuY29tQGZhZmQ2OTRhLTUwYzEtNGVlYS1iM2M3LTQ5NTE3ZTVhZGQ4OSIsImlzcyI6IjAwMDAwMDAzLTAwMDAtMGZmMS1jZTAwLTAwMDAwMDAwMDAwMCIsIm5iZiI6IjE2NDIzOTg5NzgiLCJleHAiOiIxNjQyNDg1Mzc4IiwiZW5kcG9pbnR1cmwiOiJPTzBWdHVFRTJreDNWeVdONTN1VWlSL3JtSmd4NC9sSWFmQXlnRGlldmhNPSIsImVuZHBvaW50dXJsTGVuZ3RoIjoiMjkzIiwiaXNsb29wYmFjayI6IlRydWUiLCJjaWQiOiJOV1UxWkRnNFpXSXRNREkxTWkwMFkyWmtMV0l4WW1ZdE9EbG1OelV5TTJKbVpHUTQiLCJ2ZXIiOiJoYXNoZWRwcm9vZnRva2VuIiwic2l0ZWlkIjoiT1dFek0yWTNZalF0TlROaU1pMDBaREE0TFRreU9EUXRaV1kzWVRVMFlqQXdNRGxtIiwiYXBwX2Rpc3BsYXluYW1lIjoicmNsb25lIiwiZ2l2ZW5fbmFtZSI6Ik5pY2siLCJmYW1pbHlfbmFtZSI6IkNyYWlnLVdvb2QiLCJhcHBpZCI6ImIxNTY2NWQ5LWVkYTYtNDA5Mi04NTM5LTBlZWMzNzZhZmQ1OSIsInRpZCI6ImZhZmQ2OTRhLTUwYzEtNGVlYS1iM2M3LTQ5NTE3ZTVhZGQ4OSIsInVwbiI6Im5pY2tfY3JhaWctd29vZEB3aGl0ZWNlbGwub25saW5lIiwicHVpZCI6IjEwMDNCRkZEQUMzMkY4NzEiLCJjYWNoZWtleSI6IjBoLmZ8bWVtYmVyc2hpcHwxMDAzYmZmZGFjMzJmODcxQGxpdmUuY29tIiwic2NwIjoibXlmaWxlcy5yZWFkIGFsbGZpbGVzLnJlYWQgbXlmaWxlcy53cml0ZSBhbGxmaWxlcy53cml0ZSBhbGxzaXRlcy5yZWFkIiwidHQiOiIyIiwidXNlUGVyc2lzdGVudENvb2tpZSI6bnVsbCwiaXBhZGRyIjoiMjAuMTkwLjE2MC4yNCJ9.TGxVcVdZVHBpMGlTZUhhNXBzamtHYks4Um9BMDNBaTZLYkRxa2UxZzVJST0": potato 2022/01/17 05:58:06 DEBUG : pacer: low level retry 1/10 (error Delete "https://whitecell-my.sharepoint.com/personal/nick_craig-wood_whitecell_online/_api/v2.0/drives/b!tPczmrJTCE2ShO96VLAAn_DGv2_4eZJGl83N0OQe_t9LuHcGPileQ7YByS0_6_xp/items/01W3O4PF2KLPJU5P6AQVEJOVVFAD3U526W/uploadSession?guid='f5189ab5-a82c-43c9-8fa8-f3f680106908'&overwrite=True&rename=False&dc=0&tempauth=eyJ0eXAiOiJKV1QiLCJhbGciOiJub25lIn0.eyJhdWQiOiIwMDAwMDAwMy0wMDAwLTBmZjEtY2UwMC0wMDAwMDAwMDAwMDAvd2hpdGVjZWxsLW15LnNoYXJlcG9pbnQuY29tQGZhZmQ2OTRhLTUwYzEtNGVlYS1iM2M3LTQ5NTE3ZTVhZGQ4OSIsImlzcyI6IjAwMDAwMDAzLTAwMDAtMGZmMS1jZTAwLTAwMDAwMDAwMDAwMCIsIm5iZiI6IjE2NDIzOTg5NzgiLCJleHAiOiIxNjQyNDg1Mzc4IiwiZW5kcG9pbnR1cmwiOiJPTzBWdHVFRTJreDNWeVdONTN1VWlSL3JtSmd4NC9sSWFmQXlnRGlldmhNPSIsImVuZHBvaW50dXJsTGVuZ3RoIjoiMjkzIiwiaXNsb29wYmFjayI6IlRydWUiLCJjaWQiOiJOV1UxWkRnNFpXSXRNREkxTWkwMFkyWmtMV0l4WW1ZdE9EbG1OelV5TTJKbVpHUTQiLCJ2ZXIiOiJoYXNoZWRwcm9vZnRva2VuIiwic2l0ZWlkIjoiT1dFek0yWTNZalF0TlROaU1pMDBaREE0TFRreU9EUXRaV1kzWVRVMFlqQXdNRGxtIiwiYXBwX2Rpc3BsYXluYW1lIjoicmNsb25lIiwiZ2l2ZW5fbmFtZSI6Ik5pY2siLCJmYW1pbHlfbmFtZSI6IkNyYWlnLVdvb2QiLCJhcHBpZCI6ImIxNTY2NWQ5LWVkYTYtNDA5Mi04NTM5LTBlZWMzNzZhZmQ1OSIsInRpZCI6ImZhZmQ2OTRhLTUwYzEtNGVlYS1iM2M3LTQ5NTE3ZTVhZGQ4OSIsInVwbiI6Im5pY2tfY3JhaWctd29vZEB3aGl0ZWNlbGwub25saW5lIiwicHVpZCI6IjEwMDNCRkZEQUMzMkY4NzEiLCJjYWNoZWtleSI6IjBoLmZ8bWVtYmVyc2hpcHwxMDAzYmZmZGFjMzJmODcxQGxpdmUuY29tIiwic2NwIjoibXlmaWxlcy5yZWFkIGFsbGZpbGVzLnJlYWQgbXlmaWxlcy53cml0ZSBhbGxmaWxlcy53cml0ZSBhbGxzaXRlcy5yZWFkIiwidHQiOiIyIiwidXNlUGVyc2lzdGVudENvb2tpZSI6bnVsbCwiaXBhZGRyIjoiMjAuMTkwLjE2MC4yNCJ9.TGxVcVdZVHBpMGlTZUhhNXBzamtHYks4Um9BMDNBaTZLYkRxa2UxZzVJST0": read tcp 65.108.87.141:42268->13.107.136.9:443: read: connection reset by peer) 2022/01/17 05:58:06 DEBUG : pacer: Rate limited, increasing sleep to 2s 2022/01/17 05:58:42 DEBUG : pacer: Reducing sleep to 1.5s 2022/01/17 05:58:44 DEBUG : pacer: Reducing sleep to 1.125s 2022/01/17 05:58:45 DEBUG : pacer: Reducing sleep to 843.75ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2022/01/17 05:58:45 DEBUG : zero-length-file: Starting singlepart upload 2022/01/17 05:58:47 DEBUG : pacer: Reducing sleep to 632.8125ms 2022/01/17 05:58:47 DEBUG : pacer: Reducing sleep to 474.609375ms 2022/01/17 05:58:48 DEBUG : pacer: Reducing sleep to 355.957031ms 2022/01/17 05:58:48 DEBUG : pacer: Reducing sleep to 266.967773ms 2022/01/17 05:58:49 DEBUG : pacer: Reducing sleep to 200.225829ms 2022/01/17 05:58:49 DEBUG : pacer: Reducing sleep to 150.169371ms === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:747: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:783: FS has no ChangeNotify interface === RUN TestIntegration/FsMkdir/FsPutFiles 2022/01/17 05:58:49 DEBUG : file name.txt: Starting multipart upload 2022/01/17 05:58:50 DEBUG : pacer: Reducing sleep to 112.627028ms 2022/01/17 05:58:50 DEBUG : file name.txt: Uploading segment 0/100 size 100 2022/01/17 05:58:50 DEBUG : pacer: Reducing sleep to 84.470271ms 2022/01/17 05:58:51 DEBUG : pacer: Reducing sleep to 63.352703ms 2022/01/17 05:58:51 DEBUG : pacer: Reducing sleep to 47.514527ms 2022/01/17 05:58:51 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Starting multipart upload 2022/01/17 05:58:52 DEBUG : pacer: Reducing sleep to 35.635895ms 2022/01/17 05:58:52 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Uploading segment 0/100 size 100 2022/01/17 05:58:52 DEBUG : pacer: Reducing sleep to 26.726921ms 2022/01/17 05:58:53 DEBUG : pacer: Reducing sleep to 20.04519ms 2022/01/17 05:58:53 DEBUG : pacer: Reducing sleep to 15.033892ms 2022/01/17 05:58:53 DEBUG : file name.txt: Starting multipart upload 2022/01/17 05:58:54 DEBUG : pacer: Reducing sleep to 11.275419ms 2022/01/17 05:58:54 DEBUG : file name.txt: Uploading segment 0/100 size 100 2022/01/17 05:58:54 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:378: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2022/01/17 05:59:02 DEBUG : Creating backend with remote "TestOneDriveBusiness:" 2022/01/17 05:59:02 DEBUG : Config file has changed externaly - reloading === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:378: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:378: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:378: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive/Dir === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2022/01/17 05:59:13 DEBUG : dirToPurge/fileToPurge.txt: Starting multipart upload 2022/01/17 05:59:13 DEBUG : dirToPurge/fileToPurge.txt: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2022/01/17 05:59:36 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luxoyev7dogeluz4leralev5" 2022/01/17 05:59:45 DEBUG : One drive root 'rclone-test-luxoyev7dogeluz4leralev5': Purge remote === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2022/01/17 05:59:56 DEBUG : file name.txt: Starting multipart upload 2022/01/17 05:59:57 DEBUG : file name.txt: Uploading segment 0/200 size 200 === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2022/01/17 05:59:59 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-kuqewir6muvadif0tadimob0/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2022/01/17 06:00:01 DEBUG : Too many requests. Trying again in 52 seconds. 2022/01/17 06:00:01 DEBUG : pacer: low level retry 1/10 (error activityLimitReached: throttledRequest: The request has been throttled) 2022/01/17 06:00:01 DEBUG : pacer: Rate limited, increasing sleep to 52s 2022/01/17 06:00:01 DEBUG : pacer: Reducing sleep to 39s 2022/01/17 06:00:53 DEBUG : pacer: Reducing sleep to 29.25s 2022/01/17 06:01:32 DEBUG : pacer: Reducing sleep to 21.9375s 2022/01/17 06:02:02 DEBUG : pacer: Reducing sleep to 16.453125s 2022/01/17 06:02:24 DEBUG : pacer: Reducing sleep to 12.33984375s 2022/01/17 06:02:40 DEBUG : pacer: Reducing sleep to 9.254882812s 2022/01/17 06:02:52 DEBUG : pacer: Reducing sleep to 6.941162109s 2022/01/17 06:03:02 DEBUG : pacer: Reducing sleep to 5.205871581s === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2022/01/17 06:03:02 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-kuqewir6muvadif0tadimob0/not found.txt" 2022/01/17 06:03:02 DEBUG : Config file has changed externaly - reloading 2022/01/17 06:03:05 ERROR : : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1503: Opening root remote "TestOneDriveBusiness:" path "rclone-test-kuqewir6muvadif0tadimob0" from "TestOneDriveBusiness:rclone-test-kuqewir6muvadif0tadimob0" 2022/01/17 06:03:05 DEBUG : Creating backend with remote "TestOneDriveBusiness:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:1537: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2022/01/17 06:03:10 DEBUG : rclone-test-kuqewir6muvadif0tadimob0/created from root.txt: Starting multipart upload 2022/01/17 06:03:11 DEBUG : rclone-test-kuqewir6muvadif0tadimob0/created from root.txt: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink 2022/01/17 06:03:27 DEBUG : Can't convert share link for folder to direct link - returning the link as is 2022/01/17 06:03:29 DEBUG : Can't convert share link for folder to direct link - returning the link as is 2022/01/17 06:03:29 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-gadolis4bahehat5genefog3" 2022/01/17 06:03:31 DEBUG : somefile: Starting multipart upload 2022/01/17 06:03:32 DEBUG : somefile: Uploading segment 0/11 size 11 2022/01/17 06:03:35 DEBUG : Can't convert share link for folder to direct link - returning the link as is 2022/01/17 06:03:35 DEBUG : One drive root 'rclone-test-gadolis4bahehat5genefog3': Purge remote === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:392: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:1699: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream fstests.go:1746: FS has no PutStream interface === RUN TestIntegration/FsMkdir/FsPutFiles/Internal fstests.go:1790: *onedrive.Fs does not implement InternalTester === RUN TestIntegration/FsMkdir/FsPutChunked === RUN TestIntegration/FsMkdir/FsPutChunked/320Ki === RUN TestIntegration/FsMkdir/FsPutChunked/320Ki/327679 2022/01/17 06:03:39 DEBUG : chunked-320Ki-319.999Ki.bin: Starting multipart upload 2022/01/17 06:03:39 DEBUG : chunked-320Ki-319.999Ki.bin: Uploading segment 0/327679 size 327679 === RUN TestIntegration/FsMkdir/FsPutChunked/320Ki/327680 2022/01/17 06:03:42 DEBUG : chunked-320Ki-320Ki.bin: Starting multipart upload 2022/01/17 06:03:43 DEBUG : chunked-320Ki-320Ki.bin: Uploading segment 0/327680 size 327680 === RUN TestIntegration/FsMkdir/FsPutChunked/320Ki/655361 2022/01/17 06:03:45 DEBUG : chunked-320Ki-640.001Ki.bin: Starting multipart upload 2022/01/17 06:03:46 DEBUG : chunked-320Ki-640.001Ki.bin: Uploading segment 0/655361 size 327680 2022/01/17 06:03:46 DEBUG : chunked-320Ki-640.001Ki.bin: Uploading segment 327680/655361 size 327680 2022/01/17 06:03:47 DEBUG : chunked-320Ki-640.001Ki.bin: Uploading segment 655360/655361 size 1 === RUN TestIntegration/FsMkdir/FsPutChunked/640Ki === RUN TestIntegration/FsMkdir/FsPutChunked/640Ki/655359 2022/01/17 06:03:50 DEBUG : chunked-640Ki-639.999Ki.bin: Starting multipart upload 2022/01/17 06:03:50 DEBUG : chunked-640Ki-639.999Ki.bin: Uploading segment 0/655359 size 655359 === RUN TestIntegration/FsMkdir/FsPutChunked/640Ki/655360 2022/01/17 06:03:53 DEBUG : chunked-640Ki-640Ki.bin: Starting multipart upload 2022/01/17 06:03:53 DEBUG : chunked-640Ki-640Ki.bin: Uploading segment 0/655360 size 655360 === RUN TestIntegration/FsMkdir/FsPutChunked/640Ki/1310721 2022/01/17 06:03:56 DEBUG : chunked-640Ki-1.250Mi.bin: Starting multipart upload 2022/01/17 06:03:56 DEBUG : chunked-640Ki-1.250Mi.bin: Uploading segment 0/1310721 size 655360 2022/01/17 06:03:57 DEBUG : chunked-640Ki-1.250Mi.bin: Uploading segment 655360/1310721 size 655360 2022/01/17 06:03:58 DEBUG : chunked-640Ki-1.250Mi.bin: Uploading segment 1310720/1310721 size 1 === RUN TestIntegration/FsMkdir/FsPutChunked/960Ki === RUN TestIntegration/FsMkdir/FsPutChunked/960Ki/983039 2022/01/17 06:04:00 DEBUG : chunked-960Ki-959.999Ki.bin: Starting multipart upload 2022/01/17 06:04:00 DEBUG : chunked-960Ki-959.999Ki.bin: Uploading segment 0/983039 size 983039 === RUN TestIntegration/FsMkdir/FsPutChunked/960Ki/983040 2022/01/17 06:04:03 DEBUG : chunked-960Ki-960Ki.bin: Starting multipart upload 2022/01/17 06:04:03 DEBUG : chunked-960Ki-960Ki.bin: Uploading segment 0/983040 size 983040 === RUN TestIntegration/FsMkdir/FsPutChunked/960Ki/1966081 2022/01/17 06:04:05 DEBUG : chunked-960Ki-1.875Mi.bin: Starting multipart upload 2022/01/17 06:04:06 DEBUG : chunked-960Ki-1.875Mi.bin: Uploading segment 0/1966081 size 983040 2022/01/17 06:04:06 DEBUG : chunked-960Ki-1.875Mi.bin: Uploading segment 983040/1966081 size 983040 2022/01/17 06:04:07 DEBUG : chunked-960Ki-1.875Mi.bin: Uploading segment 1966080/1966081 size 1 === RUN TestIntegration/FsMkdir/FsPutChunked/2.188Mi === RUN TestIntegration/FsMkdir/FsPutChunked/2.188Mi/2293759 2022/01/17 06:04:10 DEBUG : chunked-2.188Mi-2.187Mi.bin: Starting multipart upload 2022/01/17 06:04:11 DEBUG : chunked-2.188Mi-2.187Mi.bin: Uploading segment 0/2293759 size 2293759 === RUN TestIntegration/FsMkdir/FsPutChunked/2.188Mi/2293760 2022/01/17 06:04:15 DEBUG : chunked-2.188Mi-2.188Mi.bin: Starting multipart upload 2022/01/17 06:04:15 DEBUG : chunked-2.188Mi-2.188Mi.bin: Uploading segment 0/2293760 size 2293760 === RUN TestIntegration/FsMkdir/FsPutChunked/2.188Mi/4587521 2022/01/17 06:04:19 DEBUG : chunked-2.188Mi-4.375Mi.bin: Starting multipart upload 2022/01/17 06:04:20 DEBUG : chunked-2.188Mi-4.375Mi.bin: Uploading segment 0/4587521 size 2293760 2022/01/17 06:04:21 DEBUG : chunked-2.188Mi-4.375Mi.bin: Uploading segment 2293760/4587521 size 2293760 2022/01/17 06:04:21 DEBUG : chunked-2.188Mi-4.375Mi.bin: Uploading segment 4587520/4587521 size 1 === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2022/01/17 06:04:25 DEBUG : unknown-size-update.txt: Starting multipart upload 2022/01/17 06:04:25 DEBUG : unknown-size-update.txt: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsRootCollapse 2022/01/17 06:04:28 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-kuqewir6muvadif0tadimob0/deeper/nonexisting/directory" 2022/01/17 06:04:28 DEBUG : Config file has changed externaly - reloading 2022/01/17 06:04:34 ERROR : : error listing: directory not found === RUN TestIntegration/FsShutdown fstests.go:2003: Shutdown method not supported --- PASS: TestIntegration (619.88s) --- SKIP: TestIntegration/FsCheckWrap (0.00s) --- SKIP: TestIntegration/FsCommand (0.00s) --- PASS: TestIntegration/FsRmdirNotFound (0.28s) --- PASS: TestIntegration/FsString (0.00s) --- PASS: TestIntegration/FsName (0.00s) --- PASS: TestIntegration/FsRoot (0.00s) --- PASS: TestIntegration/FsRmdirEmpty (1.12s) --- PASS: TestIntegration/FsMkdir (615.60s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (6.31s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.27s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.63s) --- SKIP: TestIntegration/FsMkdir/FsListRDirEmpty (0.00s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.49s) --- SKIP: TestIntegration/FsMkdir/FsListRDirNotFound (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding (106.52s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (5.34s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (4.42s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (4.15s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (3.81s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (3.58s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (3.74s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (3.57s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (4.20s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (3.83s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (3.69s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (3.80s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (3.78s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (3.67s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (4.14s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (3.74s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (33.62s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (4.65s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (4.04s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (4.26s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (1.04s) --- PASS: TestIntegration/FsMkdir/FsPutError (150.08s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (4.17s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (289.52s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (1.85s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (2.50s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (1.80s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (0.53s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.82s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.86s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (2.08s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.99s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (1.06s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive/Dir (0.53s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (3.14s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.60s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (6.68s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (6.30s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (11.68s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (8.98s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.25s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.56s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.50s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.53s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.56s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.53s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.52s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (1.31s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.49s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.94s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (0.87s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (1.94s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (1.28s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (2.65s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.51s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (182.94s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (3.68s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (12.80s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (3.32s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.58s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (8.38s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (3.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/PublicLink (17.16s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (3.05s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.49s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Internal (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutChunked (45.65s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/320Ki (10.76s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/320Ki/327679 (3.53s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/320Ki/327680 (2.70s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/320Ki/655361 (4.53s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/640Ki (10.18s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/640Ki/655359 (3.30s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/640Ki/655360 (2.94s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/640Ki/1310721 (3.93s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/960Ki (10.46s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/960Ki/983039 (2.68s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/960Ki/983040 (2.62s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/960Ki/1966081 (5.15s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/2.188Mi (14.25s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/2.188Mi/2293759 (4.43s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/2.188Mi/2293760 (4.08s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/2.188Mi/4587521 (5.74s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (3.76s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (3.76s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (4.07s) --- SKIP: TestIntegration/FsShutdown (0.00s) === RUN TestIntegrationCn onedrive_test.go:26: skipping as -remote is set --- SKIP: TestIntegrationCn (0.00s) PASS ok github.com/rclone/rclone/backend/onedrive 619.892s "go test -v -timeout 1h0m0s -remote TestOneDriveBusiness: -verbose" - Finished OK in 10m20.697784802s (try 1/5)