"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:"
2021/06/01 05:20:47 DEBUG : Creating backend with remote "TestDropbox:rclone-test-maxocap8jusoned7zafohat5"
2021/06/01 05:20:47 DEBUG : Using config file from "/home/rclone/.rclone.conf"
=== RUN   TestIntegration/FsCheckWrap
    fstests.go:455: Not a wrapping Fs
=== RUN   TestIntegration/FsCommand
    fstests.go:483: 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
2021/06/01 05:20:51 DEBUG : dir/subdir: Making directory
2021/06/01 05:20:53 DEBUG : dir/subdir: Removing directory
2021/06/01 05:20:55 DEBUG : 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
2021/06/01 05:20:57 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:669: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡"
2021/06/01 05:21:00 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Uploading chunk 1/1
2021/06/01 05:21:00 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Uploading chunk 2/1
2021/06/01 05:21:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/␀\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
2021/06/01 05:21:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:21:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/␀
	

␡/␀
	

␡
2021/06/01 05:21:02 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:21:02 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:21:03 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:21:03 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:21:04 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:21:05 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:21:07 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/␀
	

␡/␀
	

␡
=== RUN   TestIntegration/FsMkdir/FsEncoding/dot
    fstests.go:669: testing "．"
2021/06/01 05:21:12 DEBUG : ．/．: Uploading chunk 1/1
2021/06/01 05:21:12 DEBUG : ．/．: Uploading chunk 2/1
2021/06/01 05:21:13 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/．/．" to batch
2021/06/01 05:21:13 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:21:13 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/．/．
2021/06/01 05:21:14 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:21:14 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:21:14 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:21:15 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:21:16 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:21:17 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:21:18 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:21:20 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:21:21 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:21:22 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/．/．
=== RUN   TestIntegration/FsMkdir/FsEncoding/dot_dot
    fstests.go:669: testing "．．"
2021/06/01 05:21:27 DEBUG : ．．/．．: Uploading chunk 1/1
2021/06/01 05:21:27 DEBUG : ．．/．．: Uploading chunk 2/1
2021/06/01 05:21:28 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/．．/．．" to batch
2021/06/01 05:21:28 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:21:28 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/．．/．．
2021/06/01 05:21:29 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:21:29 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:21:30 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:21:30 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:21:31 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:21:32 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:21:34 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:21:35 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:21:36 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/．．/．．
=== RUN   TestIntegration/FsMkdir/FsEncoding/punctuation
    fstests.go:669: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~"
2021/06/01 05:21:42 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Uploading chunk 1/1
2021/06/01 05:21:43 DEBUG : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~: Uploading chunk 2/1
2021/06/01 05:21:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/!\"#$%&'()*+,-.／:;<=>?@[＼]^_`{|}~/!\"#$%&'()*+,-.／:;<=>?@[＼]^_`{|}~" to batch
2021/06/01 05:21:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:21:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/!"#$%&'()*+,-.／:;<=>?@[＼]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[＼]^_`{|}~
2021/06/01 05:21:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:21:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:21:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:21:46 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:21:47 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:21:48 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:21:49 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:21:51 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:21:52 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/!"#$%&'()*+,-.／:;<=>?@[＼]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[＼]^_`{|}~
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_space
    fstests.go:669: testing " leading space"
2021/06/01 05:21:57 DEBUG :  leading space/ leading space: Uploading chunk 1/1
2021/06/01 05:21:58 DEBUG :  leading space/ leading space: Uploading chunk 2/1
2021/06/01 05:21:59 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/ leading space/ leading space" to batch
2021/06/01 05:21:59 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:21:59 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/ leading space/ leading space
2021/06/01 05:22:00 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:22:00 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:22:00 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:22:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:22:02 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:22:03 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:22:05 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:22:06 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:22:07 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:22:08 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/ leading space/ leading space
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_tilde
    fstests.go:669: testing "~leading tilde"
2021/06/01 05:22:14 DEBUG : ~leading tilde/~leading tilde: Uploading chunk 1/1
2021/06/01 05:22:14 DEBUG : ~leading tilde/~leading tilde: Uploading chunk 2/1
2021/06/01 05:22:15 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/~leading tilde/~leading tilde" to batch
2021/06/01 05:22:15 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:22:15 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/~leading tilde/~leading tilde
2021/06/01 05:22:16 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:22:16 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:22:17 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:22:17 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:22:18 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/~leading tilde/~leading tilde
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_CR
    fstests.go:669: testing "␍leading CR"
2021/06/01 05:22:24 DEBUG : ␍leading CR/␍leading CR: Uploading chunk 1/1
2021/06/01 05:22:25 DEBUG : ␍leading CR/␍leading CR: Uploading chunk 2/1
2021/06/01 05:22:26 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/\rleading CR/\rleading CR" to batch
2021/06/01 05:22:26 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:22:26 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/
leading CR/
leading CR
2021/06/01 05:22:27 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:22:27 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:22:28 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:22:28 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:22:29 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:22:30 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:22:32 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:22:33 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/
leading CR/
leading CR
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_LF
    fstests.go:669: testing "␊leading LF"
2021/06/01 05:22:38 DEBUG : ␊leading LF/␊leading LF: Uploading chunk 1/1
2021/06/01 05:22:38 DEBUG : ␊leading LF/␊leading LF: Uploading chunk 2/1
2021/06/01 05:22:39 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/\nleading LF/\nleading LF" to batch
2021/06/01 05:22:39 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:22:39 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/
leading LF/
leading LF
2021/06/01 05:22:40 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:22:40 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:22:41 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:22:41 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:22:42 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:22:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:22:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:22:46 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:22:47 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:22:49 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 10/120
2021/06/01 05:22:50 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 11/120
2021/06/01 05:22:51 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 12/120
2021/06/01 05:22:52 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 13/120
2021/06/01 05:22:54 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 14/120
2021/06/01 05:22:55 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/
leading LF/
leading LF
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_HT
    fstests.go:669: testing "␉leading HT"
2021/06/01 05:22:59 DEBUG : ␉leading HT/␉leading HT: Uploading chunk 1/1
2021/06/01 05:23:00 DEBUG : ␉leading HT/␉leading HT: Uploading chunk 2/1
2021/06/01 05:23:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/\tleading HT/\tleading HT" to batch
2021/06/01 05:23:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:23:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/	leading HT/	leading HT
2021/06/01 05:23:02 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:23:02 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:23:02 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:23:03 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:23:04 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:23:05 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/	leading HT/	leading HT
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_VT
    fstests.go:669: testing "␋leading VT"
