"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^TestDirMove$'" - Starting (try 2/5)
2026/06/01 03:53:04 DEBUG : Creating backend with remote "TestGoFile:rclone-test-ribumez8gana"
2026/06/01 03:53:04 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/06/01 03:53:05 DEBUG : Creating backend with remote "/tmp/rclone2022570266"
=== RUN   TestDirMove
    run.go:198: Remote "gofile root 'rclone-test-ribumez8gana'", Local "Local file system at /tmp/rclone2022570266", Modify Window "1s"
2026/06/01 03:53:09 INFO  : A1/B2: Making directory
2026/06/01 03:53:09 INFO  : A1/B1/C3: Making directory
2026/06/01 03:53:12 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2026/06/01 03:53:12 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2026/06/01 03:53:12 INFO  : A2/one: Moved (server-side) to: A3/one
2026/06/01 03:53:12 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:53:12 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:53:17 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2026/06/01 03:53:17 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/06/01 03:53:17 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:53:17 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2026/06/01 03:53:17 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/06/01 03:53:17 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:53:22 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2026/06/01 03:53:22 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/06/01 03:53:22 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:53:22 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2026/06/01 03:53:22 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/06/01 03:53:22 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:53:27 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2026/06/01 03:53:27 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/06/01 03:53:27 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:53:27 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2026/06/01 03:53:27 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/06/01 03:53:28 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:53:32 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2026/06/01 03:53:32 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/06/01 03:53:32 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:53:33 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2026/06/01 03:53:33 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/06/01 03:53:34 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:53:37 DEBUG : pacer: low level retry 5/10 (error Error "error-rateLimit")
2026/06/01 03:53:37 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/06/01 03:53:37 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:53:39 DEBUG : pacer: low level retry 5/10 (error Error "error-rateLimit")
2026/06/01 03:53:39 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/06/01 03:53:42 DEBUG : pacer: low level retry 6/10 (error Error "error-rateLimit")
2026/06/01 03:53:42 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/06/01 03:53:43 DEBUG : pacer: Reducing sleep to 10s
2026/06/01 03:53:43 INFO  : A2/two: Moved (server-side) to: A3/two
2026/06/01 03:54:03 DEBUG : pacer: Reducing sleep to 5s
2026/06/01 03:54:03 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2026/06/01 03:54:13 DEBUG : pacer: Reducing sleep to 2.5s
2026/06/01 03:54:18 DEBUG : pacer: Reducing sleep to 1.25s
2026/06/01 03:54:20 DEBUG : pacer: Reducing sleep to 625ms
2026/06/01 03:54:21 DEBUG : pacer: Reducing sleep to 312.5ms
2026/06/01 03:54:22 DEBUG : pacer: Reducing sleep to 156.25ms
2026/06/01 03:54:22 DEBUG : pacer: Reducing sleep to 78.125ms
2026/06/01 03:54:23 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/06/01 03:54:23 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/06/01 03:54:23 DEBUG : pacer: Reducing sleep to 10ms
2026/06/01 03:54:24 INFO  : gofile root 'rclone-test-ribumez8gana': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/06/01 03:54:26 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:54:26 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2026/06/01 03:54:26 INFO  : A3/two: Moved (server-side) to: A4/two
2026/06/01 03:54:26 INFO  : A3/one: Moved (server-side) to: A4/one
2026/06/01 03:54:26 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:54:31 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2026/06/01 03:54:31 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/06/01 03:54:31 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:54:31 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2026/06/01 03:54:31 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/06/01 03:54:31 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:54:36 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2026/06/01 03:54:36 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/06/01 03:54:36 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:54:36 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2026/06/01 03:54:36 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/06/01 03:54:37 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:54:41 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2026/06/01 03:54:41 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/06/01 03:54:41 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:54:42 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2026/06/01 03:54:42 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/06/01 03:54:42 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:54:46 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2026/06/01 03:54:46 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/06/01 03:54:46 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:54:47 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2026/06/01 03:54:47 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/06/01 03:54:48 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:54:51 DEBUG : pacer: low level retry 5/10 (error Error "error-rateLimit")
2026/06/01 03:54:51 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/06/01 03:54:51 DEBUG : Rate limited, sleep for 5s
2026/06/01 03:54:53 DEBUG : pacer: low level retry 5/10 (error Error "error-rateLimit")
2026/06/01 03:54:53 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/06/01 03:54:56 DEBUG : pacer: low level retry 6/10 (error Error "error-rateLimit")
2026/06/01 03:54:56 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/06/01 03:54:57 DEBUG : pacer: Reducing sleep to 10s
2026/06/01 03:54:57 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2026/06/01 03:55:17 DEBUG : pacer: Reducing sleep to 5s
2026/06/01 03:55:17 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2026/06/01 03:55:27 DEBUG : pacer: Reducing sleep to 2.5s
2026/06/01 03:55:32 DEBUG : pacer: Reducing sleep to 1.25s
2026/06/01 03:55:35 DEBUG : pacer: Reducing sleep to 625ms
2026/06/01 03:55:35 DEBUG : pacer: Reducing sleep to 312.5ms
2026/06/01 03:55:36 DEBUG : pacer: Reducing sleep to 156.25ms
2026/06/01 03:55:36 DEBUG : pacer: Reducing sleep to 78.125ms
2026/06/01 03:55:36 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/06/01 03:55:37 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/06/01 03:55:37 DEBUG : pacer: Reducing sleep to 10ms
--- PASS: TestDirMove (156.27s)
PASS
2026/06/01 03:55:41 DEBUG : gofile root 'rclone-test-ribumez8gana': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^TestDirMove$'" - Finished OK in 2m37.240740943s (try 2/5)