"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestFixCase|TestSyncCompareDest)$'" - Starting (try 2/5) 2026/04/01 03:13:03 DEBUG : Creating backend with remote "TestDropbox:rclone-test-baxajuw5kenu" 2026/04/01 03:13:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/04/01 03:13:04 DEBUG : Creating backend with remote "/tmp/rclone3329842830" === RUN TestSyncCompareDest run.go:185: Remote "Dropbox root 'rclone-test-baxajuw5kenu'", Local "Local file system at /tmp/rclone3329842830", Modify Window "1s" 2026/04/01 03:13:04 DEBUG : Creating backend with remote "TestDropbox:rclone-test-baxajuw5kenu/dst" 2026/04/01 03:13:04 DEBUG : Creating backend with remote "TestDropbox:rclone-test-baxajuw5kenu/CompareDest" 2026/04/01 03:13:05 DEBUG : one: Need to transfer - File not found at Destination 2026/04/01 03:13:05 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Waiting for checks to finish 2026/04/01 03:13:05 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Waiting for transfers to finish 2026/04/01 03:13:05 DEBUG : one: Uploading chunk 1/1 2026/04/01 03:13:06 DEBUG : one: Uploading chunk 2/1 2026/04/01 03:13:06 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Adding "one" to batch 2026/04/01 03:13:07 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Batch idle for 500ms so committing 2026/04/01 03:13:07 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Committing sync batch length 1 starting with: one 2026/04/01 03:13:07 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Committed sync batch length 1 starting with: one 2026/04/01 03:13:07 DEBUG : one: size = 3 OK 2026/04/01 03:13:07 DEBUG : one: dropbox = fe8d7a873dc48961a6af334c996b2cb3ce37149d5ce9c9253952a54c6a92c1ad OK 2026/04/01 03:13:07 INFO : one: Copied (new) 2026/04/01 03:13:07 DEBUG : Waiting for deletions to finish 2026/04/01 03:13:09 DEBUG : one: size = 5 (Local file system at /tmp/rclone3329842830) 2026/04/01 03:13:09 DEBUG : one: size = 3 (Dropbox root 'rclone-test-baxajuw5kenu/dst') 2026/04/01 03:13:09 DEBUG : one: Sizes differ 2026/04/01 03:13:09 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Waiting for checks to finish 2026/04/01 03:13:09 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Waiting for transfers to finish 2026/04/01 03:13:09 DEBUG : one: Uploading chunk 1/1 2026/04/01 03:13:10 DEBUG : one: Uploading chunk 2/1 2026/04/01 03:13:10 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Adding "one" to batch 2026/04/01 03:13:11 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Batch idle for 500ms so committing 2026/04/01 03:13:11 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Committing sync batch length 1 starting with: one 2026/04/01 03:13:12 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Committed sync batch length 1 starting with: one 2026/04/01 03:13:12 DEBUG : one: size = 5 OK 2026/04/01 03:13:12 DEBUG : one: dropbox = 7da8d117f9fb226817d81adc15c72720c9879c8b7fb821d27f217d198bd79d0d OK 2026/04/01 03:13:12 INFO : one: Copied (replaced existing) 2026/04/01 03:13:12 DEBUG : Waiting for deletions to finish 2026/04/01 03:13:13 DEBUG : dst/one: Uploading chunk 1/1 2026/04/01 03:13:13 DEBUG : dst/one: Uploading chunk 2/1 2026/04/01 03:13:14 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Adding "dst/one" to batch 2026/04/01 03:13:15 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Batch idle for 500ms so committing 2026/04/01 03:13:15 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committing sync batch length 1 starting with: dst/one 2026/04/01 03:13:16 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committed sync batch length 1 starting with: dst/one 2026/04/01 03:13:16 DEBUG : CompareDest/one: Uploading chunk 1/1 2026/04/01 03:13:17 DEBUG : CompareDest/one: Uploading chunk 2/1 2026/04/01 03:13:17 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Adding "CompareDest/one" to batch 2026/04/01 03:13:18 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Batch idle for 500ms so committing 2026/04/01 03:13:18 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committing sync batch length 1 starting with: CompareDest/one 2026/04/01 03:13:18 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committed sync batch length 1 starting with: CompareDest/one 2026/04/01 03:13:19 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Waiting for checks to finish 2026/04/01 03:13:19 DEBUG : one: size = 5 (Local file system at /tmp/rclone3329842830) 2026/04/01 03:13:19 DEBUG : one: size = 3 (Dropbox root 'rclone-test-baxajuw5kenu/dst') 2026/04/01 03:13:19 DEBUG : one: Sizes differ 2026/04/01 03:13:20 DEBUG : one: size = 5 OK 2026/04/01 03:13:20 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2026/04/01 03:13:20 DEBUG : one: Destination found in --compare-dest, skipping 2026/04/01 03:13:20 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Waiting for transfers to finish 2026/04/01 03:13:20 DEBUG : Waiting for deletions to finish 2026/04/01 03:13:20 INFO : There was nothing to transfer 2026/04/01 03:13:21 DEBUG : CompareDest/two: Uploading chunk 1/1 2026/04/01 03:13:21 DEBUG : CompareDest/two: Uploading chunk 2/1 2026/04/01 03:13:22 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Adding "CompareDest/two" to batch 2026/04/01 03:13:22 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Batch idle for 500ms so committing 2026/04/01 03:13:22 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committing sync batch length 1 starting with: CompareDest/two 2026/04/01 03:13:23 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committed sync batch length 1 starting with: CompareDest/two 2026/04/01 03:13:24 DEBUG : one: size = 5 (Local file system at /tmp/rclone3329842830) 2026/04/01 03:13:24 DEBUG : one: size = 3 (Dropbox root 'rclone-test-baxajuw5kenu/dst') 2026/04/01 03:13:24 DEBUG : one: Sizes differ 2026/04/01 03:13:24 DEBUG : two: size = 3 OK 2026/04/01 03:13:24 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2026/04/01 03:13:24 DEBUG : two: Destination found in --compare-dest, skipping 2026/04/01 03:13:24 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Waiting for checks to finish 2026/04/01 03:13:24 DEBUG : one: size = 5 OK 2026/04/01 03:13:24 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2026/04/01 03:13:24 DEBUG : one: Destination found in --compare-dest, skipping 2026/04/01 03:13:24 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Waiting for transfers to finish 2026/04/01 03:13:24 DEBUG : Waiting for deletions to finish 2026/04/01 03:13:24 INFO : There was nothing to transfer 2026/04/01 03:13:25 DEBUG : one: size = 5 (Local file system at /tmp/rclone3329842830) 2026/04/01 03:13:25 DEBUG : one: size = 3 (Dropbox root 'rclone-test-baxajuw5kenu/dst') 2026/04/01 03:13:25 DEBUG : one: Sizes differ 2026/04/01 03:13:25 DEBUG : two: size = 3 OK 2026/04/01 03:13:25 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2026/04/01 03:13:25 DEBUG : two: Destination found in --compare-dest, skipping 2026/04/01 03:13:25 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Waiting for checks to finish 2026/04/01 03:13:25 DEBUG : one: size = 5 OK 2026/04/01 03:13:25 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2026/04/01 03:13:25 DEBUG : one: Destination found in --compare-dest, skipping 2026/04/01 03:13:25 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Waiting for transfers to finish 2026/04/01 03:13:25 DEBUG : Waiting for deletions to finish 2026/04/01 03:13:25 INFO : There was nothing to transfer 2026/04/01 03:13:26 DEBUG : one: size = 5 (Local file system at /tmp/rclone3329842830) 2026/04/01 03:13:26 DEBUG : one: size = 3 (Dropbox root 'rclone-test-baxajuw5kenu/dst') 2026/04/01 03:13:26 DEBUG : one: Sizes differ 2026/04/01 03:13:26 DEBUG : two: size = 3 OK 2026/04/01 03:13:26 DEBUG : two: Modification times differ by -120h0m0s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59 +0000 UTC 2026/04/01 03:13:26 DEBUG : two: dropbox = 313db63e0283ab2a9bc72e9a0ac749da1d3acf708fcd1a7848767244dd2f17e8 OK 2026/04/01 03:13:26 DEBUG : two: Destination found in --compare-dest, skipping 2026/04/01 03:13:26 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Waiting for checks to finish 2026/04/01 03:13:26 DEBUG : one: size = 5 OK 2026/04/01 03:13:26 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2026/04/01 03:13:26 DEBUG : one: Destination found in --compare-dest, skipping 2026/04/01 03:13:26 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Waiting for transfers to finish 2026/04/01 03:13:26 DEBUG : Waiting for deletions to finish 2026/04/01 03:13:26 INFO : There was nothing to transfer 2026/04/01 03:13:28 DEBUG : one: size = 5 (Local file system at /tmp/rclone3329842830) 2026/04/01 03:13:28 DEBUG : one: size = 3 (Dropbox root 'rclone-test-baxajuw5kenu/dst') 2026/04/01 03:13:28 DEBUG : one: Sizes differ 2026/04/01 03:13:28 DEBUG : two: size = 5 (Local file system at /tmp/rclone3329842830) 2026/04/01 03:13:28 DEBUG : two: size = 3 (Dropbox root 'rclone-test-baxajuw5kenu/CompareDest') 2026/04/01 03:13:28 DEBUG : two: Sizes differ 2026/04/01 03:13:28 DEBUG : two: Need to transfer - File not found at Destination 2026/04/01 03:13:28 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Waiting for checks to finish 2026/04/01 03:13:28 DEBUG : one: size = 5 OK 2026/04/01 03:13:28 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2026/04/01 03:13:28 DEBUG : one: Destination found in --compare-dest, skipping 2026/04/01 03:13:28 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Waiting for transfers to finish 2026/04/01 03:13:29 DEBUG : two: Uploading chunk 1/1 2026/04/01 03:13:29 DEBUG : two: Uploading chunk 2/1 2026/04/01 03:13:29 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Adding "two" to batch 2026/04/01 03:13:30 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Batch idle for 500ms so committing 2026/04/01 03:13:30 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Committing sync batch length 1 starting with: two 2026/04/01 03:13:31 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu/dst': Committed sync batch length 1 starting with: two 2026/04/01 03:13:31 DEBUG : two: size = 5 OK 2026/04/01 03:13:31 DEBUG : two: dropbox = 25e70b9a05696acbcc5bd6fa74ba6fa580c9a58960a02390ae1d2f71d21c0378 OK 2026/04/01 03:13:31 INFO : two: Copied (new) 2026/04/01 03:13:31 DEBUG : Waiting for deletions to finish 2026/04/01 03:13:36 INFO : Dropbox root 'rclone-test-baxajuw5kenu/CompareDest': Committing uploads - please wait... --- PASS: TestSyncCompareDest (32.67s) === RUN TestFixCase run.go:185: Remote "Dropbox root 'rclone-test-baxajuw5kenu'", Local "Local file system at /tmp/rclone3329842830", Modify Window "1s" 2026/04/01 03:13:37 DEBUG : EXISTING: Uploading chunk 1/1 2026/04/01 03:13:37 DEBUG : EXISTING: Uploading chunk 2/1 2026/04/01 03:13:38 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Adding "EXISTING" to batch 2026/04/01 03:13:38 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Batch idle for 500ms so committing 2026/04/01 03:13:38 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committing sync batch length 1 starting with: EXISTING 2026/04/01 03:13:39 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committed sync batch length 1 starting with: EXISTING 2026/04/01 03:13:39 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 1/1 2026/04/01 03:13:40 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 2/1 2026/04/01 03:13:40 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Adding "EXISTINGBUTDIFFERENT" to batch 2026/04/01 03:13:41 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Batch idle for 500ms so committing 2026/04/01 03:13:41 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committing sync batch length 1 starting with: EXISTINGBUTDIFFERENT 2026/04/01 03:13:41 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committed sync batch length 1 starting with: EXISTINGBUTDIFFERENT 2026/04/01 03:13:42 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Uploading chunk 1/1 2026/04/01 03:13:42 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Uploading chunk 2/1 2026/04/01 03:13:43 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Adding "SUBDIRA/subdirb/SUBDIRC/HELLO" to batch 2026/04/01 03:13:43 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Batch idle for 500ms so committing 2026/04/01 03:13:43 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committing sync batch length 1 starting with: SUBDIRA/subdirb/SUBDIRC/HELLO 2026/04/01 03:13:44 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committed sync batch length 1 starting with: SUBDIRA/subdirb/SUBDIRC/HELLO 2026/04/01 03:13:45 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Uploading chunk 1/1 2026/04/01 03:13:45 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Uploading chunk 2/1 2026/04/01 03:13:46 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Adding "SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences" to batch 2026/04/01 03:13:46 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Batch idle for 500ms so committing 2026/04/01 03:13:46 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committing sync batch length 1 starting with: SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences 2026/04/01 03:13:47 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committed sync batch length 1 starting with: SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences 2026/04/01 03:13:49 DEBUG : EXISTING: size = 6 OK 2026/04/01 03:13:49 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/01 03:13:49 DEBUG : existing: Unchanged skipping 2026/04/01 03:13:49 DEBUG : existingbutdifferent: size = 5 (Local file system at /tmp/rclone3329842830) 2026/04/01 03:13:49 DEBUG : EXISTINGBUTDIFFERENT: size = 8 (Dropbox root 'rclone-test-baxajuw5kenu') 2026/04/01 03:13:49 DEBUG : existingbutdifferent: Sizes differ 2026/04/01 03:13:49 DEBUG : EXISTING: moving to existing-rclone-move-xurater7 2026/04/01 03:13:49 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-momigit6 2026/04/01 03:13:50 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/.) 2026/04/01 03:13:50 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/04/01 03:13:50 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/.) 2026/04/01 03:13:50 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/04/01 03:13:50 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/..) 2026/04/01 03:13:50 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/04/01 03:13:50 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/.) 2026/04/01 03:13:50 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/04/01 03:13:50 DEBUG : pacer: Reducing sleep to 120ms 2026/04/01 03:13:51 DEBUG : pacer: Reducing sleep to 90ms 2026/04/01 03:13:51 DEBUG : pacer: low level retry 3/10 (error too_many_write_operations/...) 2026/04/01 03:13:51 DEBUG : pacer: Rate limited, increasing sleep to 180ms 2026/04/01 03:13:51 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..) 2026/04/01 03:13:51 DEBUG : pacer: Rate limited, increasing sleep to 360ms 2026/04/01 03:13:51 DEBUG : pacer: low level retry 4/10 (error too_many_write_operations/..) 2026/04/01 03:13:51 DEBUG : pacer: Rate limited, increasing sleep to 720ms 2026/04/01 03:13:51 DEBUG : pacer: Reducing sleep to 540ms 2026/04/01 03:13:51 INFO : EXISTING: Moved (server-side) to: existing-rclone-move-xurater7 2026/04/01 03:13:51 DEBUG : EXISTING: moving to existing 2026/04/01 03:13:52 DEBUG : pacer: low level retry 5/10 (error too_many_write_operations/..) 2026/04/01 03:13:52 DEBUG : pacer: Rate limited, increasing sleep to 1.08s 2026/04/01 03:13:52 DEBUG : pacer: Reducing sleep to 810ms 2026/04/01 03:13:52 INFO : SUBDIRA: Fixed case by renaming to: subdira 2026/04/01 03:13:53 DEBUG : pacer: Reducing sleep to 607.5ms 2026/04/01 03:13:53 INFO : existing-rclone-move-xurater7: Moved (server-side) to: existing 2026/04/01 03:13:53 INFO : EXISTING: Moved (server-side) to: existing 2026/04/01 03:13:53 INFO : EXISTING: Fixed case by renaming to: existing 2026/04/01 03:13:54 DEBUG : pacer: Reducing sleep to 455.625ms 2026/04/01 03:13:54 DEBUG : pacer: Reducing sleep to 341.71875ms 2026/04/01 03:13:54 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-momigit6 2026/04/01 03:13:54 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent 2026/04/01 03:13:55 DEBUG : pacer: Reducing sleep to 256.289062ms 2026/04/01 03:13:56 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/) 2026/04/01 03:13:56 DEBUG : pacer: Rate limited, increasing sleep to 512.578124ms 2026/04/01 03:13:56 DEBUG : pacer: Reducing sleep to 384.433593ms 2026/04/01 03:13:56 INFO : existingbutdifferent-rclone-move-momigit6: Moved (server-side) to: existingbutdifferent 2026/04/01 03:13:56 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent 2026/04/01 03:13:56 INFO : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent 2026/04/01 03:13:57 DEBUG : pacer: Reducing sleep to 288.325194ms 2026/04/01 03:13:57 DEBUG : existingbutdifferent: Uploading chunk 1/1 2026/04/01 03:13:57 DEBUG : pacer: Reducing sleep to 216.243895ms 2026/04/01 03:13:57 DEBUG : pacer: Reducing sleep to 162.182921ms 2026/04/01 03:13:57 DEBUG : existingbutdifferent: Uploading chunk 2/1 2026/04/01 03:13:57 DEBUG : pacer: Reducing sleep to 121.63719ms 2026/04/01 03:13:58 DEBUG : pacer: Reducing sleep to 91.227892ms 2026/04/01 03:13:58 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Adding "existingbutdifferent" to batch 2026/04/01 03:13:58 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Batch idle for 500ms so committing 2026/04/01 03:13:58 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committing sync batch length 1 starting with: existingbutdifferent 2026/04/01 03:13:58 DEBUG : pacer: Reducing sleep to 68.420919ms 2026/04/01 03:13:58 INFO : SUBDIRA/subdirb: Fixed case by renaming to: subdira/subdirb 2026/04/01 03:13:59 DEBUG : pacer: Reducing sleep to 51.315689ms 2026/04/01 03:13:59 DEBUG : pacer: Reducing sleep to 38.486766ms 2026/04/01 03:14:00 DEBUG : pacer: Reducing sleep to 28.865074ms 2026/04/01 03:14:00 DEBUG : pacer: Reducing sleep to 21.648805ms 2026/04/01 03:14:02 DEBUG : pacer: Reducing sleep to 16.236603ms 2026/04/01 03:14:02 INFO : SUBDIRA/subdirb/SUBDIRC: Fixed case by renaming to: subdira/subdirb/subdirc 2026/04/01 03:14:02 DEBUG : pacer: Reducing sleep to 12.177452ms 2026/04/01 03:14:02 DEBUG : subdira/subdirb/subdirc/hello: size = 5 (Local file system at /tmp/rclone3329842830) 2026/04/01 03:14:02 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: size = 8 (Dropbox root 'rclone-test-baxajuw5kenu') 2026/04/01 03:14:02 DEBUG : subdira/subdirb/subdirc/hello: Sizes differ 2026/04/01 03:14:02 DEBUG : pacer: Reducing sleep to 10ms 2026/04/01 03:14:02 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello-rclone-move-pocenar3 2026/04/01 03:14:03 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/.) 2026/04/01 03:14:03 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/04/01 03:14:03 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/..) 2026/04/01 03:14:03 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/04/01 03:14:03 DEBUG : pacer: Reducing sleep to 30ms 2026/04/01 03:14:03 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-pocenar3 2026/04/01 03:14:03 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello 2026/04/01 03:14:04 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/.) 2026/04/01 03:14:04 DEBUG : pacer: Rate limited, increasing sleep to 60ms 2026/04/01 03:14:04 DEBUG : pacer: Reducing sleep to 45ms 2026/04/01 03:14:04 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/.) 2026/04/01 03:14:04 DEBUG : pacer: Rate limited, increasing sleep to 90ms 2026/04/01 03:14:05 DEBUG : pacer: Reducing sleep to 67.5ms 2026/04/01 03:14:05 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...) 2026/04/01 03:14:05 DEBUG : pacer: Rate limited, increasing sleep to 135ms 2026/04/01 03:14:05 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/..) 2026/04/01 03:14:05 DEBUG : pacer: Rate limited, increasing sleep to 270ms 2026/04/01 03:14:05 DEBUG : pacer: Reducing sleep to 202.5ms 2026/04/01 03:14:05 INFO : subdira/subdirb/subdirc/hello-rclone-move-pocenar3: Moved (server-side) to: subdira/subdirb/subdirc/hello 2026/04/01 03:14:05 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello 2026/04/01 03:14:05 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Fixed case by renaming to: subdira/subdirb/subdirc/hello 2026/04/01 03:14:06 DEBUG : pacer: Reducing sleep to 151.875ms 2026/04/01 03:14:06 DEBUG : subdira/subdirb/subdirc/hello: Uploading chunk 1/1 2026/04/01 03:14:07 DEBUG : pacer: Reducing sleep to 113.90625ms 2026/04/01 03:14:07 INFO : SUBDIRA/subdirb/SUBDIRC/subdird: Fixed case by renaming to: subdira/subdirb/subdirc/subdird 2026/04/01 03:14:07 DEBUG : pacer: Reducing sleep to 85.429687ms 2026/04/01 03:14:07 DEBUG : subdira/subdirb/subdirc/hello: Uploading chunk 2/1 2026/04/01 03:14:07 DEBUG : pacer: Reducing sleep to 64.072265ms 2026/04/01 03:14:07 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: size = 5 (Local file system at /tmp/rclone3329842830) 2026/04/01 03:14:07 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: size = 8 (Dropbox root 'rclone-test-baxajuw5kenu') 2026/04/01 03:14:07 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Sizes differ 2026/04/01 03:14:07 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Waiting for checks to finish 2026/04/01 03:14:07 DEBUG : pacer: Reducing sleep to 48.054198ms 2026/04/01 03:14:07 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-bihucir5 2026/04/01 03:14:07 DEBUG : pacer: Reducing sleep to 36.040648ms 2026/04/01 03:14:07 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Adding "subdira/subdirb/subdirc/hello" to batch 2026/04/01 03:14:08 DEBUG : pacer: Reducing sleep to 27.030486ms 2026/04/01 03:14:08 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-bihucir5 2026/04/01 03:14:08 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2026/04/01 03:14:09 DEBUG : pacer: Reducing sleep to 20.272864ms 2026/04/01 03:14:09 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-bihucir5: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2026/04/01 03:14:09 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2026/04/01 03:14:09 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Fixed case by renaming to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2026/04/01 03:14:09 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Waiting for transfers to finish 2026/04/01 03:14:09 DEBUG : pacer: low level retry 1/10 (error ) 2026/04/01 03:14:09 DEBUG : pacer: Rate limited, increasing sleep to 40.545728ms 2026/04/01 03:14:09 DEBUG : pacer: Reducing sleep to 30.409296ms 2026/04/01 03:14:09 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Uploading chunk 1/1 2026/04/01 03:14:10 DEBUG : pacer: Reducing sleep to 22.806972ms 2026/04/01 03:14:10 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Uploading chunk 2/1 2026/04/01 03:14:11 DEBUG : pacer: Reducing sleep to 17.105229ms 2026/04/01 03:14:11 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Adding "subdira/subdirb/subdirc/subdird/filewithoutcasedifferences" to batch 2026/04/01 03:14:22 DEBUG : pacer: low level retry 2/10 (error ) 2026/04/01 03:14:22 DEBUG : pacer: Rate limited, increasing sleep to 34.210458ms 2026/04/01 03:14:34 DEBUG : pacer: Reducing sleep to 25.657843ms 2026/04/01 03:14:34 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committed sync batch length 1 starting with: existingbutdifferent 2026/04/01 03:14:34 DEBUG : existingbutdifferent: size = 5 OK 2026/04/01 03:14:34 DEBUG : existingbutdifferent: dropbox = 876764f8f7cb4581b40dd4a7a1be217b2c58ae386ca01c86ea0b30bba6d050ed OK 2026/04/01 03:14:34 INFO : existingbutdifferent: Copied (replaced existing) 2026/04/01 03:14:34 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Batch idle for 500ms so committing 2026/04/01 03:14:34 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committing sync batch length 2 starting with: subdira/subdirb/subdirc/hello 2026/04/01 03:14:41 DEBUG : pacer: Reducing sleep to 19.243382ms 2026/04/01 03:14:41 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Committed sync batch length 2 starting with: subdira/subdirb/subdirc/hello 2026/04/01 03:14:41 DEBUG : subdira/subdirb/subdirc/hello: size = 5 OK 2026/04/01 03:14:41 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: size = 5 OK 2026/04/01 03:14:41 DEBUG : subdira/subdirb/subdirc/hello: dropbox = 876764f8f7cb4581b40dd4a7a1be217b2c58ae386ca01c86ea0b30bba6d050ed OK 2026/04/01 03:14:41 INFO : subdira/subdirb/subdirc/hello: Copied (replaced existing) 2026/04/01 03:14:41 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: dropbox = 876764f8f7cb4581b40dd4a7a1be217b2c58ae386ca01c86ea0b30bba6d050ed OK 2026/04/01 03:14:41 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (replaced existing) 2026/04/01 03:14:41 DEBUG : Waiting for deletions to finish 2026/04/01 03:14:41 DEBUG : pacer: Reducing sleep to 14.432536ms 2026/04/01 03:14:41 DEBUG : pacer: Reducing sleep to 10.824402ms 2026/04/01 03:14:41 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestFixCase (75.25s) PASS 2026/04/01 03:14:51 DEBUG : Dropbox root 'rclone-test-baxajuw5kenu': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestFixCase|TestSyncCompareDest)$'" - Finished OK in 1m48.892564502s (try 2/5)