2021/06/01 05:23:11 DEBUG : ␋leading VT/␋leading VT: Uploading chunk 1/1
2021/06/01 05:23:11 DEBUG : ␋leading VT/␋leading VT: Uploading chunk 2/1
2021/06/01 05:23:12 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/\vleading VT/\vleading VT" to batch
2021/06/01 05:23:12 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:23:12 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/leading VT/leading VT
2021/06/01 05:23:13 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:23:13 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:23:14 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:23:14 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:23:15 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:23:16 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:23:18 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:23:19 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:23:20 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:23:21 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 10/120
2021/06/01 05:23:23 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 11/120
2021/06/01 05:23:24 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 12/120
2021/06/01 05:23:25 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 13/120
2021/06/01 05:23:26 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 14/120
2021/06/01 05:23:27 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/leading VT/leading VT
=== RUN   TestIntegration/FsMkdir/FsEncoding/leading_dot
    fstests.go:669: testing ".leading dot"
2021/06/01 05:23:32 DEBUG : .leading dot/.leading dot: Uploading chunk 1/1
2021/06/01 05:23:33 DEBUG : .leading dot/.leading dot: Uploading chunk 2/1
2021/06/01 05:23:33 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/.leading dot/.leading dot" to batch
2021/06/01 05:23:34 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:23:34 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/.leading dot/.leading dot
2021/06/01 05:23:34 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:23:35 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:23:35 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:23:36 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:23:37 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:23:38 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/.leading dot/.leading dot
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_space
    fstests.go:669: testing "trailing space "
2021/06/01 05:23:42 DEBUG : trailing space /trailing space : Uploading chunk 1/1
2021/06/01 05:23:43 DEBUG : trailing space /trailing space : Uploading chunk 2/1
2021/06/01 05:23:43 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/trailing space␠/trailing space␠" to batch
2021/06/01 05:23:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:23:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/trailing space␠/trailing space␠
2021/06/01 05:23:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:23:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:23:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:23:46 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:23:47 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:23:48 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:23:49 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:23:50 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/trailing space␠/trailing space␠
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_CR
    fstests.go:669: testing "trailing CR␍"
2021/06/01 05:23:55 DEBUG : trailing CR␍/trailing CR␍: Uploading chunk 1/1
2021/06/01 05:23:56 DEBUG : trailing CR␍/trailing CR␍: Uploading chunk 2/1
2021/06/01 05:23:56 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/trailing CR\r/trailing CR\r" to batch
2021/06/01 05:23:57 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:23:57 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/trailing CR
/trailing CR
2021/06/01 05:23:57 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:23:57 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:23:58 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:23:58 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/trailing CR
/trailing CR
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_LF
    fstests.go:669: testing "trailing LF␊"
2021/06/01 05:24:03 DEBUG : trailing LF␊/trailing LF␊: Uploading chunk 1/1
2021/06/01 05:24:03 DEBUG : trailing LF␊/trailing LF␊: Uploading chunk 2/1
2021/06/01 05:24:04 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/trailing LF\n/trailing LF\n" to batch
2021/06/01 05:24:05 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:24:05 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/trailing LF
/trailing LF
2021/06/01 05:24:05 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:24:05 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:24:06 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:24:06 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:24:07 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:24:09 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:24:10 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:24:11 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:24:12 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:24:14 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/trailing LF
/trailing LF
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_HT
    fstests.go:669: testing "trailing HT␉"
2021/06/01 05:24:18 DEBUG : trailing HT␉/trailing HT␉: Uploading chunk 1/1
2021/06/01 05:24:19 DEBUG : trailing HT␉/trailing HT␉: Uploading chunk 2/1
2021/06/01 05:24:19 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/trailing HT\t/trailing HT\t" to batch
2021/06/01 05:24:20 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:24:20 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/trailing HT	/trailing HT	
2021/06/01 05:24:20 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:24:21 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:24:21 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:24:22 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:24:23 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:24:24 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:24:25 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:24:27 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:24:28 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:24:29 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 10/120
2021/06/01 05:24:30 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 11/120
2021/06/01 05:24:32 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/trailing HT	/trailing HT	
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_VT
    fstests.go:669: testing "trailing VT␋"
2021/06/01 05:24:37 DEBUG : trailing VT␋/trailing VT␋: Uploading chunk 1/1
2021/06/01 05:24:38 DEBUG : trailing VT␋/trailing VT␋: Uploading chunk 2/1
2021/06/01 05:24:38 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/trailing VT\v/trailing VT\v" to batch
2021/06/01 05:24:39 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:24:39 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/trailing VT/trailing VT
2021/06/01 05:24:39 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:24:39 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:24:40 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:24:41 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:24:42 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:24:43 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:24:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/trailing VT/trailing VT
=== RUN   TestIntegration/FsMkdir/FsEncoding/trailing_dot
    fstests.go:669: testing "trailing dot."
2021/06/01 05:24:49 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...)
2021/06/01 05:24:49 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:24:49 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/..)
2021/06/01 05:24:49 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2021/06/01 05:24:50 DEBUG : pacer: Reducing sleep to 30ms
2021/06/01 05:24:50 DEBUG : pacer: Reducing sleep to 22.5ms
2021/06/01 05:24:50 DEBUG : trailing dot./trailing dot.: Uploading chunk 1/1
2021/06/01 05:24:51 DEBUG : pacer: Reducing sleep to 16.875ms
2021/06/01 05:24:51 DEBUG : trailing dot./trailing dot.: Uploading chunk 2/1
2021/06/01 05:24:51 DEBUG : pacer: Reducing sleep to 12.65625ms
2021/06/01 05:24:51 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/trailing dot./trailing dot." to batch
2021/06/01 05:24:52 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:24:52 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/trailing dot./trailing dot.
2021/06/01 05:24:52 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:24:52 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:24:53 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:24:53 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:24:54 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/trailing dot./trailing dot.
=== RUN   TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8
    fstests.go:669: testing "invalid utf-8\xfe"
