"./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^(TestCopyOverSelf|TestCopyWithDryRun|TestMoveWithoutDeleteEmptySrcDirs|TestServerSideCopyOverSelf|TestServerSideMoveDeleteEmptySourceDirs|TestSyncBackupDir|TestSyncCompareDest|TestSyncCopyDest|TestSyncEmptyDirectories|TestSyncMultipleCompareDest|TestSyncNoEmptyDirectories|TestSyncNoTraverse)$'" - Starting (try 2/5)
2026/08/16 05:12:57 DEBUG : Creating backend with remote "TestDrime:rclone-test-ramocir9kaha"
2026/08/16 05:12:57 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/08/16 05:13:00 DEBUG : Creating backend with remote "/tmp/rclone3388075834"
=== RUN   TestCopyWithDryRun
    run.go:198: Remote "drime root 'rclone-test-ramocir9kaha'", Local "Local file system at /tmp/rclone3388075834", Modify Window "876000h0m0s"
2026/08/16 05:13:05 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/08/16 05:13:05 DEBUG : drime root 'rclone-test-ramocir9kaha': Waiting for checks to finish
2026/08/16 05:13:05 DEBUG : drime root 'rclone-test-ramocir9kaha': Waiting for transfers to finish
2026/08/16 05:13:05 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11)
--- PASS: TestCopyWithDryRun (8.03s)
=== RUN   TestSyncNoTraverse
    run.go:198: Remote "drime root 'rclone-test-ramocir9kaha'", Local "Local file system at /tmp/rclone3388075834", Modify Window "876000h0m0s"
2026/08/16 05:13:08 ERROR : Ignoring --no-traverse with sync
2026/08/16 05:13:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/08/16 05:13:09 DEBUG : drime root 'rclone-test-ramocir9kaha': Waiting for checks to finish
2026/08/16 05:13:09 DEBUG : drime root 'rclone-test-ramocir9kaha': Waiting for transfers to finish
2026/08/16 05:13:14 DEBUG : sub dir/hello world: size = 11 OK
2026/08/16 05:13:14 INFO  : sub dir/hello world: Copied (new)
2026/08/16 05:13:14 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncNoTraverse (55.73s)
=== RUN   TestSyncEmptyDirectories
    run.go:198: Remote "drime root 'rclone-test-ramocir9kaha'", Local "Local file system at /tmp/rclone3388075834", Modify Window "876000h0m0s"
2026/08/16 05:14:04 DEBUG : sub dir2: Making directory with metadata
2026/08/16 05:14:04 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/08/16 05:14:04 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2026/08/16 05:14:06 INFO  : sub dir2: Making directory
2026/08/16 05:14:09 INFO  : sub dir: Making directory
2026/08/16 05:14:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/08/16 05:14:11 DEBUG : drime root 'rclone-test-ramocir9kaha': Waiting for checks to finish
2026/08/16 05:14:11 DEBUG : drime root 'rclone-test-ramocir9kaha': Waiting for transfers to finish
2026/08/16 05:14:14 DEBUG : sub dir/hello world: size = 11 OK
2026/08/16 05:14:14 INFO  : sub dir/hello world: Copied (new)
2026/08/16 05:14:14 DEBUG : Waiting for deletions to finish
2026/08/16 05:14:17 DEBUG : drime root 'rclone-test-ramocir9kaha': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestSyncEmptyDirectories (77.83s)
=== RUN   TestSyncNoEmptyDirectories
    run.go:198: Remote "drime root 'rclone-test-ramocir9kaha'", Local "Local file system at /tmp/rclone3388075834", Modify Window "876000h0m0s"
2026/08/16 05:15:22 INFO  : sub dir2: Making directory
2026/08/16 05:15:23 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/08/16 05:15:23 DEBUG : drime root 'rclone-test-ramocir9kaha': Waiting for checks to finish
2026/08/16 05:15:23 DEBUG : drime root 'rclone-test-ramocir9kaha': Waiting for transfers to finish
2026/08/16 05:15:28 DEBUG : sub dir/hello world: size = 11 OK
2026/08/16 05:15:28 INFO  : sub dir/hello world: Copied (new)
2026/08/16 05:15:28 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncNoEmptyDirectories (48.14s)
=== RUN   TestCopyOverSelf
    run.go:198: Remote "drime root 'rclone-test-ramocir9kaha'", Local "Local file system at /tmp/rclone3388075834", Modify Window "876000h0m0s"
