"./sync.test -test.v -test.timeout 1h0m0s -remote TestHiDrive: -verbose -test.run '^(TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors)$'" - Starting (try 2/5)
2024/11/22 01:35:10 DEBUG : Creating backend with remote "TestHiDrive:rclone-test-hukolow9fake"
2024/11/22 01:35:10 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/22 01:35:11 DEBUG : Creating backend with remote "/tmp/rclone4170995804"
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "HiDrive root 'rclone-test-hukolow9fake'", Local "Local file system at /tmp/rclone4170995804", Modify Window "1s"
2024/11/22 01:35:11 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name sub dir
2024/11/22 01:35:11 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name hello world
2024/11/22 01:35:11 DEBUG : Creating backend with remote "TestHiDrive:rclone-test-buzatic5zuja"
    sync_test.go:626: Server side copy (if possible) HiDrive root 'rclone-test-hukolow9fake' -> HiDrive root 'rclone-test-buzatic5zuja'
2024/11/22 01:35:11 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name sub dir
2024/11/22 01:35:11 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name hello world
2024/11/22 01:35:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/22 01:35:11 DEBUG : HiDrive root 'rclone-test-buzatic5zuja': Waiting for checks to finish
2024/11/22 01:35:11 DEBUG : HiDrive root 'rclone-test-buzatic5zuja': Waiting for transfers to finish
2024/11/22 01:35:12 DEBUG : sub dir/hello world: hidrive = 456dc90e1c9b8b010bdf5c02a7cb4b435173eb4b OK
2024/11/22 01:35:12 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "HiDrive root 'rclone-test-hukolow9fake'", Local "Local file system at /tmp/rclone4170995804", Modify Window "1s"
2024/11/22 01:35:12 DEBUG : HiDrive root 'rclone-test-buzatic5zuja': found directory-element with name sub dir
2024/11/22 01:35:12 DEBUG : HiDrive root 'rclone-test-buzatic5zuja': found directory-element with name hello world
2024/11/22 01:35:13 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name sub dir
2024/11/22 01:35:13 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name hello world
2024/11/22 01:35:13 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name sub dir
2024/11/22 01:35:13 DEBUG : HiDrive root 'rclone-test-buzatic5zuja': found directory-element with name sub dir
2024/11/22 01:35:13 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name hello world
2024/11/22 01:35:13 DEBUG : HiDrive root 'rclone-test-buzatic5zuja': found directory-element with name hello world
2024/11/22 01:35:13 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/11/22 01:35:13 DEBUG : HiDrive root 'rclone-test-buzatic5zuja': Waiting for checks to finish
2024/11/22 01:35:13 DEBUG : HiDrive root 'rclone-test-buzatic5zuja': Waiting for transfers to finish
2024/11/22 01:35:14 DEBUG : sub dir/hello world: hidrive = ea39137182fa1c8271a3d15ba3d183d09dbff0ad OK
2024/11/22 01:35:14 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "HiDrive root 'rclone-test-hukolow9fake'", Local "Local file system at /tmp/rclone4170995804", Modify Window "1s"
2024/11/22 01:35:14 DEBUG : HiDrive root 'rclone-test-buzatic5zuja': found directory-element with name sub dir
2024/11/22 01:35:14 DEBUG : HiDrive root 'rclone-test-buzatic5zuja': found directory-element with name hello world
2024/11/22 01:35:14 DEBUG : HiDrive root 'rclone-test-buzatic5zuja': Purge remote
2024/11/22 01:35:14 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name sub dir
2024/11/22 01:35:14 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name hello world
--- PASS: TestServerSideCopyOverSelf (3.97s)
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "HiDrive root 'rclone-test-hukolow9fake'", Local "Local file system at /tmp/rclone4170995804", Modify Window "1s"
2024/11/22 01:35:15 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name sub dir
2024/11/22 01:35:15 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name hello world
2024/11/22 01:35:15 DEBUG : Creating backend with remote "TestHiDrive:rclone-test-jewidep6wivo"
    sync_test.go:671: Server side copy (if possible) HiDrive root 'rclone-test-hukolow9fake' -> HiDrive root 'rclone-test-jewidep6wivo'
