"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestCaseInsensitiveMoveFileDryRun|TestCopyFileCopyDest|TestDirMove|TestListDirSortedFn|TestLsLong)$'" - Starting (try 2/5)
2025/06/30 01:46:35 DEBUG : Creating backend with remote "TestDropbox:rclone-test-huyulir1mexu"
2025/06/30 01:46:35 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/06/30 01:46:36 DEBUG : Creating backend with remote "/tmp/rclone1274111692"
=== RUN   TestCopyFileCopyDest
    run.go:180: Remote "Dropbox root 'rclone-test-huyulir1mexu'", Local "Local file system at /tmp/rclone1274111692", Modify Window "1s"
2025/06/30 01:46:36 DEBUG : Creating backend with remote "TestDropbox:rclone-test-huyulir1mexu/dst"
2025/06/30 01:46:36 DEBUG : Creating backend with remote "TestDropbox:rclone-test-huyulir1mexu/CopyDest"
2025/06/30 01:46:36 DEBUG : one: Need to transfer - File not found at Destination
2025/06/30 01:46:37 DEBUG : one: Uploading chunk 1/1
2025/06/30 01:46:37 DEBUG : one: Uploading chunk 2/1
2025/06/30 01:46:38 DEBUG : Dropbox root 'rclone-test-huyulir1mexu/dst': Adding "one" to batch
2025/06/30 01:46:38 DEBUG : Dropbox root 'rclone-test-huyulir1mexu/dst': Batch idle for 500ms so committing
2025/06/30 01:46:38 DEBUG : Dropbox root 'rclone-test-huyulir1mexu/dst': Committing sync batch length 1 starting with: one
2025/06/30 01:46:40 DEBUG : Dropbox root 'rclone-test-huyulir1mexu/dst': Committed sync batch length 1 starting with: one
2025/06/30 01:46:40 DEBUG : one: dropbox = fe8d7a873dc48961a6af334c996b2cb3ce37149d5ce9c9253952a54c6a92c1ad OK
2025/06/30 01:46:40 INFO  : one: Copied (new)
2025/06/30 01:46:42 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/06/30 01:46:42 DEBUG : one: Uploading chunk 1/1
2025/06/30 01:46:42 DEBUG : one: Uploading chunk 2/1
2025/06/30 01:46:43 DEBUG : Dropbox root 'rclone-test-huyulir1mexu/dst': Adding "one" to batch
2025/06/30 01:46:43 DEBUG : Dropbox root 'rclone-test-huyulir1mexu/dst': Batch idle for 500ms so committing
2025/06/30 01:46:43 DEBUG : Dropbox root 'rclone-test-huyulir1mexu/dst': Committing sync batch length 1 starting with: one
2025/06/30 01:46:44 DEBUG : Dropbox root 'rclone-test-huyulir1mexu/dst': Committed sync batch length 1 starting with: one
2025/06/30 01:46:44 DEBUG : one: dropbox = 7da8d117f9fb226817d81adc15c72720c9879c8b7fb821d27f217d198bd79d0d OK
2025/06/30 01:46:44 INFO  : one: Copied (replaced existing)
2025/06/30 01:46:45 DEBUG : dst/one: Uploading chunk 1/1
2025/06/30 01:46:45 DEBUG : dst/one: Uploading chunk 2/1
2025/06/30 01:46:46 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Adding "dst/one" to batch
2025/06/30 01:46:46 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Batch idle for 500ms so committing
2025/06/30 01:46:46 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committing sync batch length 1 starting with: dst/one
2025/06/30 01:46:47 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committed sync batch length 1 starting with: dst/one
2025/06/30 01:46:48 DEBUG : CopyDest/one: Uploading chunk 1/1
2025/06/30 01:46:48 DEBUG : CopyDest/one: Uploading chunk 2/1
2025/06/30 01:46:48 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Adding "CopyDest/one" to batch
2025/06/30 01:46:49 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Batch idle for 500ms so committing
2025/06/30 01:46:49 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committing sync batch length 1 starting with: CopyDest/one
2025/06/30 01:46:50 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committed sync batch length 1 starting with: CopyDest/one
2025/06/30 01:46:51 DEBUG : Creating backend with remote "TestDropbox:rclone-test-huyulir1mexu/BackupDir"
2025/06/30 01:46:51 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/06/30 01:46:51 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/06/30 01:46:51 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/06/30 01:46:52 DEBUG : pacer: low level retry 1/10 (error from_write/too_many_write_operations/...)
2025/06/30 01:46:52 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/06/30 01:46:54 DEBUG : pacer: Reducing sleep to 15ms
2025/06/30 01:46:54 INFO  : one: Moved (server-side)
2025/06/30 01:46:55 DEBUG : one: dropbox = 7da8d117f9fb226817d81adc15c72720c9879c8b7fb821d27f217d198bd79d0d OK
2025/06/30 01:46:55 INFO  : one: Copied (server-side copy)
2025/06/30 01:46:55 DEBUG : one: Destination found in --copy-dest, using server-side copy
2025/06/30 01:46:56 DEBUG : CopyDest/two: Uploading chunk 1/1
2025/06/30 01:46:56 DEBUG : CopyDest/two: Uploading chunk 2/1
2025/06/30 01:46:57 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Adding "CopyDest/two" to batch
2025/06/30 01:46:57 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Batch idle for 500ms so committing
2025/06/30 01:46:57 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committing sync batch length 1 starting with: CopyDest/two
2025/06/30 01:46:58 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committed sync batch length 1 starting with: CopyDest/two
2025/06/30 01:46:59 DEBUG : two: Need to transfer - File not found at Destination
2025/06/30 01:46:59 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/06/30 01:47:00 DEBUG : two: dropbox = 313db63e0283ab2a9bc72e9a0ac749da1d3acf708fcd1a7848767244dd2f17e8 OK
2025/06/30 01:47:00 INFO  : two: Copied (server-side copy)
2025/06/30 01:47:00 DEBUG : two: Destination found in --copy-dest, using server-side copy
2025/06/30 01:47:01 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/06/30 01:47:01 DEBUG : two: Unchanged skipping
2025/06/30 01:47:02 DEBUG : CopyDest/three: Uploading chunk 1/1
2025/06/30 01:47:02 DEBUG : CopyDest/three: Uploading chunk 2/1
2025/06/30 01:47:03 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Adding "CopyDest/three" to batch
2025/06/30 01:47:03 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Batch idle for 500ms so committing
2025/06/30 01:47:03 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committing sync batch length 1 starting with: CopyDest/three
2025/06/30 01:47:04 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committed sync batch length 1 starting with: CopyDest/three
2025/06/30 01:47:05 DEBUG : three: Need to transfer - File not found at Destination
2025/06/30 01:47:05 DEBUG : three: Sizes differ (src 7 vs dst 5)
2025/06/30 01:47:05 DEBUG : three: Destination not found in --copy-dest
2025/06/30 01:47:05 DEBUG : three: Uploading chunk 1/1
2025/06/30 01:47:06 DEBUG : three: Uploading chunk 2/1
2025/06/30 01:47:06 DEBUG : Dropbox root 'rclone-test-huyulir1mexu/dst': Adding "three" to batch
2025/06/30 01:47:06 DEBUG : Dropbox root 'rclone-test-huyulir1mexu/dst': Batch idle for 500ms so committing
2025/06/30 01:47:06 DEBUG : Dropbox root 'rclone-test-huyulir1mexu/dst': Committing sync batch length 1 starting with: three
2025/06/30 01:47:07 DEBUG : Dropbox root 'rclone-test-huyulir1mexu/dst': Committed sync batch length 1 starting with: three
2025/06/30 01:47:07 DEBUG : three: dropbox = 78af7ad260025156e50fd9648fd9a36509cd3541c9a8d02597e9b3c598937cbd OK
2025/06/30 01:47:07 INFO  : three: Copied (new)
2025/06/30 01:47:17 INFO  : Dropbox root 'rclone-test-huyulir1mexu/BackupDir': Committing uploads - please wait...
2025/06/30 01:47:17 INFO  : Dropbox root 'rclone-test-huyulir1mexu/CopyDest': Committing uploads - please wait...
--- PASS: TestCopyFileCopyDest (41.34s)
=== RUN   TestListDirSortedFn
    run.go:180: Remote "Dropbox root 'rclone-test-huyulir1mexu'", Local "Local file system at /tmp/rclone1274111692", Modify Window "1s"