2026/08/16 05:16:20 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone3388075834)
2026/08/16 05:16:20 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-ramocir9kaha')
2026/08/16 05:16:20 DEBUG : sub dir/hello world: Sizes differ
2026/08/16 05:16:20 DEBUG : drime root 'rclone-test-ramocir9kaha': Waiting for checks to finish
2026/08/16 05:16:20 DEBUG : drime root 'rclone-test-ramocir9kaha': Waiting for transfers to finish
2026/08/16 05:16:21 DEBUG : sub dir/hello world: Removing old object on successful upload
2026/08/16 05:16:22 DEBUG : sub dir/hello world: Old object already deleted, safely ignoring
2026/08/16 05:16:22 DEBUG : sub dir/hello world: size = 17 OK
2026/08/16 05:16:22 INFO  : sub dir/hello world: Copied (replaced existing)
--- PASS: TestCopyOverSelf (60.15s)
=== RUN   TestServerSideCopyOverSelf
    run.go:198: Remote "drime root 'rclone-test-ramocir9kaha'", Local "Local file system at /tmp/rclone3388075834", Modify Window "876000h0m0s"
2026/08/16 05:17:20 DEBUG : Creating backend with remote "TestDrime:rclone-test-dofuhal8yeze"
    sync_test.go:656: Server side copy (if possible) drime root 'rclone-test-ramocir9kaha' -> drime root 'rclone-test-dofuhal8yeze'
2026/08/16 05:17:25 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/08/16 05:17:25 DEBUG : drime root 'rclone-test-dofuhal8yeze': Waiting for checks to finish
2026/08/16 05:17:25 DEBUG : drime root 'rclone-test-dofuhal8yeze': Waiting for transfers to finish
2026/08/16 05:17:31 DEBUG : sub dir/hello world: size = 11 OK
2026/08/16 05:17:31 INFO  : sub dir/hello world: Copied (server-side copy)
2026/08/16 05:17:38 DEBUG : sub dir/hello world: Removing old object on successful upload
2026/08/16 05:17:40 DEBUG : sub dir/hello world: Old object already deleted, safely ignoring
2026/08/16 05:17:47 DEBUG : drime root 'rclone-test-dofuhal8yeze': Waiting for checks to finish
2026/08/16 05:17:47 DEBUG : sub dir/hello world: size = 17 (drime root 'rclone-test-ramocir9kaha')
2026/08/16 05:17:47 DEBUG : sub dir/hello world: size = 11 (drime root 'rclone-test-dofuhal8yeze')
2026/08/16 05:17:47 DEBUG : sub dir/hello world: Sizes differ
2026/08/16 05:17:47 DEBUG : drime root 'rclone-test-dofuhal8yeze': Waiting for transfers to finish
2026/08/16 05:17:51 DEBUG : sub dir/hello world: Server side copy: removing existing object after successful copy
2026/08/16 05:18:10 DEBUG : sub dir/hello world: size = 17 OK
2026/08/16 05:18:10 INFO  : sub dir/hello world: Copied (server-side copy)
2026/08/16 05:18:13 DEBUG : drime root 'rclone-test-dofuhal8yeze': Purge remote
--- PASS: TestServerSideCopyOverSelf (125.82s)
=== RUN   TestMoveWithoutDeleteEmptySrcDirs
    run.go:198: Remote "drime root 'rclone-test-ramocir9kaha'", Local "Local file system at /tmp/rclone3388075834", Modify Window "876000h0m0s"
