"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestCopyFileCopyDest|TestLsd)$'" - Starting (try 2/5)
2025/06/19 02:37:02 DEBUG : Creating backend with remote "TestDropbox:rclone-test-wahabir4muwi"
2025/06/19 02:37:02 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/06/19 02:37:02 DEBUG : Creating backend with remote "/tmp/rclone3045662707"
=== RUN   TestCopyFileCopyDest
    run.go:180: Remote "Dropbox root 'rclone-test-wahabir4muwi'", Local "Local file system at /tmp/rclone3045662707", Modify Window "1s"
2025/06/19 02:37:02 DEBUG : Creating backend with remote "TestDropbox:rclone-test-wahabir4muwi/dst"
2025/06/19 02:37:03 DEBUG : Creating backend with remote "TestDropbox:rclone-test-wahabir4muwi/CopyDest"
2025/06/19 02:37:03 DEBUG : one: Need to transfer - File not found at Destination
2025/06/19 02:37:04 DEBUG : one: Uploading chunk 1/1
2025/06/19 02:37:04 DEBUG : one: Uploading chunk 2/1
2025/06/19 02:37:05 DEBUG : Dropbox root 'rclone-test-wahabir4muwi/dst': Adding "one" to batch
2025/06/19 02:37:05 DEBUG : Dropbox root 'rclone-test-wahabir4muwi/dst': Batch idle for 500ms so committing
2025/06/19 02:37:05 DEBUG : Dropbox root 'rclone-test-wahabir4muwi/dst': Committing sync batch length 1 starting with: one
2025/06/19 02:37:06 DEBUG : Dropbox root 'rclone-test-wahabir4muwi/dst': Committed sync batch length 1 starting with: one
2025/06/19 02:37:06 DEBUG : one: dropbox = fe8d7a873dc48961a6af334c996b2cb3ce37149d5ce9c9253952a54c6a92c1ad OK
2025/06/19 02:37:06 INFO  : one: Copied (new)
2025/06/19 02:37:07 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/06/19 02:37:08 DEBUG : one: Uploading chunk 1/1
2025/06/19 02:37:08 DEBUG : one: Uploading chunk 2/1
2025/06/19 02:37:08 DEBUG : Dropbox root 'rclone-test-wahabir4muwi/dst': Adding "one" to batch
2025/06/19 02:37:09 DEBUG : Dropbox root 'rclone-test-wahabir4muwi/dst': Batch idle for 500ms so committing
2025/06/19 02:37:09 DEBUG : Dropbox root 'rclone-test-wahabir4muwi/dst': Committing sync batch length 1 starting with: one
2025/06/19 02:37:09 DEBUG : Dropbox root 'rclone-test-wahabir4muwi/dst': Committed sync batch length 1 starting with: one
2025/06/19 02:37:09 DEBUG : one: dropbox = 7da8d117f9fb226817d81adc15c72720c9879c8b7fb821d27f217d198bd79d0d OK
2025/06/19 02:37:09 INFO  : one: Copied (replaced existing)
2025/06/19 02:37:10 DEBUG : dst/one: Uploading chunk 1/1
2025/06/19 02:37:11 DEBUG : dst/one: Uploading chunk 2/1
2025/06/19 02:37:11 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Adding "dst/one" to batch
2025/06/19 02:37:12 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Batch idle for 500ms so committing
2025/06/19 02:37:12 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Committing sync batch length 1 starting with: dst/one
2025/06/19 02:37:13 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Committed sync batch length 1 starting with: dst/one
2025/06/19 02:37:13 DEBUG : CopyDest/one: Uploading chunk 1/1
2025/06/19 02:37:13 DEBUG : CopyDest/one: Uploading chunk 2/1
2025/06/19 02:37:14 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Adding "CopyDest/one" to batch
2025/06/19 02:37:14 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Batch idle for 500ms so committing
2025/06/19 02:37:14 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Committing sync batch length 1 starting with: CopyDest/one
2025/06/19 02:37:15 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Committed sync batch length 1 starting with: CopyDest/one
2025/06/19 02:37:16 DEBUG : Creating backend with remote "TestDropbox:rclone-test-wahabir4muwi/BackupDir"
2025/06/19 02:37:16 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/06/19 02:37:16 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/06/19 02:37:16 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/06/19 02:37:17 DEBUG : pacer: low level retry 1/10 (error from_write/too_many_write_operations/)
2025/06/19 02:37:17 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/06/19 02:37:17 DEBUG : pacer: low level retry 2/10 (error from_write/too_many_write_operations/.)
2025/06/19 02:37:17 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/06/19 02:37:18 DEBUG : pacer: low level retry 3/10 (error from_write/too_many_write_operations/)
2025/06/19 02:37:18 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2025/06/19 02:37:19 DEBUG : pacer: Reducing sleep to 60ms
2025/06/19 02:37:19 INFO  : one: Moved (server-side)
2025/06/19 02:37:21 DEBUG : one: dropbox = 7da8d117f9fb226817d81adc15c72720c9879c8b7fb821d27f217d198bd79d0d OK
2025/06/19 02:37:21 INFO  : one: Copied (server-side copy)
2025/06/19 02:37:21 DEBUG : one: Destination found in --copy-dest, using server-side copy
2025/06/19 02:37:22 DEBUG : CopyDest/two: Uploading chunk 1/1
2025/06/19 02:37:22 DEBUG : CopyDest/two: Uploading chunk 2/1
2025/06/19 02:37:23 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Adding "CopyDest/two" to batch
2025/06/19 02:37:23 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Batch idle for 500ms so committing
2025/06/19 02:37:23 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Committing sync batch length 1 starting with: CopyDest/two
2025/06/19 02:37:24 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Committed sync batch length 1 starting with: CopyDest/two
2025/06/19 02:37:24 DEBUG : two: Need to transfer - File not found at Destination
2025/06/19 02:37:25 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/06/19 02:37:26 DEBUG : two: dropbox = 313db63e0283ab2a9bc72e9a0ac749da1d3acf708fcd1a7848767244dd2f17e8 OK
2025/06/19 02:37:26 INFO  : two: Copied (server-side copy)
2025/06/19 02:37:26 DEBUG : two: Destination found in --copy-dest, using server-side copy
2025/06/19 02:37:27 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/06/19 02:37:27 DEBUG : two: Unchanged skipping
2025/06/19 02:37:28 DEBUG : CopyDest/three: Uploading chunk 1/1
2025/06/19 02:37:28 DEBUG : CopyDest/three: Uploading chunk 2/1
2025/06/19 02:37:28 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Adding "CopyDest/three" to batch
2025/06/19 02:37:29 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Batch idle for 500ms so committing
2025/06/19 02:37:29 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Committing sync batch length 1 starting with: CopyDest/three
2025/06/19 02:37:30 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Committed sync batch length 1 starting with: CopyDest/three
2025/06/19 02:37:30 DEBUG : three: Need to transfer - File not found at Destination
2025/06/19 02:37:31 DEBUG : three: Sizes differ (src 7 vs dst 5)
2025/06/19 02:37:31 DEBUG : three: Destination not found in --copy-dest
2025/06/19 02:37:31 DEBUG : three: Uploading chunk 1/1
2025/06/19 02:37:31 DEBUG : three: Uploading chunk 2/1
2025/06/19 02:37:32 DEBUG : Dropbox root 'rclone-test-wahabir4muwi/dst': Adding "three" to batch
2025/06/19 02:37:32 DEBUG : Dropbox root 'rclone-test-wahabir4muwi/dst': Batch idle for 500ms so committing
2025/06/19 02:37:32 DEBUG : Dropbox root 'rclone-test-wahabir4muwi/dst': Committing sync batch length 1 starting with: three
2025/06/19 02:37:33 DEBUG : Dropbox root 'rclone-test-wahabir4muwi/dst': Committed sync batch length 1 starting with: three
2025/06/19 02:37:33 DEBUG : three: dropbox = 78af7ad260025156e50fd9648fd9a36509cd3541c9a8d02597e9b3c598937cbd OK
2025/06/19 02:37:33 INFO  : three: Copied (new)
2025/06/19 02:37:43 INFO  : Dropbox root 'rclone-test-wahabir4muwi/BackupDir': Committing uploads - please wait...
2025/06/19 02:37:43 INFO  : Dropbox root 'rclone-test-wahabir4muwi/CopyDest': Committing uploads - please wait...
--- PASS: TestCopyFileCopyDest (40.56s)
=== RUN   TestLsd
    run.go:180: Remote "Dropbox root 'rclone-test-wahabir4muwi'", Local "Local file system at /tmp/rclone3045662707", Modify Window "1s"
2025/06/19 02:37:43 DEBUG : sub dir/hello world: Uploading chunk 1/1
2025/06/19 02:37:43 DEBUG : sub dir/hello world: Uploading chunk 2/1
2025/06/19 02:37:43 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Adding "sub dir/hello world" to batch
2025/06/19 02:37:44 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Batch idle for 500ms so committing
2025/06/19 02:37:44 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Committing sync batch length 1 starting with: sub dir/hello world
2025/06/19 02:37:45 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Committed sync batch length 1 starting with: sub dir/hello world
--- PASS: TestLsd (5.90s)
PASS
2025/06/19 02:37:48 DEBUG : Dropbox root 'rclone-test-wahabir4muwi': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestCopyFileCopyDest|TestLsd)$'" - Finished OK in 48.141391683s (try 2/5)