"./vfs.test -test.v -test.timeout 1h0m0s -remote TestUlozto: -verbose -test.run '^TestDirRename$'" - Starting (try 3/5) 2024/04/13 05:34:13 DEBUG : Creating backend with remote "TestUlozto:rclone-test-hohaniz8ruyefed0zopetam9" 2024/04/13 05:34:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/13 05:34:15 DEBUG : Creating backend with remote "/tmp/rclone2612892798" === RUN TestDirRename run.go:180: Remote "uloz.to root 'rclone-test-hohaniz8ruyefed0zopetam9'", Local "Local file system at /tmp/rclone2612892798", Modify Window "1µs" 2024/04/13 05:34:15 INFO : uloz.to root 'rclone-test-hohaniz8ruyefed0zopetam9': poll-interval is not supported by this remote 2024/04/13 05:34:15 NOTICE: uloz.to root 'rclone-test-hohaniz8ruyefed0zopetam9': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/13 05:35:52 ERROR : dir/not found: Dir.Rename error: file does not exist 2024/04/13 05:35:57 DEBUG : pacer: low level retry 1/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:35:57 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/04/13 05:36:02 DEBUG : pacer: low level retry 2/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:36:02 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2024/04/13 05:36:03 DEBUG : pacer: Reducing sleep to 30ms 2024/04/13 05:36:10 DEBUG : pacer: low level retry 1/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:36:10 DEBUG : pacer: Rate limited, increasing sleep to 60ms 2024/04/13 05:36:29 DEBUG : pacer: low level retry 2/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:36:29 DEBUG : pacer: Rate limited, increasing sleep to 120ms 2024/04/13 05:36:48 DEBUG : pacer: low level retry 3/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:36:48 DEBUG : pacer: Rate limited, increasing sleep to 240ms 2024/04/13 05:37:07 DEBUG : pacer: low level retry 4/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:37:07 DEBUG : pacer: Rate limited, increasing sleep to 480ms 2024/04/13 05:37:27 DEBUG : pacer: low level retry 5/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:37:27 DEBUG : pacer: Rate limited, increasing sleep to 960ms 2024/04/13 05:37:46 DEBUG : pacer: low level retry 6/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:37:46 DEBUG : pacer: Rate limited, increasing sleep to 1.92s 2024/04/13 05:38:06 DEBUG : pacer: low level retry 7/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:38:06 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/04/13 05:38:26 DEBUG : pacer: low level retry 8/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:38:46 DEBUG : pacer: low level retry 9/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:39:07 DEBUG : pacer: low level retry 10/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:39:07 ERROR : dir: Dir.Rename error: Error 130003 (429): Endpoint received too many requests. Try it again later. dir_test.go:535: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:535 Error: Received unexpected error: Error 130003 (429): Endpoint received too many requests. Try it again later. Test: TestDirRename dir_test.go:247: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:247 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:536 Error: Not equal: expected: []string{"dir2,0,true"} actual : []string{"dir,0,true"} Diff: --- Expected +++ Actual @@ -1,3 +1,3 @@ ([]string) (len=1) { - (string) (len=11) "dir2,0,true" + (string) (len=10) "dir,0,true" } Test: TestDirRename 2024/04/13 05:39:08 DEBUG : pacer: Reducing sleep to 1.5s 2024/04/13 05:39:10 DEBUG : pacer: Reducing sleep to 1.125s 2024/04/13 05:39:11 DEBUG : pacer: Reducing sleep to 843.75ms 2024/04/13 05:39:12 DEBUG : pacer: Reducing sleep to 632.8125ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/04/13 05:39:13 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/04/13 05:39:14 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/04/13 05:39:14 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/04/13 05:39:15 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/04/13 05:39:15 DEBUG : pacer: Reducing sleep to 150.169371ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache 2024/04/13 05:39:17 DEBUG : pacer: Reducing sleep to 112.627028ms 2024/04/13 05:39:17 DEBUG : pacer: Reducing sleep to 84.470271ms 2024/04/13 05:39:18 DEBUG : pacer: Reducing sleep to 63.352703ms 2024/04/13 05:39:18 DEBUG : pacer: Reducing sleep to 47.514527ms 2024/04/13 05:39:18 DEBUG : pacer: Reducing sleep to 35.635895ms fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:542 Error: Should be true Test: TestDirRename Messages: listing wrong, want dir2/file1 (14), dir2/file3 (15) got dir/file1 (14), dir/file3 (15) fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:542 Error: Should be true Test: TestDirRename Messages: Unexpected file "dir/file1" fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:542 Error: Should be true Test: TestDirRename Messages: Unexpected file "dir/file3" fstest.go:197: Not found "dir2/file1" fstest.go:197: Not found "dir2/file3" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:542 Error: Not equal: expected: 0 actual : 2 Test: TestDirRename Messages: 2 objects not found fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:542 Error: Not equal: expected: []string{"dir2"} actual : []string{"dir"} Diff: --- Expected +++ Actual @@ -1,3 +1,3 @@ ([]string) (len=1) { - (string) (len=4) "dir2" + (string) (len=3) "dir" } Test: TestDirRename Messages: directories dir_test.go:546: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:546 Error: Received unexpected error: file does not exist Test: TestDirRename 2024/04/13 05:39:22 DEBUG : WaitForWriters: timeout=30s 2024/04/13 05:39:22 DEBUG : dir: Looking for writers 2024/04/13 05:39:22 DEBUG : file1: reading active writers 2024/04/13 05:39:22 DEBUG : file3: reading active writers 2024/04/13 05:39:22 DEBUG : : Looking for writers 2024/04/13 05:39:22 DEBUG : dir: reading active writers 2024/04/13 05:39:22 DEBUG : >WaitForWriters: 2024/04/13 05:39:22 DEBUG : pacer: Reducing sleep to 26.726921ms 2024/04/13 05:39:22 DEBUG : pacer: Reducing sleep to 20.04519ms 2024/04/13 05:39:22 DEBUG : pacer: Reducing sleep to 15.033892ms 2024/04/13 05:39:23 DEBUG : pacer: Reducing sleep to 11.275419ms 2024/04/13 05:39:23 DEBUG : pacer: Reducing sleep to 10ms --- FAIL: TestDirRename (325.61s) panic: interface conversion: vfs.Node is nil, not *vfs.Dir [recovered] panic: interface conversion: vfs.Node is nil, not *vfs.Dir goroutine 66 [running]: testing.tRunner.func1.2({0x1d5d1c0, 0xc0006c6270}) /usr/local/go/src/testing/testing.go:1631 +0x24a testing.tRunner.func1() /usr/local/go/src/testing/testing.go:1634 +0x377 panic({0x1d5d1c0?, 0xc0006c6270?}) /usr/local/go/src/runtime/panic.go:770 +0x132 github.com/rclone/rclone/vfs.TestDirRename(0xc0006d6ea0) /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:547 +0xe16 testing.tRunner(0xc0006d6ea0, 0x2291ab8) /usr/local/go/src/testing/testing.go:1689 +0xfb created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1742 +0x390 "./vfs.test -test.v -test.timeout 1h0m0s -remote TestUlozto: -verbose -test.run '^TestDirRename$'" - Finished ERROR in 5m27.501454623s (try 3/5): exit status 2: Failed [TestDirRename]