plan9-arm at f1a2a0e0bf1a31f0f32c7756229afbca98473237 building crypto at 729f1e841bcca28c2cd9f4034d6f7672604dbeda 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" "*=" "WORKDIR=/boot/workdir" "GO_BUILDER_NAME=plan9-arm" "GOROOT=/boot/workdir/go" "GOPATH=/boot/workdir/gopath" "GOPROXY=http://10.240.0.39: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 17.950s 2020/05/02 22:04:41 GET / 2020/05/02 22:04:41 POST /new-reg 2020/05/02 22:04:41 POST /new-order 2020/05/02 22:04:41 POST /authz/example.org 2020/05/02 22:04:41 POST /challenge/tls-alpn-01/example.org 2020/05/02 22:04:41 POST /authz/example.org 2020/05/02 22:04:41 validated "tls-alpn-01" for "example.org"; authz status is now: valid 2020/05/02 22:04:41 order 0 is now ready 2020/05/02 22:04:42 POST /authz/example.org 2020/05/02 22:04:42 POST /orders/0 2020/05/02 22:04:42 POST /new-cert/0 2020/05/02 22:04:42 HEAD /new-nonce 2020/05/02 22:04:42 POST /authz/example.org 2020/05/02 22:04:42 POST /issued-cert/0 2020/05/02 22:04:47 httptest.Server blocked in Close after 5 seconds, waiting for connections: *tls.Conn 0x11220800 127.0.0.1:36898 in state active panic: test timed out after 10m0s goroutine 1033 [running]: testing.(*M).startAlarm.func1() /boot/workdir/go/src/testing/testing.go:1534 +0xbc created by time.goFunc /boot/workdir/go/src/time/sleep.go:167 +0x34 goroutine 1 [chan receive, 9 minutes]: testing.(*T).Run(0x10ccab40, 0x3c0d39, 0xc, 0x3e0bbc, 0x301) /boot/workdir/go/src/testing/testing.go:1087 +0x2d0 testing.runTests.func1(0x10cca000) /boot/workdir/go/src/testing/testing.go:1355 +0x68 testing.tRunner(0x10cca000, 0x10c28ec8) /boot/workdir/go/src/testing/testing.go:1035 +0xbc testing.runTests(0x10c161d0, 0x6c7a58, 0x1a, 0x1a, 0xf38bdd03, 0xbfa3968d, 0xb43a1fa1, 0x8b, 0x6cb828, 0x10c862d0) /boot/workdir/go/src/testing/testing.go:1353 +0x258 testing.(*M).Run(0x10c862d0, 0x0) /boot/workdir/go/src/testing/testing.go:1263 +0x16c main.main() _testmain.go:95 +0x120 goroutine 937 [semacquire, 9 minutes]: sync.runtime_Semacquire(0x10d26ee8) /boot/workdir/go/src/runtime/sema.go:56 +0x34 sync.(*WaitGroup).Wait(0x10d26ee0) /boot/workdir/go/src/sync/waitgroup.go:130 +0x84 net/http/httptest.(*Server).Close(0x10d26ec0) /boot/workdir/go/src/net/http/httptest/server.go:249 +0x1cc golang.org/x/crypto/acme/autocert.TestEndToEnd(0x10ccab40) /boot/workdir/gopath/src/golang.org/x/crypto/acme/autocert/autocert_test.go:1232 +0x540 testing.tRunner(0x10ccab40, 0x3e0bbc) /boot/workdir/go/src/testing/testing.go:1035 +0xbc created by testing.(*T).Run /boot/workdir/go/src/testing/testing.go:1086 +0x2b0 goroutine 1028 [chan receive, 9 minutes]: internal/poll.(*asyncIO).Wait(...) /boot/workdir/go/src/internal/poll/fd_io_plan9.go:84 internal/poll.(*FD).Read(0x10c6b880, 0x10d812e0, 0x10f46000, 0x7d4, 0x7d4, 0x0, 0x0, 0x0) /boot/workdir/go/src/internal/poll/fd_plan9.go:73 +0x124 net.(*netFD).Read(0x10c6b880, 0x10f46000, 0x7d4, 0x7d4, 0x0, 0x7cf, 0xce28) /boot/workdir/go/src/net/fd_plan9.go:86 +0xb0 net.(*conn).Read(0x10ea98e0, 0x10f46000, 0x7d4, 0x7d4, 0x0, 0x0, 0x0) /boot/workdir/go/src/net/net.go:182 +0x6c crypto/tls.(*atLeastReader).Read(0x10f4a840, 0x10f46000, 0x7d4, 0x7d4, 0x938c, 0x1dd2b0, 0x10b04258) /boot/workdir/go/src/crypto/tls/conn.go:774 +0x78 bytes.(*Buffer).ReadFrom(0x10cc2364, 0x45a9a8, 0x10f4a840, 0x10f46005, 0x1dab74, 0x3715a8, 0x3aeb38) /boot/workdir/go/src/bytes/buffer.go:204 +0xa4 crypto/tls.(*Conn).readFromUntil(0x10cc2200, 0x10b04258, 0x10ea98e0, 0x5, 0x10ea98e0, 0x76) /boot/workdir/go/src/crypto/tls/conn.go:796 +0xc4 crypto/tls.(*Conn).readRecordOrCCS(0x10cc2200, 0x0, 0x0, 0x5440) /boot/workdir/go/src/crypto/tls/conn.go:603 +0xfc crypto/tls.(*Conn).readRecord(...) /boot/workdir/go/src/crypto/tls/conn.go:571 crypto/tls.(*Conn).Read(0x10cc2200, 0x10f54000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /boot/workdir/go/src/crypto/tls/conn.go:1247 +0x14c net/http.(*persistConn).Read(0x11232140, 0x10f54000, 0x1000, 0x1000, 0x10f51c00, 0x28cc88, 0x10f51c00) /boot/workdir/go/src/net/http/transport.go:1827 +0x16c bufio.(*Reader).fill(0x10f449f0) /boot/workdir/go/src/bufio/bufio.go:100 +0x108 bufio.(*Reader).Peek(0x10f449f0, 0x1, 0x0, 0x0, 0x1, 0x10f51b00, 0x0) /boot/workdir/go/src/bufio/bufio.go:138 +0x38 net/http.(*persistConn).readLoop(0x11232140) /boot/workdir/go/src/net/http/transport.go:1980 +0x15c created by net/http.(*Transport).dialConn /boot/workdir/go/src/net/http/transport.go:1649 +0x9b8 goroutine 904 [sync.Cond.Wait, 9 minutes]: runtime.goparkunlock(...) /boot/workdir/go/src/runtime/proc.go:312 sync.runtime_notifyListWait(0x10ed00a8, 0x0) /boot/workdir/go/src/runtime/sema.go:513 +0x154 sync.(*Cond).Wait(0x10ed00a0) /boot/workdir/go/src/sync/cond.go:56 +0x6c net/http.(*connReader).abortPendingRead(0x10ed0060) /boot/workdir/go/src/net/http/server.go:726 +0x90 net/http.(*response).finishRequest(0x10c64f30) /boot/workdir/go/src/net/http/server.go:1596 +0x90 net/http.(*conn).serve(0x10d8a6c0, 0x45e020, 0x10ed0040) /boot/workdir/go/src/net/http/server.go:1900 +0x804 created by net/http.(*Server).Serve /boot/workdir/go/src/net/http/server.go:2939 +0x2d0 goroutine 1031 [syscall, 9 minutes, locked to thread]: syscall.Syscall6(0x32, 0x9, 0x10f46000, 0x7d4, 0xffffffff, 0xffffffff, 0x0, 0xe, 0x10f4c280, 0x0, ...) /boot/workdir/go/src/syscall/asm_plan9_arm.s:51 +0x8 syscall.Pread(0x9, 0x10f46000, 0x7d4, 0x7d4, 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(0x10ea98c8, 0x10f46000, 0x7d4, 0x7d4, 0x894ec, 0x18, 0x0) /boot/workdir/go/src/os/file_plan9.go:247 +0x50 os.(*File).Read(0x10ea98c8, 0x10f46000, 0x7d4, 0x7d4, 0x10f4a854, 0xffffffff, 0x0) /boot/workdir/go/src/os/file.go:116 +0x60 internal/poll.newAsyncIO.func1(0x10f4a850, 0x10d812e0, 0x10f46000, 0x7d4, 0x7d4) /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 899 [syscall, 9 minutes]: syscall.Syscall(0xe, 0x10e91460, 0x0, 0x0, 0x13, 0x0, 0x0, 0x6d2dc) /boot/workdir/go/src/syscall/asm_plan9_arm.s:16 +0x8 syscall.open(0x10e91440, 0x12, 0x0, 0x0, 0x12d884, 0x6cb448) /boot/workdir/go/src/syscall/zsyscall_plan9_arm.go:61 +0x78 syscall.Open(0x10e91440, 0x12, 0x0, 0x6cb448, 0x3e114c, 0x75198) /boot/workdir/go/src/syscall/syscall_plan9.go:382 +0x64 os.openFileNolog(0x10e91440, 0x12, 0x0, 0x0, 0x2, 0x10e91440, 0x12) /boot/workdir/go/src/os/file_plan9.go:113 +0x28c os.OpenFile(0x10e91440, 0x12, 0x0, 0x0, 0x7, 0x10e91440, 0x12) /boot/workdir/go/src/os/file.go:307 +0x48 os.Open(...) /boot/workdir/go/src/os/file.go:287 net.(*netFD).acceptPlan9(0x10d885b0, 0x0, 0x0, 0x0) /boot/workdir/go/src/net/ipsock_plan9.go:250 +0xe0 net.(*TCPListener).accept(0x10d0ae00, 0x45f468, 0x10ea9c68, 0x3f014) /boot/workdir/go/src/net/tcpsock_plan9.go:48 +0x20 net.(*TCPListener).Accept(0x10d0ae00, 0x10c25f38, 0xc, 0x10e2b340, 0x279b68) /boot/workdir/go/src/net/tcpsock.go:261 +0x68 net/http.(*Server).Serve(0x10ee26c0, 0x45d6a0, 0x10d0ae00, 0x0, 0x0) /boot/workdir/go/src/net/http/server.go:2907 +0x1fc net/http/httptest.(*Server).goServe.func1(0x10d26e00) /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 1029 [select, 9 minutes]: net/http.(*persistConn).writeLoop(0x11232140) /boot/workdir/go/src/net/http/transport.go:2279 +0xc4 created by net/http.(*Transport).dialConn /boot/workdir/go/src/net/http/transport.go:1650 +0x9d4 goroutine 1012 [chan receive, 9 minutes]: internal/poll.(*asyncIO).Wait(...) /boot/workdir/go/src/internal/poll/fd_io_plan9.go:84 internal/poll.(*FD).Read(0x10d888c0, 0x10d01410, 0x10cea000, 0x205, 0x205, 0x0, 0x0, 0x0) /boot/workdir/go/src/internal/poll/fd_plan9.go:73 +0x124 net.(*netFD).Read(0x10d888c0, 0x10cea000, 0x205, 0x205, 0x8, 0x10d00f50, 0xce28) /boot/workdir/go/src/net/fd_plan9.go:86 +0xb0 net.(*conn).Read(0x10e12f20, 0x10cea000, 0x205, 0x205, 0x0, 0x0, 0x0) /boot/workdir/go/src/net/net.go:182 +0x6c crypto/tls.(*atLeastReader).Read(0x111564b0, 0x10cea000, 0x205, 0x205, 0x938c, 0x1dd2b0, 0x10b04258) /boot/workdir/go/src/crypto/tls/conn.go:774 +0x78 bytes.(*Buffer).ReadFrom(0x11220964, 0x45a9a8, 0x111564b0, 0x10d00fd0, 0x1dab74, 0x3715a8, 0x3aeb38) /boot/workdir/go/src/bytes/buffer.go:204 +0xa4 crypto/tls.(*Conn).readFromUntil(0x11220800, 0x10b04258, 0x10e12f20, 0x5, 0x10e12f20, 0x10d00fd0) /boot/workdir/go/src/crypto/tls/conn.go:796 +0xc4 crypto/tls.(*Conn).readRecordOrCCS(0x11220800, 0x0, 0x0, 0x64110) /boot/workdir/go/src/crypto/tls/conn.go:603 +0xfc crypto/tls.(*Conn).readRecord(...) /boot/workdir/go/src/crypto/tls/conn.go:571 crypto/tls.(*Conn).Read(0x11220800, 0x10ed006d, 0x1, 0x1, 0x0, 0x0, 0x0) /boot/workdir/go/src/crypto/tls/conn.go:1247 +0x14c net/http.(*connReader).backgroundRead(0x10ed0060) /boot/workdir/go/src/net/http/server.go:678 +0x44 created by net/http.(*connReader).startBackgroundRead /boot/workdir/go/src/net/http/server.go:674 +0xc0 goroutine 1059 [syscall, 9 minutes, locked to thread]: syscall.Syscall6(0x32, 0xb, 0x10cea000, 0x205, 0xffffffff, 0xffffffff, 0x0, 0xe, 0x1115c1b0, 0x0, ...) /boot/workdir/go/src/syscall/asm_plan9_arm.s:51 +0x8 syscall.Pread(0xb, 0x10cea000, 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(0x10e12f08, 0x10cea000, 0x205, 0x205, 0x894ec, 0x18, 0x0) /boot/workdir/go/src/os/file_plan9.go:247 +0x50 os.(*File).Read(0x10e12f08, 0x10cea000, 0x205, 0x205, 0x111564c4, 0xffffffff, 0x0) /boot/workdir/go/src/os/file.go:116 +0x60 internal/poll.newAsyncIO.func1(0x111564c0, 0x10d01410, 0x10cea000, 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 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 1.035s ok golang.org/x/crypto/bcrypt 2.422s ok golang.org/x/crypto/blake2b 0.209s ok golang.org/x/crypto/blake2s 0.138s ok golang.org/x/crypto/blowfish 0.071s ok golang.org/x/crypto/bn256 5.854s ok golang.org/x/crypto/cast5 0.066s ok golang.org/x/crypto/chacha20 0.126s ok golang.org/x/crypto/chacha20poly1305 4.280s ok golang.org/x/crypto/cryptobyte 0.078s ? golang.org/x/crypto/cryptobyte/asn1 [no test files] ok golang.org/x/crypto/curve25519 0.937s ok golang.org/x/crypto/ed25519 0.949s ? golang.org/x/crypto/ed25519/internal/edwards25519 [no test files] ok golang.org/x/crypto/hkdf 0.048s ok golang.org/x/crypto/internal/subtle 0.060s ok golang.org/x/crypto/internal/wycheproof 110.153s ? golang.org/x/crypto/internal/wycheproof/internal/dsa [no test files] ok golang.org/x/crypto/md4 0.054s ok golang.org/x/crypto/nacl/auth 0.056s ok golang.org/x/crypto/nacl/box 0.459s ok golang.org/x/crypto/nacl/secretbox 0.050s ok golang.org/x/crypto/nacl/sign 0.065s ok golang.org/x/crypto/ocsp 1.249s ok golang.org/x/crypto/openpgp 75.436s ok golang.org/x/crypto/openpgp/armor 0.044s ok golang.org/x/crypto/openpgp/clearsign 0.221s ok golang.org/x/crypto/openpgp/elgamal 0.100s ? golang.org/x/crypto/openpgp/errors [no test files] ok golang.org/x/crypto/openpgp/packet 2.077s ok golang.org/x/crypto/openpgp/s2k 65.283s ok golang.org/x/crypto/otr 4.988s ok golang.org/x/crypto/pbkdf2 0.374s ok golang.org/x/crypto/pkcs12 0.277s ok golang.org/x/crypto/pkcs12/internal/rc2 0.056s ok golang.org/x/crypto/poly1305 0.141s ok golang.org/x/crypto/ripemd160 0.228s ok golang.org/x/crypto/salsa20 0.062s ok golang.org/x/crypto/salsa20/salsa 0.043s ok golang.org/x/crypto/scrypt 3.104s ok golang.org/x/crypto/sha3 0.471s ok golang.org/x/crypto/ssh 91.942s ok golang.org/x/crypto/ssh/agent 17.279s ok golang.org/x/crypto/ssh/internal/bcrypt_pbkdf 0.954s ok golang.org/x/crypto/ssh/knownhosts 0.056s ok golang.org/x/crypto/ssh/terminal 0.049s ok golang.org/x/crypto/ssh/test 0.990s ok golang.org/x/crypto/tea 0.046s ok golang.org/x/crypto/twofish 0.094s ok golang.org/x/crypto/xtea 0.042s ok golang.org/x/crypto/xts 0.048s FAIL