2026/08/16 05:19:17 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/08/16 05:19:17 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination
2026/08/16 05:19:17 DEBUG : drime root 'rclone-test-ramocir9kaha': Waiting for checks to finish
2026/08/16 05:19:17 DEBUG : drime root 'rclone-test-ramocir9kaha': Waiting for transfers to finish
2026/08/16 05:19:29 DEBUG : sub dir/hello world: size = 11 OK
2026/08/16 05:19:29 INFO  : sub dir/hello world: Copied (new)
2026/08/16 05:19:29 INFO  : sub dir/hello world: Deleted
2026/08/16 05:19:29 DEBUG : nested/sub dir/file: size = 6 OK
2026/08/16 05:19:29 INFO  : nested/sub dir/file: Copied (new)
2026/08/16 05:19:29 INFO  : nested/sub dir/file: Deleted
--- PASS: TestMoveWithoutDeleteEmptySrcDirs (121.86s)
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:198: Remote "drime root 'rclone-test-ramocir9kaha'", Local "Local file system at /tmp/rclone3388075834", Modify Window "876000h0m0s"
2026/08/16 05:21:18 DEBUG : Creating backend with remote "TestDrime:rclone-test-memugih5wexe"
2026/08/16 05:21:18 DEBUG : Config file has changed externally - reloading
2026/08/16 05:21:27 INFO  : tomatoDir: Making directory
    sync_test.go:1736: Server side move (if possible) drime root 'rclone-test-ramocir9kaha' -> drime root 'rclone-test-memugih5wexe'
2026/08/16 05:21:41 DEBUG : drime root 'rclone-test-memugih5wexe': Using server-side directory move
2026/08/16 05:21:41 INFO  : drime root 'rclone-test-memugih5wexe': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2026/08/16 05:21:43 DEBUG : potato2: Need to transfer - File not found at Destination
2026/08/16 05:21:43 DEBUG : empty space: size = 1 OK
2026/08/16 05:21:43 DEBUG : empty space: Sizes identical
2026/08/16 05:21:43 DEBUG : empty space: Unchanged skipping
2026/08/16 05:21:43 DEBUG : potato3: size = 68 (drime root 'rclone-test-ramocir9kaha')
2026/08/16 05:21:43 DEBUG : potato3: size = 60 (drime root 'rclone-test-memugih5wexe')
2026/08/16 05:21:43 DEBUG : potato3: Sizes differ
2026/08/16 05:21:44 DEBUG : drime root 'rclone-test-memugih5wexe': Waiting for checks to finish
2026/08/16 05:21:45 INFO  : potato2: Moved (server-side)
2026/08/16 05:22:01 INFO  : potato3: Deleted
2026/08/16 05:22:01 INFO  : empty space: Deleted
2026/08/16 05:22:01 DEBUG : drime root 'rclone-test-memugih5wexe': Waiting for transfers to finish
2026/08/16 05:22:03 INFO  : potato3: Moved (server-side)
2026/08/16 05:22:03 INFO  : tomatoDir: Removing directory
2026/08/16 05:22:26 DEBUG : drime root 'rclone-test-ramocir9kaha': deleted 1 directories
2026/08/16 05:22:29 DEBUG : Creating backend with remote "TestDrime:rclone-test-bojibox9siju"
2026/08/16 05:22:31 INFO  : tomatoDir: Making directory
2026/08/16 05:22:33 DEBUG : drime root 'rclone-test-bojibox9siju': Using server-side directory move
2026/08/16 05:24:11 INFO  : drime root 'rclone-test-bojibox9siju': Server side directory move succeeded
2026/08/16 05:24:14 ERROR : error listing: directory not found
2026/08/16 05:24:15 ERROR : error listing: directory not found
2026/08/16 05:24:15 DEBUG : drime root 'rclone-test-bojibox9siju': Purge remote
2026/08/16 05:24:33 DEBUG : drime root 'rclone-test-memugih5wexe': Purge remote
2026/08/16 05:24:34 NOTICE: purge failed: directory not found
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (198.79s)
=== RUN   TestSyncCompareDest
    run.go:198: Remote "drime root 'rclone-test-ramocir9kaha'", Local "Local file system at /tmp/rclone3388075834", Modify Window "876000h0m0s"
