"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestFixCase$'" - Starting (try 2/5)
2025/10/08 03:49:48 DEBUG : Creating backend with remote "TestDropbox:rclone-test-vatacib1qixu"
2025/10/08 03:49:48 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/08 03:49:48 DEBUG : Creating backend with remote "/tmp/rclone591900968"
=== RUN   TestFixCase
    run.go:180: Remote "Dropbox root 'rclone-test-vatacib1qixu'", Local "Local file system at /tmp/rclone591900968", Modify Window "1s"
2025/10/08 03:49:49 DEBUG : EXISTING: Uploading chunk 1/1
2025/10/08 03:49:49 DEBUG : EXISTING: Uploading chunk 2/1
2025/10/08 03:49:50 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Adding "EXISTING" to batch
2025/10/08 03:49:51 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Batch idle for 500ms so committing
2025/10/08 03:49:51 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Committing sync batch length 1 starting with: EXISTING
2025/10/08 03:49:51 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Committed sync batch length 1 starting with: EXISTING
2025/10/08 03:49:52 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 1/1
2025/10/08 03:49:52 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 2/1
2025/10/08 03:49:53 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Adding "EXISTINGBUTDIFFERENT" to batch
2025/10/08 03:49:53 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Batch idle for 500ms so committing
2025/10/08 03:49:53 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Committing sync batch length 1 starting with: EXISTINGBUTDIFFERENT
2025/10/08 03:49:54 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Committed sync batch length 1 starting with: EXISTINGBUTDIFFERENT
2025/10/08 03:49:54 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Uploading chunk 1/1
2025/10/08 03:49:54 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Uploading chunk 2/1
2025/10/08 03:49:55 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Adding "SUBDIRA/subdirb/SUBDIRC/HELLO" to batch
2025/10/08 03:49:56 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Batch idle for 500ms so committing
2025/10/08 03:49:56 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Committing sync batch length 1 starting with: SUBDIRA/subdirb/SUBDIRC/HELLO
2025/10/08 03:49:56 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Committed sync batch length 1 starting with: SUBDIRA/subdirb/SUBDIRC/HELLO
2025/10/08 03:49:57 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Uploading chunk 1/1
2025/10/08 03:49:57 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Uploading chunk 2/1
2025/10/08 03:49:58 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Adding "SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences" to batch
2025/10/08 03:49:58 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Batch idle for 500ms so committing
2025/10/08 03:49:58 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Committing sync batch length 1 starting with: SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences
2025/10/08 03:49:59 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Committed sync batch length 1 starting with: SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences
2025/10/08 03:50:00 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/10/08 03:50:00 DEBUG : existing: Unchanged skipping
2025/10/08 03:50:00 DEBUG : existingbutdifferent: Sizes differ (src 5 vs dst 8)
2025/10/08 03:50:01 DEBUG : EXISTING: moving to existing-rclone-move-qirepik9
2025/10/08 03:50:01 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-lahenic0
2025/10/08 03:50:01 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...)
2025/10/08 03:50:01 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/10/08 03:50:01 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2025/10/08 03:50:01 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/10/08 03:50:02 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/...)
2025/10/08 03:50:02 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2025/10/08 03:50:02 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/..)
2025/10/08 03:50:02 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2025/10/08 03:50:02 DEBUG : pacer: low level retry 3/10 (error too_many_write_operations/...)
2025/10/08 03:50:02 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2025/10/08 03:50:03 DEBUG : pacer: low level retry 4/10 (error too_many_write_operations/.)
2025/10/08 03:50:03 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2025/10/08 03:50:03 DEBUG : pacer: Reducing sleep to 480ms
2025/10/08 03:50:03 DEBUG : pacer: Reducing sleep to 360ms
2025/10/08 03:50:03 INFO  : EXISTING: Moved (server-side) to: existing-rclone-move-qirepik9
2025/10/08 03:50:03 DEBUG : EXISTING: moving to existing
2025/10/08 03:50:04 DEBUG : pacer: Reducing sleep to 270ms
2025/10/08 03:50:04 DEBUG : pacer: Reducing sleep to 202.5ms
2025/10/08 03:50:04 INFO  : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-lahenic0
2025/10/08 03:50:04 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent
2025/10/08 03:50:05 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...)
2025/10/08 03:50:05 DEBUG : pacer: Rate limited, increasing sleep to 405ms
2025/10/08 03:50:05 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2025/10/08 03:50:05 DEBUG : pacer: Rate limited, increasing sleep to 810ms
2025/10/08 03:50:05 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/.)
2025/10/08 03:50:05 DEBUG : pacer: Rate limited, increasing sleep to 1.62s
2025/10/08 03:50:05 DEBUG : pacer: Reducing sleep to 1.215s
2025/10/08 03:50:05 INFO  : existing-rclone-move-qirepik9: Moved (server-side) to: existing
2025/10/08 03:50:05 INFO  : EXISTING: Moved (server-side) to: existing
2025/10/08 03:50:05 INFO  : EXISTING: Fixed case by renaming to: existing
2025/10/08 03:50:06 DEBUG : pacer: Reducing sleep to 911.25ms
2025/10/08 03:50:06 INFO  : existingbutdifferent-rclone-move-lahenic0: Moved (server-side) to: existingbutdifferent
2025/10/08 03:50:06 INFO  : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent
2025/10/08 03:50:06 INFO  : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent
2025/10/08 03:50:06 DEBUG : pacer: low level retry 3/10 (error too_many_write_operations/)
2025/10/08 03:50:06 DEBUG : pacer: Rate limited, increasing sleep to 1.8225s
2025/10/08 03:50:07 DEBUG : pacer: Reducing sleep to 1.366875s
2025/10/08 03:50:07 DEBUG : existingbutdifferent: Uploading chunk 1/1
2025/10/08 03:50:10 DEBUG : pacer: Reducing sleep to 1.02515625s
2025/10/08 03:50:10 INFO  : SUBDIRA: Fixed case by renaming to: subdira
2025/10/08 03:50:11 DEBUG : pacer: Reducing sleep to 768.867187ms
2025/10/08 03:50:11 DEBUG : existingbutdifferent: Uploading chunk 2/1
2025/10/08 03:50:12 DEBUG : pacer: Reducing sleep to 576.65039ms
2025/10/08 03:50:13 DEBUG : pacer: Reducing sleep to 432.487792ms
2025/10/08 03:50:13 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Adding "existingbutdifferent" to batch
2025/10/08 03:50:13 DEBUG : pacer: Reducing sleep to 324.365844ms
2025/10/08 03:50:14 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Batch idle for 500ms so committing
2025/10/08 03:50:14 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Committing sync batch length 1 starting with: existingbutdifferent
2025/10/08 03:50:15 DEBUG : pacer: Reducing sleep to 243.274383ms
2025/10/08 03:50:15 DEBUG : pacer: Reducing sleep to 182.455787ms
2025/10/08 03:50:16 DEBUG : pacer: Reducing sleep to 136.84184ms
2025/10/08 03:50:16 ERROR : Dropbox root 'rclone-test-vatacib1qixu': sync batch commit: failed to commit batch length 1: batch had 1 errors: last error: upload failed: too_many_write_operations
2025/10/08 03:50:16 ERROR : existingbutdifferent: Failed to copy: upload failed: batch upload failed: upload failed: too_many_write_operations
2025/10/08 03:50:17 DEBUG : pacer: Reducing sleep to 102.63138ms
2025/10/08 03:50:17 INFO  : SUBDIRA/subdirb: Fixed case by renaming to: subdira/subdirb
2025/10/08 03:50:17 DEBUG : pacer: Reducing sleep to 76.973535ms
2025/10/08 03:50:17 DEBUG : pacer: Reducing sleep to 57.730151ms
2025/10/08 03:50:19 DEBUG : pacer: Reducing sleep to 43.297613ms
2025/10/08 03:50:19 DEBUG : pacer: Reducing sleep to 32.473209ms
2025/10/08 03:50:20 DEBUG : pacer: Reducing sleep to 24.354906ms
2025/10/08 03:50:20 INFO  : SUBDIRA/subdirb/SUBDIRC: Fixed case by renaming to: subdira/subdirb/subdirc
2025/10/08 03:50:21 DEBUG : pacer: Reducing sleep to 18.266179ms
2025/10/08 03:50:21 DEBUG : subdira/subdirb/subdirc/hello: Sizes differ (src 5 vs dst 8)
2025/10/08 03:50:21 DEBUG : pacer: Reducing sleep to 13.699634ms
2025/10/08 03:50:21 DEBUG : pacer: Reducing sleep to 10.274725ms
2025/10/08 03:50:21 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello-rclone-move-dilivaf3
2025/10/08 03:50:21 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...)
2025/10/08 03:50:21 DEBUG : pacer: Rate limited, increasing sleep to 20.54945ms
2025/10/08 03:50:22 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/..)
2025/10/08 03:50:22 DEBUG : pacer: Rate limited, increasing sleep to 41.0989ms
2025/10/08 03:50:22 DEBUG : pacer: Reducing sleep to 30.824175ms
2025/10/08 03:50:22 DEBUG : pacer: Reducing sleep to 23.118131ms
2025/10/08 03:50:23 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2025/10/08 03:50:23 DEBUG : pacer: Rate limited, increasing sleep to 46.236262ms
2025/10/08 03:50:23 DEBUG : pacer: Reducing sleep to 34.677196ms
2025/10/08 03:50:23 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-dilivaf3
2025/10/08 03:50:23 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello
2025/10/08 03:50:23 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2025/10/08 03:50:23 DEBUG : pacer: Rate limited, increasing sleep to 69.354392ms
2025/10/08 03:50:24 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/)
2025/10/08 03:50:24 DEBUG : pacer: Rate limited, increasing sleep to 138.708784ms
2025/10/08 03:50:24 DEBUG : pacer: Reducing sleep to 104.031588ms
2025/10/08 03:50:24 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird: Fixed case by renaming to: subdira/subdirb/subdirc/subdird
2025/10/08 03:50:24 DEBUG : pacer: Reducing sleep to 78.023691ms
2025/10/08 03:50:24 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Sizes differ (src 5 vs dst 8)
2025/10/08 03:50:24 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Waiting for checks to finish
2025/10/08 03:50:25 DEBUG : pacer: Reducing sleep to 58.517768ms
2025/10/08 03:50:25 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-cidozox7
2025/10/08 03:50:25 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...)
2025/10/08 03:50:25 DEBUG : pacer: Rate limited, increasing sleep to 117.035536ms
2025/10/08 03:50:25 DEBUG : pacer: Reducing sleep to 87.776652ms
2025/10/08 03:50:25 INFO  : subdira/subdirb/subdirc/hello-rclone-move-dilivaf3: Moved (server-side) to: subdira/subdirb/subdirc/hello
2025/10/08 03:50:25 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello
2025/10/08 03:50:25 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Fixed case by renaming to: subdira/subdirb/subdirc/hello
2025/10/08 03:50:26 DEBUG : pacer: Reducing sleep to 65.832489ms
2025/10/08 03:50:26 DEBUG : subdira/subdirb/subdirc/hello: Uploading chunk 1/1
2025/10/08 03:50:26 DEBUG : pacer: Reducing sleep to 49.374366ms
2025/10/08 03:50:26 DEBUG : subdira/subdirb/subdirc/hello: Uploading chunk 2/1
2025/10/08 03:50:26 DEBUG : pacer: Reducing sleep to 37.030774ms
2025/10/08 03:50:26 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Adding "subdira/subdirb/subdirc/hello" to batch
2025/10/08 03:50:26 DEBUG : pacer: Reducing sleep to 27.77308ms
2025/10/08 03:50:26 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-cidozox7
2025/10/08 03:50:26 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2025/10/08 03:50:27 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Batch idle for 500ms so committing
2025/10/08 03:50:27 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Committing sync batch length 1 starting with: subdira/subdirb/subdirc/hello
2025/10/08 03:50:28 DEBUG : pacer: Reducing sleep to 20.82981ms
2025/10/08 03:50:28 INFO  : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-cidozox7: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2025/10/08 03:50:28 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2025/10/08 03:50:28 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Fixed case by renaming to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2025/10/08 03:50:28 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Waiting for transfers to finish
2025/10/08 03:50:28 DEBUG : pacer: Reducing sleep to 15.622357ms
2025/10/08 03:50:28 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Uploading chunk 1/1
2025/10/08 03:50:28 DEBUG : pacer: Reducing sleep to 11.716767ms
2025/10/08 03:50:28 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Uploading chunk 2/1
2025/10/08 03:50:29 DEBUG : pacer: Reducing sleep to 10ms
2025/10/08 03:50:29 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Adding "subdira/subdirb/subdirc/subdird/filewithoutcasedifferences" to batch
2025/10/08 03:50:29 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Committed sync batch length 1 starting with: subdira/subdirb/subdirc/hello
2025/10/08 03:50:29 DEBUG : subdira/subdirb/subdirc/hello: dropbox = 876764f8f7cb4581b40dd4a7a1be217b2c58ae386ca01c86ea0b30bba6d050ed OK
2025/10/08 03:50:29 INFO  : subdira/subdirb/subdirc/hello: Copied (replaced existing)
2025/10/08 03:50:29 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Batch idle for 500ms so committing
2025/10/08 03:50:29 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Committing sync batch length 1 starting with: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2025/10/08 03:50:30 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Committed sync batch length 1 starting with: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2025/10/08 03:50:30 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: dropbox = 876764f8f7cb4581b40dd4a7a1be217b2c58ae386ca01c86ea0b30bba6d050ed OK
2025/10/08 03:50:30 INFO  : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (replaced existing)
2025/10/08 03:50:30 ERROR : Dropbox root 'rclone-test-vatacib1qixu': not deleting files as there were IO errors
2025/10/08 03:50:30 ERROR : Dropbox root 'rclone-test-vatacib1qixu': not deleting directories as there were IO errors
    sync_test.go:2685: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2685
        	Error:      	Received unexpected error:
        	            	upload failed: batch upload failed: upload failed: too_many_write_operations
        	Test:       	TestFixCase
--- FAIL: TestFixCase (50.18s)
FAIL
2025/10/08 03:50:38 DEBUG : Dropbox root 'rclone-test-vatacib1qixu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestFixCase$'" - Finished ERROR in 51.235336615s (try 2/5): exit status 1: Failed [TestFixCase]