"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestNothingToTransferWithoutEmptyDirs|TestSyncWithTrackRenames)$'" - Starting (try 2/5)
2025/11/25 03:54:17 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-conazeh8gaki"
2025/11/25 03:54:17 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/25 03:54:18 DEBUG : Creating backend with remote "/tmp/rclone843229283"
=== RUN   TestSyncWithTrackRenames
    run.go:185: Remote "files root 'rclone-test-conazeh8gaki'", Local "Local file system at /tmp/rclone843229283", Modify Window "1s"
    sync_test.go:1568: Can track renames: true
2025/11/25 03:54:18 INFO  : files root 'rclone-test-conazeh8gaki': Making map for --track-renames
2025/11/25 03:54:18 INFO  : files root 'rclone-test-conazeh8gaki': Finished making map for --track-renames
2025/11/25 03:54:18 DEBUG : files root 'rclone-test-conazeh8gaki': Waiting for checks to finish
2025/11/25 03:54:18 DEBUG : potato: Need to transfer - No matching file found at Destination
2025/11/25 03:54:18 DEBUG : yam: Need to transfer - No matching file found at Destination
2025/11/25 03:54:18 DEBUG : files root 'rclone-test-conazeh8gaki': Waiting for renames to finish
2025/11/25 03:54:18 DEBUG : files root 'rclone-test-conazeh8gaki': Waiting for transfers to finish
2025/11/25 03:54:19 DEBUG : yam: size = 11 OK
2025/11/25 03:54:19 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK
2025/11/25 03:54:19 INFO  : yam: Copied (new)
2025/11/25 03:54:19 DEBUG : potato: size = 14 OK
2025/11/25 03:54:19 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK
2025/11/25 03:54:19 INFO  : potato: Copied (new)
2025/11/25 03:54:19 DEBUG : Waiting for deletions to finish
2025/11/25 03:54:20 DEBUG : potato: size = 14 OK
2025/11/25 03:54:20 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/11/25 03:54:20 INFO  : files root 'rclone-test-conazeh8gaki': Making map for --track-renames
2025/11/25 03:54:20 INFO  : files root 'rclone-test-conazeh8gaki': Finished making map for --track-renames
2025/11/25 03:54:20 DEBUG : files root 'rclone-test-conazeh8gaki': Waiting for checks to finish
2025/11/25 03:54:20 DEBUG : potato: Unchanged skipping
2025/11/25 03:54:20 DEBUG : files root 'rclone-test-conazeh8gaki': Waiting for renames to finish
2025/11/25 03:54:21 INFO  : yam: Moved (server-side) to: yaml
2025/11/25 03:54:21 INFO  : yaml: Renamed from "yam"
2025/11/25 03:54:21 DEBUG : files root 'rclone-test-conazeh8gaki': Waiting for transfers to finish
2025/11/25 03:54:21 DEBUG : Waiting for deletions to finish
2025/11/25 03:54:21 INFO  : There was nothing to transfer
--- PASS: TestSyncWithTrackRenames (3.89s)
=== RUN   TestNothingToTransferWithoutEmptyDirs
    run.go:185: Remote "files root 'rclone-test-conazeh8gaki'", Local "Local file system at /tmp/rclone843229283", Modify Window "1s"
2025/11/25 03:54:22 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/11/25 03:54:22 INFO  : sub dir: Making directory
2025/11/25 03:54:22 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
2025/11/25 03:54:29 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2025/11/25 03:54:29 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2025/11/25 03:54:29 INFO  : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/11/25 03:54:29 INFO  : sub dirEmpty: Set directory modification time (using DirSetModTime)
2025/11/25 03:54:35 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-conazeh8gaki/sub dir2/very/very/very/very/very/nested not empty`)
2025/11/25 03:54:35 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/11/25 03:54:35 DEBUG : pacer: Reducing sleep to 15ms
2025/11/25 03:54:35 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-conazeh8gaki/sub dir2/very/very/very/very/very not empty`)
2025/11/25 03:54:35 DEBUG : pacer: Rate limited, increasing sleep to 30ms
2025/11/25 03:54:35 DEBUG : pacer: Reducing sleep to 22.5ms
2025/11/25 03:54:35 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-conazeh8gaki/sub dir2/very/very/very/very not empty`)
2025/11/25 03:54:35 DEBUG : pacer: Rate limited, increasing sleep to 45ms
2025/11/25 03:54:35 DEBUG : pacer: Reducing sleep to 33.75ms
2025/11/25 03:54:36 DEBUG : pacer: Reducing sleep to 25.3125ms
2025/11/25 03:54:36 DEBUG : pacer: Reducing sleep to 18.984375ms
2025/11/25 03:54:36 DEBUG : pacer: Reducing sleep to 14.238281ms
2025/11/25 03:54:36 DEBUG : pacer: Reducing sleep to 10.67871ms
2025/11/25 03:54:36 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-conazeh8gaki/sub dir2 not empty`)
2025/11/25 03:54:36 DEBUG : pacer: Rate limited, increasing sleep to 21.35742ms
2025/11/25 03:54:36 DEBUG : pacer: Reducing sleep to 16.018065ms
2025/11/25 03:54:37 DEBUG : pacer: Reducing sleep to 12.013548ms
2025/11/25 03:54:37 DEBUG : pacer: Reducing sleep to 10ms
--- PASS: TestNothingToTransferWithoutEmptyDirs (15.15s)
PASS
2025/11/25 03:54:37 DEBUG : files root 'rclone-test-conazeh8gaki': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestNothingToTransferWithoutEmptyDirs|TestSyncWithTrackRenames)$'" - Finished OK in 19.961881552s (try 2/5)