"./vfs.test -test.v -test.timeout 1h0m0s -remote TestUlozto: -verbose -test.run '^TestDirRename$'" - Starting (try 2/5) 2024/04/13 05:29:22 DEBUG : Creating backend with remote "TestUlozto:rclone-test-cocixor4cavoqek6sevisub3" 2024/04/13 05:29:22 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/13 05:29:24 DEBUG : Creating backend with remote "/tmp/rclone2388307804" === RUN TestDirRename run.go:180: Remote "uloz.to root 'rclone-test-cocixor4cavoqek6sevisub3'", Local "Local file system at /tmp/rclone2388307804", Modify Window "1µs" 2024/04/13 05:29:24 INFO : uloz.to root 'rclone-test-cocixor4cavoqek6sevisub3': poll-interval is not supported by this remote 2024/04/13 05:29:24 NOTICE: uloz.to root 'rclone-test-cocixor4cavoqek6sevisub3': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2024/04/13 05:29:57 ERROR : dir/not found: Dir.Rename error: file does not exist 2024/04/13 05:30:03 DEBUG : pacer: low level retry 1/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:30:03 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/04/13 05:30:08 DEBUG : pacer: low level retry 2/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:30:08 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2024/04/13 05:30:09 DEBUG : pacer: Reducing sleep to 30ms 2024/04/13 05:30:16 DEBUG : pacer: low level retry 1/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:30:16 DEBUG : pacer: Rate limited, increasing sleep to 60ms 2024/04/13 05:30:35 DEBUG : pacer: low level retry 2/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:30:35 DEBUG : pacer: Rate limited, increasing sleep to 120ms 2024/04/13 05:30:54 DEBUG : pacer: low level retry 3/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:30:54 DEBUG : pacer: Rate limited, increasing sleep to 240ms 2024/04/13 05:31:15 DEBUG : pacer: low level retry 4/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:31:15 DEBUG : pacer: Rate limited, increasing sleep to 480ms 2024/04/13 05:31:34 DEBUG : pacer: low level retry 5/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:31:34 DEBUG : pacer: Rate limited, increasing sleep to 960ms 2024/04/13 05:31:52 DEBUG : pacer: low level retry 6/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:31:52 DEBUG : pacer: Rate limited, increasing sleep to 1.92s 2024/04/13 05:32:10 DEBUG : pacer: low level retry 7/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:32:10 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/04/13 05:32:30 DEBUG : pacer: low level retry 8/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:32:48 DEBUG : pacer: low level retry 9/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:33:06 DEBUG : pacer: low level retry 10/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:33:06 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:33:07 DEBUG : pacer: Reducing sleep to 1.5s 2024/04/13 05:33:09 DEBUG : pacer: Reducing sleep to 1.125s 2024/04/13 05:33:10 DEBUG : pacer: Reducing sleep to 843.75ms 2024/04/13 05:33:11 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:33:12 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/04/13 05:33:13 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/04/13 05:33:14 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/04/13 05:33:14 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/04/13 05:33:14 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:33:16 DEBUG : pacer: Reducing sleep to 112.627028ms 2024/04/13 05:33:17 DEBUG : pacer: Reducing sleep to 84.470271ms 2024/04/13 05:33:17 DEBUG : pacer: Reducing sleep to 63.352703ms 2024/04/13 05:33:17 DEBUG : pacer: Reducing sleep to 47.514527ms 2024/04/13 05:33:17 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:33:21 DEBUG : WaitForWriters: timeout=30s 2024/04/13 05:33:21 DEBUG : dir: Looking for writers 2024/04/13 05:33:21 DEBUG : file1: reading active writers 2024/04/13 05:33:21 DEBUG : file3: reading active writers 2024/04/13 05:33:21 DEBUG : : Looking for writers 2024/04/13 05:33:21 DEBUG : dir: reading active writers 2024/04/13 05:33:21 DEBUG : >WaitForWriters: 2024/04/13 05:33:21 DEBUG : pacer: Reducing sleep to 26.726921ms 2024/04/13 05:33:21 DEBUG : pacer: Reducing sleep to 20.04519ms 2024/04/13 05:33:22 DEBUG : pacer: Reducing sleep to 15.033892ms 2024/04/13 05:33:22 DEBUG : pacer: Reducing sleep to 11.275419ms 2024/04/13 05:33:22 DEBUG : pacer: Reducing sleep to 10ms 2024/04/13 05:33:28 DEBUG : pacer: low level retry 1/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:33:28 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/04/13 05:33:33 DEBUG : pacer: low level retry 2/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:33:33 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2024/04/13 05:33:39 DEBUG : pacer: low level retry 3/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:33:39 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2024/04/13 05:33:45 DEBUG : pacer: low level retry 4/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:33:45 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2024/04/13 05:33:50 DEBUG : pacer: low level retry 5/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:33:50 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2024/04/13 05:33:56 DEBUG : pacer: low level retry 6/10 (error Error 130003 (429): Endpoint received too many requests. Try it again later.) 2024/04/13 05:33:56 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2024/04/13 05:34:12 DEBUG : pacer: Reducing sleep to 480ms 2024/04/13 05:34:12 DEBUG : pacer: Reducing sleep to 360ms 2024/04/13 05:34:13 DEBUG : pacer: Reducing sleep to 270ms --- FAIL: TestDirRename (288.96s) panic: interface conversion: vfs.Node is nil, not *vfs.Dir [recovered] panic: interface conversion: vfs.Node is nil, not *vfs.Dir goroutine 14 [running]: testing.tRunner.func1.2({0x1d5d1c0, 0xc0005373b0}) /usr/local/go/src/testing/testing.go:1631 +0x24a testing.tRunner.func1() /usr/local/go/src/testing/testing.go:1634 +0x377 panic({0x1d5d1c0?, 0xc0005373b0?}) /usr/local/go/src/runtime/panic.go:770 +0x132 github.com/rclone/rclone/vfs.TestDirRename(0xc0000fc9c0) /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:547 +0xe16 testing.tRunner(0xc0000fc9c0, 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 4m50.847262872s (try 2/5): exit status 2: Failed [TestDirRename]