plan9-arm at d49fecc474d04a04d1d22851c06099338abd4391 building crypto at 78000ba7a073cafc0278790f6bce552a0f25850e testing in module mode; GOMOD=/boot/glenda/workdir-pi3plus3/gopath/src/golang.org/x/crypto/go.mod :: Running /boot/glenda/workdir-pi3plus3/go/bin/go with args ["/boot/glenda/workdir-pi3plus3/go/bin/go" "test" "-short" "golang.org/x/crypto/..."] and env ["home=/usr/glenda" "GOPATH=/usr/glenda/gopath" "path=/boot/glenda/workdir-pi3plus3/go/bin\x00.\x00/bin" "type=host-plan9-arm-0intro" "GOARM=7" "GO_BUILD_KEY_DELETE_AFTER_READ=false" "status=" "GOROOT_BOOTSTRAP=/boot/glenda/workdir-pi3plus3/go1.4" "objtype=arm" "*=" "i=" "WORKDIR=/boot/glenda/workdir-pi3plus3" "GO_BUILDER_NAME=plan9-arm" "GOROOT=/boot/glenda/workdir-pi3plus3/go" "GOPATH=/boot/glenda/workdir-pi3plus3/gopath" "GOPROXY=http://10.240.0.4:30157" "GOPROXY=https://proxy.golang.org" "GOROOT_BOOTSTRAP=/usr/glenda/go"] in dir /boot/glenda/workdir-pi3plus3/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 14.636s 2020/03/10 14:21:05 GET / 2020/03/10 14:21:05 POST /new-reg 2020/03/10 14:21:05 POST /new-order 2020/03/10 14:21:05 POST /authz/example.org 2020/03/10 14:21:05 POST /challenge/tls-alpn-01/example.org 2020/03/10 14:21:05 POST /authz/example.org 2020/03/10 14:21:05 validated "tls-alpn-01" for "example.org"; authz status is now: valid 2020/03/10 14:21:05 order 0 is now ready 2020/03/10 14:21:06 POST /authz/example.org 2020/03/10 14:21:06 POST /orders/0 2020/03/10 14:21:06 POST /new-cert/0 2020/03/10 14:21:06 HEAD /new-nonce 2020/03/10 14:21:06 POST /authz/example.org 2020/03/10 14:21:06 POST /issued-cert/0 2020/03/10 14:21:11 httptest.Server blocked in Close after 5 seconds, waiting for connections: *tls.Conn 0x10cc2600 127.0.0.1:54420 in state active panic: test timed out after 10m0s goroutine 986 [running]: testing.(*M).startAlarm.func1() /boot/glenda/workdir-pi3plus3/go/src/testing/testing.go:1479 +0xbc created by time.goFunc /boot/glenda/workdir-pi3plus3/go/src/time/sleep.go:169 +0x34 goroutine 1 [chan receive, 9 minutes]: testing.(*T).Run(0x1116e640, 0x3cd1fe, 0xc, 0x3ed010, 0x301) /boot/glenda/workdir-pi3plus3/go/src/testing/testing.go:1044 +0x2d0 testing.runTests.func1(0x10da0000) /boot/glenda/workdir-pi3plus3/go/src/testing/testing.go:1300 +0x68 testing.tRunner(0x10da0000, 0x10c28ed4) /boot/glenda/workdir-pi3plus3/go/src/testing/testing.go:992 +0xbc testing.runTests(0x10d84020, 0x6dadf0, 0x1a, 0x1a, 0x36369a8b, 0xbf920708, 0xb4bd788a, 0x8b, 0x6dea38, 0x0) /boot/glenda/workdir-pi3plus3/go/src/testing/testing.go:1298 +0x258 testing.(*M).Run(0x10d98000, 0x0) /boot/glenda/workdir-pi3plus3/go/src/testing/testing.go:1210 +0x150 main.main() _testmain.go:96 +0x120 goroutine 948 [semacquire, 9 minutes]: sync.runtime_Semacquire(0x10f24b68) /boot/glenda/workdir-pi3plus3/go/src/runtime/sema.go:56 +0x34 sync.(*WaitGroup).Wait(0x10f24b60) /boot/glenda/workdir-pi3plus3/go/src/sync/waitgroup.go:130 +0x84 net/http/httptest.(*Server).Close(0x10f24b40) /boot/glenda/workdir-pi3plus3/go/src/net/http/httptest/server.go:249 +0x1cc golang.org/x/crypto/acme/autocert.TestEndToEnd(0x1116e640) /boot/glenda/workdir-pi3plus3/gopath/src/golang.org/x/crypto/acme/autocert/autocert_test.go:1232 +0x550 testing.tRunner(0x1116e640, 0x3ed010) /boot/glenda/workdir-pi3plus3/go/src/testing/testing.go:992 +0xbc created by testing.(*T).Run /boot/glenda/workdir-pi3plus3/go/src/testing/testing.go:1043 +0x2b0 goroutine 984 [syscall, 9 minutes, locked to thread]: syscall.Syscall6(0x32, 0xa, 0x10d3e480, 0x205, 0xffffffff, 0xffffffff, 0x0, 0xe, 0x10dec1a0, 0x0, ...) /boot/glenda/workdir-pi3plus3/go/src/syscall/asm_plan9_arm.s:51 +0x8 syscall.Pread(0xa, 0x10d3e480, 0x205, 0x205, 0xffffffff, 0xffffffff, 0x4, 0x16, 0x0) /boot/glenda/workdir-pi3plus3/go/src/syscall/zsyscall_plan9_arm.go:218 +0x58 syscall.Read(...) /boot/glenda/workdir-pi3plus3/go/src/syscall/syscall_plan9.go:166 os.(*File).read(0x10f29948, 0x10d3e480, 0x205, 0x205, 0x86ae4, 0x16, 0x0) /boot/glenda/workdir-pi3plus3/go/src/os/file_plan9.go:248 +0x50 os.(*File).Read(0x10f29948, 0x10d3e480, 0x205, 0x205, 0x10de0d94, 0xffffffff, 0x0) /boot/glenda/workdir-pi3plus3/go/src/os/file.go:116 +0x60 internal/poll.newAsyncIO.func1(0x10de0d90, 0x10d175d0, 0x10d3e480, 0x205, 0x205) /boot/glenda/workdir-pi3plus3/go/src/internal/poll/fd_io_plan9.go:54 +0x6c created by internal/poll.newAsyncIO /boot/glenda/workdir-pi3plus3/go/src/internal/poll/fd_io_plan9.go:43 +0xa0 goroutine 1042 [chan receive, 9 minutes]: internal/poll.(*asyncIO).Wait(...) /boot/glenda/workdir-pi3plus3/go/src/internal/poll/fd_io_plan9.go:84 internal/poll.(*FD).Read(0x10d1e5b0, 0x10f7e3d8, 0x10ddb000, 0x7b5, 0x7b5, 0x0, 0x0, 0x0) /boot/glenda/workdir-pi3plus3/go/src/internal/poll/fd_plan9.go:73 +0x124 net.(*netFD).Read(0x10d1e5b0, 0x10ddb000, 0x7b5, 0x7b5, 0x0, 0xc7b8, 0xce18) /boot/glenda/workdir-pi3plus3/go/src/net/fd_plan9.go:86 +0xb0 net.(*conn).Read(0x10d17000, 0x10ddb000, 0x7b5, 0x7b5, 0x0, 0x0, 0x0) /boot/glenda/workdir-pi3plus3/go/src/net/net.go:184 +0x6c crypto/tls.(*atLeastReader).Read(0x10fec3d0, 0x10ddb000, 0x7b5, 0x7b5, 0x94a4, 0x1e7834, 0x10b40148) /boot/glenda/workdir-pi3plus3/go/src/crypto/tls/conn.go:760 +0x78 bytes.(*Buffer).ReadFrom(0x10d30160, 0x464898, 0x10fec3d0, 0x1107c, 0x1e6348, 0x37d8c0, 0x3bac78) /boot/glenda/workdir-pi3plus3/go/src/bytes/buffer.go:204 +0xa8 crypto/tls.(*Conn).readFromUntil(0x10d30000, 0x10b40148, 0x10d17000, 0x5, 0x10d17000, 0x10f35c78) /boot/glenda/workdir-pi3plus3/go/src/crypto/tls/conn.go:782 +0xc4 crypto/tls.(*Conn).readRecordOrCCS(0x10d30000, 0x0, 0x0, 0x513c) /boot/glenda/workdir-pi3plus3/go/src/crypto/tls/conn.go:589 +0xfc crypto/tls.(*Conn).readRecord(...) /boot/glenda/workdir-pi3plus3/go/src/crypto/tls/conn.go:557 crypto/tls.(*Conn).Read(0x10d30000, 0x10fdf000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /boot/glenda/workdir-pi3plus3/go/src/crypto/tls/conn.go:1233 +0x14c net/http.(*persistConn).Read(0x1116e6e0, 0x10fdf000, 0x1000, 0x1000, 0x10ff6680, 0x297788, 0x10ff6680) /boot/glenda/workdir-pi3plus3/go/src/net/http/transport.go:1827 +0x16c bufio.(*Reader).fill(0x10ff2420) /boot/glenda/workdir-pi3plus3/go/src/bufio/bufio.go:100 +0x108 bufio.(*Reader).Peek(0x10ff2420, 0x1, 0x0, 0x0, 0x1, 0x10ff6600, 0x0) /boot/glenda/workdir-pi3plus3/go/src/bufio/bufio.go:138 +0x38 net/http.(*persistConn).readLoop(0x1116e6e0) /boot/glenda/workdir-pi3plus3/go/src/net/http/transport.go:1980 +0x15c created by net/http.(*Transport).dialConn /boot/glenda/workdir-pi3plus3/go/src/net/http/transport.go:1649 +0x9b8 goroutine 950 [syscall, 9 minutes]: syscall.Syscall(0xe, 0x11008b40, 0x0, 0x0, 0x14, 0x0, 0x0, 0x6bf50) /boot/glenda/workdir-pi3plus3/go/src/syscall/asm_plan9_arm.s:16 +0x8 syscall.open(0x11008b20, 0x13, 0x0, 0x0, 0x12a8a8, 0x6de668) /boot/glenda/workdir-pi3plus3/go/src/syscall/zsyscall_plan9_arm.go:61 +0x78 syscall.Open(0x11008b20, 0x13, 0x0, 0x6de668, 0x3ed5a0, 0x73e84) /boot/glenda/workdir-pi3plus3/go/src/syscall/syscall_plan9.go:382 +0x64 os.openFileNolog(0x11008b20, 0x13, 0x0, 0x0, 0x2, 0x11008b20, 0x13) /boot/glenda/workdir-pi3plus3/go/src/os/file_plan9.go:113 +0x28c os.OpenFile(0x11008b20, 0x13, 0x0, 0x0, 0x7, 0x11008b20, 0x13) /boot/glenda/workdir-pi3plus3/go/src/os/file.go:307 +0x48 os.Open(...) /boot/glenda/workdir-pi3plus3/go/src/os/file.go:287 net.(*netFD).acceptPlan9(0x10e14e70, 0x0, 0x0, 0x0) /boot/glenda/workdir-pi3plus3/go/src/net/ipsock_plan9.go:250 +0xe0 net.(*TCPListener).accept(0x10fbeb00, 0x4698d0, 0x10e13458, 0x3f83c) /boot/glenda/workdir-pi3plus3/go/src/net/tcpsock_plan9.go:48 +0x20 net.(*TCPListener).Accept(0x10fbeb00, 0x10ceff40, 0xc, 0x10e1c9a0, 0x284478) /boot/glenda/workdir-pi3plus3/go/src/net/tcpsock.go:261 +0x68 net/http.(*Server).Serve(0x10c65170, 0x4677e0, 0x10fbeb00, 0x0, 0x0) /boot/glenda/workdir-pi3plus3/go/src/net/http/server.go:2901 +0x1fc net/http/httptest.(*Server).goServe.func1(0x10f24a80) /boot/glenda/workdir-pi3plus3/go/src/net/http/httptest/server.go:308 +0x60 created by net/http/httptest.(*Server).goServe /boot/glenda/workdir-pi3plus3/go/src/net/http/httptest/server.go:306 +0x48 goroutine 955 [sync.Cond.Wait, 9 minutes]: runtime.goparkunlock(...) /boot/glenda/workdir-pi3plus3/go/src/runtime/proc.go:311 sync.runtime_notifyListWait(0x10ea9da8, 0x0) /boot/glenda/workdir-pi3plus3/go/src/runtime/sema.go:513 +0x154 sync.(*Cond).Wait(0x10ea9da0) /boot/glenda/workdir-pi3plus3/go/src/sync/cond.go:56 +0x6c net/http.(*connReader).abortPendingRead(0x10ea9d80) /boot/glenda/workdir-pi3plus3/go/src/net/http/server.go:726 +0x90 net/http.(*response).finishRequest(0x10e08750) /boot/glenda/workdir-pi3plus3/go/src/net/http/server.go:1596 +0x90 net/http.(*conn).serve(0x11154ae0, 0x468220, 0x10ea9d60) /boot/glenda/workdir-pi3plus3/go/src/net/http/server.go:1900 +0x808 created by net/http.(*Server).Serve /boot/glenda/workdir-pi3plus3/go/src/net/http/server.go:2933 +0x2d0 goroutine 1015 [chan receive, 9 minutes]: internal/poll.(*asyncIO).Wait(...) /boot/glenda/workdir-pi3plus3/go/src/internal/poll/fd_io_plan9.go:84 internal/poll.(*FD).Read(0x10e15180, 0x10d175d0, 0x10d3e480, 0x205, 0x205, 0x0, 0x0, 0x0) /boot/glenda/workdir-pi3plus3/go/src/internal/poll/fd_plan9.go:73 +0x124 net.(*netFD).Read(0x10e15180, 0x10d3e480, 0x205, 0x205, 0x3eae8, 0x11002668, 0xce18) /boot/glenda/workdir-pi3plus3/go/src/net/fd_plan9.go:86 +0xb0 net.(*conn).Read(0x10f29960, 0x10d3e480, 0x205, 0x205, 0x0, 0x0, 0x0) /boot/glenda/workdir-pi3plus3/go/src/net/net.go:184 +0x6c crypto/tls.(*atLeastReader).Read(0x10de0d80, 0x10d3e480, 0x205, 0x205, 0x94a4, 0x1e7834, 0x10b40148) /boot/glenda/workdir-pi3plus3/go/src/crypto/tls/conn.go:760 +0x78 bytes.(*Buffer).ReadFrom(0x10cc2760, 0x464898, 0x10de0d80, 0x3, 0x1e6348, 0x37d8c0, 0x3bac78) /boot/glenda/workdir-pi3plus3/go/src/bytes/buffer.go:204 +0xa8 crypto/tls.(*Conn).readFromUntil(0x10cc2600, 0x10b40148, 0x10f29960, 0x5, 0x10f29960, 0x72aa0) /boot/glenda/workdir-pi3plus3/go/src/crypto/tls/conn.go:782 +0xc4 crypto/tls.(*Conn).readRecordOrCCS(0x10cc2600, 0x0, 0x0, 0x62d34) /boot/glenda/workdir-pi3plus3/go/src/crypto/tls/conn.go:589 +0xfc crypto/tls.(*Conn).readRecord(...) /boot/glenda/workdir-pi3plus3/go/src/crypto/tls/conn.go:557 crypto/tls.(*Conn).Read(0x10cc2600, 0x10ea9d8d, 0x1, 0x1, 0x0, 0x0, 0x0) /boot/glenda/workdir-pi3plus3/go/src/crypto/tls/conn.go:1233 +0x14c net/http.(*connReader).backgroundRead(0x10ea9d80) /boot/glenda/workdir-pi3plus3/go/src/net/http/server.go:678 +0x44 created by net/http.(*connReader).startBackgroundRead /boot/glenda/workdir-pi3plus3/go/src/net/http/server.go:674 +0xc0 goroutine 1045 [syscall, 9 minutes, locked to thread]: syscall.Syscall6(0x32, 0xb, 0x10ddb000, 0x7b5, 0xffffffff, 0xffffffff, 0x0, 0xe, 0x10efb7d0, 0x0, ...) /boot/glenda/workdir-pi3plus3/go/src/syscall/asm_plan9_arm.s:51 +0x8 syscall.Pread(0xb, 0x10ddb000, 0x7b5, 0x7b5, 0xffffffff, 0xffffffff, 0x4, 0x16, 0x0) /boot/glenda/workdir-pi3plus3/go/src/syscall/zsyscall_plan9_arm.go:218 +0x58 syscall.Read(...) /boot/glenda/workdir-pi3plus3/go/src/syscall/syscall_plan9.go:166 os.(*File).read(0x10d16fe8, 0x10ddb000, 0x7b5, 0x7b5, 0x86ae4, 0x16, 0x0) /boot/glenda/workdir-pi3plus3/go/src/os/file_plan9.go:248 +0x50 os.(*File).Read(0x10d16fe8, 0x10ddb000, 0x7b5, 0x7b5, 0x10fec3e4, 0xffffffff, 0x0) /boot/glenda/workdir-pi3plus3/go/src/os/file.go:116 +0x60 internal/poll.newAsyncIO.func1(0x10fec3e0, 0x10f7e3d8, 0x10ddb000, 0x7b5, 0x7b5) /boot/glenda/workdir-pi3plus3/go/src/internal/poll/fd_io_plan9.go:54 +0x6c created by internal/poll.newAsyncIO /boot/glenda/workdir-pi3plus3/go/src/internal/poll/fd_io_plan9.go:43 +0xa0 goroutine 1043 [select, 9 minutes]: net/http.(*persistConn).writeLoop(0x1116e6e0) /boot/glenda/workdir-pi3plus3/go/src/net/http/transport.go:2279 +0xc4 created by net/http.(*Transport).dialConn /boot/glenda/workdir-pi3plus3/go/src/net/http/transport.go:1650 +0x9d4 FAIL golang.org/x/crypto/acme/autocert 600.132s ? 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 1.174s ok golang.org/x/crypto/bcrypt 2.392s ok golang.org/x/crypto/blake2b 0.246s ok golang.org/x/crypto/blake2s 0.148s ok golang.org/x/crypto/blowfish 0.064s ok golang.org/x/crypto/bn256 5.758s ok golang.org/x/crypto/cast5 0.072s ok golang.org/x/crypto/chacha20 0.140s ok golang.org/x/crypto/chacha20poly1305 3.973s ok golang.org/x/crypto/cryptobyte 0.074s ? golang.org/x/crypto/cryptobyte/asn1 [no test files] ok golang.org/x/crypto/curve25519 0.927s ok golang.org/x/crypto/ed25519 0.979s ? golang.org/x/crypto/ed25519/internal/edwards25519 [no test files] ok golang.org/x/crypto/hkdf 0.054s ok golang.org/x/crypto/internal/subtle 0.048s ok golang.org/x/crypto/internal/wycheproof 115.363s ? golang.org/x/crypto/internal/wycheproof/internal/dsa [no test files] ? golang.org/x/crypto/internal/wycheproof/internal/ecdsa [no test files] ok golang.org/x/crypto/md4 0.058s ok golang.org/x/crypto/nacl/auth 0.054s ok golang.org/x/crypto/nacl/box 0.452s ok golang.org/x/crypto/nacl/secretbox 0.066s ok golang.org/x/crypto/nacl/sign 0.070s ok golang.org/x/crypto/ocsp 0.406s ok golang.org/x/crypto/openpgp 54.111s ok golang.org/x/crypto/openpgp/armor 0.068s ok golang.org/x/crypto/openpgp/clearsign 0.219s ok golang.org/x/crypto/openpgp/elgamal 0.105s ? golang.org/x/crypto/openpgp/errors [no test files] ok golang.org/x/crypto/openpgp/packet 2.074s ok golang.org/x/crypto/openpgp/s2k 64.050s ok golang.org/x/crypto/otr 4.672s ok golang.org/x/crypto/pbkdf2 0.343s ok golang.org/x/crypto/pkcs12 0.280s ok golang.org/x/crypto/pkcs12/internal/rc2 0.048s ok golang.org/x/crypto/poly1305 0.121s ok golang.org/x/crypto/ripemd160 0.216s ok golang.org/x/crypto/salsa20 0.069s ok golang.org/x/crypto/salsa20/salsa 0.051s ok golang.org/x/crypto/scrypt 2.918s ok golang.org/x/crypto/sha3 3.591s ok golang.org/x/crypto/ssh 81.497s ok golang.org/x/crypto/ssh/agent 13.906s ok golang.org/x/crypto/ssh/internal/bcrypt_pbkdf 0.897s ok golang.org/x/crypto/ssh/knownhosts 0.080s ok golang.org/x/crypto/ssh/terminal 0.058s ok golang.org/x/crypto/ssh/test 1.008s ok golang.org/x/crypto/tea 0.048s ok golang.org/x/crypto/twofish 0.095s ok golang.org/x/crypto/xtea 0.056s ok golang.org/x/crypto/xts 0.049s FAIL