"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^(TestDirMove|TestRmdirsWithFilter)$'" - Starting (try 4/5) 2024/11/20 01:22:33 DEBUG : Creating backend with remote "TestGoFile:rclone-test-puqalin1weri" 2024/11/20 01:22:33 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/11/20 01:22:34 DEBUG : Creating backend with remote "/tmp/rclone4166562420" === RUN TestRmdirsWithFilter run.go:180: Remote "gofile root 'rclone-test-puqalin1weri'", Local "Local file system at /tmp/rclone4166562420", Modify Window "1s" 2024/11/20 01:22:35 DEBUG : A1: Making directory 2024/11/20 01:22:36 DEBUG : A1/B1: Making directory 2024/11/20 01:22:37 DEBUG : A1/B1/C1: Making directory 2024/11/20 01:22:39 DEBUG : removing 1 level 3 directories 2024/11/20 01:22:39 INFO : A1/B1/C1: Removing directory 2024/11/20 01:22:40 DEBUG : removing 1 level 2 directories 2024/11/20 01:22:40 INFO : A1/B1: Removing directory fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure --- PASS: TestRmdirsWithFilter (25.12s) === RUN TestDirMove run.go:180: Remote "gofile root 'rclone-test-puqalin1weri'", Local "Local file system at /tmp/rclone4166562420", Modify Window "1s" 2024/11/20 01:23:00 DEBUG : Upload server store5 (eu) responded in 123.874827ms 2024/11/20 01:23:00 DEBUG : Upload server store10 (eu) responded in 127.130313ms 2024/11/20 01:23:00 DEBUG : A1/one: Using upload server store5 (eu) 2024/11/20 01:23:02 DEBUG : A1/two: Using upload server store10 (eu) 2024/11/20 01:23:04 DEBUG : A1/B1/three: Using upload server store10 (eu) 2024/11/20 01:23:06 DEBUG : A1/B1/C1/four: Using upload server store10 (eu) 2024/11/20 01:23:08 DEBUG : A1/B1/C2/five: Using upload server store10 (eu) 2024/11/20 01:23:08 DEBUG : Couldn't decode error response: invalid character 'e' looking for beginning of value 2024/11/20 01:23:08 DEBUG : pacer: low level retry 1/1 (error Error "500 Internal Server Error (500): error-query\n") 2024/11/20 01:23:08 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/11/20 01:23:08 DEBUG : gofile root 'rclone-test-puqalin1weri': Clearing upload servers run.go:281: Retry Put of "A1/B1/C2/five" to gofile root 'rclone-test-puqalin1weri': 1/10 (failed to upload file: Error "500 Internal Server Error (500): error-query\n") 2024/11/20 01:23:10 DEBUG : pacer: Reducing sleep to 10ms 2024/11/20 01:23:10 DEBUG : Upload server store1 (eu) responded in 37.67913ms 2024/11/20 01:23:10 DEBUG : Upload server store10 (eu) responded in 37.851583ms 2024/11/20 01:23:10 DEBUG : A1/B1/C2/five: Using upload server store1 (eu) 2024/11/20 01:23:10 DEBUG : A1/B2: Making directory 2024/11/20 01:23:12 DEBUG : A1/B1/C3: Making directory 2024/11/20 01:23:25 DEBUG : Rate limited, sleep for 5s 2024/11/20 01:23:25 DEBUG : Rate limited, sleep for 5s 2024/11/20 01:23:26 INFO : A2/two: Moved (server-side) to: A3/two 2024/11/20 01:23:26 INFO : A2/one: Moved (server-side) to: A3/one 2024/11/20 01:23:26 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2024/11/20 01:23:30 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit") 2024/11/20 01:23:30 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/11/20 01:23:30 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit") 2024/11/20 01:23:30 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2024/11/20 01:23:30 DEBUG : Rate limited, sleep for 5s 2024/11/20 01:23:30 DEBUG : Rate limited, sleep for 5s 2024/11/20 01:23:35 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit") 2024/11/20 01:23:35 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2024/11/20 01:23:35 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit") 2024/11/20 01:23:35 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2024/11/20 01:23:36 DEBUG : Rate limited, sleep for 5s 2024/11/20 01:23:37 DEBUG : pacer: Reducing sleep to 80ms 2024/11/20 01:23:37 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2024/11/20 01:23:41 DEBUG : pacer: low level retry 3/10 (error Error "error-rateLimit") 2024/11/20 01:23:41 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2024/11/20 01:23:42 DEBUG : pacer: Reducing sleep to 80ms 2024/11/20 01:23:42 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five 2024/11/20 01:23:42 DEBUG : pacer: Reducing sleep to 40ms 2024/11/20 01:23:43 DEBUG : pacer: Reducing sleep to 20ms 2024/11/20 01:23:43 DEBUG : pacer: Reducing sleep to 10ms 2024/11/20 01:23:49 INFO : gofile root 'rclone-test-puqalin1weri': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2024/11/20 01:23:58 DEBUG : Rate limited, sleep for 5s 2024/11/20 01:23:58 INFO : A3/B1/three: Moved (server-side) to: A4/B1/three 2024/11/20 01:23:59 INFO : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five 2024/11/20 01:23:59 INFO : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four 2024/11/20 01:23:59 INFO : A3/one: Moved (server-side) to: A4/one 2024/11/20 01:24:03 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit") 2024/11/20 01:24:03 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/11/20 01:24:03 DEBUG : Rate limited, sleep for 5s 2024/11/20 01:24:08 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit") 2024/11/20 01:24:08 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2024/11/20 01:24:09 DEBUG : pacer: Reducing sleep to 20ms 2024/11/20 01:24:09 INFO : A3/two: Moved (server-side) to: A4/two 2024/11/20 01:24:09 DEBUG : pacer: Reducing sleep to 10ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure --- PASS: TestDirMove (94.92s) PASS 2024/11/20 01:24:34 DEBUG : gofile root 'rclone-test-puqalin1weri': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^(TestDirMove|TestRmdirsWithFilter)$'" - Finished OK in 2m1.400502296s (try 4/5)