"go test -v -timeout 1h0m0s -remote TestDropbox: -verbose" - Starting (try 1/5) === RUN TestInternalCheckPathLength --- PASS: TestInternalCheckPathLength (0.00s) === RUN TestIntegration fstests.go:418: Using remote "TestDropbox:" 2022/04/09 06:25:05 DEBUG : Creating backend with remote "TestDropbox:rclone-test-cucagir0xomubom4dawiwos3" 2022/04/09 06:25:05 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 2022/04/09 06:25:09 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/.) 2022/04/09 06:25:09 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/04/09 06:25:09 DEBUG : pacer: Reducing sleep to 15ms 2022/04/09 06:25:09 DEBUG : pacer: Reducing sleep to 11.25ms 2022/04/09 06:25:10 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2022/04/09 06:25:10 DEBUG : dir/subdir: Making directory 2022/04/09 06:25:10 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..) 2022/04/09 06:25:10 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/04/09 06:25:11 DEBUG : pacer: Reducing sleep to 15ms 2022/04/09 06:25:11 DEBUG : pacer: Reducing sleep to 11.25ms 2022/04/09 06:25:11 DEBUG : pacer: Reducing sleep to 10ms 2022/04/09 06:25:12 INFO : dir/subdir: Removing directory 2022/04/09 06:25:13 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/04/09 06:25:15 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/04/09 06:25:16 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Uploading chunk 1/1 2022/04/09 06:25:17 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Uploading chunk 2/1 2022/04/09 06:25:17 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/␀\x01\x02\x03\x04\x05\x06\a\b\t\n\v\f\r\x0e\x0f\x10\x11\x12\x13\x14\x15\x16\x17\x18\x19\x1a\x1b\x1c\x1d\x1e\x1f␡/␀\x01\x02\x03\x04\x05\x06\a\b\t\n\v\f\r\x0e\x0f\x10\x11\x12\x13\x14\x15\x16\x17\x18\x19\x1a\x1b\x1c\x1d\x1e\x1f␡" to batch 2022/04/09 06:25:18 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:25:18 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/␀ ␡/␀ ␡ 2022/04/09 06:25:18 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:25:18 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:25:19 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 187.814992ms 2022/04/09 06:25:19 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/␀ ␡/␀ ␡ === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:674: testing "." 2022/04/09 06:25:22 DEBUG : ./.: Uploading chunk 1/1 2022/04/09 06:25:23 DEBUG : ./.: Uploading chunk 2/1 2022/04/09 06:25:23 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/./." to batch 2022/04/09 06:25:24 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:25:24 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/./. 2022/04/09 06:25:24 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:25:24 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:25:25 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 165.509501ms 2022/04/09 06:25:25 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/./. === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:674: testing ".." 2022/04/09 06:25:29 DEBUG : ../..: Uploading chunk 1/1 2022/04/09 06:25:29 DEBUG : ../..: Uploading chunk 2/1 2022/04/09 06:25:30 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/../.." to batch 2022/04/09 06:25:31 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:25:31 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/../.. 2022/04/09 06:25:31 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:25:31 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:25:32 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 168.447938ms 2022/04/09 06:25:32 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/../.. === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:674: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2022/04/09 06:25:37 DEBUG : !"#$%&'()*+,-./:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-./:;<=>?@[\]^_`{|}~: Uploading chunk 1/1 2022/04/09 06:25:38 DEBUG : !"#$%&'()*+,-./:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-./:;<=>?@[\]^_`{|}~: Uploading chunk 2/1 2022/04/09 06:25:39 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/!\"#$%&'()*+,-./:;<=>?@[\]^_`{|}~/!\"#$%&'()*+,-./:;<=>?@[\]^_`{|}~" to batch 2022/04/09 06:25:39 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:25:39 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/!"#$%&'()*+,-./:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-./:;<=>?@[\]^_`{|}~ 2022/04/09 06:25:39 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:25:39 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:25:40 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 171.762134ms 2022/04/09 06:25:40 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/!"#$%&'()*+,-./:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-./:;<=>?@[\]^_`{|}~ === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:674: testing " leading space" 2022/04/09 06:25:44 DEBUG : leading space/ leading space: Uploading chunk 1/1 2022/04/09 06:25:45 DEBUG : leading space/ leading space: Uploading chunk 2/1 2022/04/09 06:25:45 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/ leading space/ leading space" to batch 2022/04/09 06:25:46 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:25:46 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/ leading space/ leading space 2022/04/09 06:25:46 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:25:46 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:25:46 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 178.312453ms 2022/04/09 06:25:46 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/ leading space/ leading space === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:674: testing "~leading tilde" 2022/04/09 06:25:50 DEBUG : ~leading tilde/~leading tilde: Uploading chunk 1/1 2022/04/09 06:25:51 DEBUG : ~leading tilde/~leading tilde: Uploading chunk 2/1 2022/04/09 06:25:51 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/~leading tilde/~leading tilde" to batch 2022/04/09 06:25:52 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:25:52 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/~leading tilde/~leading tilde 2022/04/09 06:25:52 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:25:52 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:25:54 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 1.294551984s 2022/04/09 06:25:54 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/~leading tilde/~leading tilde === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:674: testing "␍leading CR" 2022/04/09 06:25:58 DEBUG : ␍leading CR/␍leading CR: Uploading chunk 1/1 2022/04/09 06:25:59 DEBUG : ␍leading CR/␍leading CR: Uploading chunk 2/1 2022/04/09 06:25:59 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/\rleading CR/\rleading CR" to batch 2022/04/09 06:26:00 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:26:00 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/ leading CR/ leading CR 2022/04/09 06:26:00 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:26:00 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:26:01 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 183.981968ms 2022/04/09 06:26:01 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/ leading CR/ leading CR === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:674: testing "␊leading LF" 2022/04/09 06:26:04 DEBUG : ␊leading LF/␊leading LF: Uploading chunk 1/1 2022/04/09 06:26:05 DEBUG : ␊leading LF/␊leading LF: Uploading chunk 2/1 2022/04/09 06:26:05 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/\nleading LF/\nleading LF" to batch 2022/04/09 06:26:06 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:26:06 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/ leading LF/ leading LF 2022/04/09 06:26:06 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:26:06 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:26:07 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 181.220635ms 2022/04/09 06:26:07 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/ leading LF/ leading LF === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:674: testing "␉leading HT" 2022/04/09 06:26:11 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...) 2022/04/09 06:26:11 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/04/09 06:26:11 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/...) 2022/04/09 06:26:11 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2022/04/09 06:26:11 DEBUG : pacer: Reducing sleep to 30ms 2022/04/09 06:26:12 DEBUG : pacer: Reducing sleep to 22.5ms 2022/04/09 06:26:12 DEBUG : ␉leading HT/␉leading HT: Uploading chunk 1/1 2022/04/09 06:26:14 DEBUG : pacer: Reducing sleep to 16.875ms 2022/04/09 06:26:14 DEBUG : ␉leading HT/␉leading HT: Uploading chunk 2/1 2022/04/09 06:26:14 DEBUG : pacer: Reducing sleep to 12.65625ms 2022/04/09 06:26:14 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/\tleading HT/\tleading HT" to batch 2022/04/09 06:26:15 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:26:15 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/ leading HT/ leading HT 2022/04/09 06:26:15 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:26:15 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:26:15 DEBUG : pacer: Reducing sleep to 10ms 2022/04/09 06:26:16 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 168.221157ms 2022/04/09 06:26:16 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/ leading HT/ leading HT === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:674: testing "␋leading VT" 2022/04/09 06:26:20 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...) 2022/04/09 06:26:20 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/04/09 06:26:20 DEBUG : pacer: Reducing sleep to 15ms 2022/04/09 06:26:21 DEBUG : pacer: Reducing sleep to 11.25ms 2022/04/09 06:26:21 DEBUG : ␋leading VT/␋leading VT: Uploading chunk 1/1 2022/04/09 06:26:21 DEBUG : pacer: Reducing sleep to 10ms 2022/04/09 06:26:21 DEBUG : ␋leading VT/␋leading VT: Uploading chunk 2/1 2022/04/09 06:26:22 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/\vleading VT/\vleading VT" to batch 2022/04/09 06:26:22 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:26:22 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/ leading VT/ leading VT 2022/04/09 06:26:22 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:26:22 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:26:23 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 166.899243ms 2022/04/09 06:26:23 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/ leading VT/ leading VT === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:674: testing ".leading dot" 2022/04/09 06:26:27 DEBUG : .leading dot/.leading dot: Uploading chunk 1/1 2022/04/09 06:26:28 DEBUG : .leading dot/.leading dot: Uploading chunk 2/1 2022/04/09 06:26:28 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/.leading dot/.leading dot" to batch 2022/04/09 06:26:29 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:26:29 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/.leading dot/.leading dot 2022/04/09 06:26:29 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:26:29 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:26:29 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 170.985529ms 2022/04/09 06:26:29 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/.leading dot/.leading dot === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:674: testing "trailing space " 2022/04/09 06:26:34 DEBUG : trailing space /trailing space : Uploading chunk 1/1 2022/04/09 06:26:34 DEBUG : trailing space /trailing space : Uploading chunk 2/1 2022/04/09 06:26:35 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/trailing space␠/trailing space␠" to batch 2022/04/09 06:26:35 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:26:35 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/trailing space␠/trailing space␠ 2022/04/09 06:26:35 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:26:35 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:26:36 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 171.232245ms 2022/04/09 06:26:36 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/trailing space␠/trailing space␠ === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:674: testing "trailing CR␍" 2022/04/09 06:26:40 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/) 2022/04/09 06:26:40 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/04/09 06:26:40 DEBUG : pacer: Reducing sleep to 15ms 2022/04/09 06:26:41 DEBUG : pacer: Reducing sleep to 11.25ms 2022/04/09 06:26:41 DEBUG : trailing CR␍/trailing CR␍: Uploading chunk 1/1 2022/04/09 06:26:42 DEBUG : pacer: Reducing sleep to 10ms 2022/04/09 06:26:42 DEBUG : trailing CR␍/trailing CR␍: Uploading chunk 2/1 2022/04/09 06:26:42 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/trailing CR\r/trailing CR\r" to batch 2022/04/09 06:26:43 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:26:43 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/trailing CR /trailing CR 2022/04/09 06:26:43 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:26:43 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:26:43 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 171.906784ms 2022/04/09 06:26:43 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/trailing CR /trailing CR === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:674: testing "trailing LF␊" 2022/04/09 06:26:48 DEBUG : trailing LF␊/trailing LF␊: Uploading chunk 1/1 2022/04/09 06:26:49 DEBUG : trailing LF␊/trailing LF␊: Uploading chunk 2/1 2022/04/09 06:26:49 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/trailing LF\n/trailing LF\n" to batch 2022/04/09 06:26:50 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:26:50 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/trailing LF /trailing LF 2022/04/09 06:26:50 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:26:50 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:26:51 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 170.217777ms 2022/04/09 06:26:51 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/trailing LF /trailing LF === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:674: testing "trailing HT␉" 2022/04/09 06:26:55 DEBUG : trailing HT␉/trailing HT␉: Uploading chunk 1/1 2022/04/09 06:26:56 DEBUG : trailing HT␉/trailing HT␉: Uploading chunk 2/1 2022/04/09 06:26:56 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/trailing HT\t/trailing HT\t" to batch 2022/04/09 06:26:57 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:26:57 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/trailing HT /trailing HT 2022/04/09 06:26:57 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:26:57 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:26:57 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 168.330618ms 2022/04/09 06:26:57 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/trailing HT /trailing HT === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:674: testing "trailing VT␋" 2022/04/09 06:27:03 DEBUG : trailing VT␋/trailing VT␋: Uploading chunk 1/1 2022/04/09 06:27:03 DEBUG : trailing VT␋/trailing VT␋: Uploading chunk 2/1 2022/04/09 06:27:04 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/trailing VT\v/trailing VT\v" to batch 2022/04/09 06:27:04 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:27:04 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/trailing VT /trailing VT 2022/04/09 06:27:04 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:27:04 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:27:05 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 166.402333ms 2022/04/09 06:27:05 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/trailing VT /trailing VT === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:674: testing "trailing dot." 2022/04/09 06:27:10 DEBUG : trailing dot./trailing dot.: Uploading chunk 1/1 2022/04/09 06:27:10 DEBUG : trailing dot./trailing dot.: Uploading chunk 2/1 2022/04/09 06:27:11 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/trailing dot./trailing dot." to batch 2022/04/09 06:27:11 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:27:11 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/trailing dot./trailing dot. 2022/04/09 06:27:11 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:27:11 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:27:12 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 173.043815ms 2022/04/09 06:27:12 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/trailing dot./trailing dot. === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:674: testing "invalid utf-8\xfe" 2022/04/09 06:27:17 DEBUG : invalid utf-8/invalid utf-8: Uploading chunk 1/1 2022/04/09 06:27:18 DEBUG : invalid utf-8/invalid utf-8: Uploading chunk 2/1 2022/04/09 06:27:19 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/invalid utf-8‛FE/invalid utf-8‛FE" to batch 2022/04/09 06:27:19 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:27:19 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/invalid utf-8‛FE/invalid utf-8‛FE 2022/04/09 06:27:19 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:27:19 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:27:20 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 172.204108ms 2022/04/09 06:27:20 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/invalid utf-8‛FE/invalid utf-8‛FE === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:674: testing "test%46.txt" 2022/04/09 06:27:25 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..) 2022/04/09 06:27:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/04/09 06:27:25 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/...) 2022/04/09 06:27:25 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2022/04/09 06:27:26 DEBUG : pacer: Reducing sleep to 30ms 2022/04/09 06:27:26 DEBUG : pacer: Reducing sleep to 22.5ms 2022/04/09 06:27:26 DEBUG : test%46.txt/test%46.txt: Uploading chunk 1/1 2022/04/09 06:27:27 DEBUG : pacer: Reducing sleep to 16.875ms 2022/04/09 06:27:27 DEBUG : test%46.txt/test%46.txt: Uploading chunk 2/1 2022/04/09 06:27:28 DEBUG : pacer: Reducing sleep to 12.65625ms 2022/04/09 06:27:28 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/test%46.txt/test%46.txt" to batch 2022/04/09 06:27:28 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:27:28 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/test%46.txt/test%46.txt 2022/04/09 06:27:28 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:27:28 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:27:29 DEBUG : pacer: Reducing sleep to 10ms 2022/04/09 06:27:29 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 169.48867ms 2022/04/09 06:27:29 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/test%46.txt/test%46.txt === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2022/04/09 06:27:33 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Uploading chunk 1/1 2022/04/09 06:27:33 DEBUG : pacer: low level retry 1/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato) 2022/04/09 06:27:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/04/09 06:27:33 DEBUG : pacer: low level retry 2/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato) 2022/04/09 06:27:33 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2022/04/09 06:27:33 DEBUG : pacer: low level retry 3/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato) 2022/04/09 06:27:33 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2022/04/09 06:27:33 DEBUG : pacer: low level retry 4/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato) 2022/04/09 06:27:33 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2022/04/09 06:27:33 DEBUG : pacer: low level retry 5/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato) 2022/04/09 06:27:33 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2022/04/09 06:27:34 DEBUG : pacer: low level retry 6/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato) 2022/04/09 06:27:34 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2022/04/09 06:27:34 DEBUG : pacer: low level retry 7/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato) 2022/04/09 06:27:34 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2022/04/09 06:27:35 DEBUG : pacer: low level retry 8/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato) 2022/04/09 06:27:35 DEBUG : pacer: Rate limited, increasing sleep to 2s 2022/04/09 06:27:36 DEBUG : pacer: low level retry 9/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato) 2022/04/09 06:27:38 DEBUG : pacer: low level retry 10/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato) 2022/04/09 06:27:40 DEBUG : pacer: Reducing sleep to 1.5s === RUN TestIntegration/FsMkdir/FsPutZeroLength 2022/04/09 06:27:42 DEBUG : pacer: Reducing sleep to 1.125s 2022/04/09 06:27:42 DEBUG : zero-length-file: Uploading chunk 1/0 2022/04/09 06:27:44 DEBUG : pacer: Reducing sleep to 843.75ms 2022/04/09 06:27:44 DEBUG : zero-length-file: Uploading chunk 2/0 2022/04/09 06:27:45 DEBUG : pacer: Reducing sleep to 632.8125ms 2022/04/09 06:27:45 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/zero-length-file" to batch 2022/04/09 06:27:45 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:27:45 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/zero-length-file 2022/04/09 06:27:45 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:27:45 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:27:46 DEBUG : pacer: Reducing sleep to 474.609375ms 2022/04/09 06:27:46 DEBUG : pacer: Reducing sleep to 355.957031ms 2022/04/09 06:27:46 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 238.821436ms 2022/04/09 06:27:46 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/zero-length-file 2022/04/09 06:27:47 DEBUG : pacer: Reducing sleep to 266.967773ms 2022/04/09 06:27:47 DEBUG : pacer: Reducing sleep to 200.225829ms 2022/04/09 06:27:48 DEBUG : pacer: Reducing sleep to 150.169371ms === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:747: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsChangeNotify 2022/04/09 06:27:48 DEBUG : dir: Making directory 2022/04/09 06:27:48 DEBUG : pacer: Reducing sleep to 112.627028ms 2022/04/09 06:27:49 DEBUG : pacer: Reducing sleep to 84.470271ms 2022/04/09 06:27:49 DEBUG : pacer: Reducing sleep to 63.352703ms 2022/04/09 06:27:49 DEBUG : dir/subdir1: Making directory 2022/04/09 06:27:49 DEBUG : pacer: Reducing sleep to 47.514527ms 2022/04/09 06:27:50 DEBUG : pacer: Reducing sleep to 35.635895ms 2022/04/09 06:27:50 DEBUG : dir/subdir3: Making directory 2022/04/09 06:27:50 DEBUG : pacer: Reducing sleep to 26.726921ms 2022/04/09 06:27:50 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:27:50 DEBUG : pacer: Reducing sleep to 20.04519ms 2022/04/09 06:27:50 DEBUG : dir/subdir2: Making directory 2022/04/09 06:27:50 DEBUG : pacer: Reducing sleep to 15.033892ms 2022/04/09 06:27:50 DEBUG : pacer: Reducing sleep to 11.275419ms 2022/04/09 06:27:51 DEBUG : pacer: Reducing sleep to 10ms 2022/04/09 06:27:51 DEBUG : doChangeNotify("dir/subdir1", 0) 2022/04/09 06:27:51 DEBUG : doChangeNotify("dir/subdir3", 0) 2022/04/09 06:27:51 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:27:51 DEBUG : dir/file2: Uploading chunk 1/1 2022/04/09 06:27:51 DEBUG : doChangeNotify("dir/subdir2", 0) 2022/04/09 06:27:52 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:27:52 DEBUG : dir/file2: Uploading chunk 2/1 2022/04/09 06:27:53 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/dir/file2" to batch 2022/04/09 06:27:53 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:27:53 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:27:53 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/dir/file2 2022/04/09 06:27:53 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:27:53 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:27:54 DEBUG : doChangeNotify("dir/file2", 1) 2022/04/09 06:27:54 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:27:54 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 171.768351ms 2022/04/09 06:27:54 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/dir/file2 2022/04/09 06:27:55 DEBUG : dir/file4: Uploading chunk 1/1 2022/04/09 06:27:55 DEBUG : dir/file4: Uploading chunk 2/1 2022/04/09 06:27:56 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:27:56 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/dir/file4" to batch 2022/04/09 06:27:57 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:27:57 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/dir/file4 2022/04/09 06:27:57 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:27:57 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:27:57 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:27:57 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 176.975516ms 2022/04/09 06:27:57 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/dir/file4 2022/04/09 06:27:57 DEBUG : doChangeNotify("dir/file4", 1) 2022/04/09 06:27:57 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:27:58 DEBUG : dir/file3: Uploading chunk 1/1 2022/04/09 06:27:58 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:27:58 DEBUG : dir/file3: Uploading chunk 2/1 2022/04/09 06:27:59 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/dir/file3" to batch 2022/04/09 06:28:00 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:28:00 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/dir/file3 2022/04/09 06:28:00 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:28:00 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:28:00 DEBUG : doChangeNotify("dir/file3", 1) 2022/04/09 06:28:00 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:28:00 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 169.19482ms 2022/04/09 06:28:00 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/dir/file3 2022/04/09 06:28:01 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:28:02 DEBUG : doChangeNotify("dir/file2", 1) 2022/04/09 06:28:02 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:28:03 DEBUG : doChangeNotify("dir/file4", 1) 2022/04/09 06:28:03 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:28:04 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:28:05 DEBUG : doChangeNotify("dir/file3", 1) 2022/04/09 06:28:05 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:28:06 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:28:07 DEBUG : doChangeNotify("dir/subdir1", 1) 2022/04/09 06:28:07 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:28:08 DEBUG : doChangeNotify("dir/subdir3", 1) 2022/04/09 06:28:08 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:28:09 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote 2022/04/09 06:28:09 DEBUG : doChangeNotify("dir/subdir2", 1) 2022/04/09 06:28:10 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Checking for changes on remote === RUN TestIntegration/FsMkdir/FsPutFiles 2022/04/09 06:28:10 DEBUG : doChangeNotify("dir", 1) 2022/04/09 06:28:10 DEBUG : file name.txt: Uploading chunk 1/1 2022/04/09 06:28:11 DEBUG : file name.txt: Uploading chunk 2/1 2022/04/09 06:28:12 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/file name.txt" to batch 2022/04/09 06:28:13 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:28:13 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/file name.txt 2022/04/09 06:28:13 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:28:13 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:28:13 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 178.000765ms 2022/04/09 06:28:13 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/file name.txt 2022/04/09 06:28:14 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Uploading chunk 1/1 2022/04/09 06:28:15 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Uploading chunk 2/1 2022/04/09 06:28:15 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" to batch 2022/04/09 06:28:16 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:28:16 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt 2022/04/09 06:28:16 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:28:16 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:28:17 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 180.880672ms 2022/04/09 06:28:17 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt 2022/04/09 06:28:17 DEBUG : file name.txt: Uploading chunk 1/1 2022/04/09 06:28:18 DEBUG : file name.txt: Uploading chunk 2/1 2022/04/09 06:28:19 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/file name.txt" to batch 2022/04/09 06:28:19 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:28:19 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/file name.txt 2022/04/09 06:28:19 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:28:19 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:28:20 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 167.190823ms 2022/04/09 06:28:20 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/file name.txt === 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/04/09 06:28:23 DEBUG : Creating backend with remote "TestDropbox:" === 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/04/09 06:28:32 DEBUG : dirToPurge/fileToPurge.txt: Uploading chunk 1/1 2022/04/09 06:28:33 DEBUG : dirToPurge/fileToPurge.txt: Uploading chunk 2/1 2022/04/09 06:28:34 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/dirToPurge/fileToPurge.txt" to batch 2022/04/09 06:28:34 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:28:34 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/dirToPurge/fileToPurge.txt 2022/04/09 06:28:34 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:28:34 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:28:35 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 174.61619ms 2022/04/09 06:28:35 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/dirToPurge/fileToPurge.txt === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2022/04/09 06:28:45 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...) 2022/04/09 06:28:45 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/04/09 06:28:46 DEBUG : pacer: Reducing sleep to 15ms 2022/04/09 06:28:46 DEBUG : pacer: Reducing sleep to 11.25ms 2022/04/09 06:28:46 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2022/04/09 06:28:54 DEBUG : Creating backend with remote "TestDropbox:rclone-test-gawutew3neritax8fibozac7" 2022/04/09 06:29:01 DEBUG : Dropbox root 'rclone-test-gawutew3neritax8fibozac7': 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 fstests.go:1343: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime fstests.go:1366: can't set modified time without deleting existing object === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2022/04/09 06:29:09 DEBUG : file name.txt: Uploading chunk 1/1 2022/04/09 06:29:10 DEBUG : file name.txt: Uploading chunk 2/1 2022/04/09 06:29:10 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/file name.txt" to batch 2022/04/09 06:29:11 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:29:11 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/file name.txt 2022/04/09 06:29:11 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:29:11 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:29:12 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 168.184677ms 2022/04/09 06:29:12 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/file name.txt === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2022/04/09 06:29:13 DEBUG : Creating backend with remote "TestDropbox:rclone-test-cucagir0xomubom4dawiwos3/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2022/04/09 06:29:13 DEBUG : Creating backend with remote "TestDropbox:rclone-test-cucagir0xomubom4dawiwos3/not found.txt" 2022/04/09 06:29:14 ERROR : : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1503: Opening root remote "TestDropbox:" path "rclone-test-cucagir0xomubom4dawiwos3" from "TestDropbox:rclone-test-cucagir0xomubom4dawiwos3" 2022/04/09 06:29:14 DEBUG : Creating backend with remote "TestDropbox:" === 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/04/09 06:29:15 DEBUG : rclone-test-cucagir0xomubom4dawiwos3/created from root.txt: Uploading chunk 1/1 2022/04/09 06:29:16 DEBUG : rclone-test-cucagir0xomubom4dawiwos3/created from root.txt: Uploading chunk 2/1 2022/04/09 06:29:17 DEBUG : Dropbox root '': Adding "/rclone-test-cucagir0xomubom4dawiwos3/created from root.txt" to batch 2022/04/09 06:29:17 DEBUG : Dropbox root '': Batch idle for 500ms so committing 2022/04/09 06:29:17 DEBUG : Dropbox root '': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/created from root.txt 2022/04/09 06:29:17 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:29:17 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:29:18 DEBUG : Dropbox root '': Upload batch completed in 170.355992ms 2022/04/09 06:29:18 DEBUG : Dropbox root '': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/created from root.txt === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink 2022/04/09 06:29:21 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': attempting to share 'file name.txt_does_not_exist' (absolute path: /rclone-test-cucagir0xomubom4dawiwos3/file name.txt_does_not_exist) 2022/04/09 06:29:21 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': attempting to share 'file name.txt' (absolute path: /rclone-test-cucagir0xomubom4dawiwos3/file name.txt) fstests.go:1626: Error Trace: fstests.go:1626 Error: Received unexpected error: settings_error/not_authorized/ Test: TestIntegration/FsMkdir/FsPutFiles/PublicLink === 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 === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 2022/04/09 06:29:23 DEBUG : piped data.txt: Uploading chunk 1/0 2022/04/09 06:29:24 DEBUG : piped data.txt: Uploading chunk 2/0 2022/04/09 06:29:24 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/piped data.txt" to batch 2022/04/09 06:29:25 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:29:25 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/piped data.txt 2022/04/09 06:29:25 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:29:25 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:29:25 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 328.181367ms 2022/04/09 06:29:25 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/piped data.txt === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2022/04/09 06:29:27 DEBUG : piped data.txt: Uploading chunk 1/0 2022/04/09 06:29:28 DEBUG : piped data.txt: Uploading chunk 2/0 2022/04/09 06:29:29 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/piped data.txt" to batch 2022/04/09 06:29:29 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:29:29 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/piped data.txt 2022/04/09 06:29:29 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:29:29 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:29:30 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 172.513272ms 2022/04/09 06:29:30 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/piped data.txt === RUN TestIntegration/FsMkdir/FsPutFiles/Internal fstests.go:1790: *dropbox.Fs does not implement InternalTester === RUN TestIntegration/FsMkdir/FsPutChunked === RUN TestIntegration/FsMkdir/FsPutChunked/100 === RUN TestIntegration/FsMkdir/FsPutChunked/100/99 2022/04/09 06:29:32 DEBUG : chunked-100-99.bin: Uploading chunk 1/1 2022/04/09 06:29:32 DEBUG : chunked-100-99.bin: Uploading chunk 2/1 2022/04/09 06:29:33 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-100-99.bin" to batch 2022/04/09 06:29:33 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:29:33 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-100-99.bin 2022/04/09 06:29:33 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:29:33 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:29:34 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 164.254196ms 2022/04/09 06:29:34 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-100-99.bin === RUN TestIntegration/FsMkdir/FsPutChunked/100/100 2022/04/09 06:29:36 DEBUG : chunked-100-100.bin: Uploading chunk 1/1 2022/04/09 06:29:37 DEBUG : chunked-100-100.bin: Uploading chunk 2/1 2022/04/09 06:29:37 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-100-100.bin" to batch 2022/04/09 06:29:38 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:29:38 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-100-100.bin 2022/04/09 06:29:38 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:29:38 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:29:39 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 172.193039ms 2022/04/09 06:29:39 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-100-100.bin === RUN TestIntegration/FsMkdir/FsPutChunked/100/201 2022/04/09 06:29:41 DEBUG : chunked-100-201.bin: Uploading chunk 1/3 2022/04/09 06:29:42 DEBUG : chunked-100-201.bin: Uploading chunk 2/3 2022/04/09 06:29:43 DEBUG : chunked-100-201.bin: Uploading chunk 3/3 2022/04/09 06:29:43 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-100-201.bin" to batch 2022/04/09 06:29:43 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:29:43 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-100-201.bin 2022/04/09 06:29:43 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:29:43 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:29:44 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 174.200606ms 2022/04/09 06:29:44 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-100-201.bin === RUN TestIntegration/FsMkdir/FsPutChunked/128 === RUN TestIntegration/FsMkdir/FsPutChunked/128/127 2022/04/09 06:29:47 DEBUG : chunked-128-127.bin: Uploading chunk 1/1 2022/04/09 06:29:47 DEBUG : chunked-128-127.bin: Uploading chunk 2/1 2022/04/09 06:29:48 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-128-127.bin" to batch 2022/04/09 06:29:48 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:29:48 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-128-127.bin 2022/04/09 06:29:48 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:29:48 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:29:49 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 335.184403ms 2022/04/09 06:29:49 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-128-127.bin === RUN TestIntegration/FsMkdir/FsPutChunked/128/128 2022/04/09 06:29:52 DEBUG : chunked-128-128.bin: Uploading chunk 1/1 2022/04/09 06:29:52 DEBUG : chunked-128-128.bin: Uploading chunk 2/1 2022/04/09 06:29:53 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-128-128.bin" to batch 2022/04/09 06:29:53 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:29:53 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-128-128.bin 2022/04/09 06:29:53 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:29:53 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:29:54 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 175.527282ms 2022/04/09 06:29:54 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-128-128.bin === RUN TestIntegration/FsMkdir/FsPutChunked/128/257 2022/04/09 06:29:56 DEBUG : chunked-128-257.bin: Uploading chunk 1/3 2022/04/09 06:29:57 DEBUG : chunked-128-257.bin: Uploading chunk 2/3 2022/04/09 06:29:58 DEBUG : chunked-128-257.bin: Uploading chunk 3/3 2022/04/09 06:29:58 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-128-257.bin" to batch 2022/04/09 06:29:59 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:29:59 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-128-257.bin 2022/04/09 06:29:59 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:29:59 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:30:00 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 172.944204ms 2022/04/09 06:30:00 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-128-257.bin === RUN TestIntegration/FsMkdir/FsPutChunked/97.656Ki === RUN TestIntegration/FsMkdir/FsPutChunked/97.656Ki/99999 2022/04/09 06:30:02 DEBUG : chunked-97.656Ki-97.655Ki.bin: Uploading chunk 1/1 2022/04/09 06:30:02 DEBUG : chunked-97.656Ki-97.655Ki.bin: Uploading chunk 2/1 2022/04/09 06:30:03 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-97.656Ki-97.655Ki.bin" to batch 2022/04/09 06:30:03 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:30:03 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-97.656Ki-97.655Ki.bin 2022/04/09 06:30:03 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:30:03 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:30:05 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 175.63768ms 2022/04/09 06:30:05 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-97.656Ki-97.655Ki.bin === RUN TestIntegration/FsMkdir/FsPutChunked/97.656Ki/100000 2022/04/09 06:30:07 DEBUG : chunked-97.656Ki-97.656Ki.bin: Uploading chunk 1/1 2022/04/09 06:30:08 DEBUG : chunked-97.656Ki-97.656Ki.bin: Uploading chunk 2/1 2022/04/09 06:30:09 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-97.656Ki-97.656Ki.bin" to batch 2022/04/09 06:30:09 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:30:09 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-97.656Ki-97.656Ki.bin 2022/04/09 06:30:09 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:30:09 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:30:10 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 175.356463ms 2022/04/09 06:30:10 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-97.656Ki-97.656Ki.bin === RUN TestIntegration/FsMkdir/FsPutChunked/97.656Ki/200001 2022/04/09 06:30:12 DEBUG : chunked-97.656Ki-195.313Ki.bin: Uploading chunk 1/3 2022/04/09 06:30:13 DEBUG : chunked-97.656Ki-195.313Ki.bin: Uploading chunk 2/3 2022/04/09 06:30:13 DEBUG : chunked-97.656Ki-195.313Ki.bin: Uploading chunk 3/3 2022/04/09 06:30:14 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-97.656Ki-195.313Ki.bin" to batch 2022/04/09 06:30:14 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:30:14 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-97.656Ki-195.313Ki.bin 2022/04/09 06:30:14 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:30:14 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:30:15 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 169.586299ms 2022/04/09 06:30:15 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-97.656Ki-195.313Ki.bin === RUN TestIntegration/FsMkdir/FsPutChunked/97.657Ki === RUN TestIntegration/FsMkdir/FsPutChunked/97.657Ki/100000 2022/04/09 06:30:17 DEBUG : chunked-97.657Ki-97.656Ki.bin: Uploading chunk 1/1 2022/04/09 06:30:18 DEBUG : chunked-97.657Ki-97.656Ki.bin: Uploading chunk 2/1 2022/04/09 06:30:18 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-97.657Ki-97.656Ki.bin" to batch 2022/04/09 06:30:19 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:30:19 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-97.657Ki-97.656Ki.bin 2022/04/09 06:30:19 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:30:19 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:30:19 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 175.721379ms 2022/04/09 06:30:19 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-97.657Ki-97.656Ki.bin === RUN TestIntegration/FsMkdir/FsPutChunked/97.657Ki/100001 2022/04/09 06:30:21 DEBUG : chunked-97.657Ki-97.657Ki.bin: Uploading chunk 1/1 2022/04/09 06:30:22 DEBUG : chunked-97.657Ki-97.657Ki.bin: Uploading chunk 2/1 2022/04/09 06:30:23 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-97.657Ki-97.657Ki.bin" to batch 2022/04/09 06:30:23 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:30:23 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-97.657Ki-97.657Ki.bin 2022/04/09 06:30:23 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:30:23 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:30:24 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 159.557026ms 2022/04/09 06:30:24 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-97.657Ki-97.657Ki.bin === RUN TestIntegration/FsMkdir/FsPutChunked/97.657Ki/200003 2022/04/09 06:30:26 DEBUG : chunked-97.657Ki-195.315Ki.bin: Uploading chunk 1/3 2022/04/09 06:30:26 DEBUG : chunked-97.657Ki-195.315Ki.bin: Uploading chunk 2/3 2022/04/09 06:30:27 DEBUG : chunked-97.657Ki-195.315Ki.bin: Uploading chunk 3/3 2022/04/09 06:30:28 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-97.657Ki-195.315Ki.bin" to batch 2022/04/09 06:30:28 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:30:28 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-97.657Ki-195.315Ki.bin 2022/04/09 06:30:28 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:30:28 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:30:30 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 178.93246ms 2022/04/09 06:30:30 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-97.657Ki-195.315Ki.bin === RUN TestIntegration/FsMkdir/FsPutChunked/682.731Ki === RUN TestIntegration/FsMkdir/FsPutChunked/682.731Ki/699116 2022/04/09 06:30:32 DEBUG : chunked-682.731Ki-682.730Ki.bin: Uploading chunk 1/1 2022/04/09 06:30:33 DEBUG : chunked-682.731Ki-682.730Ki.bin: Uploading chunk 2/1 2022/04/09 06:30:34 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-682.731Ki-682.730Ki.bin" to batch 2022/04/09 06:30:34 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:30:34 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-682.731Ki-682.730Ki.bin 2022/04/09 06:30:34 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:30:34 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:30:35 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 241.880471ms 2022/04/09 06:30:35 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-682.731Ki-682.730Ki.bin === RUN TestIntegration/FsMkdir/FsPutChunked/682.731Ki/699117 2022/04/09 06:30:37 DEBUG : chunked-682.731Ki-682.731Ki.bin: Uploading chunk 1/1 2022/04/09 06:30:38 DEBUG : chunked-682.731Ki-682.731Ki.bin: Uploading chunk 2/1 2022/04/09 06:30:39 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-682.731Ki-682.731Ki.bin" to batch 2022/04/09 06:30:39 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:30:39 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-682.731Ki-682.731Ki.bin 2022/04/09 06:30:39 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:30:39 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:30:40 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 174.595856ms 2022/04/09 06:30:40 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-682.731Ki-682.731Ki.bin === RUN TestIntegration/FsMkdir/FsPutChunked/682.731Ki/1398235 2022/04/09 06:30:42 DEBUG : chunked-682.731Ki-1.333Mi.bin: Uploading chunk 1/3 2022/04/09 06:30:43 DEBUG : chunked-682.731Ki-1.333Mi.bin: Uploading chunk 2/3 2022/04/09 06:30:45 DEBUG : chunked-682.731Ki-1.333Mi.bin: Uploading chunk 3/3 2022/04/09 06:30:45 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-682.731Ki-1.333Mi.bin" to batch 2022/04/09 06:30:46 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:30:46 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-682.731Ki-1.333Mi.bin 2022/04/09 06:30:46 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:30:46 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:30:46 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 173.257796ms 2022/04/09 06:30:46 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-682.731Ki-1.333Mi.bin === RUN TestIntegration/FsMkdir/FsPutChunked/2Mi === RUN TestIntegration/FsMkdir/FsPutChunked/2Mi/2097151 2022/04/09 06:30:48 DEBUG : chunked-2Mi-2.000Mi.bin: Uploading chunk 1/1 2022/04/09 06:30:50 DEBUG : chunked-2Mi-2.000Mi.bin: Uploading chunk 2/1 2022/04/09 06:30:51 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-2Mi-2.000Mi.bin" to batch 2022/04/09 06:30:52 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:30:52 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-2Mi-2.000Mi.bin 2022/04/09 06:30:52 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:30:52 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:30:52 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 174.550559ms 2022/04/09 06:30:52 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-2Mi-2.000Mi.bin === RUN TestIntegration/FsMkdir/FsPutChunked/2Mi/2097152 2022/04/09 06:30:54 DEBUG : chunked-2Mi-2Mi.bin: Uploading chunk 1/1 2022/04/09 06:30:56 DEBUG : chunked-2Mi-2Mi.bin: Uploading chunk 2/1 2022/04/09 06:30:56 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-2Mi-2Mi.bin" to batch 2022/04/09 06:30:57 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:30:57 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-2Mi-2Mi.bin 2022/04/09 06:30:57 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:30:57 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:30:58 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 168.38817ms 2022/04/09 06:30:58 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-2Mi-2Mi.bin === RUN TestIntegration/FsMkdir/FsPutChunked/2Mi/4194305 2022/04/09 06:31:00 DEBUG : chunked-2Mi-4.000Mi.bin: Uploading chunk 1/3 2022/04/09 06:31:01 DEBUG : chunked-2Mi-4.000Mi.bin: Uploading chunk 2/3 2022/04/09 06:31:02 DEBUG : chunked-2Mi-4.000Mi.bin: Uploading chunk 3/3 2022/04/09 06:31:03 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/chunked-2Mi-4.000Mi.bin" to batch 2022/04/09 06:31:04 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:31:04 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-2Mi-4.000Mi.bin 2022/04/09 06:31:04 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:31:04 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:31:04 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 173.612976ms 2022/04/09 06:31:04 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/chunked-2Mi-4.000Mi.bin === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2022/04/09 06:31:08 DEBUG : unknown-size-put.txt: Uploading chunk 1/0 2022/04/09 06:31:09 DEBUG : unknown-size-put.txt: Uploading chunk 2/0 2022/04/09 06:31:09 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/unknown-size-put.txt" to batch 2022/04/09 06:31:10 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:31:10 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/unknown-size-put.txt 2022/04/09 06:31:10 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:31:10 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:31:10 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 164.532607ms 2022/04/09 06:31:10 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/unknown-size-put.txt === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2022/04/09 06:31:12 DEBUG : unknown-size-update.txt: Uploading chunk 1/1 2022/04/09 06:31:13 DEBUG : unknown-size-update.txt: Uploading chunk 2/1 2022/04/09 06:31:13 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/unknown-size-update.txt" to batch 2022/04/09 06:31:14 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:31:14 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/unknown-size-update.txt 2022/04/09 06:31:14 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:31:14 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:31:15 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 172.077109ms 2022/04/09 06:31:15 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/unknown-size-update.txt 2022/04/09 06:31:16 DEBUG : unknown-size-update.txt: Uploading chunk 1/0 2022/04/09 06:31:17 DEBUG : unknown-size-update.txt: Uploading chunk 2/0 2022/04/09 06:31:17 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Adding "/rclone-test-cucagir0xomubom4dawiwos3/unknown-size-update.txt" to batch 2022/04/09 06:31:18 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Batch idle for 500ms so committing 2022/04/09 06:31:18 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committing sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/unknown-size-update.txt 2022/04/09 06:31:18 WARNING: API `UploadSessionFinishBatch` is deprecated 2022/04/09 06:31:18 Use API `UploadSessionFinishBatchV2` instead 2022/04/09 06:31:19 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Upload batch completed in 175.525935ms 2022/04/09 06:31:19 DEBUG : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Committed sync batch length 1 starting with: /rclone-test-cucagir0xomubom4dawiwos3/unknown-size-update.txt === RUN TestIntegration/FsMkdir/FsRootCollapse 2022/04/09 06:31:20 DEBUG : Creating backend with remote "TestDropbox:rclone-test-cucagir0xomubom4dawiwos3/deeper/nonexisting/directory" 2022/04/09 06:31:22 ERROR : : error listing: directory not found === CONT TestIntegration/FsMkdir fstests.go:1989: Warning: this should produce fs.ErrorDirNotFound === RUN TestIntegration/FsShutdown 2022/04/09 06:31:22 INFO : Dropbox root 'rclone-test-cucagir0xomubom4dawiwos3': Commiting uploads - please wait... --- FAIL: TestIntegration (376.71s) --- SKIP: TestIntegration/FsCheckWrap (0.00s) --- SKIP: TestIntegration/FsCommand (0.00s) --- PASS: TestIntegration/FsRmdirNotFound (0.17s) --- PASS: TestIntegration/FsString (0.00s) --- PASS: TestIntegration/FsName (0.00s) --- PASS: TestIntegration/FsRoot (0.00s) --- PASS: TestIntegration/FsRmdirEmpty (2.01s) --- FAIL: TestIntegration/FsMkdir (373.47s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (4.70s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.19s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.19s) --- SKIP: TestIntegration/FsMkdir/FsListRDirEmpty (0.00s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.16s) --- SKIP: TestIntegration/FsMkdir/FsListRDirNotFound (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding (137.57s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (6.14s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (5.88s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (8.85s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (7.10s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (5.86s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (7.96s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (6.49s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (6.55s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (9.32s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (7.08s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (6.21s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (6.77s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (7.49s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (7.36s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (7.53s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (6.85s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (7.74s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (8.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (8.20s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.33s) --- PASS: TestIntegration/FsMkdir/FsPutError (7.13s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (8.16s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- PASS: TestIntegration/FsMkdir/FsChangeNotify (21.95s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles (81.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (1.01s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (1.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.79s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (4.06s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.39s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.40s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (0.99s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.20s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (2.14s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive/Dir (1.97s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (1.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (6.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (3.13s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (12.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (9.11s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.38s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.18s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.28s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.81s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (0.52s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (2.41s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.49s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (3.83s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (0.41s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (0.35s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (6.95s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (1.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.21s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (5.73s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (1.63s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/PublicLink (0.32s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (1.85s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (8.35s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (4.05s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (4.30s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Internal (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutChunked (95.16s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/100 (15.03s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/100/99 (4.68s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/100/100 (5.01s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/100/201 (5.34s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/128 (14.94s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/128/127 (4.89s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/128/128 (4.86s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/128/257 (5.19s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/97.656Ki (15.26s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/97.656Ki/99999 (5.68s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/97.656Ki/100000 (4.77s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/97.656Ki/200001 (4.81s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/97.657Ki (15.35s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/97.657Ki/100000 (4.39s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/97.657Ki/100001 (4.31s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/97.657Ki/200003 (6.66s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/682.731Ki (16.28s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/682.731Ki/699116 (5.10s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/682.731Ki/699117 (4.94s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/682.731Ki/1398235 (6.24s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/2Mi (18.30s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/2Mi/2097151 (5.86s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/2Mi/2097152 (5.29s) --- PASS: TestIntegration/FsMkdir/FsPutChunked/2Mi/4194305 (7.15s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (13.77s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (5.21s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (8.56s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (0.34s) --- PASS: TestIntegration/FsShutdown (0.00s) FAIL exit status 1 FAIL github.com/rclone/rclone/backend/dropbox 376.734s "go test -v -timeout 1h0m0s -remote TestDropbox: -verbose" - Finished OK in 6m18.639412981s (try 1/5)