2021/06/01 05:24:58 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..)
2021/06/01 05:24:58 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:24:58 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/.)
2021/06/01 05:24:58 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2021/06/01 05:24:59 DEBUG : pacer: Reducing sleep to 30ms
2021/06/01 05:24:59 DEBUG : pacer: Reducing sleep to 22.5ms
2021/06/01 05:24:59 DEBUG : invalid utf-8�/invalid utf-8�: Uploading chunk 1/1
2021/06/01 05:25:00 DEBUG : pacer: Reducing sleep to 16.875ms
2021/06/01 05:25:00 DEBUG : invalid utf-8�/invalid utf-8�: Uploading chunk 2/1
2021/06/01 05:25:01 DEBUG : pacer: Reducing sleep to 12.65625ms
2021/06/01 05:25:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/invalid utf-8‛FE/invalid utf-8‛FE" to batch
2021/06/01 05:25:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:25:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/invalid utf-8‛FE/invalid utf-8‛FE
2021/06/01 05:25:02 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:25:02 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:25:02 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:25:03 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:25:03 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:25:04 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/invalid utf-8‛FE/invalid utf-8‛FE
=== RUN   TestIntegration/FsMkdir/FsNewObjectNotFound
=== RUN   TestIntegration/FsMkdir/FsPutError
2021/06/01 05:25:08 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Uploading chunk 1/1
2021/06/01 05:25:08 DEBUG : pacer: low level retry 1/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato)
2021/06/01 05:25:08 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:25:08 DEBUG : pacer: low level retry 2/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato)
2021/06/01 05:25:08 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2021/06/01 05:25:09 DEBUG : pacer: low level retry 3/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato)
2021/06/01 05:25:09 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2021/06/01 05:25:09 DEBUG : pacer: low level retry 4/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato)
2021/06/01 05:25:09 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2021/06/01 05:25:09 DEBUG : pacer: low level retry 5/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato)
2021/06/01 05:25:09 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2021/06/01 05:25:09 DEBUG : pacer: low level retry 6/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato)
2021/06/01 05:25:09 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2021/06/01 05:25:09 DEBUG : pacer: low level retry 7/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato)
2021/06/01 05:25:09 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2021/06/01 05:25:10 DEBUG : pacer: low level retry 8/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato)
2021/06/01 05:25:10 DEBUG : pacer: Rate limited, increasing sleep to 2s
2021/06/01 05:25:11 DEBUG : pacer: low level retry 9/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato)
2021/06/01 05:25:13 DEBUG : pacer: low level retry 10/10 (error Post "https://content.dropboxapi.com/2/files/upload_session/append_v2": potato)
2021/06/01 05:25:15 DEBUG : pacer: Reducing sleep to 1.5s
=== RUN   TestIntegration/FsMkdir/FsPutZeroLength
2021/06/01 05:25:17 DEBUG : pacer: Reducing sleep to 1.125s
2021/06/01 05:25:17 DEBUG : zero-length-file: Uploading chunk 1/0
2021/06/01 05:25:19 DEBUG : pacer: Reducing sleep to 843.75ms
2021/06/01 05:25:19 DEBUG : zero-length-file: Uploading chunk 2/0
2021/06/01 05:25:20 DEBUG : pacer: Reducing sleep to 632.8125ms
2021/06/01 05:25:20 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/zero-length-file" to batch
2021/06/01 05:25:21 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:25:21 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/zero-length-file
2021/06/01 05:25:21 DEBUG : pacer: Reducing sleep to 474.609375ms
2021/06/01 05:25:21 DEBUG : pacer: Reducing sleep to 355.957031ms
2021/06/01 05:25:21 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:25:22 DEBUG : pacer: Reducing sleep to 266.967773ms
2021/06/01 05:25:22 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:25:22 DEBUG : pacer: Reducing sleep to 200.225829ms
2021/06/01 05:25:22 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:25:23 DEBUG : pacer: Reducing sleep to 150.169371ms
2021/06/01 05:25:23 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:25:24 DEBUG : pacer: Reducing sleep to 112.627028ms
2021/06/01 05:25:24 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/zero-length-file
2021/06/01 05:25:24 DEBUG : pacer: Reducing sleep to 84.470271ms
2021/06/01 05:25:25 DEBUG : pacer: Reducing sleep to 63.352703ms
2021/06/01 05:25:25 DEBUG : pacer: Reducing sleep to 47.514527ms
=== RUN   TestIntegration/FsMkdir/FsOpenWriterAt
    fstests.go:742: FS has no OpenWriterAt interface
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2021/06/01 05:25:25 DEBUG : dir: Making directory
2021/06/01 05:25:26 DEBUG : pacer: Reducing sleep to 35.635895ms
2021/06/01 05:25:26 DEBUG : pacer: Reducing sleep to 26.726921ms
2021/06/01 05:25:27 DEBUG : pacer: Reducing sleep to 20.04519ms
2021/06/01 05:25:27 DEBUG : dir/subdir1: Making directory
2021/06/01 05:25:27 DEBUG : pacer: Reducing sleep to 15.033892ms
2021/06/01 05:25:27 DEBUG : pacer: Reducing sleep to 11.275419ms
2021/06/01 05:25:27 DEBUG : dir/subdir3: Making directory
2021/06/01 05:25:28 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:25:28 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:28 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..)
2021/06/01 05:25:28 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:25:28 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:25:28 DEBUG : doChangeNotify("dir/subdir1", 2)
2021/06/01 05:25:29 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:29 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:25:29 DEBUG : dir/subdir2: Making directory
2021/06/01 05:25:29 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:25:29 DEBUG : doChangeNotify("dir/subdir3", 2)
2021/06/01 05:25:30 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:30 DEBUG : dir/file2: Uploading chunk 1/1
2021/06/01 05:25:30 DEBUG : doChangeNotify("dir/subdir2", 2)
2021/06/01 05:25:31 DEBUG : dir/file2: Uploading chunk 2/1
2021/06/01 05:25:31 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:31 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/dir/file2" to batch
2021/06/01 05:25:32 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:25:32 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/dir/file2
2021/06/01 05:25:32 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:32 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:25:33 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:25:33 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:33 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:25:34 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:25:34 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:35 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:25:35 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:35 DEBUG : doChangeNotify("dir/file2", 3)
2021/06/01 05:25:36 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:36 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/dir/file2
2021/06/01 05:25:36 DEBUG : dir/file4: Uploading chunk 1/1
2021/06/01 05:25:37 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:37 DEBUG : dir/file4: Uploading chunk 2/1
2021/06/01 05:25:38 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/dir/file4" to batch
2021/06/01 05:25:38 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:25:38 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/dir/file4
2021/06/01 05:25:39 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:25:39 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:25:40 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:25:40 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:25:41 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/dir/file4
2021/06/01 05:25:41 DEBUG : doChangeNotify("dir/file4", 3)
2021/06/01 05:25:41 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:42 DEBUG : dir/file3: Uploading chunk 1/1
2021/06/01 05:25:43 DEBUG : dir/file3: Uploading chunk 2/1
2021/06/01 05:25:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/dir/file3" to batch
2021/06/01 05:25:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:25:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/dir/file3
2021/06/01 05:25:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:25:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:25:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:25:46 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:25:47 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:25:48 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:25:49 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:25:51 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:25:51 DEBUG : doChangeNotify("dir/file3", 3)
2021/06/01 05:25:51 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:52 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:25:53 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/dir/file3
2021/06/01 05:25:54 DEBUG : doChangeNotify("dir/file2", 3)
2021/06/01 05:25:54 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:55 DEBUG : doChangeNotify("dir/file4", 3)
2021/06/01 05:25:55 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:56 DEBUG : doChangeNotify("dir/file3", 3)
2021/06/01 05:25:56 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:57 DEBUG : doChangeNotify("dir/subdir1", 3)
2021/06/01 05:25:57 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:25:59 DEBUG : doChangeNotify("dir/subdir3", 3)
2021/06/01 05:25:59 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:26:00 DEBUG : doChangeNotify("dir/subdir2", 3)
2021/06/01 05:26:00 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
=== RUN   TestIntegration/FsMkdir/FsPutFiles
2021/06/01 05:26:01 DEBUG : doChangeNotify("dir", 3)
2021/06/01 05:26:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:26:02 DEBUG : file name.txt: Uploading chunk 1/1
2021/06/01 05:26:03 DEBUG : file name.txt: Uploading chunk 2/1
2021/06/01 05:26:03 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/file name.txt" to batch
2021/06/01 05:26:04 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:26:04 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/file name.txt
2021/06/01 05:26:04 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:26:05 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:26:05 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:26:06 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:26:07 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:26:07 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:26:07 DEBUG : doChangeNotify("file name.txt", 3)
2021/06/01 05:26:07 DEBUG : Ignoring notify for file1 or file2: "file name.txt", 3
2021/06/01 05:26:07 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:26:08 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/file name.txt
2021/06/01 05:26:08 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Uploading chunk 1/1
2021/06/01 05:26:09 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Uploading chunk 2/1
2021/06/01 05:26:09 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" to batch
2021/06/01 05:26:10 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:26:10 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt
2021/06/01 05:26:10 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:26:11 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:26:11 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:26:11 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:26:12 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:26:13 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:26:14 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:26:15 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:26:16 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:26:16 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:26:18 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:26:18 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Checking for changes on remote
2021/06/01 05:26:19 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 10/120
2021/06/01 05:26:20 DEBUG : doChangeNotify("hello? sausage", 2)
2021/06/01 05:26:20 DEBUG : Ignoring notify for file1 or file2: "hello? sausage", 2
2021/06/01 05:26:20 DEBUG : doChangeNotify("hello? sausage/êé", 2)
2021/06/01 05:26:20 DEBUG : Ignoring notify for file1 or file2: "hello? sausage/êé", 2
2021/06/01 05:26:20 DEBUG : doChangeNotify("hello? sausage/êé/Hello, 世界", 2)
2021/06/01 05:26:20 DEBUG : Ignoring notify for file1 or file2: "hello? sausage/êé/Hello, 世界", 2
2021/06/01 05:26:20 DEBUG : doChangeNotify("hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠", 2)
2021/06/01 05:26:20 DEBUG : Ignoring notify for file1 or file2: "hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠", 2
2021/06/01 05:26:20 DEBUG : doChangeNotify("hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt", 3)
2021/06/01 05:26:20 DEBUG : Ignoring notify for file1 or file2: "hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt", 3
2021/06/01 05:26:20 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 11/120
2021/06/01 05:26:21 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt
2021/06/01 05:26:22 DEBUG : file name.txt: Uploading chunk 1/1
2021/06/01 05:26:23 DEBUG : file name.txt: Uploading chunk 2/1
2021/06/01 05:26:23 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/file name.txt" to batch
2021/06/01 05:26:24 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:26:24 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/file name.txt
2021/06/01 05:26:24 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:26:24 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:26:25 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:26:25 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:26:26 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:26:28 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/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
2021/06/01 05:26:32 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
2021/06/01 05:26:43 DEBUG : dirToPurge/fileToPurge.txt: Uploading chunk 1/1
2021/06/01 05:26:44 DEBUG : dirToPurge/fileToPurge.txt: Uploading chunk 2/1
2021/06/01 05:26:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/dirToPurge/fileToPurge.txt" to batch
2021/06/01 05:26:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:26:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/dirToPurge/fileToPurge.txt
2021/06/01 05:26:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:26:46 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:26:46 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:26:47 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:26:48 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:26:49 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:26:50 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:26:51 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:26:53 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:26:54 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 10/120
2021/06/01 05:26:55 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 11/120
2021/06/01 05:26:56 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 12/120
2021/06/01 05:26:58 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/dirToPurge/fileToPurge.txt
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsCopy
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsMove
2021/06/01 05:27:06 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2021/06/01 05:27:06 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:27:07 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:27:08 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:27:08 DEBUG : pacer: Reducing sleep to 10ms
2021/06/01 05:27:16 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2021/06/01 05:27:16 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:27:18 DEBUG : pacer: Reducing sleep to 15ms
2021/06/01 05:27:18 DEBUG : pacer: Reducing sleep to 11.25ms
2021/06/01 05:27:18 DEBUG : pacer: Reducing sleep to 10ms
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsDirMove
2021/06/01 05:27:21 DEBUG : Creating backend with remote "TestDropbox:rclone-test-kijujuz6jocusob4falebiy1"
2021/06/01 05:27:30 DEBUG : Dropbox root 'rclone-test-kijujuz6jocusob4falebiy1': 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:1338: MimeType method not supported
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime
    fstests.go:1359: 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
