"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestFixCase|TestSyncBackupDirWithSuffix|TestSyncWithUpdateOlder)$'" - Starting (try 2/5)
2026/04/12 02:20:59 DEBUG : Creating backend with remote "TestDropbox:rclone-test-fiyizem9teye"
2026/04/12 02:20:59 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/04/12 02:20:59 DEBUG : Creating backend with remote "/tmp/rclone3073435196"
=== RUN   TestSyncWithUpdateOlder
    run.go:185: Remote "Dropbox root 'rclone-test-fiyizem9teye'", Local "Local file system at /tmp/rclone3073435196", Modify Window "1s"
2026/04/12 02:21:00 DEBUG : one: Uploading chunk 1/1
2026/04/12 02:21:01 DEBUG : one: Uploading chunk 2/1
2026/04/12 02:21:01 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "one" to batch
2026/04/12 02:21:02 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:21:02 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 1 starting with: one
2026/04/12 02:21:02 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 1 starting with: one
2026/04/12 02:21:03 DEBUG : two: Uploading chunk 1/1
2026/04/12 02:21:03 DEBUG : two: Uploading chunk 2/1
2026/04/12 02:21:04 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "two" to batch
2026/04/12 02:21:04 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:21:04 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 1 starting with: two
2026/04/12 02:21:05 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 1 starting with: two
2026/04/12 02:21:05 DEBUG : three: Uploading chunk 1/1
2026/04/12 02:21:06 DEBUG : three: Uploading chunk 2/1
2026/04/12 02:21:06 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "three" to batch
2026/04/12 02:21:07 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:21:07 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 1 starting with: three
2026/04/12 02:21:08 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 1 starting with: three
2026/04/12 02:21:08 DEBUG : four: Uploading chunk 1/1
2026/04/12 02:21:08 DEBUG : four: Uploading chunk 2/1
2026/04/12 02:21:09 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "four" to batch
2026/04/12 02:21:10 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:21:10 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 1 starting with: four
2026/04/12 02:21:10 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 1 starting with: four
2026/04/12 02:21:11 DEBUG : five: Need to transfer - File not found at Destination
2026/04/12 02:21:11 DEBUG : one: Destination is newer than source, skipping
2026/04/12 02:21:11 DEBUG : four: size = 4 (Local file system at /tmp/rclone3073435196)
2026/04/12 02:21:11 DEBUG : two: size = 3 OK
2026/04/12 02:21:11 DEBUG : four: size = 8 (Dropbox root 'rclone-test-fiyizem9teye')
2026/04/12 02:21:11 DEBUG : four: Sizes differ
2026/04/12 02:21:11 DEBUG : three: size = 5 OK
2026/04/12 02:21:11 DEBUG : three: Sizes identical
2026/04/12 02:21:11 DEBUG : two: dropbox = 313db63e0283ab2a9bc72e9a0ac749da1d3acf708fcd1a7848767244dd2f17e8 (Local file system at /tmp/rclone3073435196)
2026/04/12 02:21:11 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring
2026/04/12 02:21:11 DEBUG : two: dropbox = 00b165e375c521d9e15870a04a889919b652d5f0b499d7e4f9304fb142dc36d8 (Dropbox root 'rclone-test-fiyizem9teye')
2026/04/12 02:21:11 DEBUG : two: dropbox differ
2026/04/12 02:21:11 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Waiting for checks to finish
2026/04/12 02:21:11 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping
2026/04/12 02:21:11 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Waiting for transfers to finish
2026/04/12 02:21:11 DEBUG : five: Uploading chunk 1/1
2026/04/12 02:21:11 DEBUG : two: Uploading chunk 1/1
2026/04/12 02:21:11 DEBUG : four: Uploading chunk 1/1
2026/04/12 02:21:12 DEBUG : two: Uploading chunk 2/1
2026/04/12 02:21:12 DEBUG : five: Uploading chunk 2/1
2026/04/12 02:21:12 DEBUG : four: Uploading chunk 2/1
2026/04/12 02:21:12 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "two" to batch
2026/04/12 02:21:12 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "four" to batch
2026/04/12 02:21:12 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "five" to batch
2026/04/12 02:21:13 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:21:13 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 3 starting with: two
2026/04/12 02:21:14 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 3 starting with: two
2026/04/12 02:21:14 DEBUG : four: size = 4 OK
2026/04/12 02:21:14 DEBUG : two: size = 3 OK
2026/04/12 02:21:14 DEBUG : five: size = 4 OK
2026/04/12 02:21:14 DEBUG : two: dropbox = 313db63e0283ab2a9bc72e9a0ac749da1d3acf708fcd1a7848767244dd2f17e8 OK
2026/04/12 02:21:14 INFO  : two: Copied (replaced existing)
2026/04/12 02:21:14 DEBUG : four: dropbox = ad1d7d5339655cf0a7e22a8560b56f563ea95eba7b0a043776c99a4db108702a OK
2026/04/12 02:21:14 DEBUG : five: dropbox = 785ec2cf1199779cb1593eac85d7465db3603834e9edd6d1420f2b369bb01dc3 OK
2026/04/12 02:21:14 INFO  : four: Copied (replaced existing)
2026/04/12 02:21:14 INFO  : five: Copied (new)
2026/04/12 02:21:14 DEBUG : Waiting for deletions to finish
2026/04/12 02:21:14 DEBUG : five: size = 4 OK
2026/04/12 02:21:14 DEBUG : four: size = 4 OK
2026/04/12 02:21:14 DEBUG : one: Destination is newer than source, skipping
2026/04/12 02:21:14 DEBUG : three: size = 5 OK
2026/04/12 02:21:14 DEBUG : two: size = 3 OK
2026/04/12 02:21:14 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Waiting for checks to finish
2026/04/12 02:21:14 DEBUG : five: dropbox = 785ec2cf1199779cb1593eac85d7465db3603834e9edd6d1420f2b369bb01dc3 OK
2026/04/12 02:21:14 DEBUG : five: Size and dropbox of src and dst objects identical
2026/04/12 02:21:14 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping
2026/04/12 02:21:14 DEBUG : four: dropbox = ad1d7d5339655cf0a7e22a8560b56f563ea95eba7b0a043776c99a4db108702a OK
2026/04/12 02:21:14 DEBUG : four: Size and dropbox of src and dst objects identical
2026/04/12 02:21:14 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping
2026/04/12 02:21:14 DEBUG : three: dropbox = 1dbfa84faa926d00a27e7578746a13ad3063e0e9bde3b25015e545ad80f5f703 (Local file system at /tmp/rclone3073435196)
2026/04/12 02:21:14 DEBUG : three: dropbox = 8c4bfd5e4dc14d49026d0a5aa224be92b3af4b622e8c08296defc8e299b0b6fa (Dropbox root 'rclone-test-fiyizem9teye')
2026/04/12 02:21:14 DEBUG : three: dropbox differ
2026/04/12 02:21:14 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring
2026/04/12 02:21:14 DEBUG : two: dropbox = 313db63e0283ab2a9bc72e9a0ac749da1d3acf708fcd1a7848767244dd2f17e8 OK
2026/04/12 02:21:14 DEBUG : two: Size and dropbox of src and dst objects identical
2026/04/12 02:21:14 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping
2026/04/12 02:21:14 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Waiting for transfers to finish
2026/04/12 02:21:15 DEBUG : three: Uploading chunk 1/1
2026/04/12 02:21:15 DEBUG : three: Uploading chunk 2/1
2026/04/12 02:21:16 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "three" to batch
2026/04/12 02:21:16 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:21:16 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 1 starting with: three
2026/04/12 02:21:17 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 1 starting with: three
2026/04/12 02:21:17 DEBUG : three: size = 5 OK
2026/04/12 02:21:17 DEBUG : three: dropbox = 1dbfa84faa926d00a27e7578746a13ad3063e0e9bde3b25015e545ad80f5f703 OK
2026/04/12 02:21:17 INFO  : three: Copied (replaced existing)
2026/04/12 02:21:17 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncWithUpdateOlder (21.52s)
=== RUN   TestSyncBackupDirWithSuffix
    run.go:185: Remote "Dropbox root 'rclone-test-fiyizem9teye'", Local "Local file system at /tmp/rclone3073435196", Modify Window "1s"
