"go test -v -timeout 1h0m0s -remote TestIA:rclone-integration-test -verbose" - Starting (try 4/5) === RUN TestIntegration fstests.go:418: Using remote "TestIA:rclone-integration-test" 2022/05/18 08:48:09 DEBUG : Creating backend with remote "TestIA:rclone-integration-test/rclone-test-lefihis1lenujep3movijar3" 2022/05/18 08:48:09 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/18 08:48:11 DEBUG : dir/subdir: Making directory fstest.go:251: Filtering empty directory "dir" fstest.go:251: Filtering empty directory "dir/subdir" 2022/05/18 08:48:11 INFO : dir/subdir: Removing directory fstest.go:251: Filtering empty directory "dir" 2022/05/18 08:48:11 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 807 [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(0xc000083040, {0x9f802b, 0x46f173}, 0xa31260) /usr/local/go/src/testing/testing.go:1307 +0x375 testing.runTests.func1(0xc0000aecf0) /usr/local/go/src/testing/testing.go:1598 +0x6e testing.tRunner(0xc000083040, 0xc000233d18) /usr/local/go/src/testing/testing.go:1259 +0x102 testing.runTests(0xc0000d4300, {0xe7a420, 0x1, 0x1}, {0x48cfad, 0x9f7b8e, 0xe8f440}) /usr/local/go/src/testing/testing.go:1596 +0x43f testing.(*M).Run(0xc0000d4300) /usr/local/go/src/testing/testing.go:1504 +0x51d main.main() _testmain.go:45 +0x14b goroutine 34 [chan receive, 59 minutes]: testing.(*T).Run(0xc0000831e0, {0x9f360f, 0xc0000c0210}, 0xc0000c0420) /usr/local/go/src/testing/testing.go:1307 +0x375 github.com/rclone/rclone/fstest/fstests.Run(0xc0000831e0, 0xc0000c00b0) /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(0xc0000831e0, 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 47 [chan receive, 59 minutes]: testing.(*T).Run(0xc000344820, {0x9f50a4, 0x0}, 0xc000307500) /usr/local/go/src/testing/testing.go:1307 +0x375 github.com/rclone/rclone/fstest/fstests.Run.func13(0xc000344820) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:633 +0x65a testing.tRunner(0xc000344820, 0xc0000c0420) /usr/local/go/src/testing/testing.go:1259 +0x102 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1306 +0x35a goroutine 41 [IO wait]: internal/poll.runtime_pollWait(0x7f302e325018, 0x72) /usr/local/go/src/runtime/netpoll.go:234 +0x89 internal/poll.(*pollDesc).wait(0xc0000d5f80, 0xc0005b0000, 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(0xc0000d5f80, {0xc0005b0000, 0x45e3, 0x45e3}) /usr/local/go/src/internal/poll/fd_unix.go:167 +0x25a net.(*netFD).Read(0xc0000d5f80, {0xc0005b0000, 0xc0000d8c80, 0xc0005b2bbc}) /usr/local/go/src/net/fd_posix.go:56 +0x29 net.(*conn).Read(0xc0001eb498, {0xc0005b0000, 0xc0005b2bbc, 0x19}) /usr/local/go/src/net/net.go:183 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0001f73e0, {0xc0005b0000, 0x0, 0x10000002d2e0650}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:104 +0x32 crypto/tls.(*atLeastReader).Read(0xc000302258, {0xc0005b0000, 0x0, 0x40bacd}) /usr/local/go/src/crypto/tls/conn.go:777 +0x3d bytes.(*Buffer).ReadFrom(0xc000256cf8, {0xabbd60, 0xc000302258}) /usr/local/go/src/bytes/buffer.go:204 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000256a80, {0x7f302e350e98, 0xc0001f73e0}, 0x1a34) /usr/local/go/src/crypto/tls/conn.go:799 +0xe5 crypto/tls.(*Conn).readRecordOrCCS(0xc000256a80, 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(0xc000256a80, {0xc00015d000, 0x1000, 0x6969d7}) /usr/local/go/src/crypto/tls/conn.go:1277 +0x16f bufio.(*Reader).Read(0xc00008f020, {0xc0002f2e38, 0x9, 0xc00039c630}) /usr/local/go/src/bufio/bufio.go:227 +0x1b4 io.ReadAtLeast({0xabbbe0, 0xc00008f020}, {0xc0002f2e38, 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({0xc0002f2e38, 0x9, 0xc0003064b0}, {0xabbbe0, 0xc00008f020}) /usr/local/go/src/net/http/h2_bundle.go:1555 +0x6e net/http.(*http2Framer).ReadFrame(0xc0002f2e00) /usr/local/go/src/net/http/h2_bundle.go:1813 +0x95 net/http.(*http2clientConnReadLoop).run(0xc000055f98) /usr/local/go/src/net/http/h2_bundle.go:8583 +0x130 net/http.(*http2ClientConn).readLoop(0xc00039c180) /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 107 [chan receive, 59 minutes]: testing.(*T).Run(0xc0004a51e0, {0x9f70b8, 0x0}, 0xc0001609c0) /usr/local/go/src/testing/testing.go:1307 +0x375 github.com/rclone/rclone/fstest/fstests.Run.func13.7(0xc0004a51e0) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:667 +0x2bc testing.tRunner(0xc0004a51e0, 0xc000307500) /usr/local/go/src/testing/testing.go:1259 +0x102 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1306 +0x35a goroutine 73 [select, 59 minutes]: github.com/rclone/rclone/backend/internetarchive.(*Fs).waitFileUpload(0xc0000c0210, {0xac97f0, 0xc000024058}, {0xc0003bc410, 0xc7}, {0xc0002f4e40, 0x20}, 0x64) /home/rclone/go/src/github.com/rclone/rclone/backend/internetarchive/internetarchive.go:943 +0x506 github.com/rclone/rclone/backend/internetarchive.(*Object).Update(0xc00023d1f0, {0xac97f0, 0xc000024058}, {0xabc820, 0xc00048b720}, {0xad06a0, 0xc000337db0}, {0x0, 0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/backend/internetarchive/internetarchive.go:721 +0x9c5 github.com/rclone/rclone/backend/internetarchive.(*Fs).Put(0xc0000c0210, {0xac97f0, 0xc000024058}, {0xabc820, 0xc00048b720}, {0xad06a0, 0xc000337db0}, {0x0, 0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/backend/internetarchive/internetarchive.go:444 +0x1d6 github.com/rclone/rclone/fstest/fstests.putTestContentsMimeType.func1() /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:197 +0x2d3 github.com/rclone/rclone/fstest/fstests.retry(0xc000344340, {0x9f1d9f, 0x3}, 0xc00010bd48) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:145 +0x119 github.com/rclone/rclone/fstest/fstests.putTestContentsMimeType({0xac97f0, 0xc000024058}, 0xc000410df8, {0xad5718, 0xc0000c0210}, 0xc00010bf28, {0xc00002c310, 0x64}, 0x1, {0x0, ...}) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:187 +0x151 github.com/rclone/rclone/fstest/fstests.PutTestContents(...) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:212 github.com/rclone/rclone/fstest/fstests.testPut({0xac97f0, 0xc000024058}, 0x0, {0xad5718, 0xc0000c0210}, 0xc00002c0e0) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:217 +0x8d github.com/rclone/rclone/fstest/fstests.Run.func13.7.1(0xc000344340) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:680 +0x289 testing.tRunner(0xc000344340, 0xc0001609c0) /usr/local/go/src/testing/testing.go:1259 +0x102 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1306 +0x35a goroutine 91 [sleep]: time.Sleep(0x2540be400) /usr/local/go/src/runtime/time.go:193 +0x12e github.com/rclone/rclone/backend/internetarchive.(*Fs).waitFileUpload.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/internetarchive/internetarchive.go:885 +0x153 created by github.com/rclone/rclone/backend/internetarchive.(*Fs).waitFileUpload /home/rclone/go/src/github.com/rclone/rclone/backend/internetarchive/internetarchive.go:879 +0x47b exit status 2 FAIL github.com/rclone/rclone/backend/internetarchive 3600.014s "go test -v -timeout 1h0m0s -remote TestIA:rclone-integration-test -verbose" - Finished ERROR in 1h0m0.616529121s (try 4/5): exit status 1: Failed []