"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestCopyOverSelf|TestNoTag)$'" - Starting (try 2/5)
2025/11/02 04:52:46 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-qemipeg7pani"
2025/11/02 04:52:46 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/02 04:52:47 DEBUG : Creating backend with remote "/tmp/rclone2025532251"
2025/11/02 04:52:47 DEBUG : Config file has changed externally - reloading
=== RUN   TestCopyOverSelf
    run.go:185: Remote "files root 'rclone-test-qemipeg7pani'", Local "Local file system at /tmp/rclone2025532251", Modify Window "1s"
2025/11/02 04:52:50 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/11/02 04:52:50 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2025/11/02 04:52:51 DEBUG : files root 'rclone-test-qemipeg7pani': Waiting for checks to finish
2025/11/02 04:52:51 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone2025532251)
2025/11/02 04:52:51 DEBUG : sub dir/hello world: size = 11 (files root 'rclone-test-qemipeg7pani')
2025/11/02 04:52:51 DEBUG : sub dir/hello world: Sizes differ
2025/11/02 04:52:51 DEBUG : files root 'rclone-test-qemipeg7pani': Waiting for transfers to finish
2025/11/02 04:52:52 DEBUG : sub dir/hello world: size = 17 OK
2025/11/02 04:52:52 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2025/11/02 04:52:52 INFO  : sub dir/hello world: Copied (replaced existing)
2025/11/02 04:52:52 INFO  : sub dir: Set directory modification time (using DirSetModTime)
--- PASS: TestCopyOverSelf (5.43s)
=== RUN   TestNoTag
    run.go:185: Remote "files root 'rclone-test-qemipeg7pani'", Local "Local file system at /tmp/rclone2025532251", Modify Window "1s"
2025/11/02 04:52:53 INFO  : toe: Making directory
2025/11/02 04:52:53 INFO  : toe: Made directory with modification time 2025-11-02 04:52:53.155861893 +0000 UTC
2025/11/02 04:52:53 DEBUG : Added delayed dir = "toe", newDst=<nil>
2025/11/02 04:52:53 INFO  : toe/toe: Making directory
2025/11/02 04:52:53 INFO  : toe/toe: Made directory with modification time 2025-11-02 04:52:53.155861893 +0000 UTC
2025/11/02 04:52:53 DEBUG : Added delayed dir = "toe/toe", newDst=<nil>
2025/11/02 04:52:53 DEBUG : toe: transformed to: tictactoe
2025/11/02 04:52:53 DEBUG : toe/toe/toe: transformed to: toe/toe/tictactoe
2025/11/02 04:52:53 DEBUG : toe/toe/toe: Need to transfer - File not found at Destination
2025/11/02 04:52:53 DEBUG : toe/toe/toe: transformed to: toe/toe/tictactoe
2025/11/02 04:52:53 DEBUG : files root 'rclone-test-qemipeg7pani': Waiting for checks to finish
2025/11/02 04:52:53 DEBUG : files root 'rclone-test-qemipeg7pani': Waiting for transfers to finish
2025/11/02 04:52:54 DEBUG : toe/toe/tictactoe: size = 11 OK
2025/11/02 04:52:54 DEBUG : toe/toe/tictactoe: Dst hash empty - aborting Src hash check
2025/11/02 04:52:54 INFO  : toe/toe/toe: Copied (new) to: toe/toe/tictactoe
2025/11/02 04:52:54 DEBUG : Waiting for deletions to finish
2025/11/02 04:52:54 INFO  : toe/toe: Set directory modification time (using DirSetModTime)
2025/11/02 04:52:54 INFO  : toe: Set directory modification time (using DirSetModTime)
2025/11/02 04:52:56 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-qemipeg7pani/toe not empty`)
2025/11/02 04:52:56 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/11/02 04:52:56 DEBUG : pacer: Reducing sleep to 15ms
2025/11/02 04:52:56 DEBUG : pacer: Reducing sleep to 11.25ms
--- PASS: TestNoTag (3.34s)
PASS
2025/11/02 04:52:56 DEBUG : files root 'rclone-test-qemipeg7pani': Purge remote
2025/11/02 04:52:56 DEBUG : pacer: Reducing sleep to 10ms
"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestCopyOverSelf|TestNoTag)$'" - Finished OK in 10.620719767s (try 2/5)