2025/06/30 01:47:17 DEBUG : a.txt: Uploading chunk 1/1
2025/06/30 01:47:18 DEBUG : a.txt: Uploading chunk 2/1
2025/06/30 01:47:19 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Adding "a.txt" to batch
2025/06/30 01:47:19 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Batch idle for 500ms so committing
2025/06/30 01:47:19 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committing sync batch length 1 starting with: a.txt
2025/06/30 01:47:20 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committed sync batch length 1 starting with: a.txt
2025/06/30 01:47:20 DEBUG : zend.txt: Uploading chunk 1/1
2025/06/30 01:47:21 DEBUG : zend.txt: Uploading chunk 2/1
2025/06/30 01:47:21 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Adding "zend.txt" to batch
2025/06/30 01:47:21 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Batch idle for 500ms so committing
2025/06/30 01:47:21 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committing sync batch length 1 starting with: zend.txt
2025/06/30 01:47:22 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committed sync batch length 1 starting with: zend.txt
2025/06/30 01:47:23 DEBUG : sub dir/hello world: Uploading chunk 1/1
2025/06/30 01:47:23 DEBUG : sub dir/hello world: Uploading chunk 2/1
2025/06/30 01:47:23 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Adding "sub dir/hello world" to batch
2025/06/30 01:47:24 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Batch idle for 500ms so committing
2025/06/30 01:47:24 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committing sync batch length 1 starting with: sub dir/hello world
2025/06/30 01:47:25 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committed sync batch length 1 starting with: sub dir/hello world
2025/06/30 01:47:25 DEBUG : sub dir/hello world2: Uploading chunk 1/1
2025/06/30 01:47:26 DEBUG : sub dir/hello world2: Uploading chunk 2/1
2025/06/30 01:47:26 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Adding "sub dir/hello world2" to batch
2025/06/30 01:47:27 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Batch idle for 500ms so committing
2025/06/30 01:47:27 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committing sync batch length 1 starting with: sub dir/hello world2
2025/06/30 01:47:28 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committed sync batch length 1 starting with: sub dir/hello world2
2025/06/30 01:47:29 DEBUG : sub dir/ignore dir/.ignore: Uploading chunk 1/1
2025/06/30 01:47:29 DEBUG : sub dir/ignore dir/.ignore: Uploading chunk 2/1
2025/06/30 01:47:29 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Adding "sub dir/ignore dir/.ignore" to batch
2025/06/30 01:47:30 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Batch idle for 500ms so committing
2025/06/30 01:47:30 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committing sync batch length 1 starting with: sub dir/ignore dir/.ignore
2025/06/30 01:47:31 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committed sync batch length 1 starting with: sub dir/ignore dir/.ignore
2025/06/30 01:47:31 DEBUG : sub dir/ignore dir/should be ignored: Uploading chunk 1/1
2025/06/30 01:47:31 DEBUG : sub dir/ignore dir/should be ignored: Uploading chunk 2/1
2025/06/30 01:47:32 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Adding "sub dir/ignore dir/should be ignored" to batch
2025/06/30 01:47:33 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Batch idle for 500ms so committing
2025/06/30 01:47:33 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committing sync batch length 1 starting with: sub dir/ignore dir/should be ignored
2025/06/30 01:47:33 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committed sync batch length 1 starting with: sub dir/ignore dir/should be ignored
2025/06/30 01:47:33 DEBUG : sub dir/sub sub dir/hello world3: Uploading chunk 1/1
2025/06/30 01:47:34 DEBUG : sub dir/sub sub dir/hello world3: Uploading chunk 2/1
2025/06/30 01:47:34 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Adding "sub dir/sub sub dir/hello world3" to batch
2025/06/30 01:47:35 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Batch idle for 500ms so committing
2025/06/30 01:47:35 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committing sync batch length 1 starting with: sub dir/sub sub dir/hello world3
2025/06/30 01:47:36 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committed sync batch length 1 starting with: sub dir/sub sub dir/hello world3
2025/06/30 01:47:37 DEBUG : a.txt: Excluded (Size Filter)
2025/06/30 01:47:37 DEBUG : a.txt: Excluded
2025/06/30 01:47:37 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/06/30 01:47:37 DEBUG : sub dir/hello world: Excluded
2025/06/30 01:47:37 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/06/30 01:47:37 DEBUG : sub dir/hello world2: Excluded
2025/06/30 01:47:38 DEBUG : sub dir/ignore dir: Excluded
2025/06/30 01:47:38 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/06/30 01:47:38 DEBUG : sub dir/hello world: Excluded
2025/06/30 01:47:38 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/06/30 01:47:38 DEBUG : sub dir/hello world2: Excluded
2025/06/30 01:47:39 DEBUG : sub dir/ignore dir: Excluded
--- PASS: TestListDirSortedFn (31.42s)
=== RUN   TestLsLong
    run.go:180: Remote "Dropbox root 'rclone-test-huyulir1mexu'", Local "Local file system at /tmp/rclone1274111692", Modify Window "1s"