2026/08/16 05:24:37 DEBUG : Creating backend with remote "TestDrime:rclone-test-ramocir9kaha/dst"
2026/08/16 05:24:42 DEBUG : Creating backend with remote "TestDrime:rclone-test-ramocir9kaha/CompareDest"
2026/08/16 05:24:47 DEBUG : one: Need to transfer - File not found at Destination
2026/08/16 05:24:47 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for checks to finish
2026/08/16 05:24:47 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for transfers to finish
2026/08/16 05:24:48 DEBUG : one: size = 3 OK
2026/08/16 05:24:48 INFO  : one: Copied (new)
2026/08/16 05:24:48 DEBUG : Waiting for deletions to finish
2026/08/16 05:24:55 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for checks to finish
2026/08/16 05:24:55 DEBUG : one: size = 5 (Local file system at /tmp/rclone3388075834)
2026/08/16 05:24:55 DEBUG : one: size = 3 (drime root 'rclone-test-ramocir9kaha/dst')
2026/08/16 05:24:55 DEBUG : one: Sizes differ
2026/08/16 05:24:56 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for transfers to finish
2026/08/16 05:24:57 DEBUG : one: Removing old object on successful upload
2026/08/16 05:24:58 DEBUG : one: Old object already deleted, safely ignoring
2026/08/16 05:24:58 DEBUG : one: size = 5 OK
2026/08/16 05:24:58 INFO  : one: Copied (replaced existing)
2026/08/16 05:24:58 DEBUG : Waiting for deletions to finish
2026/08/16 05:25:03 DEBUG : dst/one: Removing old object on successful upload
2026/08/16 05:25:05 DEBUG : dst/one: Old object already deleted, safely ignoring
2026/08/16 05:25:09 DEBUG : one: size = 5 (Local file system at /tmp/rclone3388075834)
2026/08/16 05:25:09 DEBUG : one: size = 3 (drime root 'rclone-test-ramocir9kaha/dst')
2026/08/16 05:25:09 DEBUG : one: Sizes differ
2026/08/16 05:25:09 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for checks to finish
2026/08/16 05:25:12 DEBUG : one: size = 5 OK
2026/08/16 05:25:12 DEBUG : one: Sizes identical
2026/08/16 05:25:12 DEBUG : one: Destination found in --compare-dest, skipping
2026/08/16 05:25:12 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for transfers to finish
2026/08/16 05:25:12 DEBUG : Waiting for deletions to finish
2026/08/16 05:25:12 INFO  : There was nothing to transfer
2026/08/16 05:25:21 DEBUG : one: size = 5 (Local file system at /tmp/rclone3388075834)
2026/08/16 05:25:21 DEBUG : one: size = 3 (drime root 'rclone-test-ramocir9kaha/dst')
2026/08/16 05:25:21 DEBUG : one: Sizes differ
2026/08/16 05:25:22 DEBUG : two: size = 3 OK
2026/08/16 05:25:22 DEBUG : two: Sizes identical
2026/08/16 05:25:22 DEBUG : two: Destination found in --compare-dest, skipping
2026/08/16 05:25:22 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for checks to finish
2026/08/16 05:25:22 DEBUG : one: size = 5 OK
2026/08/16 05:25:22 DEBUG : one: Sizes identical
2026/08/16 05:25:22 DEBUG : one: Destination found in --compare-dest, skipping
2026/08/16 05:25:22 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for transfers to finish
2026/08/16 05:25:22 DEBUG : Waiting for deletions to finish
2026/08/16 05:25:22 INFO  : There was nothing to transfer
2026/08/16 05:25:24 DEBUG : one: size = 5 (Local file system at /tmp/rclone3388075834)
2026/08/16 05:25:24 DEBUG : one: size = 3 (drime root 'rclone-test-ramocir9kaha/dst')
2026/08/16 05:25:24 DEBUG : one: Sizes differ
2026/08/16 05:25:26 DEBUG : two: size = 3 OK
2026/08/16 05:25:26 DEBUG : two: Sizes identical
2026/08/16 05:25:26 DEBUG : two: Destination found in --compare-dest, skipping
2026/08/16 05:25:26 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for checks to finish
2026/08/16 05:25:26 DEBUG : one: size = 5 OK
2026/08/16 05:25:26 DEBUG : one: Sizes identical
2026/08/16 05:25:26 DEBUG : one: Destination found in --compare-dest, skipping
2026/08/16 05:25:26 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for transfers to finish
2026/08/16 05:25:26 DEBUG : Waiting for deletions to finish
2026/08/16 05:25:26 INFO  : There was nothing to transfer
    sync_test.go:2206: No hash on uploaded file so skipping compare timestamp test
