"./operations.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestDirMove|TestRmdirsWithFilter)$'" - Starting (try 2/5)
2025/10/13 02:00:21 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-milecof1zano"
2025/10/13 02:00:21 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/13 02:00:22 DEBUG : Creating backend with remote "/tmp/rclone269836451"
2025/10/13 02:00:22 DEBUG : Config file has changed externally - reloading
=== RUN   TestRmdirsWithFilter
    run.go:180: Remote "files root 'rclone-test-milecof1zano'", Local "Local file system at /tmp/rclone269836451", Modify Window "1s"
2025/10/13 02:00:23 INFO  : A1: Making directory
2025/10/13 02:00:23 INFO  : A1/B1: Making directory
2025/10/13 02:00:23 INFO  : A1/B1/C1: Making directory
2025/10/13 02:00:25 DEBUG : removing 1 level 3 directories
2025/10/13 02:00:25 INFO  : A1/B1/C1: Removing directory
2025/10/13 02:00:25 DEBUG : removing 1 level 2 directories
2025/10/13 02:00:25 INFO  : A1/B1: Removing directory
2025/10/13 02:00:26 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-milecof1zano/A1/B1 not empty`)
2025/10/13 02:00:26 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/10/13 02:00:26 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-milecof1zano/A1/B1 not empty`)
2025/10/13 02:00:26 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/10/13 02:00:26 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-milecof1zano/A1/B1 not empty`)
2025/10/13 02:00:26 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2025/10/13 02:00:26 DEBUG : pacer: low level retry 4/10 (error Folder Not Empty - `Folder rclone-test-milecof1zano/A1/B1 not empty`)
2025/10/13 02:00:26 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2025/10/13 02:00:26 DEBUG : pacer: low level retry 5/10 (error Folder Not Empty - `Folder rclone-test-milecof1zano/A1/B1 not empty`)
2025/10/13 02:00:26 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2025/10/13 02:00:26 DEBUG : pacer: Reducing sleep to 240ms
2025/10/13 02:00:26 DEBUG : pacer: Reducing sleep to 180ms
2025/10/13 02:00:27 DEBUG : pacer: Reducing sleep to 135ms
2025/10/13 02:00:27 DEBUG : pacer: Reducing sleep to 101.25ms
2025/10/13 02:00:27 DEBUG : pacer: Reducing sleep to 75.9375ms
2025/10/13 02:00:27 DEBUG : pacer: Reducing sleep to 56.953125ms
2025/10/13 02:00:27 DEBUG : pacer: Reducing sleep to 42.714843ms
--- PASS: TestRmdirsWithFilter (5.35s)
=== RUN   TestDirMove
    run.go:180: Remote "files root 'rclone-test-milecof1zano'", Local "Local file system at /tmp/rclone269836451", Modify Window "1s"
2025/10/13 02:00:27 DEBUG : pacer: Reducing sleep to 32.036132ms
2025/10/13 02:00:28 DEBUG : pacer: Reducing sleep to 24.027099ms
2025/10/13 02:00:29 DEBUG : pacer: Reducing sleep to 18.020324ms
2025/10/13 02:00:29 DEBUG : pacer: Reducing sleep to 13.515243ms
2025/10/13 02:00:29 DEBUG : pacer: Reducing sleep to 10.136432ms
2025/10/13 02:00:30 DEBUG : pacer: Reducing sleep to 10ms
2025/10/13 02:00:32 INFO  : A1/B2: Making directory
2025/10/13 02:00:32 INFO  : A1/B1/C3: Making directory
2025/10/13 02:00:36 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2025/10/13 02:00:36 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2025/10/13 02:00:36 INFO  : A2/one: Moved (server-side) to: A3/one
2025/10/13 02:00:36 INFO  : A2/two: Moved (server-side) to: A3/two
2025/10/13 02:00:36 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2025/10/13 02:00:37 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-milecof1zano/A2/B1 not empty`)
2025/10/13 02:00:37 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/10/13 02:00:37 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-milecof1zano/A2/B1 not empty`)
2025/10/13 02:00:37 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/10/13 02:00:37 DEBUG : pacer: Reducing sleep to 30ms
2025/10/13 02:00:38 DEBUG : pacer: Reducing sleep to 22.5ms
2025/10/13 02:00:38 DEBUG : pacer: Reducing sleep to 16.875ms
2025/10/13 02:00:38 DEBUG : pacer: Reducing sleep to 12.65625ms
2025/10/13 02:00:38 DEBUG : pacer: Reducing sleep to 10ms
2025/10/13 02:00:38 INFO  : files root 'rclone-test-milecof1zano': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2025/10/13 02:00:40 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2025/10/13 02:00:40 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2025/10/13 02:00:40 INFO  : A3/two: Moved (server-side) to: A4/two
2025/10/13 02:00:40 INFO  : A3/one: Moved (server-side) to: A4/one
2025/10/13 02:00:40 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2025/10/13 02:00:41 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-milecof1zano/A3 not empty`)
2025/10/13 02:00:41 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/10/13 02:00:42 DEBUG : pacer: Reducing sleep to 15ms
2025/10/13 02:00:42 DEBUG : pacer: Reducing sleep to 11.25ms
2025/10/13 02:00:42 DEBUG : pacer: Reducing sleep to 10ms
2025/10/13 02:00:45 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-milecof1zano/A4 not empty`)
2025/10/13 02:00:45 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/10/13 02:00:45 DEBUG : pacer: Reducing sleep to 15ms
2025/10/13 02:00:45 DEBUG : pacer: Reducing sleep to 11.25ms
--- PASS: TestDirMove (17.63s)
PASS
2025/10/13 02:00:45 DEBUG : files root 'rclone-test-milecof1zano': Purge remote
2025/10/13 02:00:45 DEBUG : pacer: Reducing sleep to 10ms
"./operations.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestDirMove|TestRmdirsWithFilter)$'" - Finished OK in 24.256659074s (try 2/5)