2026/04/12 02:21:21 DEBUG : dst/one: Uploading chunk 1/1
2026/04/12 02:21:21 DEBUG : dst/one: Uploading chunk 2/1
2026/04/12 02:21:22 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "dst/one" to batch
2026/04/12 02:21:22 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:21:22 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 1 starting with: dst/one
2026/04/12 02:21:23 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 1 starting with: dst/one
2026/04/12 02:21:24 DEBUG : dst/two: Uploading chunk 1/1
2026/04/12 02:21:24 DEBUG : dst/two: Uploading chunk 2/1
2026/04/12 02:21:25 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "dst/two" to batch
2026/04/12 02:21:25 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:21:25 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 1 starting with: dst/two
2026/04/12 02:21:26 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 1 starting with: dst/two
2026/04/12 02:21:26 DEBUG : dst/three.txt: Uploading chunk 1/1
2026/04/12 02:21:27 DEBUG : dst/three.txt: Uploading chunk 2/1
2026/04/12 02:21:27 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "dst/three.txt" to batch
2026/04/12 02:21:28 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:21:28 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 1 starting with: dst/three.txt
2026/04/12 02:21:28 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 1 starting with: dst/three.txt
2026/04/12 02:21:29 DEBUG : Creating backend with remote "TestDropbox:rclone-test-fiyizem9teye/dst"
2026/04/12 02:21:29 DEBUG : Creating backend with remote "TestDropbox:rclone-test-fiyizem9teye/backup"
2026/04/12 02:21:30 DEBUG : two: size = 3 OK
2026/04/12 02:21:30 DEBUG : Dropbox root 'rclone-test-fiyizem9teye/dst': Waiting for checks to finish
2026/04/12 02:21:30 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/04/12 02:21:30 DEBUG : two: Unchanged skipping
2026/04/12 02:21:30 DEBUG : one: size = 4 (Local file system at /tmp/rclone3073435196)
2026/04/12 02:21:30 DEBUG : one: size = 3 (Dropbox root 'rclone-test-fiyizem9teye/dst')
2026/04/12 02:21:30 DEBUG : one: Sizes differ
2026/04/12 02:21:31 INFO  : one: Moved (server-side) to: one.bak
2026/04/12 02:21:31 DEBUG : Dropbox root 'rclone-test-fiyizem9teye/dst': Waiting for transfers to finish
2026/04/12 02:21:32 DEBUG : one: Uploading chunk 1/1
2026/04/12 02:21:32 DEBUG : one: Uploading chunk 2/1
2026/04/12 02:21:33 DEBUG : Dropbox root 'rclone-test-fiyizem9teye/dst': Adding "one" to batch
2026/04/12 02:21:33 DEBUG : Dropbox root 'rclone-test-fiyizem9teye/dst': Batch idle for 500ms so committing
2026/04/12 02:21:33 DEBUG : Dropbox root 'rclone-test-fiyizem9teye/dst': Committing sync batch length 1 starting with: one
2026/04/12 02:21:34 DEBUG : Dropbox root 'rclone-test-fiyizem9teye/dst': Committed sync batch length 1 starting with: one
2026/04/12 02:21:34 DEBUG : one: size = 4 OK
2026/04/12 02:21:34 DEBUG : one: dropbox = 525034bfc3bcd23ac5a856a953b1204f255bc2a5c313f3ab750d60ab581b8e81 OK
2026/04/12 02:21:34 INFO  : one: Copied (new)
2026/04/12 02:21:34 DEBUG : Waiting for deletions to finish
2026/04/12 02:21:35 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2026/04/12 02:21:35 INFO  : three.txt: Moved into backup dir
2026/04/12 02:21:36 DEBUG : dst/three.txt: Uploading chunk 1/1
2026/04/12 02:21:37 DEBUG : dst/three.txt: Uploading chunk 2/1
2026/04/12 02:21:37 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "dst/three.txt" to batch
2026/04/12 02:21:38 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:21:38 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 1 starting with: dst/three.txt
2026/04/12 02:21:38 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 1 starting with: dst/three.txt
2026/04/12 02:21:39 DEBUG : one: size = 5 (Local file system at /tmp/rclone3073435196)
2026/04/12 02:21:39 DEBUG : one: size = 4 (Dropbox root 'rclone-test-fiyizem9teye/dst')
2026/04/12 02:21:39 DEBUG : one: Sizes differ
2026/04/12 02:21:39 DEBUG : two: size = 3 OK
2026/04/12 02:21:39 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/04/12 02:21:39 DEBUG : two: Unchanged skipping
2026/04/12 02:21:39 DEBUG : Dropbox root 'rclone-test-fiyizem9teye/dst': Waiting for checks to finish
2026/04/12 02:21:40 INFO  : one.bak: Deleted
2026/04/12 02:21:41 INFO  : one: Moved (server-side) to: one.bak
2026/04/12 02:21:41 DEBUG : Dropbox root 'rclone-test-fiyizem9teye/dst': Waiting for transfers to finish
2026/04/12 02:21:41 DEBUG : one: Uploading chunk 1/1
2026/04/12 02:21:42 DEBUG : one: Uploading chunk 2/1
2026/04/12 02:21:42 DEBUG : Dropbox root 'rclone-test-fiyizem9teye/dst': Adding "one" to batch
2026/04/12 02:21:43 DEBUG : Dropbox root 'rclone-test-fiyizem9teye/dst': Batch idle for 500ms so committing
2026/04/12 02:21:43 DEBUG : Dropbox root 'rclone-test-fiyizem9teye/dst': Committing sync batch length 1 starting with: one
2026/04/12 02:21:43 DEBUG : Dropbox root 'rclone-test-fiyizem9teye/dst': Committed sync batch length 1 starting with: one
2026/04/12 02:21:43 DEBUG : one: size = 5 OK
2026/04/12 02:21:43 DEBUG : one: dropbox = 1147280340b0797198e7d42f57f0340e2579e601846419ad967bdd7fa2dec259 OK
2026/04/12 02:21:43 INFO  : one: Copied (new)
2026/04/12 02:21:43 DEBUG : Waiting for deletions to finish
2026/04/12 02:21:44 INFO  : three.txt.bak: Deleted
2026/04/12 02:21:45 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2026/04/12 02:21:45 INFO  : three.txt: Moved into backup dir
2026/04/12 02:21:51 INFO  : Dropbox root 'rclone-test-fiyizem9teye/backup': Committing uploads - please wait...
--- PASS: TestSyncBackupDirWithSuffix (30.17s)
=== RUN   TestFixCase
    run.go:185: Remote "Dropbox root 'rclone-test-fiyizem9teye'", Local "Local file system at /tmp/rclone3073435196", Modify Window "1s"