2026/08/16 05:25:31 DEBUG : one: size = 5 (Local file system at /tmp/rclone3388075834)
2026/08/16 05:25:31 DEBUG : one: size = 3 (drime root 'rclone-test-ramocir9kaha/dst')
2026/08/16 05:25:31 DEBUG : one: Sizes differ
2026/08/16 05:25:32 DEBUG : two: size = 5 (Local file system at /tmp/rclone3388075834)
2026/08/16 05:25:32 DEBUG : two: size = 3 (drime root 'rclone-test-ramocir9kaha/CompareDest')
2026/08/16 05:25:32 DEBUG : two: Sizes differ
2026/08/16 05:25:32 DEBUG : two: Need to transfer - File not found at Destination
2026/08/16 05:25:32 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for checks to finish
2026/08/16 05:25:32 DEBUG : one: size = 5 OK
2026/08/16 05:25:32 DEBUG : one: Sizes identical
2026/08/16 05:25:32 DEBUG : one: Destination found in --compare-dest, skipping
2026/08/16 05:25:32 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for transfers to finish
2026/08/16 05:25:33 DEBUG : two: size = 5 OK
2026/08/16 05:25:33 INFO  : two: Copied (new)
2026/08/16 05:25:33 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCompareDest (173.79s)
=== RUN   TestSyncMultipleCompareDest
    run.go:198: Remote "drime root 'rclone-test-ramocir9kaha'", Local "Local file system at /tmp/rclone3388075834", Modify Window "876000h0m0s"
2026/08/16 05:27:34 DEBUG : pacer: low level retry 1/1 (error Error "Server Error")
2026/08/16 05:27:34 DEBUG : pacer: Rate limited, increasing sleep to 20ms
    run.go:299: Retry Put of "pre-dest1/1" to drime root 'rclone-test-ramocir9kaha': 1/10 (failed to upload file: Error "Server Error")
2026/08/16 05:27:37 DEBUG : pacer: Reducing sleep to 10ms
2026/08/16 05:27:37 DEBUG : pre-dest1/1: Removing old object on successful upload
2026/08/16 05:27:38 DEBUG : pre-dest1/1: Old object already deleted, safely ignoring
2026/08/16 05:27:38 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/16 05:27:38 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/16 05:27:38 DEBUG : pacer: Reducing sleep to 10ms
2026/08/16 05:27:39 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/16 05:27:39 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/16 05:27:39 DEBUG : pacer: Reducing sleep to 10ms
2026/08/16 05:27:39 DEBUG : pacer: low level retry 1/1 (error Error "Server Error")
2026/08/16 05:27:39 DEBUG : pacer: Rate limited, increasing sleep to 20ms
    run.go:299: Retry Put of "pre-dest2/2" to drime root 'rclone-test-ramocir9kaha': 1/10 (failed to upload file: Error "Server Error")
2026/08/16 05:27:41 DEBUG : pacer: Reducing sleep to 10ms
2026/08/16 05:27:41 DEBUG : pacer: low level retry 1/1 (error Error "Server Error")
2026/08/16 05:27:41 DEBUG : pacer: Rate limited, increasing sleep to 20ms
    run.go:299: Retry Put of "pre-dest2/2" to drime root 'rclone-test-ramocir9kaha': 2/10 (failed to upload file: Error "Server Error")
