"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^(TestDirMove|TestMaxDelete|TestMergeDirs)$'" - Starting (try 2/5)
2025/09/07 01:14:10 DEBUG : Creating backend with remote "TestGoFile:rclone-test-wogudet9joxi"
2025/09/07 01:14:10 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/09/07 01:14:11 DEBUG : Creating backend with remote "/tmp/rclone175707438"
=== RUN   TestMergeDirs
    run.go:180: Remote "gofile root 'rclone-test-wogudet9joxi'", Local "Local file system at /tmp/rclone175707438", Modify Window "1s"
2025/09/07 01:14:15 INFO  : dupe2: merging "two.txt"
2025/09/07 01:14:15 INFO  : dupe2: removing empty directory
2025/09/07 01:14:16 INFO  : dupe3: merging "three.txt"
2025/09/07 01:14:16 INFO  : dupe3: removing empty directory
--- PASS: TestMergeDirs (7.69s)
=== RUN   TestMaxDelete
    run.go:180: Remote "gofile root 'rclone-test-wogudet9joxi'", Local "Local file system at /tmp/rclone175707438", Modify Window "1s"
2025/09/07 01:14:20 DEBUG : Waiting for deletions to finish
2025/09/07 01:14:20 ERROR : large: Got fatal error on delete: --max-delete threshold reached
2025/09/07 01:14:21 INFO  : medium: Deleted
2025/09/07 01:14:21 INFO  : small: Deleted
    fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:298: Flushing the directory cache
    fstest.go:289: Sleeping for 6s just to make sure
--- PASS: TestMaxDelete (11.43s)
=== RUN   TestDirMove
    run.go:180: Remote "gofile root 'rclone-test-wogudet9joxi'", Local "Local file system at /tmp/rclone175707438", Modify Window "1s"
2025/09/07 01:14:33 INFO  : A1/B2: Making directory
2025/09/07 01:14:33 INFO  : A1/B1/C3: Making directory
2025/09/07 01:14:41 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:14:41 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:14:41 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:14:41 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2025/09/07 01:14:41 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2025/09/07 01:14:46 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/09/07 01:14:46 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/09/07 01:14:46 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/09/07 01:14:46 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/09/07 01:14:46 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/09/07 01:14:46 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2025/09/07 01:14:46 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:14:46 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:14:46 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:14:51 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/09/07 01:14:51 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2025/09/07 01:14:51 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/09/07 01:14:51 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2025/09/07 01:14:51 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:14:51 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/09/07 01:14:51 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2025/09/07 01:14:51 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:14:52 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:14:56 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/09/07 01:14:56 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2025/09/07 01:14:56 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:14:56 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/09/07 01:14:56 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2025/09/07 01:14:57 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/09/07 01:14:57 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2025/09/07 01:14:57 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:15:01 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2025/09/07 01:15:01 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2025/09/07 01:15:02 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2025/09/07 01:15:02 DEBUG : pacer: Rate limited, increasing sleep to 20s
2025/09/07 01:15:03 DEBUG : pacer: Reducing sleep to 10s
2025/09/07 01:15:03 INFO  : A2/two: Moved (server-side) to: A3/two
2025/09/07 01:15:13 DEBUG : pacer: Reducing sleep to 5s
2025/09/07 01:15:13 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2025/09/07 01:15:23 DEBUG : pacer: Reducing sleep to 2.5s
2025/09/07 01:15:23 INFO  : A2/one: Moved (server-side) to: A3/one
2025/09/07 01:15:28 DEBUG : pacer: Reducing sleep to 1.25s
2025/09/07 01:15:30 DEBUG : pacer: Reducing sleep to 625ms
2025/09/07 01:15:32 DEBUG : pacer: Reducing sleep to 312.5ms
2025/09/07 01:15:33 DEBUG : pacer: Reducing sleep to 156.25ms
2025/09/07 01:15:34 DEBUG : pacer: Reducing sleep to 78.125ms
2025/09/07 01:15:34 DEBUG : pacer: Reducing sleep to 39.0625ms
2025/09/07 01:15:34 DEBUG : pacer: Reducing sleep to 19.53125ms
2025/09/07 01:15:34 DEBUG : pacer: Reducing sleep to 10ms
2025/09/07 01:15:37 INFO  : gofile root 'rclone-test-wogudet9joxi': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2025/09/07 01:15:40 INFO  : A3/one: Moved (server-side) to: A4/one
2025/09/07 01:15:40 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2025/09/07 01:15:40 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:15:40 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2025/09/07 01:15:40 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:15:45 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/09/07 01:15:45 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/09/07 01:15:45 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:15:45 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/09/07 01:15:45 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/09/07 01:15:45 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:15:50 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/09/07 01:15:50 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2025/09/07 01:15:50 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:15:50 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/09/07 01:15:50 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2025/09/07 01:15:50 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:15:55 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/09/07 01:15:55 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2025/09/07 01:15:55 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:15:55 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit")
2025/09/07 01:15:55 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2025/09/07 01:15:56 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:16:00 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2025/09/07 01:16:00 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2025/09/07 01:16:00 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:16:01 DEBUG : pacer: low level retry 4/10 (error Error "error-rateLimit")
2025/09/07 01:16:01 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2025/09/07 01:16:02 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:16:05 DEBUG : pacer: low level retry 5/10 (error Error "error-rateLimit")
2025/09/07 01:16:05 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2025/09/07 01:16:05 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:16:07 DEBUG : pacer: low level retry 5/10 (error Error "error-rateLimit")
2025/09/07 01:16:07 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2025/09/07 01:16:10 DEBUG : pacer: low level retry 6/10 (error Error "error-rateLimit")
2025/09/07 01:16:10 DEBUG : pacer: Rate limited, increasing sleep to 20s
2025/09/07 01:16:10 DEBUG : Rate limited, sleep for 5s
2025/09/07 01:16:15 DEBUG : pacer: low level retry 6/10 (error Error "error-rateLimit")
2025/09/07 01:16:31 DEBUG : pacer: Reducing sleep to 10s
2025/09/07 01:16:31 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2025/09/07 01:16:51 DEBUG : pacer: Reducing sleep to 5s
2025/09/07 01:16:51 INFO  : A3/two: Moved (server-side) to: A4/two
2025/09/07 01:17:01 DEBUG : pacer: Reducing sleep to 2.5s
2025/09/07 01:17:05 DEBUG : pacer: Reducing sleep to 1.25s
2025/09/07 01:17:08 DEBUG : pacer: Reducing sleep to 625ms
2025/09/07 01:17:10 DEBUG : pacer: Reducing sleep to 312.5ms
2025/09/07 01:17:10 DEBUG : pacer: Reducing sleep to 156.25ms
2025/09/07 01:17:10 DEBUG : pacer: Reducing sleep to 78.125ms
2025/09/07 01:17:11 DEBUG : pacer: Reducing sleep to 39.0625ms
2025/09/07 01:17:12 DEBUG : pacer: Reducing sleep to 19.53125ms
2025/09/07 01:17:12 DEBUG : pacer: Reducing sleep to 10ms
    fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:298: Flushing the directory cache
    fstest.go:289: Sleeping for 6s just to make sure
--- PASS: TestDirMove (177.22s)
PASS
2025/09/07 01:17:27 DEBUG : gofile root 'rclone-test-wogudet9joxi': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^(TestDirMove|TestMaxDelete|TestMergeDirs)$'" - Finished OK in 3m17.696254262s (try 2/5)