"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^TestDirMove$'" - Starting (try 2/5)
2025/01/20 03:57:07 DEBUG : Creating backend with remote "TestGoFile:rclone-test-tasayot2zego"
2025/01/20 03:57:07 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/01/20 03:57:07 DEBUG : Creating backend with remote "/tmp/rclone411426132"
=== RUN   TestDirMove
    run.go:180: Remote "gofile root 'rclone-test-tasayot2zego'", Local "Local file system at /tmp/rclone411426132", Modify Window "1s"
2025/01/20 03:57:09 DEBUG : Upload server store-eu-par-2 (eu) responded in 123.155896ms
2025/01/20 03:57:09 DEBUG : Upload server store-eu-par-5 (eu) responded in 125.985842ms
2025/01/20 03:57:09 DEBUG : A1/one: Using upload server store-eu-par-5 (eu)
2025/01/20 03:57:09 DEBUG : A1/two: Using upload server store-eu-par-5 (eu)
2025/01/20 03:57:10 DEBUG : A1/B1/three: Using upload server store-eu-par-5 (eu)
2025/01/20 03:57:11 DEBUG : A1/B1/C1/four: Using upload server store-eu-par-2 (eu)
2025/01/20 03:57:12 DEBUG : A1/B1/C2/five: Using upload server store-eu-par-2 (eu)
2025/01/20 03:57:12 INFO  : A1/B2: Making directory
2025/01/20 03:57:13 INFO  : A1/B1/C3: Making directory
2025/01/20 03:57:20 DEBUG : Rate limited, sleep for 5s
2025/01/20 03:57:20 DEBUG : Rate limited, sleep for 5s
2025/01/20 03:57:20 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2025/01/20 03:57:20 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2025/01/20 03:57:20 INFO  : A2/one: Moved (server-side) to: A3/one
2025/01/20 03:57:25 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/01/20 03:57:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/01/20 03:57:25 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/01/20 03:57:25 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/01/20 03:57:25 DEBUG : Rate limited, sleep for 5s
2025/01/20 03:57:25 DEBUG : Rate limited, sleep for 5s
2025/01/20 03:57:30 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/01/20 03:57:30 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2025/01/20 03:57:30 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/01/20 03:57:30 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2025/01/20 03:57:30 DEBUG : Rate limited, sleep for 5s
2025/01/20 03:57:31 DEBUG : pacer: Reducing sleep to 80ms
2025/01/20 03:57:31 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2025/01/20 03:57:35 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/01/20 03:57:35 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2025/01/20 03:57:36 DEBUG : pacer: Reducing sleep to 80ms
2025/01/20 03:57:36 INFO  : A2/two: Moved (server-side) to: A3/two
2025/01/20 03:57:36 DEBUG : pacer: Reducing sleep to 40ms
2025/01/20 03:57:36 DEBUG : pacer: Reducing sleep to 20ms
2025/01/20 03:57:36 DEBUG : pacer: Reducing sleep to 10ms
2025/01/20 03:57:40 INFO  : gofile root 'rclone-test-tasayot2zego': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2025/01/20 03:57:44 DEBUG : Rate limited, sleep for 5s
2025/01/20 03:57:44 DEBUG : Rate limited, sleep for 5s
2025/01/20 03:57:44 DEBUG : Rate limited, sleep for 5s
2025/01/20 03:57:45 INFO  : A3/one: Moved (server-side) to: A4/one
2025/01/20 03:57:45 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2025/01/20 03:57:49 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/01/20 03:57:49 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/01/20 03:57:49 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/01/20 03:57:49 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/01/20 03:57:49 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/01/20 03:57:49 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2025/01/20 03:57:49 DEBUG : Rate limited, sleep for 5s
2025/01/20 03:57:49 DEBUG : Rate limited, sleep for 5s
2025/01/20 03:57:49 DEBUG : Rate limited, sleep for 5s
2025/01/20 03:57:54 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/01/20 03:57:54 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2025/01/20 03:57:54 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/01/20 03:57:54 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2025/01/20 03:57:54 DEBUG : Rate limited, sleep for 5s
2025/01/20 03:57:54 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/01/20 03:57:54 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2025/01/20 03:57:55 DEBUG : Rate limited, sleep for 5s
2025/01/20 03:57:55 DEBUG : Rate limited, sleep for 5s
2025/01/20 03:57:59 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/01/20 03:57:59 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2025/01/20 03:57:59 DEBUG : Rate limited, sleep for 5s
2025/01/20 03:58:00 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/01/20 03:58:00 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2025/01/20 03:58:00 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/01/20 03:58:00 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2025/01/20 03:58:01 DEBUG : Rate limited, sleep for 5s
2025/01/20 03:58:04 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2025/01/20 03:58:04 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2025/01/20 03:58:06 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2025/01/20 03:58:06 DEBUG : pacer: Rate limited, increasing sleep to 20s
2025/01/20 03:58:06 DEBUG : pacer: Reducing sleep to 10s
2025/01/20 03:58:06 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2025/01/20 03:58:26 DEBUG : pacer: Reducing sleep to 5s
2025/01/20 03:58:26 INFO  : A3/two: Moved (server-side) to: A4/two
2025/01/20 03:58:36 DEBUG : pacer: Reducing sleep to 2.5s
2025/01/20 03:58:36 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2025/01/20 03:58:41 DEBUG : pacer: Reducing sleep to 1.25s
2025/01/20 03:58:44 DEBUG : pacer: Reducing sleep to 625ms
2025/01/20 03:58:45 DEBUG : pacer: Reducing sleep to 312.5ms
2025/01/20 03:58:45 DEBUG : pacer: Reducing sleep to 156.25ms
2025/01/20 03:58:46 DEBUG : pacer: Reducing sleep to 78.125ms
2025/01/20 03:58:46 DEBUG : pacer: Reducing sleep to 39.0625ms
2025/01/20 03:58:46 DEBUG : pacer: Reducing sleep to 19.53125ms
2025/01/20 03:58:47 DEBUG : pacer: Reducing sleep to 10ms
--- PASS: TestDirMove (107.14s)
PASS
2025/01/20 03:58:54 DEBUG : gofile root 'rclone-test-tasayot2zego': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^TestDirMove$'" - Finished OK in 1m48.048087796s (try 2/5)