"./sync.test -test.v -test.timeout 1h0m0s -remote TestHiDrive: -verbose -test.run '^(TestCopyNoTraverse|TestCopyRedownload|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf)$'" - Starting (try 2/5)
2025/03/05 04:08:22 DEBUG : Creating backend with remote "TestHiDrive:rclone-test-horavoh4lilo"
2025/03/05 04:08:22 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/05 04:08:22 DEBUG : Creating backend with remote "/tmp/rclone1050224022"
=== RUN   TestCopyNoTraverse
    run.go:180: Remote "HiDrive root 'rclone-test-horavoh4lilo'", Local "Local file system at /tmp/rclone1050224022", Modify Window "1s"
2025/03/05 04:08:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/05 04:08:22 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': Waiting for checks to finish
2025/03/05 04:08:22 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': Waiting for transfers to finish
2025/03/05 04:08:23 DEBUG : sub dir/hello world: hidrive = 456dc90e1c9b8b010bdf5c02a7cb4b435173eb4b OK
2025/03/05 04:08:23 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "HiDrive root 'rclone-test-horavoh4lilo'", Local "Local file system at /tmp/rclone1050224022", Modify Window "1s"
2025/03/05 04:08:23 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:23 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name hello world
2025/03/05 04:08:23 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:23 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name hello world
--- PASS: TestCopyNoTraverse (0.66s)
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "HiDrive root 'rclone-test-horavoh4lilo'", Local "Local file system at /tmp/rclone1050224022", Modify Window "1s"
2025/03/05 04:08:23 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:23 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name hello world
2025/03/05 04:08:23 DEBUG : Creating backend with remote "TestHiDrive:rclone-test-kiyupuc4xiwa"
2025/03/05 04:08:23 DEBUG : Config file has changed externally - reloading
    sync_test.go:626: Server side copy (if possible) HiDrive root 'rclone-test-horavoh4lilo' -> HiDrive root 'rclone-test-kiyupuc4xiwa'
2025/03/05 04:08:24 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:24 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name hello world
2025/03/05 04:08:24 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/05 04:08:24 DEBUG : HiDrive root 'rclone-test-kiyupuc4xiwa': Waiting for checks to finish
2025/03/05 04:08:24 DEBUG : HiDrive root 'rclone-test-kiyupuc4xiwa': Waiting for transfers to finish
2025/03/05 04:08:24 DEBUG : sub dir/hello world: hidrive = 456dc90e1c9b8b010bdf5c02a7cb4b435173eb4b OK
2025/03/05 04:08:24 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "HiDrive root 'rclone-test-horavoh4lilo'", Local "Local file system at /tmp/rclone1050224022", Modify Window "1s"
2025/03/05 04:08:24 DEBUG : HiDrive root 'rclone-test-kiyupuc4xiwa': found directory-element with name sub dir
2025/03/05 04:08:24 DEBUG : HiDrive root 'rclone-test-kiyupuc4xiwa': found directory-element with name hello world
2025/03/05 04:08:25 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:25 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name hello world
2025/03/05 04:08:25 DEBUG : HiDrive root 'rclone-test-kiyupuc4xiwa': found directory-element with name sub dir
2025/03/05 04:08:25 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:25 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name hello world
2025/03/05 04:08:25 DEBUG : HiDrive root 'rclone-test-kiyupuc4xiwa': found directory-element with name hello world
2025/03/05 04:08:25 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/03/05 04:08:25 DEBUG : HiDrive root 'rclone-test-kiyupuc4xiwa': Waiting for checks to finish
2025/03/05 04:08:25 DEBUG : HiDrive root 'rclone-test-kiyupuc4xiwa': Waiting for transfers to finish
2025/03/05 04:08:25 DEBUG : sub dir/hello world: hidrive = ea39137182fa1c8271a3d15ba3d183d09dbff0ad OK
2025/03/05 04:08:25 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "HiDrive root 'rclone-test-horavoh4lilo'", Local "Local file system at /tmp/rclone1050224022", Modify Window "1s"
2025/03/05 04:08:25 DEBUG : HiDrive root 'rclone-test-kiyupuc4xiwa': found directory-element with name sub dir
2025/03/05 04:08:25 DEBUG : HiDrive root 'rclone-test-kiyupuc4xiwa': found directory-element with name hello world
2025/03/05 04:08:25 DEBUG : HiDrive root 'rclone-test-kiyupuc4xiwa': Purge remote
2025/03/05 04:08:26 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:26 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name hello world
--- PASS: TestServerSideCopyOverSelf (2.74s)
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "HiDrive root 'rclone-test-horavoh4lilo'", Local "Local file system at /tmp/rclone1050224022", Modify Window "1s"
2025/03/05 04:08:26 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:26 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name hello world
2025/03/05 04:08:26 DEBUG : Creating backend with remote "TestHiDrive:rclone-test-rigoxux0wemo"
2025/03/05 04:08:26 DEBUG : Config file has changed externally - reloading
    sync_test.go:671: Server side copy (if possible) HiDrive root 'rclone-test-horavoh4lilo' -> HiDrive root 'rclone-test-rigoxux0wemo'