2025/06/30 01:47:49 DEBUG : potato2: Uploading chunk 1/1
2025/06/30 01:47:49 DEBUG : potato2: Uploading chunk 2/1
2025/06/30 01:47:50 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Adding "potato2" to batch
2025/06/30 01:47:50 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Batch idle for 500ms so committing
2025/06/30 01:47:50 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committing sync batch length 1 starting with: potato2
2025/06/30 01:47:51 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committed sync batch length 1 starting with: potato2
2025/06/30 01:47:51 DEBUG : empty space: Uploading chunk 1/1
2025/06/30 01:47:51 DEBUG : empty space: Uploading chunk 2/1
2025/06/30 01:47:52 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Adding "empty space" to batch
2025/06/30 01:47:52 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Batch idle for 500ms so committing
2025/06/30 01:47:52 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committing sync batch length 1 starting with: empty space
2025/06/30 01:47:53 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committed sync batch length 1 starting with: empty space
--- PASS: TestLsLong (6.90s)
=== RUN   TestCaseInsensitiveMoveFileDryRun
    run.go:180: Remote "Dropbox root 'rclone-test-huyulir1mexu'", Local "Local file system at /tmp/rclone1274111692", Modify Window "1s"
2025/06/30 01:47:56 DEBUG : hello: Uploading chunk 1/1
2025/06/30 01:47:56 DEBUG : hello: Uploading chunk 2/1
2025/06/30 01:47:56 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Adding "hello" to batch
2025/06/30 01:47:57 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Batch idle for 500ms so committing
2025/06/30 01:47:57 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committing sync batch length 1 starting with: hello
2025/06/30 01:47:58 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committed sync batch length 1 starting with: hello
2025/06/30 01:47:59 NOTICE: hello: Skipped rename to HELLO as --dry-run is set
--- PASS: TestCaseInsensitiveMoveFileDryRun (4.53s)
=== RUN   TestDirMove
    run.go:180: Remote "Dropbox root 'rclone-test-huyulir1mexu'", Local "Local file system at /tmp/rclone1274111692", Modify Window "1s"
