"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestBase64|TestManualTransformFile|TestSyncConcurrentTruncate)$'" - Starting (try 2/5)
2025/06/29 04:33:01 DEBUG : Creating backend with remote "TestDropbox:rclone-test-qoyihin2zuhi"
2025/06/29 04:33:01 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/06/29 04:33:01 DEBUG : Creating backend with remote "/tmp/rclone3270724200"
=== RUN   TestSyncConcurrentTruncate
    run.go:180: Remote "Dropbox root 'rclone-test-qoyihin2zuhi'", Local "Local file system at /tmp/rclone3270724200", Modify Window "1s"
2025/06/29 04:33:02 DEBUG : both0: Uploading chunk 1/1
2025/06/29 04:33:02 DEBUG : both0: Uploading chunk 2/1
2025/06/29 04:33:03 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "both0" to batch
2025/06/29 04:33:03 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:03 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: both0
2025/06/29 04:33:04 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: both0
2025/06/29 04:33:04 DEBUG : only0: Uploading chunk 1/1
2025/06/29 04:33:04 DEBUG : only0: Uploading chunk 2/1
2025/06/29 04:33:05 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "only0" to batch
2025/06/29 04:33:05 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:05 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: only0
2025/06/29 04:33:06 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: only0
2025/06/29 04:33:06 DEBUG : both1: Uploading chunk 1/1
2025/06/29 04:33:07 DEBUG : both1: Uploading chunk 2/1
2025/06/29 04:33:07 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "both1" to batch
2025/06/29 04:33:08 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:08 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: both1
2025/06/29 04:33:08 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: both1
2025/06/29 04:33:09 DEBUG : only1: Uploading chunk 1/1
2025/06/29 04:33:09 DEBUG : only1: Uploading chunk 2/1
2025/06/29 04:33:09 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "only1" to batch
2025/06/29 04:33:10 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:10 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: only1
2025/06/29 04:33:11 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: only1
2025/06/29 04:33:11 DEBUG : both2: Uploading chunk 1/1
2025/06/29 04:33:11 DEBUG : both2: Uploading chunk 2/1
2025/06/29 04:33:12 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "both2" to batch
2025/06/29 04:33:12 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:12 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: both2
2025/06/29 04:33:13 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: both2
2025/06/29 04:33:13 DEBUG : only2: Uploading chunk 1/1
2025/06/29 04:33:14 DEBUG : only2: Uploading chunk 2/1
2025/06/29 04:33:14 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "only2" to batch
2025/06/29 04:33:15 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:15 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: only2
2025/06/29 04:33:15 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: only2
2025/06/29 04:33:16 DEBUG : both3: Uploading chunk 1/1
2025/06/29 04:33:16 DEBUG : both3: Uploading chunk 2/1
2025/06/29 04:33:16 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "both3" to batch
2025/06/29 04:33:17 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:17 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: both3
2025/06/29 04:33:18 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: both3
2025/06/29 04:33:18 DEBUG : only3: Uploading chunk 1/1
2025/06/29 04:33:19 DEBUG : only3: Uploading chunk 2/1
2025/06/29 04:33:19 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "only3" to batch
2025/06/29 04:33:20 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:20 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: only3
2025/06/29 04:33:20 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: only3
2025/06/29 04:33:21 DEBUG : both4: Uploading chunk 1/1
2025/06/29 04:33:21 DEBUG : both4: Uploading chunk 2/1
2025/06/29 04:33:21 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "both4" to batch
2025/06/29 04:33:22 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:22 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: both4
2025/06/29 04:33:23 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: both4
2025/06/29 04:33:23 DEBUG : only4: Uploading chunk 1/1
2025/06/29 04:33:23 DEBUG : only4: Uploading chunk 2/1
2025/06/29 04:33:24 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "only4" to batch
2025/06/29 04:33:24 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:24 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: only4
2025/06/29 04:33:25 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: only4
2025/06/29 04:33:25 DEBUG : both5: Uploading chunk 1/1
2025/06/29 04:33:25 DEBUG : both5: Uploading chunk 2/1
2025/06/29 04:33:25 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "both5" to batch
2025/06/29 04:33:26 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:26 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: both5
2025/06/29 04:33:27 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: both5
2025/06/29 04:33:27 DEBUG : only5: Uploading chunk 1/1
2025/06/29 04:33:28 DEBUG : only5: Uploading chunk 2/1
2025/06/29 04:33:28 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "only5" to batch
2025/06/29 04:33:29 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:29 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: only5
2025/06/29 04:33:30 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: only5
2025/06/29 04:33:30 DEBUG : both6: Uploading chunk 1/1
2025/06/29 04:33:30 DEBUG : both6: Uploading chunk 2/1
2025/06/29 04:33:30 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "both6" to batch
2025/06/29 04:33:31 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:31 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: both6
2025/06/29 04:33:32 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: both6
2025/06/29 04:33:32 DEBUG : only6: Uploading chunk 1/1
2025/06/29 04:33:32 DEBUG : only6: Uploading chunk 2/1
2025/06/29 04:33:33 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "only6" to batch
2025/06/29 04:33:33 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:33 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: only6
2025/06/29 04:33:34 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: only6
2025/06/29 04:33:34 DEBUG : both7: Uploading chunk 1/1
2025/06/29 04:33:35 DEBUG : both7: Uploading chunk 2/1
2025/06/29 04:33:35 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "both7" to batch
2025/06/29 04:33:35 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:35 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: both7
2025/06/29 04:33:36 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: both7
2025/06/29 04:33:36 DEBUG : only7: Uploading chunk 1/1
2025/06/29 04:33:36 DEBUG : only7: Uploading chunk 2/1
2025/06/29 04:33:37 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "only7" to batch
2025/06/29 04:33:37 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:37 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: only7
2025/06/29 04:33:38 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: only7
2025/06/29 04:33:38 DEBUG : both8: Uploading chunk 1/1
2025/06/29 04:33:39 DEBUG : both8: Uploading chunk 2/1
2025/06/29 04:33:39 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "both8" to batch
2025/06/29 04:33:40 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:40 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: both8
2025/06/29 04:33:40 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: both8
2025/06/29 04:33:41 DEBUG : only8: Uploading chunk 1/1
2025/06/29 04:33:41 DEBUG : only8: Uploading chunk 2/1
2025/06/29 04:33:41 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "only8" to batch
2025/06/29 04:33:42 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:42 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: only8
2025/06/29 04:33:43 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: only8
2025/06/29 04:33:43 DEBUG : both9: Uploading chunk 1/1
2025/06/29 04:33:44 DEBUG : both9: Uploading chunk 2/1
2025/06/29 04:33:44 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "both9" to batch
2025/06/29 04:33:44 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:44 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: both9
2025/06/29 04:33:45 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: both9
2025/06/29 04:33:45 DEBUG : only9: Uploading chunk 1/1
2025/06/29 04:33:46 DEBUG : only9: Uploading chunk 2/1
2025/06/29 04:33:46 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "only9" to batch
2025/06/29 04:33:46 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:46 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: only9
2025/06/29 04:33:49 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: only9
2025/06/29 04:33:49 DEBUG : both10: Uploading chunk 1/1
2025/06/29 04:33:50 DEBUG : both10: Uploading chunk 2/1
2025/06/29 04:33:50 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "both10" to batch
2025/06/29 04:33:50 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:50 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: both10
2025/06/29 04:33:51 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: both10
2025/06/29 04:33:51 DEBUG : only10: Uploading chunk 1/1
2025/06/29 04:33:52 DEBUG : only10: Uploading chunk 2/1
2025/06/29 04:33:52 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "only10" to batch
2025/06/29 04:33:53 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:53 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: only10
2025/06/29 04:33:53 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: only10
2025/06/29 04:33:54 DEBUG : both11: Uploading chunk 1/1
2025/06/29 04:33:54 DEBUG : both11: Uploading chunk 2/1
2025/06/29 04:33:54 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "both11" to batch
2025/06/29 04:33:55 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:55 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: both11
2025/06/29 04:33:56 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: both11
2025/06/29 04:33:56 DEBUG : only11: Uploading chunk 1/1
2025/06/29 04:33:56 DEBUG : only11: Uploading chunk 2/1
2025/06/29 04:33:57 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "only11" to batch
2025/06/29 04:33:57 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:33:57 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: only11
2025/06/29 04:33:58 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: only11
2025/06/29 04:33:58 DEBUG : both12: Uploading chunk 1/1
2025/06/29 04:33:59 DEBUG : both12: Uploading chunk 2/1
2025/06/29 04:33:59 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "both12" to batch
2025/06/29 04:34:00 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:34:00 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: both12
2025/06/29 04:34:00 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: both12
2025/06/29 04:34:01 DEBUG : only12: Uploading chunk 1/1
2025/06/29 04:34:01 DEBUG : only12: Uploading chunk 2/1
2025/06/29 04:34:01 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "only12" to batch
2025/06/29 04:34:02 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:34:02 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: only12
2025/06/29 04:34:03 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: only12
2025/06/29 04:34:03 DEBUG : both13: Uploading chunk 1/1
2025/06/29 04:34:03 DEBUG : both13: Uploading chunk 2/1
2025/06/29 04:34:04 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "both13" to batch
2025/06/29 04:34:04 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:34:04 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: both13
2025/06/29 04:34:06 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: both13
2025/06/29 04:34:06 DEBUG : only13: Uploading chunk 1/1
2025/06/29 04:34:07 DEBUG : only13: Uploading chunk 2/1
2025/06/29 04:34:07 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "only13" to batch
2025/06/29 04:34:08 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:34:08 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: only13
2025/06/29 04:34:09 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: only13
2025/06/29 04:34:09 DEBUG : both14: Uploading chunk 1/1
2025/06/29 04:34:09 DEBUG : both14: Uploading chunk 2/1
2025/06/29 04:34:10 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "both14" to batch
2025/06/29 04:34:10 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:34:10 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: both14
2025/06/29 04:34:13 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: both14
2025/06/29 04:34:13 DEBUG : only14: Uploading chunk 1/1
2025/06/29 04:34:13 DEBUG : only14: Uploading chunk 2/1
2025/06/29 04:34:14 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "only14" to batch
2025/06/29 04:34:14 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:34:14 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: only14
2025/06/29 04:34:16 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: only14
2025/06/29 04:34:16 DEBUG : both15: Uploading chunk 1/1
2025/06/29 04:34:16 DEBUG : both15: Uploading chunk 2/1
2025/06/29 04:34:16 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "both15" to batch
2025/06/29 04:34:17 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:34:17 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: both15
2025/06/29 04:34:19 ERROR : Dropbox root 'rclone-test-qoyihin2zuhi': 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 "both15" to "Dropbox root 'rclone-test-qoyihin2zuhi'": upload failed: batch upload failed: upload failed: too_many_write_operations
--- FAIL: TestSyncConcurrentTruncate (100.52s)
=== RUN   TestManualTransformFile
    run.go:180: Remote "Dropbox root 'rclone-test-qoyihin2zuhi'", Local "Local file system at /tmp/rclone3270724200", Modify Window "1s"