2021/06/01 05:27:38 DEBUG : file name.txt: Uploading chunk 1/1
2021/06/01 05:27:38 DEBUG : file name.txt: Uploading chunk 2/1
2021/06/01 05:27:39 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/file name.txt" to batch
2021/06/01 05:27:39 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:27:39 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/file name.txt
2021/06/01 05:27:40 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:27:40 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:27:41 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:27:41 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:27:42 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:27:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:27:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:27:46 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:27:47 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:27:48 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 10/120
2021/06/01 05:27:50 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 11/120
2021/06/01 05:27:51 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/file name.txt
=== RUN   TestIntegration/FsMkdir/FsPutFiles/ObjectStorable
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsIsFile
2021/06/01 05:27:52 DEBUG : Creating backend with remote "TestDropbox:rclone-test-maxocap8jusoned7zafohat5/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt"
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound
2021/06/01 05:27:53 DEBUG : Creating backend with remote "TestDropbox:rclone-test-maxocap8jusoned7zafohat5/not found.txt"
2021/06/01 05:27:53 ERROR : : error listing: directory not found
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot
    fstests.go:1496: Opening root remote "TestDropbox:" path "rclone-test-maxocap8jusoned7zafohat5" from "TestDropbox:rclone-test-maxocap8jusoned7zafohat5"