2026/08/16 05:27:45 DEBUG : pacer: Reducing sleep to 10ms
2026/08/16 05:27:48 DEBUG : Creating backend with remote "TestDrime:rclone-test-ramocir9kaha/dest"
2026/08/16 05:27:53 DEBUG : Creating backend with remote "TestDrime:rclone-test-ramocir9kaha/pre-dest1"
2026/08/16 05:27:56 DEBUG : Creating backend with remote "TestDrime:rclone-test-ramocir9kaha/pre-dest2"
2026/08/16 05:27:59 DEBUG : 1: size = 1 OK
2026/08/16 05:27:59 DEBUG : 1: Sizes identical
2026/08/16 05:27:59 DEBUG : 1: Destination found in --compare-dest, skipping
2026/08/16 05:28:01 DEBUG : 2: size = 1 OK
2026/08/16 05:28:01 DEBUG : 2: Sizes identical
2026/08/16 05:28:01 DEBUG : 2: Destination found in --compare-dest, skipping
2026/08/16 05:28:04 DEBUG : 3: Need to transfer - File not found at Destination
2026/08/16 05:28:04 DEBUG : drime root 'rclone-test-ramocir9kaha/dest': Waiting for checks to finish
2026/08/16 05:28:04 DEBUG : drime root 'rclone-test-ramocir9kaha/dest': Waiting for transfers to finish
2026/08/16 05:28:08 DEBUG : pacer: low level retry 1/1 (error Error "Server Error")
2026/08/16 05:28:08 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/16 05:28:08 DEBUG : 3: Received error: failed to upload file: Error "Server Error" - low level retry 0/10
2026/08/16 05:28:10 DEBUG : pacer: Reducing sleep to 10ms
2026/08/16 05:28:11 DEBUG : 3: Removing old object on successful upload
2026/08/16 05:28:15 DEBUG : 3: Old object already deleted, safely ignoring
2026/08/16 05:28:15 DEBUG : 3: size = 1 OK
2026/08/16 05:28:15 INFO  : 3: Copied (new)
2026/08/16 05:28:15 DEBUG : Waiting for deletions to finish
2026/08/16 05:29:24 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/16 05:29:24 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/16 05:29:44 DEBUG : pacer: Reducing sleep to 10ms
--- PASS: TestSyncMultipleCompareDest (173.77s)
=== RUN   TestSyncCopyDest
    run.go:198: Remote "drime root 'rclone-test-ramocir9kaha'", Local "Local file system at /tmp/rclone3388075834", Modify Window "876000h0m0s"
