"./operations.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestDirMove|TestRmdirsWithFilter)$|^TestHashSums$/^Md5$'" - Starting (try 2/5)
2024/10/15 03:10:14 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-ricanit0kapi"
2024/10/15 03:10:14 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/10/15 03:10:15 DEBUG : Creating backend with remote "/tmp/rclone433872235"
=== RUN   TestHashSums
    run.go:180: Remote "files root 'rclone-test-ricanit0kapi'", Local "Local file system at /tmp/rclone433872235", Modify Window "1s"
=== RUN   TestHashSums/Md5
--- PASS: TestHashSums (3.56s)
    --- PASS: TestHashSums/Md5 (0.13s)
=== RUN   TestRmdirsWithFilter
    run.go:180: Remote "files root 'rclone-test-ricanit0kapi'", Local "Local file system at /tmp/rclone433872235", Modify Window "1s"
2024/10/15 03:10:18 DEBUG : A1: Making directory
2024/10/15 03:10:19 DEBUG : A1/B1: Making directory
2024/10/15 03:10:19 DEBUG : A1/B1/C1: Making directory
2024/10/15 03:10:20 DEBUG : removing 1 level 3 directories
2024/10/15 03:10:20 INFO  : A1/B1/C1: Removing directory
2024/10/15 03:10:21 DEBUG : removing 1 level 2 directories
2024/10/15 03:10:21 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (3.40s)
=== RUN   TestDirMove
    run.go:180: Remote "files root 'rclone-test-ricanit0kapi'", Local "Local file system at /tmp/rclone433872235", Modify Window "1s"