2021/06/01 05:27:53 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:1530: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put
2021/06/01 05:27:55 DEBUG : rclone-test-maxocap8jusoned7zafohat5/created from root.txt: Uploading chunk 1/1
2021/06/01 05:27:56 DEBUG : rclone-test-maxocap8jusoned7zafohat5/created from root.txt: Uploading chunk 2/1
2021/06/01 05:27:57 DEBUG : Dropbox root '': Adding "/rclone-test-maxocap8jusoned7zafohat5/created from root.txt" to batch
2021/06/01 05:27:57 DEBUG : Dropbox root '': Batch idle for 500ms so committing
2021/06/01 05:27:57 DEBUG : Dropbox root '': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/created from root.txt
2021/06/01 05:27:58 DEBUG : Dropbox root '': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:27:58 DEBUG : Dropbox root '': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:27:58 DEBUG : Dropbox root '': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:27:59 DEBUG : Dropbox root '': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:28:00 DEBUG : Dropbox root '': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:28:01 DEBUG : Dropbox root '': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:28:02 DEBUG : Dropbox root '': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:28:04 DEBUG : Dropbox root '': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:28:05 DEBUG : Dropbox root '': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:28:06 DEBUG : Dropbox root '': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/created from root.txt
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove
=== RUN   TestIntegration/FsMkdir/FsPutFiles/PublicLink
2021/06/01 05:28:11 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': attempting to share 'file name.txt_does_not_exist' (absolute path: /rclone-test-maxocap8jusoned7zafohat5/file name.txt_does_not_exist)
2021/06/01 05:28:11 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': attempting to share 'file name.txt' (absolute path: /rclone-test-maxocap8jusoned7zafohat5/file name.txt)
    fstests.go:1601: 
        	Error Trace:	fstests.go:1601
        	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:1674: 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
2021/06/01 05:28:14 DEBUG : piped data.txt: Uploading chunk 1/0
2021/06/01 05:28:14 DEBUG : piped data.txt: Uploading chunk 2/0
2021/06/01 05:28:15 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/piped data.txt" to batch
2021/06/01 05:28:15 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:28:15 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/piped data.txt
2021/06/01 05:28:16 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:28:16 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:28:16 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:28:17 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:28:18 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:28:19 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/piped data.txt
=== RUN   TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100
2021/06/01 05:28:21 DEBUG : piped data.txt: Uploading chunk 1/0
2021/06/01 05:28:21 DEBUG : piped data.txt: Uploading chunk 2/0
2021/06/01 05:28:22 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/piped data.txt" to batch
2021/06/01 05:28:22 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:28:22 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/piped data.txt
2021/06/01 05:28:23 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:28:23 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:28:23 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:28:24 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:28:25 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:28:26 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:28:28 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:28:29 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:28:30 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:28:31 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 10/120
2021/06/01 05:28:33 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/piped data.txt
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal
    fstests.go:1765: *dropbox.Fs does not implement InternalTester
