"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestServerSideMoveWithFilter$'" - Starting (try 2/5)
2025/01/15 04:41:50 DEBUG : Creating backend with remote "TestDropbox:rclone-test-datefuz5juni"
2025/01/15 04:41:50 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/01/15 04:41:51 DEBUG : Creating backend with remote "/tmp/rclone1198986083"
=== RUN   TestServerSideMoveWithFilter
    run.go:180: Remote "Dropbox root 'rclone-test-datefuz5juni'", Local "Local file system at /tmp/rclone1198986083", Modify Window "1s"
2025/01/15 04:41:51 DEBUG : Creating backend with remote "TestDropbox:rclone-test-mifumub7riyu"
2025/01/15 04:41:52 DEBUG : potato2: Uploading chunk 1/1
2025/01/15 04:41:53 DEBUG : potato2: Uploading chunk 2/1
2025/01/15 04:41:53 DEBUG : Dropbox root 'rclone-test-datefuz5juni': Adding "potato2" to batch
2025/01/15 04:41:54 DEBUG : Dropbox root 'rclone-test-datefuz5juni': Batch idle for 500ms so committing
2025/01/15 04:41:54 DEBUG : Dropbox root 'rclone-test-datefuz5juni': Committing sync batch length 1 starting with: potato2
2025/01/15 04:41:54 DEBUG : Dropbox root 'rclone-test-datefuz5juni': Committed sync batch length 1 starting with: potato2
2025/01/15 04:41:54 DEBUG : empty space: Uploading chunk 1/1
2025/01/15 04:41:55 DEBUG : empty space: Uploading chunk 2/1
2025/01/15 04:41:55 DEBUG : Dropbox root 'rclone-test-datefuz5juni': Adding "empty space" to batch
2025/01/15 04:41:56 DEBUG : Dropbox root 'rclone-test-datefuz5juni': Batch idle for 500ms so committing
2025/01/15 04:41:56 DEBUG : Dropbox root 'rclone-test-datefuz5juni': Committing sync batch length 1 starting with: empty space
2025/01/15 04:41:56 DEBUG : Dropbox root 'rclone-test-datefuz5juni': Committed sync batch length 1 starting with: empty space
2025/01/15 04:41:57 DEBUG : potato3: Uploading chunk 1/1
2025/01/15 04:41:57 DEBUG : potato3: Uploading chunk 2/1
2025/01/15 04:41:58 DEBUG : Dropbox root 'rclone-test-datefuz5juni': Adding "potato3" to batch
2025/01/15 04:41:58 DEBUG : Dropbox root 'rclone-test-datefuz5juni': Batch idle for 500ms so committing
2025/01/15 04:41:58 DEBUG : Dropbox root 'rclone-test-datefuz5juni': Committing sync batch length 1 starting with: potato3
2025/01/15 04:41:59 DEBUG : Dropbox root 'rclone-test-datefuz5juni': Committed sync batch length 1 starting with: potato3
    sync_test.go:1705: Server side move (if possible) Dropbox root 'rclone-test-datefuz5juni' -> Dropbox root 'rclone-test-mifumub7riyu'