2026/08/16 05:30:24 DEBUG : Creating backend with remote "TestDrime:rclone-test-ramocir9kaha/dst"
2026/08/16 05:30:31 DEBUG : Creating backend with remote "TestDrime:rclone-test-ramocir9kaha/CopyDest"
2026/08/16 05:30:39 DEBUG : one: Need to transfer - File not found at Destination
2026/08/16 05:30:39 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for checks to finish
2026/08/16 05:30:39 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for transfers to finish
2026/08/16 05:30:46 DEBUG : pacer: low level retry 1/1 (error Error "Server Error")
2026/08/16 05:30:46 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/16 05:30:46 DEBUG : one: Received error: failed to upload file: Error "Server Error" - low level retry 0/10
2026/08/16 05:30:48 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/16 05:30:48 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/08/16 05:30:49 DEBUG : pacer: Reducing sleep to 20ms
2026/08/16 05:30:51 DEBUG : pacer: Reducing sleep to 10ms
2026/08/16 05:30:51 DEBUG : one: size = 3 OK
2026/08/16 05:30:51 INFO  : one: Copied (new)
2026/08/16 05:30:51 DEBUG : Waiting for deletions to finish
2026/08/16 05:30:55 DEBUG : one: size = 5 (Local file system at /tmp/rclone3388075834)
2026/08/16 05:30:55 DEBUG : one: size = 3 (drime root 'rclone-test-ramocir9kaha/dst')
2026/08/16 05:30:55 DEBUG : one: Sizes differ
2026/08/16 05:30:55 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for checks to finish
2026/08/16 05:30:56 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for transfers to finish
2026/08/16 05:30:57 DEBUG : one: Removing old object on successful upload
2026/08/16 05:30:59 DEBUG : one: Old object already deleted, safely ignoring
2026/08/16 05:30:59 DEBUG : one: size = 5 OK
2026/08/16 05:30:59 INFO  : one: Copied (replaced existing)
2026/08/16 05:30:59 DEBUG : Waiting for deletions to finish
2026/08/16 05:31:06 DEBUG : dst/one: Removing old object on successful upload
2026/08/16 05:31:08 DEBUG : dst/one: Old object already deleted, safely ignoring
2026/08/16 05:31:17 DEBUG : Creating backend with remote "TestDrime:rclone-test-ramocir9kaha/BackupDir"
2026/08/16 05:31:25 DEBUG : one: size = 5 (Local file system at /tmp/rclone3388075834)
2026/08/16 05:31:25 DEBUG : one: size = 3 (drime root 'rclone-test-ramocir9kaha/dst')
2026/08/16 05:31:25 DEBUG : one: Sizes differ
2026/08/16 05:31:25 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for checks to finish
2026/08/16 05:31:27 DEBUG : one: size = 5 OK
2026/08/16 05:31:27 DEBUG : one: Sizes identical
2026/08/16 05:31:27 DEBUG : one: size = 5 (Local file system at /tmp/rclone3388075834)
2026/08/16 05:31:27 DEBUG : one: size = 3 (drime root 'rclone-test-ramocir9kaha/dst')
2026/08/16 05:31:27 DEBUG : one: Sizes differ
2026/08/16 05:31:32 INFO  : one: Moved (server-side)
2026/08/16 05:31:35 DEBUG : one: size = 5 OK
2026/08/16 05:31:35 INFO  : one: Copied (server-side copy)
2026/08/16 05:31:35 DEBUG : one: Destination found in --copy-dest, using server-side copy
2026/08/16 05:31:35 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for transfers to finish
2026/08/16 05:31:35 DEBUG : Waiting for deletions to finish
2026/08/16 05:31:44 DEBUG : one: size = 5 OK
2026/08/16 05:31:44 DEBUG : one: Sizes identical
2026/08/16 05:31:44 DEBUG : one: Unchanged skipping
2026/08/16 05:31:45 DEBUG : two: size = 3 OK
2026/08/16 05:31:45 DEBUG : two: Sizes identical
2026/08/16 05:31:46 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/16 05:31:46 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/16 05:31:48 DEBUG : pacer: Reducing sleep to 10ms
2026/08/16 05:31:49 DEBUG : two: size = 3 OK
2026/08/16 05:31:49 INFO  : two: Copied (server-side copy)
2026/08/16 05:31:49 DEBUG : two: Destination found in --copy-dest, using server-side copy
2026/08/16 05:31:49 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for checks to finish
2026/08/16 05:31:49 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for transfers to finish
2026/08/16 05:31:49 DEBUG : Waiting for deletions to finish
2026/08/16 05:31:54 DEBUG : one: size = 5 OK
2026/08/16 05:31:54 DEBUG : one: Sizes identical
2026/08/16 05:31:54 DEBUG : one: Unchanged skipping
2026/08/16 05:31:54 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for checks to finish
2026/08/16 05:31:54 DEBUG : two: size = 3 OK
2026/08/16 05:31:54 DEBUG : two: Sizes identical
2026/08/16 05:31:54 DEBUG : two: Unchanged skipping
2026/08/16 05:31:54 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for transfers to finish
2026/08/16 05:31:54 DEBUG : Waiting for deletions to finish
2026/08/16 05:31:54 INFO  : There was nothing to transfer
2026/08/16 05:32:05 DEBUG : one: size = 5 OK
2026/08/16 05:32:05 DEBUG : one: Sizes identical
2026/08/16 05:32:05 DEBUG : one: Unchanged skipping
2026/08/16 05:32:06 DEBUG : three: size = 7 (Local file system at /tmp/rclone3388075834)
2026/08/16 05:32:06 DEBUG : three: size = 5 (drime root 'rclone-test-ramocir9kaha/CopyDest')
2026/08/16 05:32:06 DEBUG : three: Sizes differ
2026/08/16 05:32:06 DEBUG : three: Destination not found in --copy-dest
2026/08/16 05:32:06 DEBUG : three: Need to transfer - File not found at Destination
2026/08/16 05:32:06 DEBUG : two: size = 3 OK
2026/08/16 05:32:06 DEBUG : two: Sizes identical
2026/08/16 05:32:06 DEBUG : two: Unchanged skipping
2026/08/16 05:32:06 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for checks to finish
2026/08/16 05:32:06 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for transfers to finish
2026/08/16 05:32:09 DEBUG : three: size = 7 OK
2026/08/16 05:32:09 INFO  : three: Copied (new)
2026/08/16 05:32:09 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCopyDest (305.51s)
=== RUN   TestSyncBackupDir
    run.go:198: Remote "drime root 'rclone-test-ramocir9kaha'", Local "Local file system at /tmp/rclone3388075834", Modify Window "876000h0m0s"
