"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestMoveWithoutDeleteEmptySrcDirs|TestNoTag)$'" - Starting (try 2/5)
2025/12/15 02:53:37 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-vasugom5begi"
2025/12/15 02:53:37 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/12/15 02:53:38 DEBUG : Creating backend with remote "/tmp/rclone3629775565"
=== RUN   TestMoveWithoutDeleteEmptySrcDirs
    run.go:185: Remote "files root 'rclone-test-vasugom5begi'", Local "Local file system at /tmp/rclone3629775565", Modify Window "1s"
2025/12/15 02:53:38 DEBUG : Added delayed dir = "nested", newDst=<nil>
2025/12/15 02:53:38 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2025/12/15 02:53:38 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/12/15 02:53:38 DEBUG : Added delayed dir = "nested/sub dir", newDst=<nil>
2025/12/15 02:53:38 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination
2025/12/15 02:53:38 DEBUG : files root 'rclone-test-vasugom5begi': Waiting for checks to finish
2025/12/15 02:53:38 DEBUG : files root 'rclone-test-vasugom5begi': Waiting for transfers to finish
2025/12/15 02:53:40 DEBUG : sub dir/hello world: size = 11 OK
2025/12/15 02:53:40 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/12/15 02:53:40 INFO  : sub dir/hello world: Copied (new)
2025/12/15 02:53:40 INFO  : sub dir/hello world: Deleted
2025/12/15 02:53:40 DEBUG : nested/sub dir/file: size = 6 OK
2025/12/15 02:53:40 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK
2025/12/15 02:53:40 INFO  : nested/sub dir/file: Copied (new)
2025/12/15 02:53:40 INFO  : nested/sub dir/file: Deleted
2025/12/15 02:53:40 INFO  : nested/sub dir: Set directory modification time (using DirSetModTime)
2025/12/15 02:53:40 INFO  : nested: Set directory modification time (using DirSetModTime)
2025/12/15 02:53:40 INFO  : sub dir: Set directory modification time (using DirSetModTime)
--- PASS: TestMoveWithoutDeleteEmptySrcDirs (4.43s)
=== RUN   TestNoTag
    run.go:185: Remote "files root 'rclone-test-vasugom5begi'", Local "Local file system at /tmp/rclone3629775565", Modify Window "1s"
2025/12/15 02:53:42 INFO  : toe: Making directory
2025/12/15 02:53:43 INFO  : toe: Made directory with modification time 2025-12-15 02:53:42.558231588 +0000 UTC
2025/12/15 02:53:43 DEBUG : Added delayed dir = "toe", newDst=<nil>
2025/12/15 02:53:43 INFO  : toe/toe: Making directory
2025/12/15 02:53:43 INFO  : toe/toe: Made directory with modification time 2025-12-15 02:53:42.558231588 +0000 UTC
2025/12/15 02:53:43 DEBUG : Added delayed dir = "toe/toe", newDst=<nil>
2025/12/15 02:53:43 DEBUG : toe: transformed to: tictactoe
2025/12/15 02:53:43 DEBUG : toe/toe/toe: transformed to: toe/toe/tictactoe
2025/12/15 02:53:43 DEBUG : toe/toe/toe: Need to transfer - File not found at Destination
2025/12/15 02:53:43 DEBUG : toe/toe/toe: transformed to: toe/toe/tictactoe
2025/12/15 02:53:43 DEBUG : files root 'rclone-test-vasugom5begi': Waiting for checks to finish
2025/12/15 02:53:43 DEBUG : files root 'rclone-test-vasugom5begi': Waiting for transfers to finish
2025/12/15 02:53:43 DEBUG : toe/toe/tictactoe: size = 11 OK
2025/12/15 02:53:43 DEBUG : toe/toe/tictactoe: Dst hash empty - aborting Src hash check
2025/12/15 02:53:43 INFO  : toe/toe/toe: Copied (new) to: toe/toe/tictactoe
2025/12/15 02:53:43 DEBUG : Waiting for deletions to finish
2025/12/15 02:53:44 INFO  : toe/toe: Set directory modification time (using DirSetModTime)
2025/12/15 02:53:44 INFO  : toe: Set directory modification time (using DirSetModTime)
2025/12/15 02:53:45 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-vasugom5begi/toe not empty`)
2025/12/15 02:53:45 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/12/15 02:53:45 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-vasugom5begi/toe not empty`)
2025/12/15 02:53:45 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/12/15 02:53:45 DEBUG : pacer: Reducing sleep to 30ms
2025/12/15 02:53:45 DEBUG : pacer: Reducing sleep to 22.5ms
--- PASS: TestNoTag (3.34s)
PASS
2025/12/15 02:53:45 DEBUG : files root 'rclone-test-vasugom5begi': Purge remote
2025/12/15 02:53:46 DEBUG : pacer: Reducing sleep to 16.875ms
"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestMoveWithoutDeleteEmptySrcDirs|TestNoTag)$'" - Finished OK in 8.663793156s (try 2/5)