2025/03/05 04:08:26 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:26 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name hello world
2025/03/05 04:08:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/05 04:08:26 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': Waiting for checks to finish
2025/03/05 04:08:26 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': Waiting for transfers to finish
2025/03/05 04:08:27 DEBUG : sub dir/hello world: hidrive = 456dc90e1c9b8b010bdf5c02a7cb4b435173eb4b OK
2025/03/05 04:08:27 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "HiDrive root 'rclone-test-horavoh4lilo'", Local "Local file system at /tmp/rclone1050224022", Modify Window "1s"
2025/03/05 04:08:27 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': found directory-element with name sub dir
2025/03/05 04:08:27 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': found directory-element with name hello world
2025/03/05 04:08:28 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:28 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name hello world
2025/03/05 04:08:28 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': Using server-side directory move
2025/03/05 04:08:28 INFO  : HiDrive root 'rclone-test-rigoxux0wemo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/03/05 04:08:28 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': found directory-element with name sub dir
2025/03/05 04:08:28 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:28 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': found directory-element with name hello world
2025/03/05 04:08:28 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name hello world
2025/03/05 04:08:28 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/03/05 04:08:28 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': Waiting for checks to finish
2025/03/05 04:08:28 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': Waiting for transfers to finish
2025/03/05 04:08:28 INFO  : sub dir/hello world: Deleted
2025/03/05 04:08:28 INFO  : sub dir/hello world: Moved (server-side)
2025/03/05 04:08:28 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:28 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': found directory-element with name sub dir
2025/03/05 04:08:28 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': found directory-element with name hello world
2025/03/05 04:08:29 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:29 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name hello world
2025/03/05 04:08:29 DEBUG : testing file moves
2025/03/05 04:08:29 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': found directory-element with name sub dir
2025/03/05 04:08:29 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:29 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name hello world
2025/03/05 04:08:29 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': found directory-element with name hello world
2025/03/05 04:08:29 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2025/03/05 04:08:29 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': Waiting for checks to finish
2025/03/05 04:08:29 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': Waiting for transfers to finish
2025/03/05 04:08:29 INFO  : sub dir/hello world: Deleted
2025/03/05 04:08:29 INFO  : sub dir/hello world: Moved (server-side)
    run.go:180: Remote "HiDrive root 'rclone-test-horavoh4lilo'", Local "Local file system at /tmp/rclone1050224022", Modify Window "1s"
2025/03/05 04:08:29 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:29 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': found directory-element with name sub dir
2025/03/05 04:08:29 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': found directory-element with name hello world
2025/03/05 04:08:29 DEBUG : HiDrive root 'rclone-test-rigoxux0wemo': Purge remote
2025/03/05 04:08:29 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
--- PASS: TestServerSideMoveOverSelf (3.37s)
=== RUN   TestCopyRedownload
    run.go:180: Remote "HiDrive root 'rclone-test-horavoh4lilo'", Local "Local file system at /tmp/rclone1050224022", Modify Window "1s"
2025/03/05 04:08:29 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:29 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name hello world
2025/03/05 04:08:30 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:30 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2025/03/05 04:08:30 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name hello world
2025/03/05 04:08:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/03/05 04:08:30 DEBUG : Local file system at /tmp/rclone1050224022: Waiting for checks to finish
2025/03/05 04:08:30 DEBUG : Local file system at /tmp/rclone1050224022: Waiting for transfers to finish
2025/03/05 04:08:30 DEBUG : sub dir/hello world: hidrive = 456dc90e1c9b8b010bdf5c02a7cb4b435173eb4b OK
2025/03/05 04:08:30 DEBUG : sub dir/hello world.6aca8813.partial: renamed to: sub dir/hello world
2025/03/05 04:08:30 INFO  : sub dir/hello world: Copied (new)
2025/03/05 04:08:30 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "HiDrive root 'rclone-test-horavoh4lilo'", Local "Local file system at /tmp/rclone1050224022", Modify Window "1s"
2025/03/05 04:08:30 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name sub dir
2025/03/05 04:08:30 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': found directory-element with name hello world
--- PASS: TestCopyRedownload (0.63s)
PASS
2025/03/05 04:08:30 DEBUG : HiDrive root 'rclone-test-horavoh4lilo': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestHiDrive: -verbose -test.run '^(TestCopyNoTraverse|TestCopyRedownload|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf)$'" - Finished OK in 7.750528788s (try 2/5)