"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestBase64|TestServerSideMoveOverSelf)$'" - Starting (try 3/5)
2025/08/29 03:47:10 DEBUG : Creating backend with remote "TestDropbox:rclone-test-rosadar6gavi"
2025/08/29 03:47:10 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/29 03:47:10 DEBUG : Creating backend with remote "/tmp/rclone3628354768"
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "Dropbox root 'rclone-test-rosadar6gavi'", Local "Local file system at /tmp/rclone3628354768", Modify Window "1s"
2025/08/29 03:47:11 DEBUG : sub dir/hello world: Uploading chunk 1/1
2025/08/29 03:47:12 DEBUG : sub dir/hello world: Uploading chunk 2/1
2025/08/29 03:47:12 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Adding "sub dir/hello world" to batch
2025/08/29 03:47:13 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Batch idle for 500ms so committing
2025/08/29 03:47:13 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Committing sync batch length 1 starting with: sub dir/hello world
2025/08/29 03:47:13 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Committed sync batch length 1 starting with: sub dir/hello world
2025/08/29 03:47:14 DEBUG : Creating backend with remote "TestDropbox:rclone-test-renurep9josa"
    sync_test.go:701: Server side copy (if possible) Dropbox root 'rclone-test-rosadar6gavi' -> Dropbox root 'rclone-test-renurep9josa'
2025/08/29 03:47:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/08/29 03:47:15 DEBUG : Dropbox root 'rclone-test-renurep9josa': Waiting for checks to finish
2025/08/29 03:47:15 DEBUG : Dropbox root 'rclone-test-renurep9josa': Waiting for transfers to finish
2025/08/29 03:47:16 DEBUG : sub dir/hello world: dropbox = bc62d4b80d9e36da29c16c5d4d9f11731f36052c72401a76c23c0fb5a9b74423 OK
2025/08/29 03:47:16 INFO  : sub dir/hello world: Copied (server-side copy)
2025/08/29 03:47:17 DEBUG : sub dir/hello world: Uploading chunk 1/1
2025/08/29 03:47:17 DEBUG : sub dir/hello world: Uploading chunk 2/1
2025/08/29 03:47:18 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Adding "sub dir/hello world" to batch
2025/08/29 03:47:19 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Batch idle for 500ms so committing
2025/08/29 03:47:19 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Committing sync batch length 1 starting with: sub dir/hello world
2025/08/29 03:47:19 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Committed sync batch length 1 starting with: sub dir/hello world
2025/08/29 03:47:20 DEBUG : Dropbox root 'rclone-test-renurep9josa': Using server-side directory move
2025/08/29 03:47:20 INFO  : Dropbox root 'rclone-test-renurep9josa': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/08/29 03:47:21 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/08/29 03:47:21 DEBUG : Dropbox root 'rclone-test-renurep9josa': Waiting for checks to finish
2025/08/29 03:47:21 DEBUG : Dropbox root 'rclone-test-renurep9josa': Waiting for transfers to finish
2025/08/29 03:47:21 INFO  : sub dir/hello world: Deleted
2025/08/29 03:47:23 INFO  : sub dir/hello world: Moved (server-side)
2025/08/29 03:47:24 DEBUG : sub dir/hello world: Uploading chunk 1/1
2025/08/29 03:47:25 DEBUG : sub dir/hello world: Uploading chunk 2/1
2025/08/29 03:47:25 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Adding "sub dir/hello world" to batch
2025/08/29 03:47:26 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Batch idle for 500ms so committing
2025/08/29 03:47:26 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Committing sync batch length 1 starting with: sub dir/hello world
2025/08/29 03:47:26 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Committed sync batch length 1 starting with: sub dir/hello world
2025/08/29 03:47:28 DEBUG : testing file moves
2025/08/29 03:47:29 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2025/08/29 03:47:29 DEBUG : Dropbox root 'rclone-test-renurep9josa': Waiting for checks to finish
2025/08/29 03:47:29 DEBUG : Dropbox root 'rclone-test-renurep9josa': Waiting for transfers to finish
2025/08/29 03:47:30 INFO  : sub dir/hello world: Deleted
2025/08/29 03:47:31 INFO  : sub dir/hello world: Moved (server-side)
2025/08/29 03:47:32 DEBUG : Dropbox root 'rclone-test-renurep9josa': Purge remote
--- PASS: TestServerSideMoveOverSelf (24.60s)
=== RUN   TestBase64
    run.go:180: Remote "Dropbox root 'rclone-test-rosadar6gavi'", Local "Local file system at /tmp/rclone3628354768", Modify Window "1s"