2025/06/30 01:48:01 DEBUG : A1/one: Uploading chunk 1/1
2025/06/30 01:48:01 DEBUG : A1/one: Uploading chunk 2/1
2025/06/30 01:48:02 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Adding "A1/one" to batch
2025/06/30 01:48:02 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Batch idle for 500ms so committing
2025/06/30 01:48:02 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committing sync batch length 1 starting with: A1/one
2025/06/30 01:48:04 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committed sync batch length 1 starting with: A1/one
2025/06/30 01:48:04 DEBUG : A1/two: Uploading chunk 1/1
2025/06/30 01:48:05 DEBUG : A1/two: Uploading chunk 2/1
2025/06/30 01:48:05 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Adding "A1/two" to batch
2025/06/30 01:48:06 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Batch idle for 500ms so committing
2025/06/30 01:48:06 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Committing sync batch length 1 starting with: A1/two
2025/06/30 01:48:08 ERROR : Dropbox root 'rclone-test-huyulir1mexu': sync batch commit: failed to commit batch length 1: batch had 1 errors: last error: upload failed: too_many_write_operations
    run.go:285: Failed to put "A1/two" to "Dropbox root 'rclone-test-huyulir1mexu'": upload failed: batch upload failed: upload failed: too_many_write_operations
--- FAIL: TestDirMove (11.40s)
FAIL
2025/06/30 01:48:11 DEBUG : Dropbox root 'rclone-test-huyulir1mexu': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestCaseInsensitiveMoveFileDryRun|TestCopyFileCopyDest|TestDirMove|TestListDirSortedFn|TestLsLong)$'" - Finished ERROR in 1m36.82812592s (try 2/5): exit status 1: Failed [TestDirMove]