2025/06/29 04:34:41 DEBUG : Reset feature "Copy"
2025/06/29 04:34:41 DEBUG : Reset feature "Move"
2025/06/29 04:34:41 DEBUG : Reset feature "Copy"
2025/06/29 04:34:41 DEBUG : Reset feature "Move"
2025/06/29 04:34:41 DEBUG : empty_dir: Making directory with metadata
2025/06/29 04:34:41 INFO  : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z)
2025/06/29 04:34:41 DEBUG : toe: transformed to: tictactoe
2025/06/29 04:34:41 DEBUG : empty_dir: transformed to: tictacempty_dir
2025/06/29 04:34:41 DEBUG : empty_dir: transformed to: tictacempty_dir
2025/06/29 04:34:42 DEBUG : empty_dir: transformed to: tictacempty_dir
2025/06/29 04:34:42 INFO  : tictacempty_dir: Making directory
2025/06/29 04:34:42 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/)
2025/06/29 04:34:42 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/06/29 04:34:42 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/)
2025/06/29 04:34:42 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/06/29 04:34:43 DEBUG : pacer: Reducing sleep to 30ms
2025/06/29 04:34:43 DEBUG : empty_dir: transformed to: tictacempty_dir
2025/06/29 04:34:43 DEBUG : toe: transformed to: tictactoe
2025/06/29 04:34:43 DEBUG : toe: transformed to: tictactoe
2025/06/29 04:34:43 INFO  : tictactoe: Making directory
2025/06/29 04:34:43 DEBUG : pacer: Reducing sleep to 22.5ms
2025/06/29 04:34:44 DEBUG : pacer: Reducing sleep to 16.875ms
2025/06/29 04:34:44 DEBUG : toe: transformed to: tictactoe
2025/06/29 04:34:44 DEBUG : toe: transformed to: tictactoe
2025/06/29 04:34:44 DEBUG : toe/toe: transformed to: tictactoe/tictactoe
2025/06/29 04:34:44 INFO  : tictactoe/tictactoe: Making directory
2025/06/29 04:34:44 DEBUG : pacer: Reducing sleep to 12.65625ms
2025/06/29 04:34:45 DEBUG : pacer: Reducing sleep to 10ms
2025/06/29 04:34:45 DEBUG : toe/toe: transformed to: tictactoe/tictactoe
2025/06/29 04:34:45 DEBUG : toe.txt: transformed to: tictactoe.txt
2025/06/29 04:34:45 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt
2025/06/29 04:34:45 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination
2025/06/29 04:34:45 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt
2025/06/29 04:34:45 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt
2025/06/29 04:34:45 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Waiting for checks to finish
2025/06/29 04:34:45 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Waiting for transfers to finish
2025/06/29 04:34:45 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 1/1
2025/06/29 04:34:45 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 2/1
2025/06/29 04:34:46 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "tictactoe/tictactoe/tictactoe.txt" to batch
2025/06/29 04:34:46 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:34:46 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: tictactoe/tictactoe/tictactoe.txt
2025/06/29 04:34:47 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: tictactoe/tictactoe/tictactoe.txt
2025/06/29 04:34:47 DEBUG : toe/toe/toe.txt: dropbox = bc62d4b80d9e36da29c16c5d4d9f11731f36052c72401a76c23c0fb5a9b74423 OK
2025/06/29 04:34:47 INFO  : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt
2025/06/29 04:34:47 INFO  : toe/toe/toe.txt: Deleted
2025/06/29 04:34:47 INFO  : toe/toe: Removing directory
2025/06/29 04:34:47 INFO  : toe: Removing directory
2025/06/29 04:34:47 INFO  : empty_dir: Removing directory
2025/06/29 04:34:47 DEBUG : Local file system at /tmp/rclone3270724200: deleted 3 directories
2025/06/29 04:34:48 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s)
2025/06/29 04:34:48 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt
2025/06/29 04:34:48 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt
2025/06/29 04:34:48 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt
2025/06/29 04:34:49 DEBUG : toe/toe/toe.txt: Uploading chunk 1/1
2025/06/29 04:34:49 DEBUG : toe/toe/toe.txt: Uploading chunk 2/1
2025/06/29 04:34:49 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "toe/toe/toe.txt" to batch
2025/06/29 04:34:50 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:34:50 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: toe/toe/toe.txt
2025/06/29 04:34:51 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: toe/toe/toe.txt
2025/06/29 04:34:51 DEBUG : tictactoe/tictactoe/tictactoe.txt: dropbox = bc62d4b80d9e36da29c16c5d4d9f11731f36052c72401a76c23c0fb5a9b74423 OK
2025/06/29 04:34:51 INFO  : tictactoe/tictactoe/tictactoe.txt: Copied (new) to: toe/toe/toe.txt
2025/06/29 04:34:52 INFO  : tictactoe/tictactoe/tictactoe.txt: Deleted
--- PASS: TestManualTransformFile (21.05s)
=== RUN   TestBase64
    run.go:180: Remote "Dropbox root 'rclone-test-qoyihin2zuhi'", Local "Local file system at /tmp/rclone3270724200", Modify Window "1s"
