plan9-arm at f1f8f9af9a55d73dfc6603a93bee0559fdc9024d building crypto at 06a226fb4e3765ef3f48aa2852b401bc7b98e981 testing in module mode; GOMOD=/boot/workdir/gopath/src/golang.org/x/crypto/go.mod :: Running /boot/workdir/go/bin/go with args ["/boot/workdir/go/bin/go" "test" "-short" "golang.org/x/crypto/..."] and env ["home=/usr/glenda" "path=/boot/workdir/go/bin\x00.\x00/bin" "type=host-plan9-arm-0intro" "GOARM=7" "GO_BUILD_KEY_DELETE_AFTER_READ=false" "status=" "GO_TEST_TIMEOUT_SCALE=6" "fs=aoe" "GOCACHE=/boot/cache" "GOROOT_BOOTSTRAP=/boot/workdir/go1.4" "workdir=/boot/workdir" "objtype=arm" "*=" "rcname=rc" "pid=134563" "ifs= \t\n" "prompt=% \x00\t" "fn#sigexit=" "cflag=" "0=/rc/lib/rcmain" "U=glenda" "i=" "WORKDIR=/boot/workdir" "GO_BUILDER_NAME=plan9-arm" "GOROOT=/boot/workdir/go" "GOPATH=/boot/workdir/gopath" "GOPROXY=http://10.240.0.29:30157" "GOPROXY=https://proxy.golang.org" "GOROOT_BOOTSTRAP=/usr/glenda/go"] in dir /boot/workdir/gopath/src/golang.org/x/crypto go: downloading golang.org/x/net v0.0.0-20190404232315-eb5bcb51f2a3 go: downloading golang.org/x/text v0.3.0 ok golang.org/x/crypto/acme 13.421s 2020/05/31 04:29:51 GET / 2020/05/31 04:29:51 POST /new-reg 2020/05/31 04:29:51 POST /new-order 2020/05/31 04:29:51 POST /authz/example.org 2020/05/31 04:29:51 POST /challenge/tls-alpn-01/example.org 2020/05/31 04:29:51 POST /authz/example.org 2020/05/31 04:29:51 validated "tls-alpn-01" for "example.org"; authz status is now: valid 2020/05/31 04:29:51 order 0 is now ready 2020/05/31 04:29:52 POST /authz/example.org 2020/05/31 04:29:52 POST /orders/0 2020/05/31 04:29:52 POST /new-cert/0 2020/05/31 04:29:52 HEAD /new-nonce 2020/05/31 04:29:52 POST /authz/example.org 2020/05/31 04:29:52 POST /issued-cert/0 2020/05/31 04:29:58 httptest.Server blocked in Close after 5 seconds, waiting for connections: *tls.Conn 0x10e10400 127.0.0.1:44403 in state active panic: test timed out after 10m0s goroutine 1022 [running]: testing.(*M).startAlarm.func1() /boot/workdir/go/src/testing/testing.go:1610 +0xbc created by time.goFunc /boot/workdir/go/src/time/sleep.go:167 +0x34 goroutine 1 [chan receive, 9 minutes]: testing.(*T).Run(0x10f38fc0, 0x3b5038, 0xc, 0x3d51b0, 0x301) /boot/workdir/go/src/testing/testing.go:1161 +0x2f4 testing.runTests.func1(0x10d02380) /boot/workdir/go/src/testing/testing.go:1431 +0x68 testing.tRunner(0x10d02380, 0x10c28ec4) /boot/workdir/go/src/testing/testing.go:1109 +0xbc testing.runTests(0x10d1c070, 0x616a98, 0x1a, 0x1a, 0xb244d411, 0xbface7a0, 0xb46773fd, 0x8b, 0x61a8a0, 0xcdb0) /boot/workdir/go/src/testing/testing.go:1429 +0x258 testing.(*M).Run(0x10d2e050, 0x0) /boot/workdir/go/src/testing/testing.go:1339 +0x1c8 main.main() _testmain.go:95 +0x120 goroutine 909 [semacquire, 9 minutes]: sync.runtime_Semacquire(0x10e33168) /boot/workdir/go/src/runtime/sema.go:56 +0x34 sync.(*WaitGroup).Wait(0x10e33160) /boot/workdir/go/src/sync/waitgroup.go:130 +0x84 net/http/httptest.(*Server).Close(0x10e33140) /boot/workdir/go/src/net/http/httptest/server.go:249 +0x1cc golang.org/x/crypto/acme/autocert.TestEndToEnd(0x10f38fc0) /boot/workdir/gopath/src/golang.org/x/crypto/acme/autocert/autocert_test.go:1232 +0x540 testing.tRunner(0x10f38fc0, 0x3d51b0) /boot/workdir/go/src/testing/testing.go:1109 +0xbc created by testing.(*T).Run /boot/workdir/go/src/testing/testing.go:1160 +0x2d4 goroutine 969 [syscall, 9 minutes, locked to thread]: syscall.Syscall6(0x32, 0xa, 0x1106bb00, 0x205, 0xffffffff, 0xffffffff, 0x0, 0xe, 0x110967c7, 0x0, ...) /boot/workdir/go/src/syscall/asm_plan9_arm.s:51 +0x8 syscall.Pread(0xa, 0x1106bb00, 0x205, 0x205, 0xffffffff, 0xffffffff, 0x4, 0x18, 0x0) /boot/workdir/go/src/syscall/zsyscall_plan9_arm.go:218 +0x58 syscall.Read(...) /boot/workdir/go/src/syscall/syscall_plan9.go:166 os.(*File).read(0x10d81c98, 0x1106bb00, 0x205, 0x205, 0x88460, 0x18, 0x0) /boot/workdir/go/src/os/file_plan9.go:247 +0x50 os.(*File).Read(0x10d81c98, 0x1106bb00, 0x205, 0x205, 0x10f0c814, 0xffffffff, 0x0) /boot/workdir/go/src/os/file.go:116 +0x60 internal/poll.newAsyncIO.func1(0x10f0c810, 0x10ec02e0, 0x1106bb00, 0x205, 0x205) /boot/workdir/go/src/internal/poll/fd_io_plan9.go:54 +0x6c created by internal/poll.newAsyncIO /boot/workdir/go/src/internal/poll/fd_io_plan9.go:43 +0xa0 goroutine 911 [sync.Cond.Wait, 9 minutes]: runtime.goparkunlock(...) /boot/workdir/go/src/runtime/proc.go:312 sync.runtime_notifyListWait(0x10e04a68, 0x0) /boot/workdir/go/src/runtime/sema.go:513 +0x154 sync.(*Cond).Wait(0x10e04a60) /boot/workdir/go/src/sync/cond.go:56 +0x6c net/http.(*connReader).abortPendingRead(0x10e04a20) /boot/workdir/go/src/net/http/server.go:727 +0x90 net/http.(*response).finishRequest(0x10d8e120) /boot/workdir/go/src/net/http/server.go:1597 +0x90 net/http.(*conn).serve(0x10c8f5c0, 0x432bb0, 0x10e04a00) /boot/workdir/go/src/net/http/server.go:1901 +0x804 created by net/http.(*Server).Serve /boot/workdir/go/src/net/http/server.go:2940 +0x2d0 goroutine 859 [syscall, 9 minutes]: syscall.Syscall(0xe, 0x10ed63e0, 0x0, 0x0, 0x12, 0x0, 0x0, 0x6c2f0) /boot/workdir/go/src/syscall/asm_plan9_arm.s:16 +0x8 syscall.open(0x10ed63c0, 0x11, 0x0, 0x0, 0x12d6b4, 0x61a4a8) /boot/workdir/go/src/syscall/zsyscall_plan9_arm.go:61 +0x78 syscall.Open(0x10ed63c0, 0x11, 0x0, 0x61a4a8, 0x3d573c, 0x74174) /boot/workdir/go/src/syscall/syscall_plan9.go:382 +0x64 os.openFileNolog(0x10ed63c0, 0x11, 0x0, 0x0, 0x2, 0x10ed63c0, 0x11) /boot/workdir/go/src/os/file_plan9.go:113 +0x28c os.OpenFile(0x10ed63c0, 0x11, 0x0, 0x0, 0x7, 0x10ed63c0, 0x11) /boot/workdir/go/src/os/file.go:327 +0x48 os.Open(...) /boot/workdir/go/src/os/file.go:307 net.(*netFD).acceptPlan9(0x10c6a380, 0x0, 0x0, 0x0) /boot/workdir/go/src/net/ipsock_plan9.go:250 +0xe0 net.(*TCPListener).accept(0x10f0c010, 0x433ff8, 0x110981c0, 0x3fdc0) /boot/workdir/go/src/net/tcpsock_plan9.go:48 +0x20 net.(*TCPListener).Accept(0x10f0c010, 0x10d35f38, 0xc, 0x10ff01c0, 0x27af30) /boot/workdir/go/src/net/tcpsock.go:261 +0x68 net/http.(*Server).Serve(0x10d26990, 0x432210, 0x10f0c010, 0x0, 0x0) /boot/workdir/go/src/net/http/server.go:2908 +0x1fc net/http/httptest.(*Server).goServe.func1(0x10e33080) /boot/workdir/go/src/net/http/httptest/server.go:308 +0x60 created by net/http/httptest.(*Server).goServe /boot/workdir/go/src/net/http/httptest/server.go:306 +0x48 goroutine 1026 [chan receive, 9 minutes]: internal/poll.(*asyncIO).Wait(...) /boot/workdir/go/src/internal/poll/fd_io_plan9.go:84 internal/poll.(*FD).Read(0x10d89570, 0x10ec02e0, 0x1106bb00, 0x205, 0x205, 0x0, 0x0, 0x0) /boot/workdir/go/src/internal/poll/fd_plan9.go:73 +0x124 net.(*netFD).Read(0x10d89570, 0x1106bb00, 0x205, 0x205, 0x10a125d8, 0x634754, 0xcdb0) /boot/workdir/go/src/net/fd_plan9.go:86 +0xb0 net.(*conn).Read(0x10d81cb0, 0x1106bb00, 0x205, 0x205, 0x0, 0x0, 0x0) /boot/workdir/go/src/net/net.go:182 +0x6c crypto/tls.(*atLeastReader).Read(0x10f0c800, 0x1106bb00, 0x205, 0x205, 0x928c, 0x1de148, 0x10a14d30) /boot/workdir/go/src/crypto/tls/conn.go:779 +0x78 bytes.(*Buffer).ReadFrom(0x10e10570, 0x42f4a0, 0x10f0c800, 0x61654, 0x1dba0c, 0x369ed0, 0x3a38e0) /boot/workdir/go/src/bytes/buffer.go:204 +0xa4 crypto/tls.(*Conn).readFromUntil(0x10e10400, 0x10a14d30, 0x10d81cb0, 0x5, 0x10d81cb0, 0x12) /boot/workdir/go/src/crypto/tls/conn.go:801 +0xc4 crypto/tls.(*Conn).readRecordOrCCS(0x10e10400, 0x0, 0x0, 0x65150) /boot/workdir/go/src/crypto/tls/conn.go:608 +0xfc crypto/tls.(*Conn).readRecord(...) /boot/workdir/go/src/crypto/tls/conn.go:576 crypto/tls.(*Conn).Read(0x10e10400, 0x10e04a2d, 0x1, 0x1, 0x0, 0x0, 0x0) /boot/workdir/go/src/crypto/tls/conn.go:1252 +0x14c net/http.(*connReader).backgroundRead(0x10e04a20) /boot/workdir/go/src/net/http/server.go:679 +0x44 created by net/http.(*connReader).startBackgroundRead /boot/workdir/go/src/net/http/server.go:675 +0xc0 goroutine 1016 [select, 9 minutes]: net/http.(*persistConn).writeLoop(0x10f120a0) /boot/workdir/go/src/net/http/transport.go:2329 +0xc4 created by net/http.(*Transport).dialConn /boot/workdir/go/src/net/http/transport.go:1698 +0x9d4 goroutine 1018 [syscall, 9 minutes, locked to thread]: syscall.Syscall6(0x32, 0xb, 0x10fd0800, 0x7d5, 0xffffffff, 0xffffffff, 0x0, 0xe, 0x10efe7c0, 0x0, ...) /boot/workdir/go/src/syscall/asm_plan9_arm.s:51 +0x8 syscall.Pread(0xb, 0x10fd0800, 0x7d5, 0x7d5, 0xffffffff, 0xffffffff, 0x4, 0x18, 0x0) /boot/workdir/go/src/syscall/zsyscall_plan9_arm.go:218 +0x58 syscall.Read(...) /boot/workdir/go/src/syscall/syscall_plan9.go:166 os.(*File).read(0x1100f998, 0x10fd0800, 0x7d5, 0x7d5, 0x88460, 0x18, 0x0) /boot/workdir/go/src/os/file_plan9.go:247 +0x50 os.(*File).Read(0x1100f998, 0x10fd0800, 0x7d5, 0x7d5, 0x10d8a2f4, 0xffffffff, 0x0) /boot/workdir/go/src/os/file.go:116 +0x60 internal/poll.newAsyncIO.func1(0x10d8a2f0, 0x1100eb78, 0x10fd0800, 0x7d5, 0x7d5) /boot/workdir/go/src/internal/poll/fd_io_plan9.go:54 +0x6c created by internal/poll.newAsyncIO /boot/workdir/go/src/internal/poll/fd_io_plan9.go:43 +0xa0 goroutine 1015 [chan receive, 9 minutes]: internal/poll.(*asyncIO).Wait(...) /boot/workdir/go/src/internal/poll/fd_io_plan9.go:84 internal/poll.(*FD).Read(0x10d248c0, 0x1100eb78, 0x10fd0800, 0x7d5, 0x7d5, 0x0, 0x0, 0x0) /boot/workdir/go/src/internal/poll/fd_plan9.go:73 +0x124 net.(*netFD).Read(0x10d248c0, 0x10fd0800, 0x7d5, 0x7d5, 0x0, 0x7d0, 0xcdb0) /boot/workdir/go/src/net/fd_plan9.go:86 +0xb0 net.(*conn).Read(0x1100f9b0, 0x10fd0800, 0x7d5, 0x7d5, 0x0, 0x0, 0x0) /boot/workdir/go/src/net/net.go:182 +0x6c crypto/tls.(*atLeastReader).Read(0x10d8a2e0, 0x10fd0800, 0x7d5, 0x7d5, 0x928c, 0x1de148, 0x10a14d30) /boot/workdir/go/src/crypto/tls/conn.go:779 +0x78 bytes.(*Buffer).ReadFrom(0x10cc2570, 0x42f4a0, 0x10d8a2e0, 0x10fd0805, 0x1dba0c, 0x369ed0, 0x3a38e0) /boot/workdir/go/src/bytes/buffer.go:204 +0xa4 crypto/tls.(*Conn).readFromUntil(0x10cc2400, 0x10a14d30, 0x1100f9b0, 0x5, 0x1100f9b0, 0x76) /boot/workdir/go/src/crypto/tls/conn.go:801 +0xc4 crypto/tls.(*Conn).readRecordOrCCS(0x10cc2400, 0x0, 0x0, 0x5340) /boot/workdir/go/src/crypto/tls/conn.go:608 +0xfc crypto/tls.(*Conn).readRecord(...) /boot/workdir/go/src/crypto/tls/conn.go:576 crypto/tls.(*Conn).Read(0x10cc2400, 0x10ec6000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /boot/workdir/go/src/crypto/tls/conn.go:1252 +0x14c net/http.(*persistConn).Read(0x10f120a0, 0x10ec6000, 0x1000, 0x1000, 0x10c4f940, 0x28e180, 0x10c4f940) /boot/workdir/go/src/net/http/transport.go:1876 +0x16c bufio.(*Reader).fill(0x10d1ad20) /boot/workdir/go/src/bufio/bufio.go:101 +0x108 bufio.(*Reader).Peek(0x10d1ad20, 0x1, 0x0, 0x0, 0x1, 0x10c4f800, 0x0) /boot/workdir/go/src/bufio/bufio.go:139 +0x38 net/http.(*persistConn).readLoop(0x10f120a0) /boot/workdir/go/src/net/http/transport.go:2029 +0x15c created by net/http.(*Transport).dialConn /boot/workdir/go/src/net/http/transport.go:1697 +0x9b8 FAIL golang.org/x/crypto/acme/autocert 600.119s ? golang.org/x/crypto/acme/autocert/internal/acmetest [no test files] ? golang.org/x/crypto/acme/internal/acmeprobe [no test files] ok golang.org/x/crypto/argon2 0.975s ok golang.org/x/crypto/bcrypt 2.407s ok golang.org/x/crypto/blake2b 0.225s ok golang.org/x/crypto/blake2s 0.155s ok golang.org/x/crypto/blowfish 0.184s ok golang.org/x/crypto/bn256 5.889s ok golang.org/x/crypto/cast5 0.167s ok golang.org/x/crypto/chacha20 0.157s ok golang.org/x/crypto/chacha20poly1305 4.134s ok golang.org/x/crypto/cryptobyte 0.098s ? golang.org/x/crypto/cryptobyte/asn1 [no test files] ok golang.org/x/crypto/curve25519 0.944s ok golang.org/x/crypto/ed25519 0.932s ? golang.org/x/crypto/ed25519/internal/edwards25519 [no test files] ok golang.org/x/crypto/hkdf 0.058s ok golang.org/x/crypto/internal/subtle 0.056s ok golang.org/x/crypto/internal/wycheproof 130.010s ? golang.org/x/crypto/internal/wycheproof/internal/dsa [no test files] ok golang.org/x/crypto/md4 0.069s ok golang.org/x/crypto/nacl/auth 0.061s ok golang.org/x/crypto/nacl/box 0.496s ok golang.org/x/crypto/nacl/secretbox 0.071s ok golang.org/x/crypto/nacl/sign 0.087s ok golang.org/x/crypto/ocsp 0.426s ok golang.org/x/crypto/openpgp 89.255s ok golang.org/x/crypto/openpgp/armor 0.056s ok golang.org/x/crypto/openpgp/clearsign 0.228s ok golang.org/x/crypto/openpgp/elgamal 0.108s ? golang.org/x/crypto/openpgp/errors [no test files] ok golang.org/x/crypto/openpgp/packet 2.203s ok golang.org/x/crypto/openpgp/s2k 66.251s ok golang.org/x/crypto/otr 4.999s ok golang.org/x/crypto/pbkdf2 0.292s ok golang.org/x/crypto/pkcs12 0.281s ok golang.org/x/crypto/pkcs12/internal/rc2 0.061s ok golang.org/x/crypto/poly1305 0.157s ok golang.org/x/crypto/ripemd160 0.223s ok golang.org/x/crypto/salsa20 0.078s ok golang.org/x/crypto/salsa20/salsa 0.078s ok golang.org/x/crypto/scrypt 2.600s ok golang.org/x/crypto/sha3 0.475s ok golang.org/x/crypto/ssh 91.303s ok golang.org/x/crypto/ssh/agent 16.034s ok golang.org/x/crypto/ssh/internal/bcrypt_pbkdf 0.980s ok golang.org/x/crypto/ssh/knownhosts 0.077s ok golang.org/x/crypto/ssh/terminal 0.075s ok golang.org/x/crypto/ssh/test 1.105s ok golang.org/x/crypto/tea 0.061s ok golang.org/x/crypto/twofish 0.111s ok golang.org/x/crypto/xtea 0.064s ok golang.org/x/crypto/xts 0.062s FAIL