2025/01/15 04:42:00 DEBUG : empty space: Uploading chunk 1/1
2025/01/15 04:42:00 DEBUG : empty space: Uploading chunk 2/1
2025/01/15 04:42:01 DEBUG : Dropbox root 'rclone-test-mifumub7riyu': Adding "empty space" to batch
2025/01/15 04:42:01 DEBUG : Dropbox root 'rclone-test-mifumub7riyu': Batch idle for 500ms so committing
2025/01/15 04:42:01 DEBUG : Dropbox root 'rclone-test-mifumub7riyu': Committing sync batch length 1 starting with: empty space
2025/01/15 04:42:02 DEBUG : Dropbox root 'rclone-test-mifumub7riyu': Committed sync batch length 1 starting with: empty space
2025/01/15 04:42:02 DEBUG : potato3: Uploading chunk 1/1
2025/01/15 04:42:03 DEBUG : potato3: Uploading chunk 2/1
2025/01/15 04:42:03 DEBUG : Dropbox root 'rclone-test-mifumub7riyu': Adding "potato3" to batch
2025/01/15 04:42:04 DEBUG : Dropbox root 'rclone-test-mifumub7riyu': Batch idle for 500ms so committing
2025/01/15 04:42:04 DEBUG : Dropbox root 'rclone-test-mifumub7riyu': Committing sync batch length 1 starting with: potato3
2025/01/15 04:42:04 DEBUG : Dropbox root 'rclone-test-mifumub7riyu': Committed sync batch length 1 starting with: potato3
2025/01/15 04:42:05 DEBUG : empty space: Excluded (Size Filter)
2025/01/15 04:42:05 DEBUG : empty space: Excluded
2025/01/15 04:42:05 DEBUG : empty space: Excluded (Size Filter)
2025/01/15 04:42:05 DEBUG : empty space: Excluded
2025/01/15 04:42:05 DEBUG : potato2: Need to transfer - File not found at Destination
2025/01/15 04:42:05 DEBUG : Dropbox root 'rclone-test-mifumub7riyu': Waiting for checks to finish
2025/01/15 04:42:05 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2025/01/15 04:42:05 DEBUG : Dropbox root 'rclone-test-mifumub7riyu': Waiting for transfers to finish
2025/01/15 04:42:05 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..)
2025/01/15 04:42:05 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/01/15 04:42:05 DEBUG : pacer: Reducing sleep to 15ms
2025/01/15 04:42:05 INFO  : potato3: Deleted
2025/01/15 04:42:06 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..)
2025/01/15 04:42:06 DEBUG : pacer: Rate limited, increasing sleep to 30ms
2025/01/15 04:42:06 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/..)
2025/01/15 04:42:06 DEBUG : pacer: Rate limited, increasing sleep to 60ms
2025/01/15 04:42:06 DEBUG : pacer: Reducing sleep to 45ms
2025/01/15 04:42:06 INFO  : potato2: Moved (server-side)
2025/01/15 04:42:07 DEBUG : pacer: Reducing sleep to 33.75ms
2025/01/15 04:42:07 INFO  : potato3: Moved (server-side)
2025/01/15 04:42:08 DEBUG : pacer: Reducing sleep to 25.3125ms
2025/01/15 04:42:08 DEBUG : Creating backend with remote "TestDropbox:rclone-test-noboqeh6gene"
2025/01/15 04:42:09 DEBUG : pacer: Reducing sleep to 18.984375ms
2025/01/15 04:42:09 DEBUG : empty space: Excluded (Size Filter)
2025/01/15 04:42:09 DEBUG : empty space: Excluded
2025/01/15 04:42:09 DEBUG : potato2: Need to transfer - File not found at Destination
2025/01/15 04:42:09 DEBUG : potato3: Need to transfer - File not found at Destination
2025/01/15 04:42:09 DEBUG : Dropbox root 'rclone-test-noboqeh6gene': Waiting for checks to finish
2025/01/15 04:42:09 DEBUG : Dropbox root 'rclone-test-noboqeh6gene': Waiting for transfers to finish
2025/01/15 04:42:09 DEBUG : pacer: low level retry 1/10 (error from_write/too_many_write_operations/...)
2025/01/15 04:42:09 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/01/15 04:42:10 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/..)
2025/01/15 04:42:10 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/01/15 04:42:10 DEBUG : pacer: Reducing sleep to 30ms
2025/01/15 04:42:10 INFO  : potato3: Moved (server-side)
2025/01/15 04:42:11 DEBUG : pacer: Reducing sleep to 22.5ms
2025/01/15 04:42:11 INFO  : potato2: Moved (server-side)
2025/01/15 04:42:11 DEBUG : pacer: Reducing sleep to 16.875ms
2025/01/15 04:42:11 DEBUG : pacer: Reducing sleep to 14.238281ms
2025/01/15 04:42:11 DEBUG : Dropbox root 'rclone-test-noboqeh6gene': Purge remote
2025/01/15 04:42:12 DEBUG : pacer: Reducing sleep to 12.65625ms
2025/01/15 04:42:12 DEBUG : Dropbox root 'rclone-test-mifumub7riyu': Purge remote
2025/01/15 04:42:13 DEBUG : pacer: Reducing sleep to 10.67871ms
--- PASS: TestServerSideMoveWithFilter (23.34s)
PASS
2025/01/15 04:42:14 DEBUG : Dropbox root 'rclone-test-datefuz5juni': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestServerSideMoveWithFilter$'" - Finished OK in 24.397298116s (try 2/5)