"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -fast-list -test.run '^TestDirMove$'" - Starting (try 2/5)
2025/02/20 01:10:36 DEBUG : Creating backend with remote "TestGoFile:rclone-test-baquvot9meji"
2025/02/20 01:10:36 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/02/20 01:10:37 DEBUG : Creating backend with remote "/tmp/rclone3668085758"
=== RUN   TestDirMove
    run.go:180: Remote "gofile root 'rclone-test-baquvot9meji'", Local "Local file system at /tmp/rclone3668085758", Modify Window "1s"
2025/02/20 01:10:38 DEBUG : Upload server store-eu-par-2 (eu) responded in 120.789982ms
2025/02/20 01:10:38 DEBUG : Upload server store-eu-par-4 (eu) responded in 123.31519ms
2025/02/20 01:10:38 DEBUG : A1/one: Using upload server store-eu-par-2 (eu)
2025/02/20 01:10:38 DEBUG : A1/two: Using upload server store-eu-par-2 (eu)
2025/02/20 01:10:38 DEBUG : A1/B1/three: Using upload server store-eu-par-4 (eu)
2025/02/20 01:10:39 DEBUG : A1/B1/C1/four: Using upload server store-eu-par-2 (eu)
2025/02/20 01:10:40 DEBUG : A1/B1/C2/five: Using upload server store-eu-par-4 (eu)
2025/02/20 01:10:40 INFO  : A1/B2: Making directory
2025/02/20 01:10:41 INFO  : A1/B1/C3: Making directory
2025/02/20 01:10:44 DEBUG : Rate limited, sleep for 5s
2025/02/20 01:10:49 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/02/20 01:10:49 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/02/20 01:10:50 DEBUG : pacer: Reducing sleep to 10ms
2025/02/20 01:10:51 DEBUG : Rate limited, sleep for 5s
2025/02/20 01:10:51 DEBUG : Rate limited, sleep for 5s
2025/02/20 01:10:51 DEBUG : Rate limited, sleep for 5s
2025/02/20 01:10:51 DEBUG : Rate limited, sleep for 5s
2025/02/20 01:10:51 DEBUG : Rate limited, sleep for 5s
2025/02/20 01:10:56 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/02/20 01:10:56 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/02/20 01:10:56 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/02/20 01:10:56 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2025/02/20 01:10:56 DEBUG : Rate limited, sleep for 5s
2025/02/20 01:10:56 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/02/20 01:10:56 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2025/02/20 01:10:56 DEBUG : Rate limited, sleep for 5s
2025/02/20 01:10:56 DEBUG : Rate limited, sleep for 5s
2025/02/20 01:10:56 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/02/20 01:10:56 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2025/02/20 01:10:56 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2025/02/20 01:10:56 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2025/02/20 01:10:56 DEBUG : Rate limited, sleep for 5s
2025/02/20 01:10:57 DEBUG : Rate limited, sleep for 5s
2025/02/20 01:11:01 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/02/20 01:11:01 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2025/02/20 01:11:01 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/02/20 01:11:01 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2025/02/20 01:11:01 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/02/20 01:11:01 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2025/02/20 01:11:01 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/02/20 01:11:01 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2025/02/20 01:11:02 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2025/02/20 01:11:02 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2025/02/20 01:13:12 DEBUG : pacer: low level retry 3/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:13:12 DEBUG : pacer: low level retry 3/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:13:12 DEBUG : pacer: Rate limited, increasing sleep to 20s
2025/02/20 01:13:12 DEBUG : pacer: low level retry 3/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:13:12 DEBUG : pacer: low level retry 3/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:13:12 DEBUG : pacer: low level retry 3/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:14:12 DEBUG : pacer: low level retry 4/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:14:32 DEBUG : pacer: low level retry 4/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:14:52 DEBUG : pacer: low level retry 4/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:15:12 DEBUG : pacer: low level retry 4/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:15:32 DEBUG : pacer: low level retry 4/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:15:52 DEBUG : pacer: low level retry 5/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:16:12 DEBUG : pacer: low level retry 5/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:16:32 DEBUG : pacer: low level retry 5/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:16:52 DEBUG : pacer: low level retry 5/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:17:12 DEBUG : pacer: low level retry 5/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:17:32 DEBUG : pacer: low level retry 6/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:17:52 DEBUG : pacer: low level retry 6/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:18:12 DEBUG : pacer: low level retry 6/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:18:32 DEBUG : pacer: low level retry 6/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:18:52 DEBUG : pacer: low level retry 6/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:19:12 DEBUG : pacer: low level retry 7/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:19:32 DEBUG : pacer: low level retry 7/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:19:52 DEBUG : pacer: low level retry 7/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:20:12 DEBUG : pacer: low level retry 7/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:20:32 DEBUG : pacer: low level retry 7/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:20:52 DEBUG : pacer: low level retry 8/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:21:12 DEBUG : pacer: low level retry 8/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:21:32 DEBUG : pacer: low level retry 8/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:21:52 DEBUG : pacer: low level retry 8/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:22:12 DEBUG : pacer: low level retry 8/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:22:32 DEBUG : pacer: low level retry 9/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:22:52 DEBUG : pacer: low level retry 9/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:23:12 DEBUG : pacer: low level retry 9/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:23:32 DEBUG : pacer: low level retry 9/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:23:52 DEBUG : pacer: low level retry 9/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:24:12 DEBUG : pacer: low level retry 10/10 (error Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:24:12 ERROR : A2/B1/three: Couldn't move: failed to move item: Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout
2025/02/20 01:24:12 DEBUG : pacer: Reducing sleep to 10s
2025/02/20 01:24:12 ERROR : A2/B1/C1/four: Couldn't move: failed to move item: Put "https://api.gofile.io/contents/move": context canceled
2025/02/20 01:24:12 DEBUG : pacer: Reducing sleep to 5s
2025/02/20 01:24:12 ERROR : A2/B1/C2/five: Couldn't move: failed to move item: Put "https://api.gofile.io/contents/move": context canceled
2025/02/20 01:24:12 DEBUG : pacer: Reducing sleep to 2.5s
2025/02/20 01:24:12 ERROR : A2/one: Couldn't move: failed to move item: Put "https://api.gofile.io/contents/move": context canceled
2025/02/20 01:24:17 DEBUG : pacer: Reducing sleep to 1.25s
2025/02/20 01:24:17 ERROR : A2/two: Couldn't move: failed to move item: Put "https://api.gofile.io/contents/move": context canceled
    operations_test.go:1405: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1405
        	Error:      	Received unexpected error:
        	            	RenameDir renames: failed to move item: Put "https://api.gofile.io/contents/move": dial tcp 45.112.123.126:443: i/o timeout
        	Test:       	TestDirMove
2025/02/20 01:25:19 DEBUG : pacer: low level retry 1/10 (error Get "https://api.gofile.io/contents/90646547-8cad-40ed-9326-b3e56061318e?maxdepth=4&page=1&pageSize=1000": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:25:19 DEBUG : pacer: Rate limited, increasing sleep to 2.5s
2025/02/20 01:26:19 DEBUG : pacer: low level retry 2/10 (error Get "https://api.gofile.io/contents/90646547-8cad-40ed-9326-b3e56061318e?maxdepth=4&page=1&pageSize=1000": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:26:19 DEBUG : pacer: Rate limited, increasing sleep to 5s
2025/02/20 01:27:19 DEBUG : pacer: low level retry 3/10 (error Get "https://api.gofile.io/contents/90646547-8cad-40ed-9326-b3e56061318e?maxdepth=4&page=1&pageSize=1000": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:27:19 DEBUG : pacer: Rate limited, increasing sleep to 10s
2025/02/20 01:28:19 DEBUG : pacer: low level retry 4/10 (error Get "https://api.gofile.io/contents/90646547-8cad-40ed-9326-b3e56061318e?maxdepth=4&page=1&pageSize=1000": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:28:19 DEBUG : pacer: Rate limited, increasing sleep to 20s
2025/02/20 01:29:19 DEBUG : pacer: low level retry 5/10 (error Get "https://api.gofile.io/contents/90646547-8cad-40ed-9326-b3e56061318e?maxdepth=4&page=1&pageSize=1000": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:30:19 DEBUG : pacer: low level retry 6/10 (error Get "https://api.gofile.io/contents/90646547-8cad-40ed-9326-b3e56061318e?maxdepth=4&page=1&pageSize=1000": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:31:19 DEBUG : pacer: low level retry 7/10 (error Get "https://api.gofile.io/contents/90646547-8cad-40ed-9326-b3e56061318e?maxdepth=4&page=1&pageSize=1000": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:32:19 DEBUG : pacer: low level retry 8/10 (error Get "https://api.gofile.io/contents/90646547-8cad-40ed-9326-b3e56061318e?maxdepth=4&page=1&pageSize=1000": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:33:19 DEBUG : pacer: low level retry 9/10 (error Get "https://api.gofile.io/contents/90646547-8cad-40ed-9326-b3e56061318e?maxdepth=4&page=1&pageSize=1000": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:34:19 DEBUG : pacer: low level retry 10/10 (error Get "https://api.gofile.io/contents/90646547-8cad-40ed-9326-b3e56061318e?maxdepth=4&page=1&pageSize=1000": dial tcp 45.112.123.126:443: i/o timeout)
    run.go:164: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:164
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388
        	            				/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1175
        	            				/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1353
        	            				/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1683
        	            				/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/runtime/panic.go:626
        	            				/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1005
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1405
        	Error:      	Received unexpected error:
        	            	couldn't recursively list files: Get "https://api.gofile.io/contents/90646547-8cad-40ed-9326-b3e56061318e?maxdepth=4&page=1&pageSize=1000": dial tcp 45.112.123.126:443: i/o timeout
        	Test:       	TestDirMove
--- FAIL: TestDirMove (1422.41s)
FAIL
2025/02/20 01:34:19 DEBUG : gofile root 'rclone-test-baquvot9meji': Purge remote
2025/02/20 01:35:19 DEBUG : pacer: low level retry 1/10 (error Delete "https://api.gofile.io/contents/": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:36:19 DEBUG : pacer: low level retry 2/10 (error Delete "https://api.gofile.io/contents/": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:37:19 DEBUG : pacer: low level retry 3/10 (error Delete "https://api.gofile.io/contents/": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:38:19 DEBUG : pacer: low level retry 4/10 (error Delete "https://api.gofile.io/contents/": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:39:19 DEBUG : pacer: low level retry 5/10 (error Delete "https://api.gofile.io/contents/": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:40:19 DEBUG : pacer: low level retry 6/10 (error Delete "https://api.gofile.io/contents/": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:41:19 DEBUG : pacer: low level retry 7/10 (error Delete "https://api.gofile.io/contents/": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:42:19 DEBUG : pacer: low level retry 8/10 (error Delete "https://api.gofile.io/contents/": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:43:19 DEBUG : pacer: low level retry 9/10 (error Delete "https://api.gofile.io/contents/": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:44:19 DEBUG : pacer: low level retry 10/10 (error Delete "https://api.gofile.io/contents/": dial tcp 45.112.123.126:443: i/o timeout)
2025/02/20 01:44:19 NOTICE: purge failed: failed to delete item: Delete "https://api.gofile.io/contents/": dial tcp 45.112.123.126:443: i/o timeout
"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -fast-list -test.run '^TestDirMove$'" - Finished ERROR in 33m43.161415582s (try 2/5): exit status 1: Failed [TestDirMove]