2026/08/16 05:35:44 DEBUG : Creating backend with remote "TestDrime:rclone-test-ramocir9kaha/dst"
2026/08/16 05:35:44 DEBUG : Config file has changed externally - reloading
2026/08/16 05:35:47 DEBUG : Creating backend with remote "TestDrime:rclone-test-ramocir9kaha/backup"
2026/08/16 05:35:55 DEBUG : one: size = 4 (Local file system at /tmp/rclone3388075834)
2026/08/16 05:35:55 DEBUG : one: size = 3 (drime root 'rclone-test-ramocir9kaha/dst')
2026/08/16 05:35:55 DEBUG : one: Sizes differ
2026/08/16 05:35:55 DEBUG : two: size = 3 OK
2026/08/16 05:35:55 DEBUG : two: Sizes identical
2026/08/16 05:35:55 DEBUG : two: Unchanged skipping
2026/08/16 05:35:55 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for checks to finish
2026/08/16 05:36:02 INFO  : one: Moved (server-side)
2026/08/16 05:36:02 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for transfers to finish
2026/08/16 05:36:05 DEBUG : one: size = 4 OK
2026/08/16 05:36:05 INFO  : one: Copied (new)
2026/08/16 05:36:05 DEBUG : Waiting for deletions to finish
2026/08/16 05:36:10 INFO  : three.txt: Moved (server-side)
2026/08/16 05:36:10 INFO  : three.txt: Moved into backup dir
2026/08/16 05:36:19 DEBUG : one: size = 5 (Local file system at /tmp/rclone3388075834)
2026/08/16 05:36:19 DEBUG : one: size = 4 (drime root 'rclone-test-ramocir9kaha/dst')
2026/08/16 05:36:19 DEBUG : one: Sizes differ
2026/08/16 05:36:19 DEBUG : two: size = 3 OK
2026/08/16 05:36:19 DEBUG : two: Sizes identical
2026/08/16 05:36:19 DEBUG : two: Unchanged skipping
2026/08/16 05:36:19 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for checks to finish
2026/08/16 05:36:39 INFO  : one: Deleted
2026/08/16 05:36:41 INFO  : one: Moved (server-side)
2026/08/16 05:36:41 DEBUG : drime root 'rclone-test-ramocir9kaha/dst': Waiting for transfers to finish
2026/08/16 05:36:43 DEBUG : one: size = 5 OK
2026/08/16 05:36:43 INFO  : one: Copied (new)
2026/08/16 05:36:43 DEBUG : Waiting for deletions to finish
2026/08/16 05:37:03 INFO  : three.txt: Deleted
2026/08/16 05:37:05 INFO  : three.txt: Moved (server-side)
2026/08/16 05:37:05 INFO  : three.txt: Moved into backup dir
2026/08/16 05:38:46 DEBUG : pacer: low level retry 1/10 (error Error "Server Error")
2026/08/16 05:38:46 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/08/16 05:38:47 DEBUG : pacer: low level retry 2/10 (error Error "Server Error")
2026/08/16 05:38:47 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/08/16 05:38:48 DEBUG : pacer: Reducing sleep to 20ms
2026/08/16 05:39:06 DEBUG : pacer: Reducing sleep to 10ms
--- PASS: TestSyncBackupDir (217.38s)
PASS
2026/08/16 05:39:07 DEBUG : drime root 'rclone-test-ramocir9kaha': Purge remote
"./sync.test -test.v -test.timeout 2h0m0s -remote TestDrime: -list-retries 5 -verbose -test.run '^(TestCopyOverSelf|TestCopyWithDryRun|TestMoveWithoutDeleteEmptySrcDirs|TestServerSideCopyOverSelf|TestServerSideMoveDeleteEmptySourceDirs|TestSyncBackupDir|TestSyncCompareDest|TestSyncCopyDest|TestSyncEmptyDirectories|TestSyncMultipleCompareDest|TestSyncNoEmptyDirectories|TestSyncNoTraverse)$'" - Finished OK in 26m28.558710451s (try 2/5)