"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestServerSideMoveOverSelf|TestSyncBackupDir)$'" - Starting (try 2/5)
2025/11/07 05:03:09 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-qezafol7valu"
2025/11/07 05:03:09 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/07 05:03:09 DEBUG : Creating backend with remote "/tmp/rclone718943321"
=== RUN   TestServerSideMoveOverSelf
    run.go:185: Remote "files root 'rclone-test-qezafol7valu'", Local "Local file system at /tmp/rclone718943321", Modify Window "1s"
2025/11/07 05:03:12 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-zanosaq1gagu"
    sync_test.go:701: Server side copy (if possible) files root 'rclone-test-qezafol7valu' -> files root 'rclone-test-zanosaq1gagu'
2025/11/07 05:03:13 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2025/11/07 05:03:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/11/07 05:03:13 DEBUG : files root 'rclone-test-zanosaq1gagu': Waiting for checks to finish
2025/11/07 05:03:13 DEBUG : files root 'rclone-test-zanosaq1gagu': Waiting for transfers to finish
2025/11/07 05:03:15 DEBUG : sub dir/hello world: size = 11 OK
2025/11/07 05:03:15 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/11/07 05:03:15 INFO  : sub dir/hello world: Copied (server-side copy)
2025/11/07 05:03:15 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/11/07 05:03:17 DEBUG : files root 'rclone-test-zanosaq1gagu': Using server-side directory move
2025/11/07 05:03:17 INFO  : files root 'rclone-test-zanosaq1gagu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/11/07 05:03:18 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/11/07 05:03:18 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2025/11/07 05:03:18 DEBUG : sub dir/hello world: size = 17 (files root 'rclone-test-qezafol7valu')
2025/11/07 05:03:18 DEBUG : sub dir/hello world: size = 11 (files root 'rclone-test-zanosaq1gagu')
2025/11/07 05:03:18 DEBUG : sub dir/hello world: Sizes differ
2025/11/07 05:03:18 DEBUG : files root 'rclone-test-zanosaq1gagu': Waiting for checks to finish
2025/11/07 05:03:18 DEBUG : files root 'rclone-test-zanosaq1gagu': Waiting for transfers to finish
2025/11/07 05:03:18 INFO  : sub dir/hello world: Deleted
2025/11/07 05:03:18 INFO  : sub dir/hello world: Moved (server-side)
2025/11/07 05:03:18 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/11/07 05:03:20 DEBUG : testing file moves
2025/11/07 05:03:20 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/11/07 05:03:20 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2025/11/07 05:03:20 DEBUG : files root 'rclone-test-zanosaq1gagu': Waiting for checks to finish
2025/11/07 05:03:20 DEBUG : sub dir/hello world: size = 24 (files root 'rclone-test-qezafol7valu')
2025/11/07 05:03:20 DEBUG : sub dir/hello world: size = 17 (files root 'rclone-test-zanosaq1gagu')
2025/11/07 05:03:20 DEBUG : sub dir/hello world: Sizes differ
2025/11/07 05:03:20 DEBUG : files root 'rclone-test-zanosaq1gagu': Waiting for transfers to finish
2025/11/07 05:03:21 INFO  : sub dir/hello world: Deleted
2025/11/07 05:03:21 INFO  : sub dir/hello world: Moved (server-side)
2025/11/07 05:03:21 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/11/07 05:03:22 DEBUG : files root 'rclone-test-zanosaq1gagu': Purge remote
--- PASS: TestServerSideMoveOverSelf (13.38s)
=== RUN   TestSyncBackupDir
    run.go:185: Remote "files root 'rclone-test-qezafol7valu'", Local "Local file system at /tmp/rclone718943321", Modify Window "1s"
2025/11/07 05:03:25 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-qezafol7valu/dst"
2025/11/07 05:03:26 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-qezafol7valu/backup"
2025/11/07 05:03:27 DEBUG : one: size = 4 (Local file system at /tmp/rclone718943321)
2025/11/07 05:03:27 DEBUG : two: size = 3 OK
2025/11/07 05:03:27 DEBUG : one: size = 3 (files root 'rclone-test-qezafol7valu/dst')
2025/11/07 05:03:27 DEBUG : one: Sizes differ
2025/11/07 05:03:27 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/11/07 05:03:27 DEBUG : two: Unchanged skipping
2025/11/07 05:03:27 DEBUG : files root 'rclone-test-qezafol7valu/dst': Waiting for checks to finish
2025/11/07 05:03:28 INFO  : one: Moved (server-side)
2025/11/07 05:03:28 DEBUG : files root 'rclone-test-qezafol7valu/dst': Waiting for transfers to finish
2025/11/07 05:03:30 DEBUG : one: size = 4 OK
2025/11/07 05:03:30 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/11/07 05:03:30 INFO  : one: Copied (new)
2025/11/07 05:03:30 DEBUG : Waiting for deletions to finish
2025/11/07 05:03:30 INFO  : three.txt: Moved (server-side)
2025/11/07 05:03:30 INFO  : three.txt: Moved into backup dir
2025/11/07 05:03:33 DEBUG : one: size = 5 (Local file system at /tmp/rclone718943321)
2025/11/07 05:03:33 DEBUG : one: size = 4 (files root 'rclone-test-qezafol7valu/dst')
2025/11/07 05:03:33 DEBUG : one: Sizes differ
2025/11/07 05:03:33 DEBUG : two: size = 3 OK
2025/11/07 05:03:33 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/11/07 05:03:33 DEBUG : files root 'rclone-test-qezafol7valu/dst': Waiting for checks to finish
2025/11/07 05:03:33 DEBUG : two: Unchanged skipping
2025/11/07 05:03:34 INFO  : one: Deleted
2025/11/07 05:03:34 INFO  : one: Moved (server-side)
2025/11/07 05:03:34 DEBUG : files root 'rclone-test-qezafol7valu/dst': Waiting for transfers to finish
2025/11/07 05:03:35 DEBUG : one: size = 5 OK
2025/11/07 05:03:35 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/11/07 05:03:35 INFO  : one: Copied (new)
2025/11/07 05:03:35 DEBUG : Waiting for deletions to finish
2025/11/07 05:03:36 INFO  : three.txt: Deleted
2025/11/07 05:03:36 INFO  : three.txt: Moved (server-side)
2025/11/07 05:03:36 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDir (15.13s)
PASS
2025/11/07 05:03:38 DEBUG : files root 'rclone-test-qezafol7valu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestServerSideMoveOverSelf|TestSyncBackupDir)$'" - Finished OK in 29.455284672s (try 2/5)