"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestCopyFileCopyDest$'" - Starting (try 4/5)
2021/07/25 05:21:52 DEBUG : Creating backend with remote "TestDropbox:rclone-test-pixejil8cuwinuz7xowudoy5"
2021/07/25 05:21:52 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2021/07/25 05:21:52 DEBUG : Creating backend with remote "/tmp/rclone476970577"
=== RUN   TestCopyFileCopyDest
    run.go:176: Remote "Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5'", Local "Local file system at /tmp/rclone476970577", Modify Window "1s"
2021/07/25 05:21:52 DEBUG : Creating backend with remote "TestDropbox:rclone-test-pixejil8cuwinuz7xowudoy5/dst"
2021/07/25 05:21:52 DEBUG : Creating backend with remote "TestDropbox:rclone-test-pixejil8cuwinuz7xowudoy5/CopyDest"
2021/07/25 05:21:53 DEBUG : one: Need to transfer - File not found at Destination
2021/07/25 05:21:53 DEBUG : one: Uploading chunk 1/1
2021/07/25 05:21:54 DEBUG : one: Uploading chunk 2/1
2021/07/25 05:21:54 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Adding "/rclone-test-pixejil8cuwinuz7xowudoy5/dst/one" to batch
2021/07/25 05:21:54 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Batch idle for 500ms so committing
2021/07/25 05:21:54 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Committing sync batch length 1 starting with: /rclone-test-pixejil8cuwinuz7xowudoy5/dst/one
2021/07/25 05:21:55 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/07/25 05:21:55 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/07/25 05:21:55 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/07/25 05:21:56 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/07/25 05:21:57 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/07/25 05:21:58 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/07/25 05:21:59 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/07/25 05:22:00 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/07/25 05:22:01 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/07/25 05:22:03 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Committed sync batch length 1 starting with: /rclone-test-pixejil8cuwinuz7xowudoy5/dst/one
2021/07/25 05:22:03 DEBUG : one: dropbox = fe8d7a873dc48961a6af334c996b2cb3ce37149d5ce9c9253952a54c6a92c1ad OK
2021/07/25 05:22:03 INFO  : one: Copied (new)
2021/07/25 05:22:04 DEBUG : one: Sizes differ (src 5 vs dst 3)
2021/07/25 05:22:04 DEBUG : one: Uploading chunk 1/1
2021/07/25 05:22:04 DEBUG : one: Uploading chunk 2/1
2021/07/25 05:22:05 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Adding "/rclone-test-pixejil8cuwinuz7xowudoy5/dst/one" to batch
2021/07/25 05:22:05 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Batch idle for 500ms so committing
2021/07/25 05:22:05 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Committing sync batch length 1 starting with: /rclone-test-pixejil8cuwinuz7xowudoy5/dst/one
2021/07/25 05:22:05 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/07/25 05:22:06 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/07/25 05:22:06 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/07/25 05:22:06 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/07/25 05:22:07 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/07/25 05:22:09 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5/dst': Committed sync batch length 1 starting with: /rclone-test-pixejil8cuwinuz7xowudoy5/dst/one
2021/07/25 05:22:09 DEBUG : one: dropbox = 7da8d117f9fb226817d81adc15c72720c9879c8b7fb821d27f217d198bd79d0d OK
2021/07/25 05:22:09 INFO  : one: Copied (replaced existing)
2021/07/25 05:22:10 DEBUG : dst/one: Uploading chunk 1/1
2021/07/25 05:22:10 DEBUG : dst/one: Uploading chunk 2/1
2021/07/25 05:22:10 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Adding "/rclone-test-pixejil8cuwinuz7xowudoy5/dst/one" to batch
2021/07/25 05:22:11 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Batch idle for 500ms so committing
2021/07/25 05:22:11 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Committing sync batch length 1 starting with: /rclone-test-pixejil8cuwinuz7xowudoy5/dst/one
2021/07/25 05:22:11 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 100ms after status: "in_progress": try 1/120
2021/07/25 05:22:11 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 200ms after status: "in_progress": try 2/120
2021/07/25 05:22:12 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 400ms after status: "in_progress": try 3/120
2021/07/25 05:22:12 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 800ms after status: "in_progress": try 4/120
2021/07/25 05:22:13 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 1s after status: "in_progress": try 5/120
2021/07/25 05:22:14 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 1s after status: "in_progress": try 6/120
2021/07/25 05:22:15 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 1s after status: "in_progress": try 7/120
2021/07/25 05:22:16 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 1s after status: "in_progress": try 8/120
2021/07/25 05:22:18 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 1s after status: "in_progress": try 9/120
2021/07/25 05:22:19 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 1s after status: "in_progress": try 10/120
2021/07/25 05:22:20 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 1s after status: "in_progress": try 11/120
2021/07/25 05:22:21 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 1s after status: "in_progress": try 12/120
2021/07/25 05:22:22 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 1s after status: "in_progress": try 13/120
2021/07/25 05:22:23 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 1s after status: "in_progress": try 14/120
2021/07/25 05:22:24 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 1s after status: "in_progress": try 15/120
2021/07/25 05:22:26 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 1s after status: "in_progress": try 16/120
2021/07/25 05:22:27 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 1s after status: "in_progress": try 17/120
2021/07/25 05:22:28 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 1s after status: "in_progress": try 18/120
2021/07/25 05:22:29 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 1s after status: "in_progress": try 19/120
2021/07/25 05:22:30 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Wait for batch: sleeping for 1s after status: "in_progress": try 20/120
2021/07/25 05:22:31 ERROR : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': sync batch commit: failed to commit batch length 1: batch had 1 errors: last error: too_many_write_operations
    run.go:282: Failed to put "dst/one" to "Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5'": upload failed: batch upload failed: too_many_write_operations
--- FAIL: TestCopyFileCopyDest (42.70s)
FAIL
2021/07/25 05:22:35 DEBUG : Dropbox root 'rclone-test-pixejil8cuwinuz7xowudoy5': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestCopyFileCopyDest$'" - Finished ERROR in 43.854717145s (try 4/5): exit status 1: Failed [TestCopyFileCopyDest]