plan9-386 at c374984e99888bb2e2dd6c331a1328275debe19d :: Running /tmp/workdir/go/src/make.rc with args ["/tmp/workdir/go/src/make.rc"] and env ["terminal=generic /sys/src/9/pc/pccpuf" "cputype=386" "service=cpu" "bootfile=sd01!9fat!9pccpuf" "nobootprompt=local!#S/sd01/fossil" "bootargs=local!#S/sd01/fossil" "bootdisk=#S/sd01/fossil" "console=0 b115200" "dmamode=ask" "installurl=http://148.251.6.120/plan9/download" "partition=new" "mouseport=ps2intellimouse" "monitor=vesa" "vgasize=1280x1024x32" "user=glenda" "sd01part=plan9 63 20964825/9fat 63 204863/nvram 204863 204864/fossil 204864 19916249/swap 19916249 20964825" "rootdir=/root" "objtype=386" "timezone=EST -18000 EDT -14400\n 9943200 25664400 41392800 57718800 73447200 89168400\n 104896800 120618000 126669600 152067600 162352800 183517200\n 199245600 215571600 230695200 247021200 262749600 278470800\n 294199200 309920400 325648800 341370000 357098400 372819600\n 388548000 404874000 419997600 436323600 452052000 467773200\n 483501600 499222800 514951200 530672400 544586400 562122000\n 576036000 594176400 607485600 625626000 638935200 657075600\n 670989600 688525200 702439200 719974800 733888800 752029200\n 765338400 783478800 796788000 814928400 828842400 846378000\n 860292000 877827600 891741600 909277200 923191200 941331600\n 954640800 972781200 986090400 1004230800 1018144800 1035680400\n1049594400 1067130000 1081044000 1099184400 1112493600 1130634000\n1143943200 1162083600 1173578400 1194141600 1205028000 1225591200\n1236477600 1257040800 1268532000 1289095200 1299981600 1320544800\n1331431200 1351994400 1362880800 1383444000 1394330400 1414893600\n1425780000 1446343200 1457834400 1478397600 1489284000 1509847200\n1520733600 1541296800 1552183200 1572746400 1583632800 1604196000\n1615687200 1636250400 1647136800 1667700000 1678586400 1699149600\n1710036000 1730599200 1741485600 1762048800 1772935200 1793498400\n1804989600 1825552800 1836439200 1857002400 1867888800 1888452000\n1899338400 1919901600 1930788000 1951351200 1962842400 1983405600\n1994292000 2014855200 2025741600 2046304800 2057191200 2077754400\n2088640800 2109204000 2120090400 2140653600\n" "home=/usr/glenda" "path=.\x00/bin" "*=" "rcname=rc" "pid=111" "ifs= \t\n" "prompt=helix# \x00\t" "status=" "fn#sigexit=" "cflag=" "0=/rc/lib/rcmain" "boottime=Thu Aug 23 01:15:50 EDT 2018\n" "sysname=helix" "NPROC=1" "site=DOM.EXAMPLE.COM" "fileserver=kfs" "facedom=FACEDOM" "apid=102" "GOROOT_BOOTSTRAP=/tmp/workdir/go1.4" "WORKDIR=/tmp/workdir" "GO_BUILDER_NAME=plan9-386" "GO_TEST_TIMEOUT_SCALE=2" "GOBIN="] in dir /tmp/workdir/go/src Building Go cmd/dist using /tmp/workdir/go1.4 Building Go toolchain1 using /tmp/workdir/go1.4. Building Go bootstrap cmd/go (go_bootstrap) using Go toolchain1. Building Go toolchain2 using go_bootstrap and Go toolchain1. Building Go toolchain3 using go_bootstrap and Go toolchain2. Building packages and commands for plan9/386. --- Installed Go for plan9/386 in /tmp/workdir/go Installed commands in /tmp/workdir/go/bin *** You need to bind /tmp/workdir/go/bin before /bin. ##### Testing packages. ok archive/tar 0.104s ok archive/zip 1.707s ok bufio 0.137s ok bytes 1.993s ok compress/bzip2 0.093s ok compress/flate 1.401s ok compress/gzip 4.723s ok compress/lzw 0.118s ok compress/zlib 0.699s ok container/heap 0.029s ok container/list 0.025s ok container/ring 0.034s ok context 1.126s ok crypto 0.025s ok crypto/aes 0.052s ok crypto/cipher 0.116s ok crypto/des 0.040s ok crypto/dsa 0.074s ok crypto/ecdsa 0.216s ok crypto/elliptic 0.057s ok crypto/hmac 0.027s ok crypto/internal/subtle 0.024s ok crypto/md5 0.030s ok crypto/rand 0.245s ok crypto/rc4 0.073s ok crypto/rsa 0.169s ok crypto/sha1 0.078s ok crypto/sha256 0.033s ok crypto/sha512 0.036s ok crypto/subtle 0.030s ok crypto/tls 2.018s ok crypto/x509 2.917s ok database/sql 0.739s ok database/sql/driver 0.028s ok debug/dwarf 0.071s ok debug/elf 0.086s ok debug/gosym 0.027s ok debug/macho 0.032s ok debug/pe 0.037s ok debug/plan9obj 0.027s ok encoding/ascii85 0.031s ok encoding/asn1 0.035s ok encoding/base32 0.048s ok encoding/base64 0.031s ok encoding/binary 0.031s ok encoding/csv 0.034s ok encoding/gob 0.062s ok encoding/hex 0.029s ok encoding/json 0.575s ok encoding/pem 0.043s ok encoding/xml 0.068s ok errors 0.027s ok expvar 0.036s ok flag 0.028s ok fmt 0.091s ok go/ast 0.048s ok go/build 1.512s ok go/constant 0.036s ok go/doc 0.091s ok go/format 0.043s ok go/importer 0.589s ok go/internal/gccgoimporter 0.051s ok go/internal/gcimporter 4.299s ok go/internal/srcimporter 2.995s ok go/parser 0.055s ok go/printer 0.302s ok go/scanner 0.028s ok go/token 0.049s ok go/types 3.495s ok hash 0.031s ok hash/adler32 0.033s ok hash/crc32 0.035s ok hash/crc64 0.031s ok hash/fnv 0.027s ok html 0.030s ok html/template 0.181s ok image 0.118s ok image/color 0.045s ok image/draw 0.078s ok image/gif 0.459s ok image/jpeg 0.239s ok image/png 0.076s ok index/suffixarray 0.037s ok internal/cpu 0.026s ok internal/poll 0.056s ok internal/singleflight 0.040s ok internal/trace 1.620s ok io 0.197s ok io/ioutil 8.491s ok log 0.037s ok math 0.037s ok math/big 2.128s ok math/bits 0.035s ok math/cmplx 0.031s ok math/rand 0.692s ok mime 0.035s ok mime/multipart 2.063s ok mime/quotedprintable 0.203s --- FAIL: TestVariousDeadlines (7.22s) timeout_test.go:880: 1ns run 1/1 timeout_test.go:905: for 1ns run 1/1, good client timeout after 86.324µs, reading 0 bytes timeout_test.go:915: for 1ns run 1/1, server in 3.545142ms wrote 458752: readfrom tcp 127.0.0.1:41357->127.0.0.1:39826: write tcp 127.0.0.1:41357->127.0.0.1:39826: write /net/tcp/167/data: Hangup timeout_test.go:880: 2ns run 1/1 timeout_test.go:905: for 2ns run 1/1, good client timeout after 56.991µs, reading 0 bytes timeout_test.go:915: for 2ns run 1/1, server in 4.486323ms wrote 622592: readfrom tcp 127.0.0.1:41357->127.0.0.1:37187: write tcp 127.0.0.1:41357->127.0.0.1:37187: write /net/tcp/168/data: Hangup timeout_test.go:880: 5ns run 1/1 timeout_test.go:905: for 5ns run 1/1, good client timeout after 57.829µs, reading 0 bytes timeout_test.go:915: for 5ns run 1/1, server in 4.622933ms wrote 622592: readfrom tcp 127.0.0.1:41357->127.0.0.1:44354: write tcp 127.0.0.1:41357->127.0.0.1:44354: write /net/tcp/169/data: Hangup timeout_test.go:880: 50ns run 1/1 timeout_test.go:905: for 50ns run 1/1, good client timeout after 57.829µs, reading 0 bytes timeout_test.go:915: for 50ns run 1/1, server in 4.361447ms wrote 589824: readfrom tcp 127.0.0.1:41357->127.0.0.1:62061: write tcp 127.0.0.1:41357->127.0.0.1:62061: write /net/tcp/170/data: Hangup timeout_test.go:880: 100ns run 1/1 timeout_test.go:905: for 100ns run 1/1, good client timeout after 56.152µs, reading 0 bytes timeout_test.go:915: for 100ns run 1/1, server in 4.444418ms wrote 622592: readfrom tcp 127.0.0.1:41357->127.0.0.1:48634: write tcp 127.0.0.1:41357->127.0.0.1:48634: write /net/tcp/171/data: Hangup timeout_test.go:880: 200ns run 1/1 timeout_test.go:905: for 200ns run 1/1, good client timeout after 58.667µs, reading 0 bytes timeout_test.go:915: for 200ns run 1/1, server in 7.162361ms wrote 1048576: readfrom tcp 127.0.0.1:41357->127.0.0.1:42151: write tcp 127.0.0.1:41357->127.0.0.1:42151: write /net/tcp/172/data: Hangup timeout_test.go:880: 500ns run 1/1 timeout_test.go:905: for 500ns run 1/1, good client timeout after 56.152µs, reading 0 bytes timeout_test.go:915: for 500ns run 1/1, server in 4.542475ms wrote 622592: readfrom tcp 127.0.0.1:41357->127.0.0.1:60065: write tcp 127.0.0.1:41357->127.0.0.1:60065: write /net/tcp/173/data: Hangup timeout_test.go:880: 750ns run 1/1 timeout_test.go:905: for 750ns run 1/1, good client timeout after 56.99µs, reading 0 bytes timeout_test.go:915: for 750ns run 1/1, server in 3.740418ms wrote 524288: readfrom tcp 127.0.0.1:41357->127.0.0.1:57706: write tcp 127.0.0.1:41357->127.0.0.1:57706: write /net/tcp/174/data: Hangup timeout_test.go:880: 1µs run 1/1 timeout_test.go:905: for 1µs run 1/1, good client timeout after 57.828µs, reading 0 bytes timeout_test.go:915: for 1µs run 1/1, server in 4.550018ms wrote 622592: readfrom tcp 127.0.0.1:41357->127.0.0.1:55999: write tcp 127.0.0.1:41357->127.0.0.1:55999: write /net/tcp/175/data: Hangup timeout_test.go:880: 5µs run 1/1 timeout_test.go:905: for 5µs run 1/1, good client timeout after 61.181µs, reading 0 bytes timeout_test.go:915: for 5µs run 1/1, server in 3.72198ms wrote 491520: readfrom tcp 127.0.0.1:41357->127.0.0.1:50697: write tcp 127.0.0.1:41357->127.0.0.1:50697: write /net/tcp/176/data: Hangup timeout_test.go:880: 25µs run 1/1 timeout_test.go:905: for 25µs run 1/1, good client timeout after 62.857µs, reading 0 bytes timeout_test.go:915: for 25µs run 1/1, server in 7.198399ms wrote 1048576: readfrom tcp 127.0.0.1:41357->127.0.0.1:33533: write tcp 127.0.0.1:41357->127.0.0.1:33533: write /net/tcp/177/data: Hangup timeout_test.go:880: 250µs run 1/1 timeout_test.go:905: for 250µs run 1/1, good client timeout after 1.739114401s, reading 53182464 bytes timeout_test.go:915: for 250µs run 1/1, server in 1.72418206s wrote 55246848: readfrom tcp 127.0.0.1:41357->127.0.0.1:47146: write tcp 127.0.0.1:41357->127.0.0.1:47146: write /net/tcp/178/data: Hangup timeout_test.go:880: 500µs run 1/1 timeout_test.go:905: for 500µs run 1/1, good client timeout after 5.06433134s, reading 158859264 bytes timeout_test.go:919: for 500µs run 1/1, timeout waiting for server to finish writing FAIL FAIL net 31.033s 2018/08/23 01:33:10 Failed: exit status: 'go 6292: 1'