"go test -v -timeout 1h0m0s -remote TestIA:rclone-integration-test -verbose" - Starting (try 2/5) === RUN TestIntegration fstests.go:418: Using remote "TestIA:rclone-integration-test" 2022/05/18 06:48:08 DEBUG : Creating backend with remote "TestIA:rclone-integration-test/rclone-test-weyugaj7tukinag9qekulef4" 2022/05/18 06:48:08 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 06:48:09 DEBUG : dir/subdir: Making directory fstest.go:251: Filtering empty directory "dir" fstest.go:251: Filtering empty directory "dir/subdir" 2022/05/18 06:48:10 INFO : dir/subdir: Removing directory fstest.go:251: Filtering empty directory "dir" 2022/05/18 06:48:10 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 821 [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(0xc0002ccea0, {0x9f802b, 0x46f173}, 0xa31260) /usr/local/go/src/testing/testing.go:1307 +0x375 testing.runTests.func1(0xc000253c50) /usr/local/go/src/testing/testing.go:1598 +0x6e testing.tRunner(0xc0002ccea0, 0xc000225d18) /usr/local/go/src/testing/testing.go:1259 +0x102 testing.runTests(0xc00015b500, {0xe7a420, 0x1, 0x1}, {0x48cfad, 0x9f7b8e, 0xe8f440}) /usr/local/go/src/testing/testing.go:1596 +0x43f testing.(*M).Run(0xc00015b500) /usr/local/go/src/testing/testing.go:1504 +0x51d main.main() _testmain.go:45 +0x14b goroutine 7 [chan receive, 59 minutes]: testing.(*T).Run(0xc0002cd040, {0x9f360f, 0xc0001251e0}, 0xc000125130) /usr/local/go/src/testing/testing.go:1307 +0x375 github.com/rclone/rclone/fstest/fstests.Run(0xc0002cd040, 0xc000125080) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:554 +0x1109 github.com/rclone/rclone/backend/internetarchive_test.TestIntegration(0x0) /home/rclone/go/src/github.com/rclone/rclone/backend/internetarchive/internetarchive_test.go:13 +0x57 testing.tRunner(0xc0002cd040, 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 114 [chan receive, 59 minutes]: testing.(*T).Run(0xc0003044e0, {0x9f70b8, 0x0}, 0xc0001c6d80) /usr/local/go/src/testing/testing.go:1307 +0x375 github.com/rclone/rclone/fstest/fstests.Run.func13.7(0xc0003044e0) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:667 +0x2bc testing.tRunner(0xc0003044e0, 0xc000163800) /usr/local/go/src/testing/testing.go:1259 +0x102 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1306 +0x35a goroutine 37 [IO wait]: internal/poll.runtime_pollWait(0x7ff5d695d018, 0x72) /usr/local/go/src/runtime/netpoll.go:234 +0x89 internal/poll.(*pollDesc).wait(0xc0003a3180, 0xc0001a0000, 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(0xc0003a3180, {0xc0001a0000, 0x52a8, 0x52a8}) /usr/local/go/src/internal/poll/fd_unix.go:167 +0x25a net.(*netFD).Read(0xc0003a3180, {0xc0001a0000, 0xc000536640, 0xc0001a2b76}) /usr/local/go/src/net/fd_posix.go:56 +0x29 net.(*conn).Read(0xc0000cc008, {0xc0001a0000, 0xc0001a2b76, 0x19}) /usr/local/go/src/net/net.go:183 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000091038, {0xc0001a0000, 0x0, 0x100000000000143}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:104 +0x32 crypto/tls.(*atLeastReader).Read(0xc0001ca5d0, {0xc0001a0000, 0x0, 0x40bacd}) /usr/local/go/src/crypto/tls/conn.go:777 +0x3d bytes.(*Buffer).ReadFrom(0xc0000d2278, {0xabbd60, 0xc0001ca5d0}) /usr/local/go/src/bytes/buffer.go:204 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0000d2000, {0x7ff5d5913200, 0xc000091038}, 0x273f) /usr/local/go/src/crypto/tls/conn.go:799 +0xe5 crypto/tls.(*Conn).readRecordOrCCS(0xc0000d2000, 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(0xc0000d2000, {0xc00031b000, 0x1000, 0x6969d7}) /usr/local/go/src/crypto/tls/conn.go:1277 +0x16f bufio.(*Reader).Read(0xc0004d20c0, {0xc000516ab8, 0x9, 0xc00053ef30}) /usr/local/go/src/bufio/bufio.go:227 +0x1b4 io.ReadAtLeast({0xabbbe0, 0xc0004d20c0}, {0xc000516ab8, 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({0xc000516ab8, 0x9, 0xc00054e4e0}, {0xabbbe0, 0xc0004d20c0}) /usr/local/go/src/net/http/h2_bundle.go:1555 +0x6e net/http.(*http2Framer).ReadFrame(0xc000516a80) /usr/local/go/src/net/http/h2_bundle.go:1813 +0x95 net/http.(*http2clientConnReadLoop).run(0xc00010bf98) /usr/local/go/src/net/http/h2_bundle.go:8583 +0x130 net/http.(*http2ClientConn).readLoop(0xc000156300) /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 48 [chan receive, 59 minutes]: testing.(*T).Run(0xc000305380, {0x9f50a4, 0x0}, 0xc000163800) /usr/local/go/src/testing/testing.go:1307 +0x375 github.com/rclone/rclone/fstest/fstests.Run.func13(0xc000305380) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:633 +0x65a testing.tRunner(0xc000305380, 0xc000125130) /usr/local/go/src/testing/testing.go:1259 +0x102 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1306 +0x35a goroutine 117 [select, 59 minutes]: github.com/rclone/rclone/backend/internetarchive.(*Fs).waitFileUpload(0xc0001251e0, {0xac97f0, 0xc000024058}, {0xc00045c340, 0xc7}, {0xc0003b7b80, 0x20}, 0x64) /home/rclone/go/src/github.com/rclone/rclone/backend/internetarchive/internetarchive.go:943 +0x506 github.com/rclone/rclone/backend/internetarchive.(*Object).Update(0xc0000209a0, {0xac97f0, 0xc000024058}, {0xabc820, 0xc0000b4940}, {0xad06a0, 0xc000368280}, {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(0xc0001251e0, {0xac97f0, 0xc000024058}, {0xabc820, 0xc0000b4940}, {0xad06a0, 0xc000368280}, {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(0xc000305a00, {0x9f1d9f, 0x3}, 0xc00010fd48) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:145 +0x119 github.com/rclone/rclone/fstest/fstests.putTestContentsMimeType({0xac97f0, 0xc000024058}, 0xc00056cdf8, {0xad5718, 0xc0001251e0}, 0xc00010ff28, {0xc000522310, 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, 0xc0001251e0}, 0xc000522150) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:217 +0x8d github.com/rclone/rclone/fstest/fstests.Run.func13.7.1(0xc000305a00) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:680 +0x289 testing.tRunner(0xc000305a00, 0xc0001c6d80) /usr/local/go/src/testing/testing.go:1259 +0x102 created by testing.(*T).Run /usr/local/go/src/testing/testing.go:1306 +0x35a goroutine 123 [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.752955423s (try 2/5): exit status 1: Failed []