2025/08/29 03:47:35 DEBUG : toe: transformed to: dG9l
2025/08/29 03:47:35 DEBUG : toe: transformed to: dG9l
2025/08/29 03:47:35 INFO  : dG9l: Making directory
2025/08/29 03:47:36 DEBUG : toe: transformed to: dG9l
2025/08/29 03:47:36 DEBUG : toe: transformed to: dG9l
2025/08/29 03:47:36 DEBUG : toe/toe: transformed to: dG9l/dG9l
2025/08/29 03:47:36 INFO  : dG9l/dG9l: Making directory
2025/08/29 03:47:37 DEBUG : toe/toe: transformed to: dG9l/dG9l
2025/08/29 03:47:37 DEBUG : toe.txt: transformed to: dG9lLnR4dA==
2025/08/29 03:47:37 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA==
2025/08/29 03:47:37 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination
2025/08/29 03:47:37 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA==
2025/08/29 03:47:37 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Waiting for checks to finish
2025/08/29 03:47:37 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Waiting for transfers to finish
2025/08/29 03:47:38 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Uploading chunk 1/1
2025/08/29 03:47:38 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Uploading chunk 2/1
2025/08/29 03:47:39 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Adding "dG9l/dG9l/dG9lLnR4dA==" to batch
2025/08/29 03:47:39 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Batch idle for 500ms so committing
2025/08/29 03:47:39 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Committing sync batch length 1 starting with: dG9l/dG9l/dG9lLnR4dA==
2025/08/29 03:47:40 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Committed sync batch length 1 starting with: dG9l/dG9l/dG9lLnR4dA==
2025/08/29 03:47:40 DEBUG : toe/toe/toe.txt: dropbox = bc62d4b80d9e36da29c16c5d4d9f11731f36052c72401a76c23c0fb5a9b74423 OK
2025/08/29 03:47:40 INFO  : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA==
2025/08/29 03:47:40 DEBUG : Waiting for deletions to finish
2025/08/29 03:47:41 DEBUG : dG9l: transformed to: toe
2025/08/29 03:47:41 DEBUG : dG9l: transformed to: toe
2025/08/29 03:47:41 INFO  : toe: Set directory modification time (using SetModTime)
2025/08/29 03:47:41 DEBUG : Added delayed dir = "toe", newDst=toe
2025/08/29 03:47:41 DEBUG : dG9l: transformed to: toe
2025/08/29 03:47:41 DEBUG : dG9l/dG9l: transformed to: toe/toe
2025/08/29 03:47:41 INFO  : toe/toe: Set directory modification time (using SetModTime)
2025/08/29 03:47:41 DEBUG : Added delayed dir = "toe/toe", newDst=toe/toe
2025/08/29 03:47:42 DEBUG : dG9lLnR4dA==: transformed to: toe.txt
2025/08/29 03:47:42 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Size and modification time the same (differ by 499.999999ms, within tolerance 1s)
2025/08/29 03:47:42 DEBUG : dG9l/dG9l/dG9lLnR4dA==: transformed to: toe/toe/toe.txt
2025/08/29 03:47:42 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Unchanged skipping
2025/08/29 03:47:42 DEBUG : Local file system at /tmp/rclone3628354768: Waiting for checks to finish
2025/08/29 03:47:42 DEBUG : Local file system at /tmp/rclone3628354768: Waiting for transfers to finish
2025/08/29 03:47:42 DEBUG : Waiting for deletions to finish
--- PASS: TestBase64 (11.86s)
PASS
2025/08/29 03:47:46 DEBUG : Dropbox root 'rclone-test-rosadar6gavi': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestBase64|TestServerSideMoveOverSelf)$'" - Finished OK in 37.55521995s (try 3/5)