"go test -v -timeout 1h0m0s -remote TestIA:rclone-integration-test -verbose" - Starting (try 3/5) === RUN TestIntegration fstests.go:418: Using remote "TestIA:rclone-integration-test" 2022/05/21 07:34:44 DEBUG : Creating backend with remote "TestIA:rclone-integration-test/rclone-test-duzukeg0besisaq0niladil6" 2022/05/21 07:34:44 DEBUG : Using config file from "/home/rclone/.rclone.conf" === RUN TestIntegration/FsCheckWrap fstests.go:459: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:487: No commands in this remote === RUN TestIntegration/FsRmdirNotFound fstests.go:502: Skipping test as non root bucket-based remote === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2022/05/21 07:34:45 DEBUG : dir/subdir: Making directory fstest.go:251: Filtering empty directory "dir" fstest.go:251: Filtering empty directory "dir/subdir" 2022/05/21 07:34:45 INFO : dir/subdir: Removing directory fstest.go:251: Filtering empty directory "dir" 2022/05/21 07:34:46 INFO : dir: Removing directory === RUN TestIntegration/FsMkdir/FsListEmpty === RUN TestIntegration/FsMkdir/FsListDirEmpty === RUN TestIntegration/FsMkdir/FsListRDirEmpty === RUN TestIntegration/FsMkdir/FsListDirNotFound === RUN TestIntegration/FsMkdir/FsListRDirNotFound === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:674: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" panic: test timed out after 1h0m0s goroutine 778 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:1788 +0x8e created by time.goFunc /usr/local/go/src/time/sleep.go:180 +0x31 goroutine 1 [chan receive, 59 minutes]: testing.(*T).Run(0xc000398d00, {0x9f802b, 0x46f173}, 0xa31260) /usr/local/go/src/testing/testing.go:1307 +0x375 testing.runTests.func1(0xc00039ecf0) /usr/local/go/src/testing/testing.go:1598 +0x6e testing.tRunner(0xc000398d00, 0xc0001e3d18) /usr/local/go/src/testing/testing.go:1259 +0x102 testing.runTests(0xc0003c8300, {0xe7a420, 0x1, 0x1}, {0x48cfad, 0x9f7b8e, 0xe8f440}) /usr/local/go/src/testing/testing.go:1596 +0x43f testing.(*M).Run(0xc0003c8300) /usr/local/go/src/testing/testing.go:1504 +0x51d main.main() _testmain.go:45 +0x14b goroutine 34 [chan receive, 59 minutes]: testing.(*T).Run(0xc000398ea0, {0x9f360f, 0xc0003b2210}, 0xc0000c8dc0) /usr/local/go/src/testing/testing.go:1307 +0x375 github.com/rclone/rclone/fstest/fstests.Run(0xc000398ea0, 0xc0003b20b0) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:554 +0x1109 github.com/rclone/rclone/backend/internetarchive_test.TestIntegration(0x407b99) /home/rclone/go/src/github.com/rclone/rclone/backend/internetarchive/internetarchive_test.go:13 +0x57 testing.tRunner(0xc000398ea0, 0xa31260) /usr/local/go/src/testing/testing.go:1259 +0x102 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1306 +0x35a goroutine 21 [IO wait]: internal/poll.runtime_pollWait(0x7f52a07b37d8, 0x72) /usr/local/go/src/runtime/netpoll.go:234 +0x89 internal/poll.(*pollDesc).wait(0xc0003c9f80, 0xc000536000, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x32 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc0003c9f80, {0xc000536000, 0x5a74, 0x5a74}) /usr/local/go/src/internal/poll/fd_unix.go:167 +0x25a net.(*netFD).Read(0xc0003c9f80, {0xc000536000, 0xc0003cd7c0, 0xc00053a7ee}) /usr/local/go/src/net/fd_posix.go:56 +0x29 net.(*conn).Read(0xc000010018, {0xc000536000, 0xc00053a7ee, 0x19}) /usr/local/go/src/net/net.go:183 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc00000f068, {0xc000536000, 0xd, 0x10000c0002a08c0}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:104 +0x32 crypto/tls.(*atLeastReader).Read(0xc00037e198, {0xc000536000, 0x0, 0x40bacd}) /usr/local/go/src/crypto/tls/conn.go:777 +0x3d bytes.(*Buffer).ReadFrom(0xc00031e278, {0xabbd60, 0xc00037e198}) /usr/local/go/src/bytes/buffer.go:204 +0x98 crypto/tls.(*Conn).readFromUntil(0xc00031e000, {0x7f52997af0d8, 0xc00000f068}, 0x1293) /usr/local/go/src/crypto/tls/conn.go:799 +0xe5 crypto/tls.(*Conn).readRecordOrCCS(0xc00031e000, 0x0) /usr/local/go/src/crypto/tls/conn.go:606 +0x112 crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:574 crypto/tls.(*Conn).Read(0xc00031e000, {0xc0002ad000, 0x1000, 0x1}) /usr/local/go/src/crypto/tls/conn.go:1277 +0x16f bufio.(*Reader).Read(0xc0003c3f80, {0xc00034c9d8, 0x9, 0xc000108330}) /usr/local/go/src/bufio/bufio.go:227 +0x1b4 io.ReadAtLeast({0xabbbe0, 0xc0003c3f80}, {0xc00034c9d8, 0x9, 0x9}, 0x9) /usr/local/go/src/io/io.go:328 +0x9a io.ReadFull(...) /usr/local/go/src/io/io.go:347 net/http.http2readFrameHeader({0xc00034c9d8, 0x9, 0xc0001d02a0}, {0xabbbe0, 0xc0003c3f80}) /usr/local/go/src/net/http/h2_bundle.go:1555 +0x6e net/http.(*http2Framer).ReadFrame(0xc00034c9a0) /usr/local/go/src/net/http/h2_bundle.go:1813 +0x95 net/http.(*http2clientConnReadLoop).run(0xc0000aff98) /usr/local/go/src/net/http/h2_bundle.go:8583 +0x130 net/http.(*http2ClientConn).readLoop(0xc00041c180) /usr/local/go/src/net/http/h2_bundle.go:8506 +0x6f created by net/http.(*http2Transport).newClientConn /usr/local/go/src/net/http/h2_bundle.go:7317 +0xb85 goroutine 27 [chan receive, 59 minutes]: testing.(*T).Run(0xc000399ba0, {0x9f50a4, 0x0}, 0xc00007c270) /usr/local/go/src/testing/testing.go:1307 +0x375 github.com/rclone/rclone/fstest/fstests.Run.func13(0xc000399ba0) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:633 +0x65a testing.tRunner(0xc000399ba0, 0xc0000c8dc0) /usr/local/go/src/testing/testing.go:1259 +0x102 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1306 +0x35a goroutine 60 [chan receive, 59 minutes]: testing.(*T).Run(0xc000082b60, {0x9f70b8, 0x0}, 0xc000296630) /usr/local/go/src/testing/testing.go:1307 +0x375 github.com/rclone/rclone/fstest/fstests.Run.func13.7(0xc000082b60) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:667 +0x2bc testing.tRunner(0xc000082b60, 0xc00007c270) /usr/local/go/src/testing/testing.go:1259 +0x102 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1306 +0x35a goroutine 63 [select, 54 minutes]: github.com/rclone/rclone/backend/internetarchive.(*Fs).waitDelete(0xc0003b2210, {0xac97f0, 0xc0000c0000}, {0xc0005befc0, 0x17}, {0xc0005befd8, 0xec}) /home/rclone/go/src/github.com/rclone/rclone/backend/internetarchive/internetarchive.go:984 +0x1f4 github.com/rclone/rclone/backend/internetarchive.(*Object).Remove(0xc000275a40, {0xac97f0, 0xc0000c0000}) /home/rclone/go/src/github.com/rclone/rclone/backend/internetarchive/internetarchive.go:752 +0x2f9 github.com/rclone/rclone/fstest/fstests.Run.func13.7.1(0xc0000836c0) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:682 +0x416 testing.tRunner(0xc0000836c0, 0xc000296630) /usr/local/go/src/testing/testing.go:1259 +0x102 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1306 +0x35a goroutine 189 [sleep]: time.Sleep(0x2540be400) /usr/local/go/src/runtime/time.go:193 +0x12e github.com/rclone/rclone/backend/internetarchive.(*Fs).waitDelete.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/internetarchive/internetarchive.go:980 +0x85 created by github.com/rclone/rclone/backend/internetarchive.(*Fs).waitDelete /home/rclone/go/src/github.com/rclone/rclone/backend/internetarchive/internetarchive.go:958 +0x191 exit status 2 FAIL github.com/rclone/rclone/backend/internetarchive 3600.015s "go test -v -timeout 1h0m0s -remote TestIA:rclone-integration-test -verbose" - Finished ERROR in 1h0m0.673109847s (try 3/5): exit status 1: Failed []