"./operations.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^TestDirMove$'" - Starting (try 2/5)
2024/09/20 02:20:51 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-soyifif0vihe"
2024/09/20 02:20:51 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/09/20 02:20:52 DEBUG : Creating backend with remote "/tmp/rclone1764298328"
=== RUN   TestDirMove
    run.go:180: Remote "files root 'rclone-test-soyifif0vihe'", Local "Local file system at /tmp/rclone1764298328", Modify Window "1s"
2024/09/20 02:20:56 DEBUG : A1/B2: Making directory
2024/09/20 02:20:56 DEBUG : A1/B1/C3: Making directory
2024/09/20 02:21:00 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2024/09/20 02:21:01 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2024/09/20 02:21:01 INFO  : A2/two: Moved (server-side) to: A3/two
2024/09/20 02:21:01 INFO  : A2/one: Moved (server-side) to: A3/one
2024/09/20 02:21:01 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2024/09/20 02:21:01 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A2/B1 not empty`)
2024/09/20 02:21:01 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/09/20 02:21:02 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A2/B1 not empty`)
2024/09/20 02:21:02 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2024/09/20 02:21:02 DEBUG : pacer: Reducing sleep to 30ms
2024/09/20 02:21:02 DEBUG : pacer: Reducing sleep to 22.5ms
2024/09/20 02:21:02 DEBUG : pacer: Reducing sleep to 16.875ms
2024/09/20 02:21:02 DEBUG : pacer: Reducing sleep to 12.65625ms
2024/09/20 02:21:02 DEBUG : pacer: Reducing sleep to 10ms
2024/09/20 02:21:03 INFO  : files root 'rclone-test-soyifif0vihe': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2024/09/20 02:21:05 INFO  : A3/one: Moved (server-side) to: A4/one
2024/09/20 02:21:05 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2024/09/20 02:21:05 INFO  : A3/two: Moved (server-side) to: A4/two
2024/09/20 02:21:05 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2024/09/20 02:21:05 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2024/09/20 02:21:06 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A3/B1 not empty`)
2024/09/20 02:21:06 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/09/20 02:21:06 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A3/B1 not empty`)
2024/09/20 02:21:06 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2024/09/20 02:21:06 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A3/B1 not empty`)
2024/09/20 02:21:06 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2024/09/20 02:21:06 DEBUG : pacer: low level retry 4/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A3/B1 not empty`)
2024/09/20 02:21:06 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2024/09/20 02:21:07 DEBUG : pacer: Reducing sleep to 120ms
2024/09/20 02:21:07 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A3 not empty`)
2024/09/20 02:21:07 DEBUG : pacer: Rate limited, increasing sleep to 240ms
2024/09/20 02:21:07 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A3 not empty`)
2024/09/20 02:21:07 DEBUG : pacer: Rate limited, increasing sleep to 480ms
2024/09/20 02:21:07 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A3 not empty`)
2024/09/20 02:21:07 DEBUG : pacer: Rate limited, increasing sleep to 960ms
2024/09/20 02:21:08 DEBUG : pacer: Reducing sleep to 720ms
2024/09/20 02:21:08 DEBUG : pacer: Reducing sleep to 540ms
2024/09/20 02:21:09 DEBUG : pacer: Reducing sleep to 405ms
2024/09/20 02:21:10 DEBUG : pacer: Reducing sleep to 303.75ms
2024/09/20 02:21:10 DEBUG : pacer: Reducing sleep to 227.8125ms
2024/09/20 02:21:10 DEBUG : pacer: Reducing sleep to 170.859375ms
2024/09/20 02:21:11 DEBUG : pacer: Reducing sleep to 128.144531ms
2024/09/20 02:21:11 DEBUG : pacer: Reducing sleep to 96.108398ms
2024/09/20 02:21:11 DEBUG : pacer: Reducing sleep to 72.081298ms
2024/09/20 02:21:11 DEBUG : pacer: Reducing sleep to 54.060973ms
2024/09/20 02:21:11 DEBUG : pacer: Reducing sleep to 40.545729ms
2024/09/20 02:21:11 DEBUG : pacer: Reducing sleep to 30.409296ms
2024/09/20 02:21:11 DEBUG : pacer: Reducing sleep to 22.806972ms
2024/09/20 02:21:12 DEBUG : pacer: Reducing sleep to 17.105229ms
2024/09/20 02:21:12 DEBUG : pacer: Reducing sleep to 12.828921ms
2024/09/20 02:21:12 DEBUG : pacer: Reducing sleep to 10ms
2024/09/20 02:21:13 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A4/B1 not empty`)
2024/09/20 02:21:13 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/09/20 02:21:13 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A4/B1 not empty`)
2024/09/20 02:21:13 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2024/09/20 02:21:13 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A4/B1 not empty`)
2024/09/20 02:21:13 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2024/09/20 02:21:14 DEBUG : pacer: low level retry 4/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A4/B1 not empty`)
2024/09/20 02:21:14 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2024/09/20 02:21:14 DEBUG : pacer: low level retry 5/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A4/B1 not empty`)
2024/09/20 02:21:14 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2024/09/20 02:21:14 DEBUG : pacer: low level retry 6/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A4/B1 not empty`)
2024/09/20 02:21:14 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2024/09/20 02:21:14 DEBUG : pacer: low level retry 7/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A4/B1 not empty`)
2024/09/20 02:21:14 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2024/09/20 02:21:15 DEBUG : pacer: low level retry 8/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A4/B1 not empty`)
2024/09/20 02:21:15 DEBUG : pacer: Rate limited, increasing sleep to 2s
2024/09/20 02:21:16 DEBUG : pacer: low level retry 9/10 (error Folder Not Empty - `Folder rclone-test-soyifif0vihe/A4/B1 not empty`)
2024/09/20 02:21:18 DEBUG : pacer: Reducing sleep to 1.5s
2024/09/20 02:21:20 DEBUG : pacer: Reducing sleep to 1.125s
2024/09/20 02:21:21 DEBUG : pacer: Reducing sleep to 843.75ms
--- PASS: TestDirMove (30.07s)
PASS
2024/09/20 02:21:22 DEBUG : files root 'rclone-test-soyifif0vihe': Purge remote
2024/09/20 02:21:23 DEBUG : pacer: Reducing sleep to 632.8125ms
"./operations.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^TestDirMove$'" - Finished OK in 31.730314795s (try 2/5)