2026/04/12 02:21:51 DEBUG : EXISTING: Uploading chunk 1/1
2026/04/12 02:21:52 DEBUG : EXISTING: Uploading chunk 2/1
2026/04/12 02:21:52 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "EXISTING" to batch
2026/04/12 02:21:53 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:21:53 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 1 starting with: EXISTING
2026/04/12 02:21:53 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 1 starting with: EXISTING
2026/04/12 02:21:54 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 1/1
2026/04/12 02:21:54 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 2/1
2026/04/12 02:21:55 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "EXISTINGBUTDIFFERENT" to batch
2026/04/12 02:21:55 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:21:55 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 1 starting with: EXISTINGBUTDIFFERENT
2026/04/12 02:21:57 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 1 starting with: EXISTINGBUTDIFFERENT
2026/04/12 02:21:57 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Uploading chunk 1/1
2026/04/12 02:21:58 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Uploading chunk 2/1
2026/04/12 02:21:58 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "SUBDIRA/subdirb/SUBDIRC/HELLO" to batch
2026/04/12 02:21:59 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:21:59 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 1 starting with: SUBDIRA/subdirb/SUBDIRC/HELLO
2026/04/12 02:22:00 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 1 starting with: SUBDIRA/subdirb/SUBDIRC/HELLO
2026/04/12 02:22:00 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Uploading chunk 1/1
2026/04/12 02:22:00 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Uploading chunk 2/1
2026/04/12 02:22:01 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences" to batch
2026/04/12 02:22:02 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:22:02 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 1 starting with: SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences
2026/04/12 02:22:02 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 1 starting with: SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences
2026/04/12 02:22:04 DEBUG : EXISTING: size = 6 OK
2026/04/12 02:22:04 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/04/12 02:22:04 DEBUG : existing: Unchanged skipping
2026/04/12 02:22:04 DEBUG : existingbutdifferent: size = 5 (Local file system at /tmp/rclone3073435196)
2026/04/12 02:22:04 DEBUG : EXISTINGBUTDIFFERENT: size = 8 (Dropbox root 'rclone-test-fiyizem9teye')
2026/04/12 02:22:04 DEBUG : existingbutdifferent: Sizes differ
2026/04/12 02:22:04 DEBUG : EXISTING: moving to existing-rclone-move-cisulaz9
2026/04/12 02:22:04 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-calehal2
2026/04/12 02:22:05 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..)
2026/04/12 02:22:05 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/12 02:22:05 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...)
2026/04/12 02:22:05 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/04/12 02:22:05 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/...)
2026/04/12 02:22:05 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/04/12 02:22:06 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...)
2026/04/12 02:22:06 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/04/12 02:22:06 DEBUG : pacer: Reducing sleep to 120ms
2026/04/12 02:22:06 INFO  : EXISTING: Moved (server-side) to: existing-rclone-move-cisulaz9
2026/04/12 02:22:06 DEBUG : EXISTING: moving to existing
2026/04/12 02:22:06 DEBUG : pacer: low level retry 3/10 (error too_many_write_operations/)
2026/04/12 02:22:06 DEBUG : pacer: Rate limited, increasing sleep to 240ms
2026/04/12 02:22:06 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/.)
2026/04/12 02:22:06 DEBUG : pacer: Rate limited, increasing sleep to 480ms
2026/04/12 02:22:06 DEBUG : pacer: low level retry 4/10 (error too_many_write_operations/..)
2026/04/12 02:22:06 DEBUG : pacer: Rate limited, increasing sleep to 960ms
2026/04/12 02:22:06 DEBUG : pacer: Reducing sleep to 720ms
2026/04/12 02:22:06 INFO  : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-calehal2
2026/04/12 02:22:06 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent
2026/04/12 02:22:07 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/...)
2026/04/12 02:22:07 DEBUG : pacer: Rate limited, increasing sleep to 1.44s
2026/04/12 02:22:07 DEBUG : pacer: low level retry 5/10 (error too_many_write_operations/)
2026/04/12 02:22:07 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/04/12 02:22:09 DEBUG : pacer: Reducing sleep to 1.5s
2026/04/12 02:22:09 INFO  : existingbutdifferent-rclone-move-calehal2: Moved (server-side) to: existingbutdifferent
2026/04/12 02:22:09 INFO  : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent
2026/04/12 02:22:09 INFO  : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent
2026/04/12 02:22:11 DEBUG : pacer: Reducing sleep to 1.125s
2026/04/12 02:22:11 INFO  : existing-rclone-move-cisulaz9: Moved (server-side) to: existing
2026/04/12 02:22:11 INFO  : EXISTING: Moved (server-side) to: existing
2026/04/12 02:22:11 INFO  : EXISTING: Fixed case by renaming to: existing
2026/04/12 02:22:13 DEBUG : pacer: Reducing sleep to 843.75ms
2026/04/12 02:22:13 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/04/12 02:22:13 DEBUG : existingbutdifferent: Uploading chunk 1/1
2026/04/12 02:22:14 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/04/12 02:22:15 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/04/12 02:22:15 DEBUG : existingbutdifferent: Uploading chunk 2/1
2026/04/12 02:22:16 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/04/12 02:22:16 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "existingbutdifferent" to batch
2026/04/12 02:22:16 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/04/12 02:22:16 INFO  : SUBDIRA: Fixed case by renaming to: subdira
2026/04/12 02:22:16 DEBUG : pacer: Reducing sleep to 150.169371ms
2026/04/12 02:22:16 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:22:16 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 1 starting with: existingbutdifferent
2026/04/12 02:22:17 DEBUG : pacer: Reducing sleep to 112.627028ms
2026/04/12 02:22:17 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..)
2026/04/12 02:22:17 DEBUG : pacer: Rate limited, increasing sleep to 225.254056ms
2026/04/12 02:22:17 DEBUG : pacer: Reducing sleep to 168.940542ms
2026/04/12 02:22:17 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 1 starting with: existingbutdifferent
2026/04/12 02:22:17 DEBUG : existingbutdifferent: size = 5 OK
2026/04/12 02:22:17 DEBUG : existingbutdifferent: dropbox = 876764f8f7cb4581b40dd4a7a1be217b2c58ae386ca01c86ea0b30bba6d050ed OK
2026/04/12 02:22:17 INFO  : existingbutdifferent: Copied (replaced existing)
2026/04/12 02:22:18 DEBUG : pacer: Reducing sleep to 126.705406ms
2026/04/12 02:22:18 DEBUG : pacer: Reducing sleep to 95.029054ms
2026/04/12 02:22:19 DEBUG : pacer: Reducing sleep to 71.27179ms
2026/04/12 02:22:19 INFO  : SUBDIRA/subdirb: Fixed case by renaming to: subdira/subdirb
2026/04/12 02:22:20 DEBUG : pacer: Reducing sleep to 53.453842ms
2026/04/12 02:22:20 DEBUG : pacer: Reducing sleep to 40.090381ms
2026/04/12 02:22:22 DEBUG : pacer: Reducing sleep to 30.067785ms
2026/04/12 02:22:22 DEBUG : pacer: Reducing sleep to 22.550838ms
2026/04/12 02:22:23 DEBUG : pacer: Reducing sleep to 16.913128ms
2026/04/12 02:22:23 INFO  : SUBDIRA/subdirb/SUBDIRC: Fixed case by renaming to: subdira/subdirb/subdirc
2026/04/12 02:22:23 DEBUG : pacer: Reducing sleep to 12.684846ms
2026/04/12 02:22:23 DEBUG : subdira/subdirb/subdirc/hello: size = 5 (Local file system at /tmp/rclone3073435196)
2026/04/12 02:22:23 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: size = 8 (Dropbox root 'rclone-test-fiyizem9teye')
2026/04/12 02:22:23 DEBUG : subdira/subdirb/subdirc/hello: Sizes differ
2026/04/12 02:22:24 DEBUG : pacer: Reducing sleep to 10ms
2026/04/12 02:22:24 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello-rclone-move-jutihaz1
2026/04/12 02:22:25 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...)
2026/04/12 02:22:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/12 02:22:25 DEBUG : pacer: Reducing sleep to 15ms
2026/04/12 02:22:25 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-jutihaz1
2026/04/12 02:22:25 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello
2026/04/12 02:22:25 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...)
2026/04/12 02:22:25 DEBUG : pacer: Rate limited, increasing sleep to 30ms
2026/04/12 02:22:26 DEBUG : pacer: Reducing sleep to 22.5ms
2026/04/12 02:22:26 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/..)
2026/04/12 02:22:26 DEBUG : pacer: Rate limited, increasing sleep to 45ms
2026/04/12 02:22:26 DEBUG : pacer: Reducing sleep to 33.75ms
2026/04/12 02:22:26 DEBUG : pacer: low level retry 3/10 (error too_many_write_operations/.)
2026/04/12 02:22:26 DEBUG : pacer: Rate limited, increasing sleep to 67.5ms
2026/04/12 02:22:26 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..)
2026/04/12 02:22:26 DEBUG : pacer: Rate limited, increasing sleep to 135ms
2026/04/12 02:22:27 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/...)
2026/04/12 02:22:27 DEBUG : pacer: Rate limited, increasing sleep to 270ms
2026/04/12 02:22:27 DEBUG : pacer: low level retry 3/10 (error too_many_write_operations/..)
2026/04/12 02:22:27 DEBUG : pacer: Rate limited, increasing sleep to 540ms
2026/04/12 02:22:27 DEBUG : pacer: Reducing sleep to 405ms
2026/04/12 02:22:27 INFO  : subdira/subdirb/subdirc/hello-rclone-move-jutihaz1: Moved (server-side) to: subdira/subdirb/subdirc/hello
2026/04/12 02:22:27 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello
2026/04/12 02:22:27 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Fixed case by renaming to: subdira/subdirb/subdirc/hello
2026/04/12 02:22:28 DEBUG : pacer: Reducing sleep to 303.75ms
2026/04/12 02:22:28 DEBUG : subdira/subdirb/subdirc/hello: Uploading chunk 1/1
2026/04/12 02:22:28 DEBUG : pacer: Reducing sleep to 227.8125ms
2026/04/12 02:22:28 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird: Fixed case by renaming to: subdira/subdirb/subdirc/subdird
2026/04/12 02:22:29 DEBUG : pacer: Reducing sleep to 170.859375ms
2026/04/12 02:22:29 DEBUG : subdira/subdirb/subdirc/hello: Uploading chunk 2/1
2026/04/12 02:22:29 DEBUG : pacer: Reducing sleep to 128.144531ms
2026/04/12 02:22:29 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Waiting for checks to finish
2026/04/12 02:22:29 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: size = 5 (Local file system at /tmp/rclone3073435196)
2026/04/12 02:22:29 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: size = 8 (Dropbox root 'rclone-test-fiyizem9teye')
2026/04/12 02:22:29 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Sizes differ
2026/04/12 02:22:29 DEBUG : pacer: Reducing sleep to 96.108398ms
2026/04/12 02:22:29 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-dituxob9
2026/04/12 02:22:29 DEBUG : pacer: Reducing sleep to 72.081298ms
2026/04/12 02:22:29 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "subdira/subdirb/subdirc/hello" to batch
2026/04/12 02:22:30 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:22:30 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 1 starting with: subdira/subdirb/subdirc/hello
2026/04/12 02:22:30 DEBUG : pacer: Reducing sleep to 54.060973ms
2026/04/12 02:22:30 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-dituxob9
2026/04/12 02:22:30 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2026/04/12 02:22:31 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..)
2026/04/12 02:22:31 DEBUG : pacer: Rate limited, increasing sleep to 108.121946ms
2026/04/12 02:22:31 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/..)
2026/04/12 02:22:31 DEBUG : pacer: Rate limited, increasing sleep to 216.243892ms
2026/04/12 02:22:31 DEBUG : pacer: Reducing sleep to 162.182919ms
2026/04/12 02:22:31 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 1 starting with: subdira/subdirb/subdirc/hello
2026/04/12 02:22:31 DEBUG : subdira/subdirb/subdirc/hello: size = 5 OK
2026/04/12 02:22:31 DEBUG : subdira/subdirb/subdirc/hello: dropbox = 876764f8f7cb4581b40dd4a7a1be217b2c58ae386ca01c86ea0b30bba6d050ed OK
2026/04/12 02:22:31 INFO  : subdira/subdirb/subdirc/hello: Copied (replaced existing)
2026/04/12 02:22:32 DEBUG : pacer: Reducing sleep to 121.637189ms
2026/04/12 02:22:32 INFO  : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-dituxob9: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2026/04/12 02:22:32 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2026/04/12 02:22:32 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Fixed case by renaming to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2026/04/12 02:22:32 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Waiting for transfers to finish
2026/04/12 02:22:33 DEBUG : pacer: Reducing sleep to 91.227891ms
2026/04/12 02:22:33 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Uploading chunk 1/1
2026/04/12 02:22:33 DEBUG : pacer: Reducing sleep to 68.420918ms
2026/04/12 02:22:33 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Uploading chunk 2/1
2026/04/12 02:22:34 DEBUG : pacer: Reducing sleep to 51.315688ms
2026/04/12 02:22:34 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Adding "subdira/subdirb/subdirc/subdird/filewithoutcasedifferences" to batch
2026/04/12 02:22:34 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Batch idle for 500ms so committing
2026/04/12 02:22:34 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committing sync batch length 1 starting with: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2026/04/12 02:22:35 DEBUG : pacer: Reducing sleep to 38.486766ms
2026/04/12 02:22:35 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Committed sync batch length 1 starting with: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2026/04/12 02:22:35 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: size = 5 OK
2026/04/12 02:22:35 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: dropbox = 876764f8f7cb4581b40dd4a7a1be217b2c58ae386ca01c86ea0b30bba6d050ed OK
2026/04/12 02:22:35 INFO  : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (replaced existing)
2026/04/12 02:22:35 DEBUG : Waiting for deletions to finish
2026/04/12 02:22:36 DEBUG : pacer: Reducing sleep to 28.865074ms
2026/04/12 02:22:36 DEBUG : pacer: Reducing sleep to 21.648805ms
2026/04/12 02:22:36 DEBUG : pacer: Reducing sleep to 16.236603ms
2026/04/12 02:22:36 DEBUG : pacer: Reducing sleep to 12.177452ms
2026/04/12 02:22:37 DEBUG : pacer: Reducing sleep to 10ms
--- PASS: TestFixCase (54.09s)
PASS
2026/04/12 02:22:45 DEBUG : Dropbox root 'rclone-test-fiyizem9teye': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestFixCase|TestSyncBackupDirWithSuffix|TestSyncWithUpdateOlder)$'" - Finished OK in 1m46.663894358s (try 2/5)