"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^TestDirMove$'" - Starting (try 3/5)
2025/01/24 02:00:28 DEBUG : Creating backend with remote "TestGoFile:rclone-test-sohulum1ceji"
2025/01/24 02:00:28 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/01/24 02:00:29 DEBUG : Creating backend with remote "/tmp/rclone1688275804"
=== RUN   TestDirMove
    run.go:180: Remote "gofile root 'rclone-test-sohulum1ceji'", Local "Local file system at /tmp/rclone1688275804", Modify Window "1s"
2025/01/24 02:00:30 DEBUG : Upload server store-eu-par-5 (eu) responded in 119.18398ms
2025/01/24 02:00:30 DEBUG : Upload server store-eu-par-4 (eu) responded in 129.43905ms
2025/01/24 02:00:30 DEBUG : A1/one: Using upload server store-eu-par-4 (eu)
2025/01/24 02:00:31 DEBUG : A1/two: Using upload server store-eu-par-4 (eu)
2025/01/24 02:00:32 DEBUG : A1/B1/three: Using upload server store-eu-par-5 (eu)
2025/01/24 02:00:33 DEBUG : A1/B1/C1/four: Using upload server store-eu-par-4 (eu)
2025/01/24 02:00:34 DEBUG : A1/B1/C2/five: Using upload server store-eu-par-4 (eu)
2025/01/24 02:00:34 INFO  : A1/B2: Making directory
2025/01/24 02:00:34 INFO  : A1/B1/C3: Making directory
2025/01/24 02:00:42 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:00:42 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:00:42 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:00:42 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2025/01/24 02:00:43 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2025/01/24 02:00:47 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/01/24 02:00:47 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/01/24 02:00:47 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/01/24 02:00:47 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/01/24 02:00:47 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/01/24 02:00:47 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2025/01/24 02:00:47 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:00:47 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:00:47 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:00:52 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/01/24 02:00:52 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2025/01/24 02:00:52 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/01/24 02:00:52 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2025/01/24 02:00:52 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:00:52 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/01/24 02:00:52 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2025/01/24 02:00:52 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:00:53 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:00:57 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/01/24 02:00:57 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2025/01/24 02:00:57 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:00:57 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/01/24 02:00:57 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2025/01/24 02:00:58 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/01/24 02:00:58 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2025/01/24 02:00:59 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:01:02 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2025/01/24 02:01:02 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2025/01/24 02:01:04 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2025/01/24 02:01:04 DEBUG : pacer: Rate limited, increasing sleep to 20s
2025/01/24 02:01:04 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:01:09 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2025/01/24 02:01:24 DEBUG : pacer: Reducing sleep to 10s
2025/01/24 02:01:24 INFO  : A2/one: Moved (server-side) to: A3/one
2025/01/24 02:01:44 DEBUG : pacer: Reducing sleep to 5s
2025/01/24 02:01:44 INFO  : A2/two: Moved (server-side) to: A3/two
2025/01/24 02:01:54 DEBUG : pacer: Reducing sleep to 2.5s
2025/01/24 02:01:54 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2025/01/24 02:01:59 DEBUG : pacer: Reducing sleep to 1.25s
2025/01/24 02:02:01 DEBUG : pacer: Reducing sleep to 625ms
2025/01/24 02:02:03 DEBUG : pacer: Reducing sleep to 312.5ms
2025/01/24 02:02:03 DEBUG : pacer: Reducing sleep to 156.25ms
2025/01/24 02:02:04 DEBUG : pacer: Reducing sleep to 78.125ms
2025/01/24 02:02:04 DEBUG : pacer: Reducing sleep to 39.0625ms
2025/01/24 02:02:04 DEBUG : pacer: Reducing sleep to 19.53125ms
2025/01/24 02:02:05 DEBUG : pacer: Reducing sleep to 10ms
2025/01/24 02:02:07 INFO  : gofile root 'rclone-test-sohulum1ceji': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2025/01/24 02:02:12 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:02:12 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:02:12 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:02:12 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:02:12 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2025/01/24 02:02:17 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/01/24 02:02:17 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/01/24 02:02:17 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/01/24 02:02:17 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/01/24 02:02:17 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/01/24 02:02:17 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2025/01/24 02:02:17 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/01/24 02:02:17 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2025/01/24 02:02:17 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:02:17 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:02:17 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:02:17 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:02:22 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/01/24 02:02:22 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2025/01/24 02:02:22 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/01/24 02:02:22 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2025/01/24 02:02:22 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:02:22 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/01/24 02:02:22 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2025/01/24 02:02:22 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/01/24 02:02:22 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2025/01/24 02:02:22 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:02:25 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:02:27 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/01/24 02:02:27 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2025/01/24 02:02:27 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/01/24 02:02:27 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2025/01/24 02:02:28 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:02:30 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/01/24 02:02:30 DEBUG : pacer: Rate limited, increasing sleep to 20s
2025/01/24 02:02:33 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/01/24 02:02:38 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:02:43 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2025/01/24 02:02:58 DEBUG : pacer: Reducing sleep to 10s
2025/01/24 02:02:58 INFO  : A3/one: Moved (server-side) to: A4/one
2025/01/24 02:04:13 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:04:13 DEBUG : pacer: Reducing sleep to 5s
2025/01/24 02:04:13 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2025/01/24 02:04:13 DEBUG : pacer: Reducing sleep to 2.5s
2025/01/24 02:04:13 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2025/01/24 02:04:18 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2025/01/24 02:04:18 DEBUG : pacer: Rate limited, increasing sleep to 5s
2025/01/24 02:04:18 DEBUG : Rate limited, sleep for 5s
2025/01/24 02:04:23 DEBUG : pacer: low level retry 5/10 (error Error "error-rateLimit")
2025/01/24 02:04:23 DEBUG : pacer: Rate limited, increasing sleep to 10s
2025/01/24 02:04:25 DEBUG : pacer: Reducing sleep to 5s
2025/01/24 02:04:25 INFO  : A3/two: Moved (server-side) to: A4/two
2025/01/24 02:04:34 DEBUG : pacer: Reducing sleep to 2.5s
2025/01/24 02:04:39 DEBUG : pacer: Reducing sleep to 1.25s
2025/01/24 02:04:41 DEBUG : pacer: Reducing sleep to 625ms
2025/01/24 02:04:42 DEBUG : pacer: Reducing sleep to 312.5ms
2025/01/24 02:04:43 DEBUG : pacer: Reducing sleep to 156.25ms
2025/01/24 02:04:44 DEBUG : pacer: Reducing sleep to 78.125ms
2025/01/24 02:04:44 DEBUG : pacer: Reducing sleep to 39.0625ms
2025/01/24 02:04:45 DEBUG : pacer: Reducing sleep to 19.53125ms
2025/01/24 02:04:45 DEBUG : pacer: Reducing sleep to 10ms
--- PASS: TestDirMove (268.47s)
PASS
2025/01/24 02:04:57 DEBUG : gofile root 'rclone-test-sohulum1ceji': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^TestDirMove$'" - Finished OK in 4m29.382843057s (try 3/5)