2024/10/15 03:10:29 DEBUG : A1/B2: Making directory
2024/10/15 03:10:29 DEBUG : A1/B1/C3: Making directory
2024/10/15 03:10:34 INFO  : A2/one: Moved (server-side) to: A3/one
2024/10/15 03:10:34 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2024/10/15 03:10:34 INFO  : A2/two: Moved (server-side) to: A3/two
2024/10/15 03:10:34 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2024/10/15 03:10:34 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2024/10/15 03:10:35 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-ricanit0kapi/A2/B1 not empty`)
2024/10/15 03:10:35 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/10/15 03:10:35 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-ricanit0kapi/A2/B1 not empty`)
2024/10/15 03:10:35 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2024/10/15 03:10:35 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-ricanit0kapi/A2/B1 not empty`)
2024/10/15 03:10:35 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2024/10/15 03:10:35 DEBUG : pacer: low level retry 4/10 (error Folder Not Empty - `Folder rclone-test-ricanit0kapi/A2/B1 not empty`)
2024/10/15 03:10:35 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2024/10/15 03:10:35 DEBUG : pacer: low level retry 5/10 (error Folder Not Empty - `Folder rclone-test-ricanit0kapi/A2/B1 not empty`)
2024/10/15 03:10:35 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2024/10/15 03:10:35 DEBUG : pacer: low level retry 6/10 (error Folder Not Empty - `Folder rclone-test-ricanit0kapi/A2/B1 not empty`)
2024/10/15 03:10:35 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2024/10/15 03:10:36 DEBUG : pacer: low level retry 7/10 (error Folder Not Empty - `Folder rclone-test-ricanit0kapi/A2/B1 not empty`)
2024/10/15 03:10:36 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2024/10/15 03:10:36 DEBUG : pacer: low level retry 8/10 (error Folder Not Empty - `Folder rclone-test-ricanit0kapi/A2/B1 not empty`)
2024/10/15 03:10:36 DEBUG : pacer: Rate limited, increasing sleep to 2s
2024/10/15 03:10:38 DEBUG : pacer: low level retry 9/10 (error Folder Not Empty - `Folder rclone-test-ricanit0kapi/A2/B1 not empty`)
2024/10/15 03:10:40 DEBUG : pacer: Reducing sleep to 1.5s
2024/10/15 03:10:42 DEBUG : pacer: Reducing sleep to 1.125s
2024/10/15 03:10:43 DEBUG : pacer: Reducing sleep to 843.75ms
2024/10/15 03:10:44 DEBUG : pacer: Reducing sleep to 632.8125ms
2024/10/15 03:10:45 DEBUG : pacer: Reducing sleep to 474.609375ms
2024/10/15 03:10:46 DEBUG : pacer: Reducing sleep to 355.957031ms
2024/10/15 03:10:46 DEBUG : pacer: Reducing sleep to 266.967773ms
2024/10/15 03:10:46 DEBUG : pacer: Reducing sleep to 200.225829ms
2024/10/15 03:10:47 DEBUG : pacer: Reducing sleep to 150.169371ms
2024/10/15 03:10:47 INFO  : files root 'rclone-test-ricanit0kapi': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2024/10/15 03:10:47 DEBUG : pacer: Reducing sleep to 112.627028ms
2024/10/15 03:10:47 DEBUG : pacer: Reducing sleep to 84.470271ms
2024/10/15 03:10:47 DEBUG : pacer: Reducing sleep to 63.352703ms
2024/10/15 03:10:47 DEBUG : pacer: Reducing sleep to 47.514527ms
2024/10/15 03:10:47 DEBUG : pacer: Reducing sleep to 35.635895ms
2024/10/15 03:10:47 DEBUG : pacer: Reducing sleep to 26.726921ms
2024/10/15 03:10:48 DEBUG : pacer: Reducing sleep to 20.04519ms
2024/10/15 03:10:48 DEBUG : pacer: Reducing sleep to 15.033892ms
2024/10/15 03:10:49 DEBUG : pacer: Reducing sleep to 11.275419ms
2024/10/15 03:10:49 DEBUG : pacer: Reducing sleep to 10ms
2024/10/15 03:10:50 INFO  : A3/one: Moved (server-side) to: A4/one
2024/10/15 03:10:50 INFO  : A3/two: Moved (server-side) to: A4/two
2024/10/15 03:10:50 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2024/10/15 03:10:50 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2024/10/15 03:10:50 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2024/10/15 03:10:51 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-ricanit0kapi/A3 not empty`)
2024/10/15 03:10:51 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/10/15 03:10:51 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-ricanit0kapi/A3 not empty`)
2024/10/15 03:10:51 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2024/10/15 03:10:51 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-ricanit0kapi/A3 not empty`)
2024/10/15 03:10:51 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2024/10/15 03:10:51 DEBUG : pacer: low level retry 4/10 (error Folder Not Empty - `Folder rclone-test-ricanit0kapi/A3 not empty`)
2024/10/15 03:10:51 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2024/10/15 03:10:51 DEBUG : pacer: Reducing sleep to 120ms
2024/10/15 03:10:51 DEBUG : pacer: Reducing sleep to 90ms
2024/10/15 03:10:51 DEBUG : pacer: Reducing sleep to 67.5ms
2024/10/15 03:10:52 DEBUG : pacer: Reducing sleep to 50.625ms
2024/10/15 03:10:52 DEBUG : pacer: Reducing sleep to 37.96875ms
2024/10/15 03:10:52 DEBUG : pacer: Reducing sleep to 28.476562ms
2024/10/15 03:10:52 DEBUG : pacer: Reducing sleep to 21.357421ms
2024/10/15 03:10:52 DEBUG : pacer: Reducing sleep to 16.018065ms
2024/10/15 03:10:52 DEBUG : pacer: Reducing sleep to 12.013548ms
2024/10/15 03:10:52 DEBUG : pacer: Reducing sleep to 10ms
2024/10/15 03:10:54 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-ricanit0kapi/A4/B1 not empty`)
2024/10/15 03:10:54 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/10/15 03:10:54 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-ricanit0kapi/A4/B1 not empty`)
2024/10/15 03:10:54 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2024/10/15 03:10:54 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-ricanit0kapi/A4/B1 not empty`)
2024/10/15 03:10:54 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2024/10/15 03:10:54 DEBUG : pacer: Reducing sleep to 60ms
2024/10/15 03:10:55 DEBUG : pacer: Reducing sleep to 45ms
2024/10/15 03:10:55 DEBUG : pacer: Reducing sleep to 33.75ms
--- PASS: TestDirMove (33.16s)
PASS
2024/10/15 03:10:55 DEBUG : files root 'rclone-test-ricanit0kapi': Purge remote
2024/10/15 03:10:55 DEBUG : pacer: Reducing sleep to 25.3125ms
"./operations.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestDirMove|TestRmdirsWithFilter)$|^TestHashSums$/^Md5$'" - Finished OK in 41.084674646s (try 2/5)