"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^(TestDeduplicateSkip|TestDirMove)$'" - Starting (try 2/5)
2024/12/29 01:38:47 DEBUG : Creating backend with remote "TestGoFile:rclone-test-walesek4nahi"
2024/12/29 01:38:47 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/29 01:38:48 DEBUG : Creating backend with remote "/tmp/rclone2344948630"
=== RUN   TestDeduplicateSkip
    run.go:180: Remote "gofile root 'rclone-test-walesek4nahi'", Local "Local file system at /tmp/rclone2344948630", Modify Window "1s"
2024/12/29 01:38:48 DEBUG : Upload server store4 (eu) responded in 129.638684ms
2024/12/29 01:38:48 DEBUG : Upload server store5 (eu) responded in 135.09644ms
2024/12/29 01:38:48 DEBUG : one: Using upload server store4 (eu)
2024/12/29 01:38:49 DEBUG : one: Using upload server store4 (eu)
2024/12/29 01:38:49 DEBUG : one: Using upload server store5 (eu)
2024/12/29 01:38:50 INFO  : gofile root 'rclone-test-walesek4nahi': Looking for duplicate names using skip mode.
2024/12/29 01:38:51 NOTICE: one: Found 3 files with duplicate names
2024/12/29 01:38:51 NOTICE: one: Deleting 1/2 identical duplicates (md5 0f6d3e59c45e54d0f2cb30fb594620a3)
2024/12/29 01:38:51 INFO  : one: Deleted
2024/12/29 01:38:51 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSkip (4.48s)
=== RUN   TestDirMove
    run.go:180: Remote "gofile root 'rclone-test-walesek4nahi'", Local "Local file system at /tmp/rclone2344948630", Modify Window "1s"
2024/12/29 01:38:53 DEBUG : A1/one: Using upload server store4 (eu)
2024/12/29 01:38:53 DEBUG : A1/two: Using upload server store4 (eu)
2024/12/29 01:38:54 DEBUG : A1/B1/three: Using upload server store5 (eu)
2024/12/29 01:38:55 DEBUG : A1/B1/C1/four: Using upload server store4 (eu)
2024/12/29 01:38:56 DEBUG : A1/B1/C2/five: Using upload server store4 (eu)
2024/12/29 01:38:56 DEBUG : A1/B2: Making directory
2024/12/29 01:38:57 DEBUG : A1/B1/C3: Making directory
2024/12/29 01:39:02 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:07 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2024/12/29 01:39:07 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/12/29 01:39:07 DEBUG : pacer: Reducing sleep to 10ms
2024/12/29 01:39:08 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:08 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:08 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2024/12/29 01:39:08 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2024/12/29 01:39:08 INFO  : A2/one: Moved (server-side) to: A3/one
2024/12/29 01:39:13 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2024/12/29 01:39:13 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/12/29 01:39:13 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2024/12/29 01:39:13 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2024/12/29 01:39:13 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:13 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:18 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2024/12/29 01:39:18 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2024/12/29 01:39:18 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2024/12/29 01:39:18 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2024/12/29 01:39:18 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:18 DEBUG : pacer: Reducing sleep to 80ms
2024/12/29 01:39:18 INFO  : A2/two: Moved (server-side) to: A3/two
2024/12/29 01:39:23 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2024/12/29 01:39:23 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2024/12/29 01:39:24 DEBUG : pacer: Reducing sleep to 80ms
2024/12/29 01:39:24 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2024/12/29 01:39:24 DEBUG : pacer: Reducing sleep to 40ms
2024/12/29 01:39:24 DEBUG : pacer: Reducing sleep to 20ms
2024/12/29 01:39:25 DEBUG : pacer: Reducing sleep to 10ms
2024/12/29 01:39:28 INFO  : gofile root 'rclone-test-walesek4nahi': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2024/12/29 01:39:31 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:31 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:31 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:32 INFO  : A3/one: Moved (server-side) to: A4/one
2024/12/29 01:39:32 INFO  : A3/two: Moved (server-side) to: A4/two
2024/12/29 01:39:36 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2024/12/29 01:39:36 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/12/29 01:39:36 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2024/12/29 01:39:36 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2024/12/29 01:39:36 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:36 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:36 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2024/12/29 01:39:36 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2024/12/29 01:39:36 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:41 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2024/12/29 01:39:41 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2024/12/29 01:39:41 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2024/12/29 01:39:41 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2024/12/29 01:39:41 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:41 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2024/12/29 01:39:41 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2024/12/29 01:39:42 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:42 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:46 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2024/12/29 01:39:46 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2024/12/29 01:39:47 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:47 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2024/12/29 01:39:47 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2024/12/29 01:39:47 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2024/12/29 01:39:47 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2024/12/29 01:39:48 DEBUG : Rate limited, sleep for 5s
2024/12/29 01:39:52 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2024/12/29 01:39:52 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2024/12/29 01:39:53 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2024/12/29 01:39:53 DEBUG : pacer: Rate limited, increasing sleep to 20s
2024/12/29 01:39:53 DEBUG : pacer: Reducing sleep to 10s
2024/12/29 01:39:53 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2024/12/29 01:40:13 DEBUG : pacer: Reducing sleep to 5s
2024/12/29 01:40:13 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2024/12/29 01:40:23 DEBUG : pacer: Reducing sleep to 2.5s
2024/12/29 01:40:23 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2024/12/29 01:40:28 DEBUG : pacer: Reducing sleep to 1.25s
2024/12/29 01:40:31 DEBUG : pacer: Reducing sleep to 625ms
2024/12/29 01:40:32 DEBUG : pacer: Reducing sleep to 312.5ms
2024/12/29 01:40:32 DEBUG : pacer: Reducing sleep to 156.25ms
2024/12/29 01:40:33 DEBUG : pacer: Reducing sleep to 78.125ms
2024/12/29 01:40:33 DEBUG : pacer: Reducing sleep to 39.0625ms
2024/12/29 01:40:33 DEBUG : pacer: Reducing sleep to 19.53125ms
2024/12/29 01:40:34 DEBUG : pacer: Reducing sleep to 10ms
    run.go:125: removing dir "A4/B1" failed - try 1/3: directory not empty
--- PASS: TestDirMove (107.52s)
PASS
2024/12/29 01:40:40 DEBUG : gofile root 'rclone-test-walesek4nahi': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^(TestDeduplicateSkip|TestDirMove)$'" - Finished OK in 1m52.735381047s (try 2/5)