2024/11/22 01:35:15 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name sub dir
2024/11/22 01:35:15 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name hello world
2024/11/22 01:35:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/22 01:35:15 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': Waiting for checks to finish
2024/11/22 01:35:15 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': Waiting for transfers to finish
2024/11/22 01:35:16 DEBUG : sub dir/hello world: hidrive = 456dc90e1c9b8b010bdf5c02a7cb4b435173eb4b OK
2024/11/22 01:35:16 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "HiDrive root 'rclone-test-hukolow9fake'", Local "Local file system at /tmp/rclone4170995804", Modify Window "1s"
2024/11/22 01:35:16 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': found directory-element with name sub dir
2024/11/22 01:35:16 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': found directory-element with name hello world
2024/11/22 01:35:17 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name sub dir
2024/11/22 01:35:17 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name hello world
2024/11/22 01:35:17 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': Using server-side directory move
2024/11/22 01:35:17 INFO  : HiDrive root 'rclone-test-jewidep6wivo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2024/11/22 01:35:17 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': found directory-element with name sub dir
2024/11/22 01:35:17 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name sub dir
2024/11/22 01:35:17 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': found directory-element with name hello world
2024/11/22 01:35:17 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name hello world
2024/11/22 01:35:17 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/11/22 01:35:17 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': Waiting for checks to finish
2024/11/22 01:35:17 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': Waiting for transfers to finish
2024/11/22 01:35:17 INFO  : sub dir/hello world: Deleted
2024/11/22 01:35:17 INFO  : sub dir/hello world: Moved (server-side)
2024/11/22 01:35:17 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name sub dir
2024/11/22 01:35:17 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': found directory-element with name sub dir
2024/11/22 01:35:17 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': found directory-element with name hello world
2024/11/22 01:35:18 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name sub dir
2024/11/22 01:35:18 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name hello world
2024/11/22 01:35:18 DEBUG : testing file moves
2024/11/22 01:35:18 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name sub dir
2024/11/22 01:35:18 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': found directory-element with name sub dir
2024/11/22 01:35:18 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name hello world
2024/11/22 01:35:18 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': found directory-element with name hello world
2024/11/22 01:35:18 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2024/11/22 01:35:18 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': Waiting for checks to finish
2024/11/22 01:35:18 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': Waiting for transfers to finish
2024/11/22 01:35:18 INFO  : sub dir/hello world: Deleted
2024/11/22 01:35:18 INFO  : sub dir/hello world: Moved (server-side)
    run.go:180: Remote "HiDrive root 'rclone-test-hukolow9fake'", Local "Local file system at /tmp/rclone4170995804", Modify Window "1s"
2024/11/22 01:35:18 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name sub dir
2024/11/22 01:35:18 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': found directory-element with name sub dir
2024/11/22 01:35:18 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': found directory-element with name hello world
2024/11/22 01:35:18 DEBUG : HiDrive root 'rclone-test-jewidep6wivo': Purge remote
2024/11/22 01:35:18 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name sub dir
--- PASS: TestServerSideMoveOverSelf (3.75s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors
    run.go:180: Remote "HiDrive root 'rclone-test-hukolow9fake'", Local "Local file system at /tmp/rclone4170995804", Modify Window "1s"
2024/11/22 01:35:19 DEBUG : d: Making directory
2024/11/22 01:35:19 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name b
2024/11/22 01:35:19 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name c
2024/11/22 01:35:19 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name d
2024/11/22 01:35:19 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name potato
2024/11/22 01:35:19 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name non empty space
2024/11/22 01:35:19 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name b
2024/11/22 01:35:19 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name c
2024/11/22 01:35:19 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name d
2024/11/22 01:35:19 DEBUG : a/potato2: Need to transfer - File not found at Destination
2024/11/22 01:35:19 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name non empty space
2024/11/22 01:35:19 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2024/11/22 01:35:19 DEBUG : c/non empty space: Unchanged skipping
2024/11/22 01:35:19 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name potato
2024/11/22 01:35:19 DEBUG : HiDrive root 'rclone-test-hukolow9fake': Waiting for checks to finish
2024/11/22 01:35:19 DEBUG : HiDrive root 'rclone-test-hukolow9fake': Waiting for transfers to finish
2024/11/22 01:35:19 DEBUG : a/potato2: hidrive = bce3cb268703fff742fa2271e8c7fad9c26b0104 OK
2024/11/22 01:35:19 INFO  : a/potato2: Copied (new)
2024/11/22 01:35:19 ERROR : HiDrive root 'rclone-test-hukolow9fake': not deleting files as there were IO errors
2024/11/22 01:35:19 ERROR : HiDrive root 'rclone-test-hukolow9fake': not deleting directories as there were IO errors
    run.go:180: Remote "HiDrive root 'rclone-test-hukolow9fake'", Local "Local file system at /tmp/rclone4170995804", Modify Window "1s"
2024/11/22 01:35:19 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name a
2024/11/22 01:35:19 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name b
2024/11/22 01:35:19 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name c
2024/11/22 01:35:19 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name d
2024/11/22 01:35:20 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name potato2
2024/11/22 01:35:20 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name potato
2024/11/22 01:35:20 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name non empty space
2024/11/22 01:35:20 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name a
2024/11/22 01:35:20 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name b
2024/11/22 01:35:20 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name c
2024/11/22 01:35:20 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name d
2024/11/22 01:35:20 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name potato2
2024/11/22 01:35:20 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name potato
2024/11/22 01:35:20 DEBUG : HiDrive root 'rclone-test-hukolow9fake': found directory-element with name non empty space
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (1.80s)
PASS
2024/11/22 01:35:20 DEBUG : HiDrive root 'rclone-test-hukolow9fake': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestHiDrive: -verbose -test.run '^(TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors)$'" - Finished OK in 9.804494134s (try 2/5)