"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestFixCase$'" - Starting (try 3/5)
2025/10/31 02:09:44 DEBUG : Creating backend with remote "TestDropbox:rclone-test-nivaked2mucu"
2025/10/31 02:09:44 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/31 02:09:44 DEBUG : Creating backend with remote "/tmp/rclone1345971046"
=== RUN   TestFixCase
    run.go:185: Remote "Dropbox root 'rclone-test-nivaked2mucu'", Local "Local file system at /tmp/rclone1345971046", Modify Window "1s"
2025/10/31 02:09:44 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2025/10/31 02:09:44 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/10/31 02:09:45 DEBUG : pacer: Reducing sleep to 15ms
2025/10/31 02:09:45 DEBUG : pacer: Reducing sleep to 11.25ms
2025/10/31 02:09:45 DEBUG : EXISTING: Uploading chunk 1/1
2025/10/31 02:09:46 DEBUG : pacer: Reducing sleep to 10ms
2025/10/31 02:09:46 DEBUG : EXISTING: Uploading chunk 2/1
2025/10/31 02:09:46 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Adding "EXISTING" to batch
2025/10/31 02:09:47 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Batch idle for 500ms so committing
2025/10/31 02:09:47 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Committing sync batch length 1 starting with: EXISTING
2025/10/31 02:09:47 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Committed sync batch length 1 starting with: EXISTING
2025/10/31 02:09:48 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 1/1
2025/10/31 02:09:48 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 2/1
2025/10/31 02:09:49 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Adding "EXISTINGBUTDIFFERENT" to batch
2025/10/31 02:09:49 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Batch idle for 500ms so committing
2025/10/31 02:09:49 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Committing sync batch length 1 starting with: EXISTINGBUTDIFFERENT
2025/10/31 02:09:50 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Committed sync batch length 1 starting with: EXISTINGBUTDIFFERENT
2025/10/31 02:09:50 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Uploading chunk 1/1
2025/10/31 02:09:51 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Uploading chunk 2/1
2025/10/31 02:09:51 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Adding "SUBDIRA/subdirb/SUBDIRC/HELLO" to batch
2025/10/31 02:09:51 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Batch idle for 500ms so committing
2025/10/31 02:09:51 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Committing sync batch length 1 starting with: SUBDIRA/subdirb/SUBDIRC/HELLO
2025/10/31 02:09:52 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Committed sync batch length 1 starting with: SUBDIRA/subdirb/SUBDIRC/HELLO
2025/10/31 02:09:53 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Uploading chunk 1/1
2025/10/31 02:09:53 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Uploading chunk 2/1
2025/10/31 02:09:53 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Adding "SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences" to batch
2025/10/31 02:09:54 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Batch idle for 500ms so committing
2025/10/31 02:09:54 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Committing sync batch length 1 starting with: SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences
2025/10/31 02:09:55 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Committed sync batch length 1 starting with: SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences
2025/10/31 02:09:56 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/10/31 02:09:56 DEBUG : existingbutdifferent: Sizes differ (src 5 vs dst 8)
2025/10/31 02:09:56 DEBUG : existing: Unchanged skipping
2025/10/31 02:09:56 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-fehapev6
2025/10/31 02:09:56 DEBUG : EXISTING: moving to existing-rclone-move-zicekoc3
2025/10/31 02:09:57 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2025/10/31 02:09:57 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/10/31 02:09:57 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...)
2025/10/31 02:09:57 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/10/31 02:09:57 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/...)
2025/10/31 02:09:57 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2025/10/31 02:09:58 DEBUG : pacer: Reducing sleep to 60ms
2025/10/31 02:09:58 DEBUG : pacer: low level retry 3/10 (error too_many_write_operations/.)
2025/10/31 02:09:58 DEBUG : pacer: Rate limited, increasing sleep to 120ms
2025/10/31 02:09:58 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/...)
2025/10/31 02:09:58 DEBUG : pacer: Rate limited, increasing sleep to 240ms
2025/10/31 02:09:58 DEBUG : pacer: Reducing sleep to 180ms
2025/10/31 02:09:59 DEBUG : pacer: low level retry 3/10 (error too_many_write_operations/)
2025/10/31 02:09:59 DEBUG : pacer: Rate limited, increasing sleep to 360ms
2025/10/31 02:09:59 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2025/10/31 02:09:59 DEBUG : pacer: Rate limited, increasing sleep to 720ms
2025/10/31 02:09:59 DEBUG : pacer: low level retry 4/10 (error too_many_write_operations/...)
2025/10/31 02:09:59 DEBUG : pacer: Rate limited, increasing sleep to 1.44s
2025/10/31 02:09:59 DEBUG : pacer: Reducing sleep to 1.08s
2025/10/31 02:09:59 INFO  : EXISTING: Moved (server-side) to: existing-rclone-move-zicekoc3
2025/10/31 02:09:59 DEBUG : EXISTING: moving to existing
2025/10/31 02:10:00 DEBUG : pacer: low level retry 5/10 (error too_many_write_operations/...)
2025/10/31 02:10:00 DEBUG : pacer: Rate limited, increasing sleep to 2s
2025/10/31 02:10:01 DEBUG : pacer: Reducing sleep to 1.5s
2025/10/31 02:10:01 INFO  : SUBDIRA: Fixed case by renaming to: subdira
2025/10/31 02:10:01 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2025/10/31 02:10:01 DEBUG : pacer: Rate limited, increasing sleep to 2s
2025/10/31 02:10:03 DEBUG : pacer: low level retry 6/10 (error too_many_write_operations/)
2025/10/31 02:10:04 DEBUG : pacer: Reducing sleep to 1.5s
2025/10/31 02:10:07 DEBUG : pacer: Reducing sleep to 1.125s
2025/10/31 02:10:07 INFO  : existing-rclone-move-zicekoc3: Moved (server-side) to: existing
2025/10/31 02:10:07 INFO  : EXISTING: Moved (server-side) to: existing
2025/10/31 02:10:07 INFO  : EXISTING: Fixed case by renaming to: existing
2025/10/31 02:10:09 DEBUG : pacer: Reducing sleep to 843.75ms
2025/10/31 02:10:09 DEBUG : pacer: Reducing sleep to 632.8125ms
2025/10/31 02:10:09 INFO  : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-fehapev6
2025/10/31 02:10:09 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent
2025/10/31 02:10:10 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..)
2025/10/31 02:10:10 DEBUG : pacer: Rate limited, increasing sleep to 1.265625s
2025/10/31 02:10:12 DEBUG : pacer: Reducing sleep to 949.21875ms
2025/10/31 02:10:12 INFO  : existingbutdifferent-rclone-move-fehapev6: Moved (server-side) to: existingbutdifferent
2025/10/31 02:10:12 INFO  : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent
2025/10/31 02:10:12 INFO  : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent
2025/10/31 02:10:13 DEBUG : pacer: Reducing sleep to 711.914062ms
2025/10/31 02:10:13 DEBUG : pacer: Reducing sleep to 533.935546ms
2025/10/31 02:10:13 DEBUG : existingbutdifferent: Uploading chunk 1/1
2025/10/31 02:10:14 DEBUG : pacer: Reducing sleep to 400.451659ms
2025/10/31 02:10:15 DEBUG : pacer: Reducing sleep to 300.338744ms
2025/10/31 02:10:15 DEBUG : existingbutdifferent: Uploading chunk 2/1
2025/10/31 02:10:15 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...)
2025/10/31 02:10:15 DEBUG : pacer: Rate limited, increasing sleep to 600.677488ms
2025/10/31 02:10:16 DEBUG : pacer: Reducing sleep to 450.508116ms
2025/10/31 02:10:16 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Adding "existingbutdifferent" to batch
2025/10/31 02:10:16 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Batch idle for 500ms so committing
2025/10/31 02:10:16 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Committing sync batch length 1 starting with: existingbutdifferent
2025/10/31 02:10:17 DEBUG : pacer: Reducing sleep to 337.881087ms
2025/10/31 02:10:17 INFO  : SUBDIRA/subdirb: Fixed case by renaming to: subdira/subdirb
2025/10/31 02:10:17 DEBUG : pacer: Reducing sleep to 253.410815ms
2025/10/31 02:10:17 DEBUG : pacer: Reducing sleep to 190.058111ms
2025/10/31 02:10:18 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..)
2025/10/31 02:10:18 DEBUG : pacer: Rate limited, increasing sleep to 380.116222ms
2025/10/31 02:10:18 DEBUG : pacer: Reducing sleep to 285.087166ms
2025/10/31 02:10:18 ERROR : Dropbox root 'rclone-test-nivaked2mucu': sync batch commit: failed to commit batch length 1: batch had 1 errors: last error: upload failed: too_many_write_operations
2025/10/31 02:10:18 ERROR : existingbutdifferent: Failed to copy: upload failed: batch upload failed: upload failed: too_many_write_operations
2025/10/31 02:10:19 DEBUG : pacer: Reducing sleep to 213.815374ms
2025/10/31 02:10:19 DEBUG : pacer: Reducing sleep to 160.36153ms
2025/10/31 02:10:20 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..)
2025/10/31 02:10:20 DEBUG : pacer: Rate limited, increasing sleep to 320.72306ms
2025/10/31 02:10:21 DEBUG : pacer: Reducing sleep to 240.542295ms
2025/10/31 02:10:21 INFO  : SUBDIRA/subdirb/SUBDIRC: Fixed case by renaming to: subdira/subdirb/subdirc
2025/10/31 02:10:22 DEBUG : pacer: Reducing sleep to 180.406721ms
2025/10/31 02:10:22 DEBUG : subdira/subdirb/subdirc/hello: Sizes differ (src 5 vs dst 8)
2025/10/31 02:10:22 DEBUG : pacer: Reducing sleep to 135.30504ms
2025/10/31 02:10:22 DEBUG : pacer: Reducing sleep to 101.47878ms
2025/10/31 02:10:22 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello-rclone-move-dajarad6
2025/10/31 02:10:23 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2025/10/31 02:10:23 DEBUG : pacer: Rate limited, increasing sleep to 202.95756ms
2025/10/31 02:10:23 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/..)
2025/10/31 02:10:23 DEBUG : pacer: Rate limited, increasing sleep to 405.91512ms
2025/10/31 02:10:23 DEBUG : pacer: Reducing sleep to 304.43634ms
2025/10/31 02:10:24 DEBUG : pacer: Reducing sleep to 228.327255ms
2025/10/31 02:10:24 DEBUG : pacer: low level retry 3/10 (error too_many_write_operations/.)
2025/10/31 02:10:24 DEBUG : pacer: Rate limited, increasing sleep to 456.65451ms
2025/10/31 02:10:24 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/.)
2025/10/31 02:10:24 DEBUG : pacer: Rate limited, increasing sleep to 913.30902ms
2025/10/31 02:10:25 DEBUG : pacer: low level retry 4/10 (error too_many_write_operations/...)
2025/10/31 02:10:25 DEBUG : pacer: Rate limited, increasing sleep to 1.82661804s
2025/10/31 02:10:26 DEBUG : pacer: Reducing sleep to 1.36996353s
2025/10/31 02:10:26 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird: Fixed case by renaming to: subdira/subdirb/subdirc/subdird
2025/10/31 02:10:27 DEBUG : pacer: Reducing sleep to 1.027472647s
2025/10/31 02:10:27 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-dajarad6
2025/10/31 02:10:27 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello
2025/10/31 02:10:28 DEBUG : pacer: Reducing sleep to 770.604485ms
2025/10/31 02:10:28 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Sizes differ (src 5 vs dst 8)
2025/10/31 02:10:28 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Waiting for checks to finish
2025/10/31 02:10:29 DEBUG : pacer: Reducing sleep to 577.953363ms
2025/10/31 02:10:29 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-voqopoq8
2025/10/31 02:10:30 DEBUG : pacer: Reducing sleep to 433.465022ms
2025/10/31 02:10:30 INFO  : subdira/subdirb/subdirc/hello-rclone-move-dajarad6: Moved (server-side) to: subdira/subdirb/subdirc/hello
2025/10/31 02:10:30 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello
2025/10/31 02:10:30 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Fixed case by renaming to: subdira/subdirb/subdirc/hello
2025/10/31 02:10:31 DEBUG : pacer: Reducing sleep to 325.098766ms
2025/10/31 02:10:31 DEBUG : subdira/subdirb/subdirc/hello: Uploading chunk 1/1
2025/10/31 02:10:31 DEBUG : pacer: Reducing sleep to 243.824074ms
2025/10/31 02:10:31 DEBUG : subdira/subdirb/subdirc/hello: Uploading chunk 2/1
2025/10/31 02:10:31 DEBUG : pacer: Reducing sleep to 182.868055ms
2025/10/31 02:10:31 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-voqopoq8
2025/10/31 02:10:31 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2025/10/31 02:10:32 DEBUG : pacer: Reducing sleep to 137.151041ms
2025/10/31 02:10:32 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Adding "subdira/subdirb/subdirc/hello" to batch
2025/10/31 02:10:32 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Batch idle for 500ms so committing
2025/10/31 02:10:32 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Committing sync batch length 1 starting with: subdira/subdirb/subdirc/hello
2025/10/31 02:10:32 DEBUG : pacer: Reducing sleep to 102.86328ms
2025/10/31 02:10:32 INFO  : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-voqopoq8: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2025/10/31 02:10:32 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2025/10/31 02:10:32 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Fixed case by renaming to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2025/10/31 02:10:32 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Waiting for transfers to finish
2025/10/31 02:10:33 DEBUG : pacer: Reducing sleep to 77.14746ms
2025/10/31 02:10:33 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Uploading chunk 1/1
2025/10/31 02:10:33 DEBUG : pacer: Reducing sleep to 57.860595ms
2025/10/31 02:10:33 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Uploading chunk 2/1
2025/10/31 02:10:34 DEBUG : pacer: Reducing sleep to 43.395446ms
2025/10/31 02:10:34 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Committed sync batch length 1 starting with: subdira/subdirb/subdirc/hello
2025/10/31 02:10:34 DEBUG : subdira/subdirb/subdirc/hello: dropbox = 876764f8f7cb4581b40dd4a7a1be217b2c58ae386ca01c86ea0b30bba6d050ed OK
2025/10/31 02:10:34 INFO  : subdira/subdirb/subdirc/hello: Copied (replaced existing)
2025/10/31 02:10:34 DEBUG : pacer: Reducing sleep to 32.546584ms
2025/10/31 02:10:34 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Adding "subdira/subdirb/subdirc/subdird/filewithoutcasedifferences" to batch
2025/10/31 02:10:34 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Batch idle for 500ms so committing
2025/10/31 02:10:34 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Committing sync batch length 1 starting with: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2025/10/31 02:10:35 DEBUG : pacer: Reducing sleep to 24.409938ms
2025/10/31 02:10:35 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Committed sync batch length 1 starting with: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2025/10/31 02:10:35 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: dropbox = 876764f8f7cb4581b40dd4a7a1be217b2c58ae386ca01c86ea0b30bba6d050ed OK
2025/10/31 02:10:35 INFO  : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (replaced existing)
2025/10/31 02:10:35 ERROR : Dropbox root 'rclone-test-nivaked2mucu': not deleting files as there were IO errors
2025/10/31 02:10:35 ERROR : Dropbox root 'rclone-test-nivaked2mucu': 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
2025/10/31 02:10:35 DEBUG : pacer: Reducing sleep to 18.307453ms
2025/10/31 02:10:36 DEBUG : pacer: Reducing sleep to 13.730589ms
2025/10/31 02:10:37 DEBUG : pacer: Reducing sleep to 10.297941ms
2025/10/31 02:10:37 DEBUG : pacer: Reducing sleep to 10ms
--- FAIL: TestFixCase (59.64s)
FAIL
2025/10/31 02:10:44 DEBUG : Dropbox root 'rclone-test-nivaked2mucu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestFixCase$'" - Finished ERROR in 1m0.631250831s (try 3/5): exit status 1: Failed [TestFixCase]