2025/06/29 04:35:02 DEBUG : toe: transformed to: dG9l
2025/06/29 04:35:03 DEBUG : toe: transformed to: dG9l
2025/06/29 04:35:03 INFO  : dG9l: Making directory
2025/06/29 04:35:03 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/...)
2025/06/29 04:35:03 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/06/29 04:35:03 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/)
2025/06/29 04:35:03 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/06/29 04:35:04 DEBUG : pacer: Reducing sleep to 30ms
2025/06/29 04:35:04 DEBUG : toe: transformed to: dG9l
2025/06/29 04:35:04 DEBUG : toe: transformed to: dG9l
2025/06/29 04:35:04 DEBUG : toe/toe: transformed to: dG9l/dG9l
2025/06/29 04:35:04 INFO  : dG9l/dG9l: Making directory
2025/06/29 04:35:04 DEBUG : pacer: Reducing sleep to 22.5ms
2025/06/29 04:35:05 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..)
2025/06/29 04:35:05 DEBUG : pacer: Rate limited, increasing sleep to 45ms
2025/06/29 04:35:05 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/..)
2025/06/29 04:35:05 DEBUG : pacer: Rate limited, increasing sleep to 90ms
2025/06/29 04:35:06 DEBUG : pacer: Reducing sleep to 67.5ms
2025/06/29 04:35:06 DEBUG : toe/toe: transformed to: dG9l/dG9l
2025/06/29 04:35:06 DEBUG : toe.txt: transformed to: dG9lLnR4dA==
2025/06/29 04:35:06 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA==
2025/06/29 04:35:06 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination
2025/06/29 04:35:06 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA==
2025/06/29 04:35:06 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Waiting for checks to finish
2025/06/29 04:35:06 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Waiting for transfers to finish
2025/06/29 04:35:06 DEBUG : pacer: Reducing sleep to 50.625ms
2025/06/29 04:35:06 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Uploading chunk 1/1
2025/06/29 04:35:06 DEBUG : pacer: Reducing sleep to 37.96875ms
2025/06/29 04:35:06 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Uploading chunk 2/1
2025/06/29 04:35:07 DEBUG : pacer: Reducing sleep to 28.476562ms
2025/06/29 04:35:07 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Adding "dG9l/dG9l/dG9lLnR4dA==" to batch
2025/06/29 04:35:07 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Batch idle for 500ms so committing
2025/06/29 04:35:07 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committing sync batch length 1 starting with: dG9l/dG9l/dG9lLnR4dA==
2025/06/29 04:35:08 DEBUG : pacer: Reducing sleep to 21.357421ms
2025/06/29 04:35:08 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Committed sync batch length 1 starting with: dG9l/dG9l/dG9lLnR4dA==
2025/06/29 04:35:08 DEBUG : toe/toe/toe.txt: dropbox = bc62d4b80d9e36da29c16c5d4d9f11731f36052c72401a76c23c0fb5a9b74423 OK
2025/06/29 04:35:08 INFO  : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA==
2025/06/29 04:35:08 DEBUG : Waiting for deletions to finish
2025/06/29 04:35:08 DEBUG : pacer: Reducing sleep to 16.018065ms
2025/06/29 04:35:08 DEBUG : pacer: Reducing sleep to 12.013548ms
2025/06/29 04:35:08 DEBUG : pacer: Reducing sleep to 10ms
2025/06/29 04:35:09 DEBUG : dG9l: transformed to: toe
2025/06/29 04:35:09 DEBUG : dG9l: transformed to: toe
2025/06/29 04:35:09 INFO  : toe: Set directory modification time (using SetModTime)
2025/06/29 04:35:09 DEBUG : Added delayed dir = "toe", newDst=toe
2025/06/29 04:35:09 DEBUG : dG9l: transformed to: toe
2025/06/29 04:35:09 DEBUG : dG9l/dG9l: transformed to: toe/toe
2025/06/29 04:35:09 INFO  : toe/toe: Set directory modification time (using SetModTime)
2025/06/29 04:35:09 DEBUG : Added delayed dir = "toe/toe", newDst=toe/toe
2025/06/29 04:35:09 DEBUG : dG9lLnR4dA==: transformed to: toe.txt
2025/06/29 04:35:09 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Size and modification time the same (differ by 499.999999ms, within tolerance 1s)
2025/06/29 04:35:09 DEBUG : dG9l/dG9l/dG9lLnR4dA==: transformed to: toe/toe/toe.txt
2025/06/29 04:35:09 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Unchanged skipping
2025/06/29 04:35:09 DEBUG : Local file system at /tmp/rclone3270724200: Waiting for checks to finish
2025/06/29 04:35:09 DEBUG : Local file system at /tmp/rclone3270724200: Waiting for transfers to finish
2025/06/29 04:35:09 DEBUG : Waiting for deletions to finish
--- PASS: TestBase64 (11.61s)
FAIL
2025/06/29 04:35:14 DEBUG : Dropbox root 'rclone-test-qoyihin2zuhi': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestBase64|TestManualTransformFile|TestSyncConcurrentTruncate)$'" - Finished ERROR in 2m14.182122115s (try 2/5): exit status 1: Failed [TestSyncConcurrentTruncate]