"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -test.run '^TestServerSideCopyOverSelf$'" - Starting (try 2/5)
2025/11/02 03:13:10 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-perogot8moka"
2025/11/02 03:13:10 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/02 03:13:11 DEBUG : Creating backend with remote "TestDrive:crypt/0mpbaf4bur1t3n552t55753g2a6k52ih8ejuqk4gdc1tokpi2pdg"
2025/11/02 03:13:12 DEBUG : Creating backend with remote "/tmp/rclone3297040295"
=== RUN   TestServerSideCopyOverSelf
    run.go:185: Remote "Encrypted drive 'TestCryptDrive:rclone-test-perogot8moka'", Local "Local file system at /tmp/rclone3297040295", Modify Window "1ms"
2025/11/02 03:13:15 DEBUG : sub dir/hello world: md5 = ea1306c7312d87269f214f51d8d2061c OK
2025/11/02 03:13:15 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-nigiqom5rafi"
2025/11/02 03:13:15 DEBUG : Creating backend with remote "TestDrive:crypt/eb2ddt1199sumjm74g4vqimv6ha7t4tgjcba22gh73aj9g5n0fj0"
    sync_test.go:656: Server side copy (if possible) Encrypted drive 'TestCryptDrive:rclone-test-perogot8moka' -> Encrypted drive 'TestCryptDrive:rclone-test-nigiqom5rafi'
2025/11/02 03:13:18 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2025/11/02 03:13:18 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/11/02 03:13:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-nigiqom5rafi': Waiting for checks to finish
2025/11/02 03:13:18 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-nigiqom5rafi': Waiting for transfers to finish
2025/11/02 03:13:21 DEBUG : sub dir/hello world: size = 11 OK
2025/11/02 03:13:21 INFO  : sub dir/hello world: Copied (server-side copy)
2025/11/02 03:13:21 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/11/02 03:13:23 DEBUG : sub dir/hello world: md5 = 427257649b9ce240e3427e56cf9edfb2 OK
2025/11/02 03:13:24 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1ms)
2025/11/02 03:13:24 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2025/11/02 03:13:24 DEBUG : sub dir/hello world: size = 17 (Encrypted drive 'TestCryptDrive:rclone-test-perogot8moka')
2025/11/02 03:13:24 DEBUG : sub dir/hello world: size = 11 (Encrypted drive 'TestCryptDrive:rclone-test-nigiqom5rafi')
2025/11/02 03:13:24 DEBUG : sub dir/hello world: Sizes differ
2025/11/02 03:13:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-nigiqom5rafi': Waiting for checks to finish
2025/11/02 03:13:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-nigiqom5rafi': Waiting for transfers to finish
2025/11/02 03:13:26 DEBUG : sub dir/hello world: size = 17 OK
2025/11/02 03:13:26 INFO  : sub dir/hello world: Copied (server-side copy)
2025/11/02 03:13:27 INFO  : sub dir: Set directory modification time (using SetModTime)
2025/11/02 03:13:27 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-nigiqom5rafi': Purge remote
2025/11/02 03:13:29 DEBUG : 14begaa36ih249fgrnfk6nulg0: Rmdir: contains trashed file: "8j9gk4b744l35einofkqsr2mvo"
--- PASS: TestServerSideCopyOverSelf (18.09s)
PASS
2025/11/02 03:13:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-perogot8moka': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose -test.run '^TestServerSideCopyOverSelf$'" - Finished OK in 19.838820661s (try 2/5)