=== RUN   TestIntegration/FsMkdir/FsPutChunked
=== RUN   TestIntegration/FsMkdir/FsPutChunked/100
=== RUN   TestIntegration/FsMkdir/FsPutChunked/100/99
2021/06/01 05:28:34 DEBUG : chunked-100-99.bin: Uploading chunk 1/1
2021/06/01 05:28:35 DEBUG : chunked-100-99.bin: Uploading chunk 2/1
2021/06/01 05:28:35 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-100-99.bin" to batch
2021/06/01 05:28:36 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:28:36 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-100-99.bin
2021/06/01 05:28:36 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:28:37 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:28:37 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:28:38 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:28:39 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:28:40 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:28:41 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:28:42 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:28:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:28:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 10/120
2021/06/01 05:28:46 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 11/120
2021/06/01 05:28:47 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-100-99.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/100/100
2021/06/01 05:28:50 DEBUG : chunked-100-100.bin: Uploading chunk 1/1
2021/06/01 05:28:50 DEBUG : chunked-100-100.bin: Uploading chunk 2/1
2021/06/01 05:28:51 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-100-100.bin" to batch
2021/06/01 05:28:51 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:28:51 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-100-100.bin
2021/06/01 05:28:52 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:28:52 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:28:52 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:28:53 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:28:54 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:28:55 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-100-100.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/100/201
2021/06/01 05:28:57 DEBUG : chunked-100-201.bin: Uploading chunk 1/3
2021/06/01 05:28:58 DEBUG : chunked-100-201.bin: Uploading chunk 2/3
2021/06/01 05:28:59 DEBUG : chunked-100-201.bin: Uploading chunk 3/3
2021/06/01 05:28:59 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-100-201.bin" to batch
2021/06/01 05:29:00 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:29:00 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-100-201.bin
2021/06/01 05:29:00 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:29:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:29:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:29:02 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:29:03 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:29:04 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:29:05 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:29:07 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:29:08 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-100-201.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/128
=== RUN   TestIntegration/FsMkdir/FsPutChunked/128/127
2021/06/01 05:29:10 DEBUG : chunked-128-127.bin: Uploading chunk 1/1
2021/06/01 05:29:10 DEBUG : chunked-128-127.bin: Uploading chunk 2/1
2021/06/01 05:29:11 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-128-127.bin" to batch
2021/06/01 05:29:11 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:29:11 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-128-127.bin
2021/06/01 05:29:12 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:29:12 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:29:13 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:29:13 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-128-127.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/128/128
2021/06/01 05:29:16 DEBUG : chunked-128-128.bin: Uploading chunk 1/1
2021/06/01 05:29:16 DEBUG : chunked-128-128.bin: Uploading chunk 2/1
2021/06/01 05:29:17 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-128-128.bin" to batch
2021/06/01 05:29:17 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:29:17 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-128-128.bin
2021/06/01 05:29:18 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:29:18 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:29:19 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:29:19 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-128-128.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/128/257
2021/06/01 05:29:21 DEBUG : chunked-128-257.bin: Uploading chunk 1/3
2021/06/01 05:29:22 DEBUG : chunked-128-257.bin: Uploading chunk 2/3
2021/06/01 05:29:23 DEBUG : chunked-128-257.bin: Uploading chunk 3/3
2021/06/01 05:29:23 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-128-257.bin" to batch
2021/06/01 05:29:24 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:29:24 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-128-257.bin
2021/06/01 05:29:24 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:29:25 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:29:25 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:29:26 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:29:27 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:29:28 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:29:29 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-128-257.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/97.656Ki
=== RUN   TestIntegration/FsMkdir/FsPutChunked/97.656Ki/99999
2021/06/01 05:29:31 DEBUG : chunked-97.656Ki-97.655Ki.bin: Uploading chunk 1/1
2021/06/01 05:29:32 DEBUG : chunked-97.656Ki-97.655Ki.bin: Uploading chunk 2/1
2021/06/01 05:29:33 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-97.656Ki-97.655Ki.bin" to batch
2021/06/01 05:29:34 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:29:34 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-97.656Ki-97.655Ki.bin
2021/06/01 05:29:34 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:29:34 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:29:35 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:29:35 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:29:36 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:29:38 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-97.656Ki-97.655Ki.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/97.656Ki/100000
2021/06/01 05:29:40 DEBUG : chunked-97.656Ki-97.656Ki.bin: Uploading chunk 1/1
2021/06/01 05:29:41 DEBUG : chunked-97.656Ki-97.656Ki.bin: Uploading chunk 2/1
2021/06/01 05:29:42 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-97.656Ki-97.656Ki.bin" to batch
2021/06/01 05:29:42 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:29:42 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-97.656Ki-97.656Ki.bin
2021/06/01 05:29:43 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:29:43 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:29:43 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:29:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:29:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:29:46 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:29:47 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:29:49 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:29:50 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-97.656Ki-97.656Ki.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/97.656Ki/200001
2021/06/01 05:29:52 DEBUG : chunked-97.656Ki-195.313Ki.bin: Uploading chunk 1/3
2021/06/01 05:29:53 DEBUG : chunked-97.656Ki-195.313Ki.bin: Uploading chunk 2/3
2021/06/01 05:29:54 DEBUG : chunked-97.656Ki-195.313Ki.bin: Uploading chunk 3/3
2021/06/01 05:29:54 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-97.656Ki-195.313Ki.bin" to batch
2021/06/01 05:29:55 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:29:55 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-97.656Ki-195.313Ki.bin
2021/06/01 05:29:55 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:29:55 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:29:56 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:29:56 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:29:58 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:29:59 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:30:00 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-97.656Ki-195.313Ki.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/97.657Ki
=== RUN   TestIntegration/FsMkdir/FsPutChunked/97.657Ki/100000
2021/06/01 05:30:03 DEBUG : chunked-97.657Ki-97.656Ki.bin: Uploading chunk 1/1
2021/06/01 05:30:03 DEBUG : chunked-97.657Ki-97.656Ki.bin: Uploading chunk 2/1
2021/06/01 05:30:04 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-97.657Ki-97.656Ki.bin" to batch
2021/06/01 05:30:05 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:30:05 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-97.657Ki-97.656Ki.bin
2021/06/01 05:30:05 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:30:05 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:30:06 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:30:06 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:30:08 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:30:09 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:30:10 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:30:11 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:30:12 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:30:14 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 10/120
2021/06/01 05:30:15 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 11/120
2021/06/01 05:30:16 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 12/120
2021/06/01 05:30:17 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-97.657Ki-97.656Ki.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/97.657Ki/100001
2021/06/01 05:30:20 DEBUG : chunked-97.657Ki-97.657Ki.bin: Uploading chunk 1/1
2021/06/01 05:30:20 DEBUG : chunked-97.657Ki-97.657Ki.bin: Uploading chunk 2/1
2021/06/01 05:30:21 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-97.657Ki-97.657Ki.bin" to batch
2021/06/01 05:30:21 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:30:21 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-97.657Ki-97.657Ki.bin
2021/06/01 05:30:22 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:30:22 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:30:22 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:30:23 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:30:24 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:30:25 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:30:26 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:30:28 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-97.657Ki-97.657Ki.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/97.657Ki/200003
2021/06/01 05:30:30 DEBUG : chunked-97.657Ki-195.315Ki.bin: Uploading chunk 1/3
2021/06/01 05:30:30 DEBUG : chunked-97.657Ki-195.315Ki.bin: Uploading chunk 2/3
2021/06/01 05:30:31 DEBUG : chunked-97.657Ki-195.315Ki.bin: Uploading chunk 3/3
2021/06/01 05:30:32 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-97.657Ki-195.315Ki.bin" to batch
2021/06/01 05:30:32 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:30:32 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-97.657Ki-195.315Ki.bin
2021/06/01 05:30:33 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:30:33 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:30:33 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:30:34 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:30:35 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:30:36 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:30:37 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:30:39 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:30:40 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-97.657Ki-195.315Ki.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/682.731Ki
=== RUN   TestIntegration/FsMkdir/FsPutChunked/682.731Ki/699116
2021/06/01 05:30:42 DEBUG : chunked-682.731Ki-682.730Ki.bin: Uploading chunk 1/1
2021/06/01 05:30:43 DEBUG : chunked-682.731Ki-682.730Ki.bin: Uploading chunk 2/1
2021/06/01 05:30:43 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-682.731Ki-682.730Ki.bin" to batch
2021/06/01 05:30:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:30:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-682.731Ki-682.730Ki.bin
2021/06/01 05:30:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:30:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:30:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:30:46 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:30:47 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:30:48 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:30:49 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-682.731Ki-682.730Ki.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/682.731Ki/699117
2021/06/01 05:30:51 DEBUG : chunked-682.731Ki-682.731Ki.bin: Uploading chunk 1/1
2021/06/01 05:30:53 DEBUG : chunked-682.731Ki-682.731Ki.bin: Uploading chunk 2/1
2021/06/01 05:30:53 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-682.731Ki-682.731Ki.bin" to batch
2021/06/01 05:30:53 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:30:53 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-682.731Ki-682.731Ki.bin
2021/06/01 05:30:54 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:30:54 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:30:55 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:30:55 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:30:56 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:30:58 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:30:59 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:31:00 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:31:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:31:03 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 10/120
2021/06/01 05:31:04 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-682.731Ki-682.731Ki.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/682.731Ki/1398235
2021/06/01 05:31:06 DEBUG : chunked-682.731Ki-1.333Mi.bin: Uploading chunk 1/3
2021/06/01 05:31:08 DEBUG : chunked-682.731Ki-1.333Mi.bin: Uploading chunk 2/3
2021/06/01 05:31:09 DEBUG : chunked-682.731Ki-1.333Mi.bin: Uploading chunk 3/3
2021/06/01 05:31:10 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-682.731Ki-1.333Mi.bin" to batch
2021/06/01 05:31:10 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:31:10 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-682.731Ki-1.333Mi.bin
2021/06/01 05:31:11 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:31:11 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:31:11 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:31:12 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:31:13 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:31:14 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:31:16 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:31:17 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:31:18 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-682.731Ki-1.333Mi.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/2Mi
=== RUN   TestIntegration/FsMkdir/FsPutChunked/2Mi/2097151
2021/06/01 05:31:20 DEBUG : chunked-2Mi-2.000Mi.bin: Uploading chunk 1/1
2021/06/01 05:31:21 DEBUG : chunked-2Mi-2.000Mi.bin: Uploading chunk 2/1
2021/06/01 05:31:22 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-2Mi-2.000Mi.bin" to batch
2021/06/01 05:31:22 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:31:22 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-2Mi-2.000Mi.bin
2021/06/01 05:31:23 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:31:23 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:31:23 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:31:24 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:31:25 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:31:26 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:31:27 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-2Mi-2.000Mi.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/2Mi/2097152
2021/06/01 05:31:30 DEBUG : chunked-2Mi-2Mi.bin: Uploading chunk 1/1
2021/06/01 05:31:31 DEBUG : chunked-2Mi-2Mi.bin: Uploading chunk 2/1
2021/06/01 05:31:31 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-2Mi-2Mi.bin" to batch
2021/06/01 05:31:32 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:31:32 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-2Mi-2Mi.bin
2021/06/01 05:31:32 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:31:33 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:31:33 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:31:34 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:31:35 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-2Mi-2Mi.bin
=== RUN   TestIntegration/FsMkdir/FsPutChunked/2Mi/4194305
2021/06/01 05:31:37 DEBUG : chunked-2Mi-4.000Mi.bin: Uploading chunk 1/3
2021/06/01 05:31:38 DEBUG : chunked-2Mi-4.000Mi.bin: Uploading chunk 2/3
2021/06/01 05:31:39 DEBUG : chunked-2Mi-4.000Mi.bin: Uploading chunk 3/3
2021/06/01 05:31:40 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/chunked-2Mi-4.000Mi.bin" to batch
2021/06/01 05:31:41 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:31:41 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-2Mi-4.000Mi.bin
2021/06/01 05:31:41 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:31:42 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:31:42 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:31:43 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:31:44 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:31:45 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:31:46 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:31:47 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:31:49 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:31:50 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/chunked-2Mi-4.000Mi.bin
=== RUN   TestIntegration/FsMkdir/FsUploadUnknownSize
=== RUN   TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize
2021/06/01 05:31:52 DEBUG : unknown-size-put.txt: Uploading chunk 1/0
2021/06/01 05:31:53 DEBUG : unknown-size-put.txt: Uploading chunk 2/0
2021/06/01 05:31:54 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/unknown-size-put.txt" to batch
2021/06/01 05:31:54 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:31:54 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/unknown-size-put.txt
2021/06/01 05:31:55 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:31:55 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:31:55 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:31:56 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:31:57 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:31:58 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committed sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/unknown-size-put.txt
=== RUN   TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize
2021/06/01 05:31:59 DEBUG : unknown-size-update.txt: Uploading chunk 1/1
2021/06/01 05:32:00 DEBUG : unknown-size-update.txt: Uploading chunk 2/1
2021/06/01 05:32:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Adding "/rclone-test-maxocap8jusoned7zafohat5/unknown-size-update.txt" to batch
2021/06/01 05:32:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Batch idle for 500ms so committing
2021/06/01 05:32:01 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Committing sync batch length 1 starting with: /rclone-test-maxocap8jusoned7zafohat5/unknown-size-update.txt
2021/06/01 05:32:02 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/06/01 05:32:02 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/06/01 05:32:02 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/06/01 05:32:03 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/06/01 05:32:04 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/06/01 05:32:05 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/06/01 05:32:06 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/06/01 05:32:08 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/06/01 05:32:09 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/06/01 05:32:10 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 10/120
2021/06/01 05:32:11 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 11/120
2021/06/01 05:32:12 DEBUG : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Wait for batch: sleeping for 1s after status: "in_progress": try 12/120
2021/06/01 05:32:14 ERROR : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': sync batch commit: failed to commit batch length 1: batch had 1 errors: last error: too_many_write_operations
    fstests.go:153: 
        	Error Trace:	fstests.go:153
        	            				fstests.go:187
        	            				fstests.go:212
        	            				fstests.go:217
        	            				fstests.go:1910
        	Error:      	Received unexpected error:
        	            	batch upload failed: too_many_write_operations
        	            	github.com/rclone/rclone/backend/dropbox.(*batcher).commitBatch
        	            		/home/rclone/go/src/github.com/rclone/rclone/backend/dropbox/batcher.go:245
        	            	github.com/rclone/rclone/backend/dropbox.(*batcher).commitLoop.func1
        	            		/home/rclone/go/src/github.com/rclone/rclone/backend/dropbox/batcher.go:270
        	            	github.com/rclone/rclone/backend/dropbox.(*batcher).commitLoop
        	            		/home/rclone/go/src/github.com/rclone/rclone/backend/dropbox/batcher.go:299
        	            	runtime.goexit
        	            		/usr/local/go/src/runtime/asm_amd64.s:1374
        	            	upload failed
        	            	github.com/rclone/rclone/backend/dropbox.(*Object).Update
        	            		/home/rclone/go/src/github.com/rclone/rclone/backend/dropbox/dropbox.go:1762
        	            	github.com/rclone/rclone/backend/dropbox.(*Fs).Put
        	            		/home/rclone/go/src/github.com/rclone/rclone/backend/dropbox/dropbox.go:926
        	            	github.com/rclone/rclone/fstest/fstests.putTestContentsMimeType.func1
        	            		/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:197
        	            	github.com/rclone/rclone/fstest/fstests.retry
        	            		/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:145
        	            	github.com/rclone/rclone/fstest/fstests.putTestContentsMimeType
        	            		/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:187
        	            	github.com/rclone/rclone/fstest/fstests.PutTestContents
        	            		/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:212
        	            	github.com/rclone/rclone/fstest/fstests.testPut
        	            		/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:217
        	            	github.com/rclone/rclone/fstest/fstests.Run.func13.15.2
        	            		/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:1910
        	            	testing.tRunner
        	            		/usr/local/go/src/testing/testing.go:1123
        	            	runtime.goexit
        	            		/usr/local/go/src/runtime/asm_amd64.s:1374
        	Test:       	TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize
        	Messages:   	Put
=== RUN   TestIntegration/FsMkdir/FsRootCollapse
2021/06/01 05:32:14 DEBUG : Creating backend with remote "TestDropbox:rclone-test-maxocap8jusoned7zafohat5/deeper/nonexisting/directory"
2021/06/01 05:32:16 ERROR : : error listing: directory not found
=== CONT  TestIntegration/FsMkdir
    fstests.go:1964: Warning: this should produce fs.ErrorDirNotFound
=== RUN   TestIntegration/FsShutdown
2021/06/01 05:32:16 INFO  : Dropbox root 'rclone-test-maxocap8jusoned7zafohat5': Commiting uploads - please wait...
--- FAIL: TestIntegration (688.93s)
    --- SKIP: TestIntegration/FsCheckWrap (0.00s)
    --- SKIP: TestIntegration/FsCommand (0.00s)
    --- PASS: TestIntegration/FsRmdirNotFound (0.30s)
    --- PASS: TestIntegration/FsString (0.00s)
    --- PASS: TestIntegration/FsName (0.00s)
    --- PASS: TestIntegration/FsRoot (0.00s)
    --- PASS: TestIntegration/FsRmdirEmpty (1.37s)
    --- FAIL: TestIntegration/FsMkdir (685.75s)
        --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (5.32s)
        --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.24s)
        --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.27s)
        --- SKIP: TestIntegration/FsMkdir/FsListRDirEmpty (0.00s)
        --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.21s)
        --- SKIP: TestIntegration/FsMkdir/FsListRDirNotFound (0.00s)
        --- PASS: TestIntegration/FsMkdir/FsEncoding (250.12s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (12.23s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (15.51s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (14.30s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (15.16s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (16.49s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (10.37s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (14.42s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (21.85s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (10.96s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (21.64s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (10.35s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (12.73s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (7.61s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (15.22s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (18.97s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (12.40s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (8.98s)
            --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (10.56s)
        --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.45s)
        --- PASS: TestIntegration/FsMkdir/FsPutError (7.19s)
        --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (10.18s)
        --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s)
        --- PASS: TestIntegration/FsMkdir/FsChangeNotify (36.06s)
        --- FAIL: TestIntegration/FsMkdir/FsPutFiles (152.08s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (1.35s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (1.78s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (1.22s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (5.33s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.66s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.54s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (1.77s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.24s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.46s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive/Dir (0.23s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (1.45s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.35s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (18.32s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (3.66s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (15.51s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (9.79s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.53s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.24s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.23s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.36s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.24s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.23s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.23s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (0.24s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.25s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.72s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (0.70s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (2.14s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.75s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (14.76s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.34s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (0.51s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (0.50s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (17.20s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (1.35s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.28s)
                --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (0.00s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (15.38s)
                    --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (2.81s)
            --- FAIL: TestIntegration/FsMkdir/FsPutFiles/PublicLink (0.46s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (2.43s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.25s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (19.88s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (6.58s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (13.30s)
            --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Internal (0.00s)
        --- PASS: TestIntegration/FsMkdir/FsPutChunked (198.33s)
            --- PASS: TestIntegration/FsMkdir/FsPutChunked/100 (35.80s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/100/99 (15.57s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/100/100 (7.63s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/100/201 (12.60s)
            --- PASS: TestIntegration/FsMkdir/FsPutChunked/128 (21.45s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/128/127 (5.80s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/128/128 (5.81s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/128/257 (9.84s)
            --- PASS: TestIntegration/FsMkdir/FsPutChunked/97.656Ki (31.41s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/97.656Ki/99999 (9.14s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/97.656Ki/100000 (11.74s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/97.656Ki/200001 (10.53s)
            --- PASS: TestIntegration/FsMkdir/FsPutChunked/97.657Ki (39.69s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/97.657Ki/100000 (17.13s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/97.657Ki/100001 (10.03s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/97.657Ki/200003 (12.53s)
            --- PASS: TestIntegration/FsMkdir/FsPutChunked/682.731Ki (38.09s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/682.731Ki/699116 (9.08s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/682.731Ki/699117 (14.73s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/682.731Ki/1398235 (14.28s)
            --- PASS: TestIntegration/FsMkdir/FsPutChunked/2Mi (31.89s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/2Mi/2097151 (9.85s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/2Mi/2097152 (7.09s)
                --- PASS: TestIntegration/FsMkdir/FsPutChunked/2Mi/4194305 (14.95s)
        --- FAIL: TestIntegration/FsMkdir/FsUploadUnknownSize (21.85s)
            --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (7.04s)
            --- FAIL: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (14.81s)
        --- PASS: TestIntegration/FsMkdir/FsRootCollapse (0.48s)
    --- PASS: TestIntegration/FsShutdown (0.00s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/dropbox	688.955s
"go test -v -timeout 1h0m0s -remote TestDropbox: -verbose" - Finished ERROR in 11m31.842165574s (try 1/5): exit status 1: Failed [TestIntegration/FsMkdir/FsPutFiles/PublicLink TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize]