linux-386-longtest at 5d1287fa62de7b9c401749cd79147a5e38b11209 :: Running /workdir/go/src/make.bash with args ["/workdir/go/src/make.bash" "-force"] and env ["HOSTNAME=buildlet-linux-amd64-bullseye-rnfe7b527" "HOME=/root" "SHLVL=0" "PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin" "DEBIAN_FRONTEND=noninteractive" "USER=root" "GO_STAGE0_NET_DELAY=0s" "GO_STAGE0_DL_DELAY=200ms" "WORKDIR=/workdir" "GOROOT_BOOTSTRAP=/workdir/go1.4" "GO_BUILDER_NAME=linux-386-longtest" "GO_TEST_SHORT=0" "GOARCH=386" "GOHOSTARCH=386" "GO_TEST_TIMEOUT_SCALE=5" "GOBIN=" "TMPDIR=/workdir/tmp" "GOCACHE=/workdir/gocache" "GOPLSCACHE=/workdir/goplscache" "PWD=/workdir/go/src"] in dir /workdir/go/src Building Go cmd/dist using /workdir/go1.4. (go1.20.6 linux/amd64) Building Go toolchain1 using /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 linux/386. --- Installed Go for linux/386 in /workdir/go Installed commands in /workdir/go/bin *** You need to add /workdir/go/bin to your PATH. ##### Test execution environment. # GOARCH: 386 # CPU: AMD EPYC 7B12 # GOOS: linux # OS Version: Linux 5.15.133+ #1 SMP Sun Nov 19 11:08:25 UTC 2023 x86_64 ##### Testing packages. ok archive/tar 0.120s ok archive/zip 22.394s ok bufio 0.048s ok bytes 2.597s ok cmp 0.003s ok compress/bzip2 0.038s ok compress/flate 10.384s ok compress/gzip 1.441s ok compress/lzw 0.057s ok compress/zlib 0.610s ok container/heap 0.003s ok container/list 0.002s ok container/ring 0.003s ok context 0.019s ok crypto 0.002s ok crypto/aes 0.015s ok crypto/cipher 0.005s ok crypto/des 0.010s ok crypto/dsa 19.826s ok crypto/ecdh 0.626s ok crypto/ecdsa 4.364s ok crypto/ed25519 0.728s ok crypto/elliptic 0.468s ok crypto/hmac 0.003s ok crypto/internal/alias 0.002s ok crypto/internal/bigmod 0.118s ok crypto/internal/boring 0.002s ? crypto/internal/boring/bbig [no test files] ? crypto/internal/boring/sig [no test files] ok crypto/internal/boring/bcache 0.056s ok crypto/internal/edwards25519 13.697s ok crypto/internal/edwards25519/field 15.424s ok crypto/internal/nistec 43.579s ? crypto/internal/randutil [no test files] ok crypto/internal/nistec/fiat 0.002s [no tests to run] ok crypto/md5 0.005s ok crypto/rand 0.160s ok crypto/rc4 0.031s ok crypto/rsa 8.083s ok crypto/sha1 0.003s ok crypto/sha256 0.003s ok crypto/sha512 0.005s ok crypto/subtle 3.413s ok crypto/tls 3.041s ok crypto/x509 2.996s ? crypto/x509/pkix [no test files] ok database/sql 1.144s ok database/sql/driver 0.002s ok debug/buildinfo 3.981s ok debug/dwarf 0.011s ok debug/elf 0.194s ok debug/gosym 0.017s ok debug/macho 0.003s ok debug/pe 0.010s ok debug/plan9obj 0.002s ok embed 0.003s [no tests to run] ok embed/internal/embedtest 0.003s ? encoding [no test files] ok encoding/ascii85 0.004s ok encoding/asn1 0.004s ok encoding/base32 0.014s ok encoding/base64 0.005s ok encoding/binary 0.004s ok encoding/csv 0.007s ok encoding/gob 2.260s ok encoding/hex 0.003s ok encoding/json 0.893s ok encoding/pem 0.596s ok encoding/xml 6.078s ok errors 0.005s ok expvar 0.004s ok flag 0.019s ok fmt 0.041s ok go/ast 0.004s ok go/build 1.284s ok go/build/constraint 0.003s ok go/constant 0.004s ok go/doc 0.027s ok go/doc/comment 0.634s ok go/format 0.005s ok go/importer 0.152s ok go/internal/gccgoimporter 0.006s ok go/internal/gcimporter 39.662s ok go/internal/srcimporter 5.948s ? go/internal/typeparams [no test files] ok go/parser 9.576s ok go/printer 0.144s ok go/scanner 0.003s ok go/token 0.012s ok go/types 9.344s ok go/version 0.002s ok hash 0.003s ok hash/adler32 0.005s ok hash/crc32 0.006s ok hash/crc64 0.002s ok hash/fnv 0.002s ok hash/maphash 117.401s ok html 0.004s ok html/template 0.135s ok image 0.101s ? image/color/palette [no test files] ok image/color 0.013s ok image/draw 0.057s ? image/internal/imageutil [no test files] ok image/gif 11.238s ok image/jpeg 1.992s ok image/png 0.554s ok index/suffixarray 46.963s ? internal/bisect [no test files] ok internal/abi 0.039s ok internal/buildcfg 0.002s ? internal/bytealg [no test files] ? internal/cfg [no test files] ? internal/coverage [no test files] ? internal/coverage/calloc [no test files] ok internal/coverage/cformat 0.002s ok internal/coverage/cmerge 0.002s ? internal/coverage/decodecounter [no test files] ? internal/coverage/decodemeta [no test files] ? internal/coverage/encodecounter [no test files] ? internal/coverage/encodemeta [no test files] ? internal/coverage/rtcov [no test files] ok internal/coverage/pods 0.003s ? internal/coverage/stringtab [no test files] ok internal/coverage/slicereader 0.002s ok internal/coverage/slicewriter 0.002s ? internal/coverage/uleb128 [no test files] ok internal/coverage/test 0.006s ok internal/cpu 0.006s ok internal/dag 0.002s ok internal/diff 0.003s ok internal/fmtsort 0.002s ? internal/goarch [no test files] ok internal/fuzz 0.006s ok internal/godebug 0.279s ? internal/goexperiment [no test files] ? internal/goos [no test files] ok internal/godebugs 0.002s ? internal/goroot [no test files] ? internal/goversion [no test files] ok internal/gover 0.002s ? internal/lazyregexp [no test files] ok internal/intern 1.722s ok internal/itoa 0.002s ? internal/lazytemplate [no test files] ? internal/nettrace [no test files] ? internal/obscuretestdata [no test files] ? internal/oserror [no test files] ? internal/pkgbits [no test files] ok internal/platform 0.504s ? internal/race [no test files] ok internal/poll 1.689s ok internal/profile 0.002s ok internal/reflectlite 0.003s ok internal/safefilepath 0.002s ok internal/saferio 0.023s ? internal/syscall/execenv [no test files] ? internal/syscall/unix [no test files] ok internal/singleflight 0.019s ? internal/sysinfo [no test files] ? internal/testlog [no test files] ok internal/testenv 0.127s ? internal/testpty [no test files] ? internal/trace/traceviewer [no test files] ok internal/trace 1.567s ? internal/trace/traceviewer/format [no test files] ? internal/trace/v2/event [no test files] --- FAIL: TestTraceAnnotationsStress (0.44s) --- FAIL: TestTraceAnnotationsStress/Stress (0.17s) trace_test.go:551: stderr: SIGSEGV: segmentation violation PC=0x80a4347 m=2 sigcode=1 addr=0x0 goroutine 0 gp=0x94086c8 m=2 mp=0x944c408 [idle]: runtime.readgstatus(...) /workdir/go/src/runtime/proc.go:1056 runtime.traceWriter.writeProcStatusForP({{0x944c408, 0x29}, 0x0}, 0x942c008, 0x0) /workdir/go/src/runtime/trace2status.go:91 +0x67 fp=0x9465eac sp=0x9465e84 pc=0x80a4347 runtime.traceAdvance.func5(0x942c008) /workdir/go/src/runtime/trace2.go:551 +0x8f fp=0x9465ed8 sp=0x9465eac pc=0x80ac14f runtime.runSafePointFn() /workdir/go/src/runtime/proc.go:2044 +0x6a fp=0x9465ef0 sp=0x9465ed8 pc=0x808323a runtime.findRunnable() /workdir/go/src/runtime/proc.go:3169 +0x7e fp=0x9465f9c sp=0x9465ef0 pc=0x808508e runtime.schedule() /workdir/go/src/runtime/proc.go:3884 +0xbd fp=0x9465fb8 sp=0x9465f9c pc=0x80870ed runtime.park_m(0x94825a8) /workdir/go/src/runtime/proc.go:4052 +0x1b5 fp=0x9465fd8 sp=0x9465fb8 pc=0x8087835 runtime.mcall(0x4086c8) /workdir/go/src/runtime/asm_386.s:329 +0x44 fp=0x9465fe0 sp=0x9465fd8 pc=0x80af4b4 goroutine 1 gp=0x9408128 m=nil [sleep]: runtime.gopark(0x80ffb8c, 0x94ba060, 0x13, 0xe, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x94486ec sp=0x94486d8 pc=0x807ff6c time.Sleep(0x989680) /workdir/go/src/runtime/time.go:195 +0x128 fp=0x9448708 sp=0x94486ec pc=0x80ae848 main.do({0x811897c, 0x81b75a0}, 0x4) /workdir/go/src/internal/trace/v2/testdata/testprog/annotations-stress.go:83 +0x170 fp=0x9448750 sp=0x9448708 pc=0x80de900 main.main() /workdir/go/src/internal/trace/v2/testdata/testprog/annotations-stress.go:43 +0x1c1 fp=0x94487ac sp=0x9448750 pc=0x80de6b1 runtime.main() /workdir/go/src/runtime/proc.go:271 +0x2b6 fp=0x94487f0 sp=0x94487ac pc=0x807fac6 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x94487f4 sp=0x94487f0 pc=0x80b08a1 goroutine 2 gp=0x9408488 m=nil [force gc (idle)]: runtime.gopark(0x80ffb6c, 0x818b9a8, 0x11, 0xa, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x9448fdc sp=0x9448fc8 pc=0x807ff6c runtime.goparkunlock(...) /workdir/go/src/runtime/proc.go:408 runtime.forcegchelper() /workdir/go/src/runtime/proc.go:326 +0xd3 fp=0x9448ff0 sp=0x9448fdc pc=0x807fdb3 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x9448ff4 sp=0x9448ff0 pc=0x80b08a1 created by runtime.init.5 in goroutine 1 /workdir/go/src/runtime/proc.go:314 +0x23 goroutine 3 gp=0x94087e8 m=nil [GC sweep wait]: runtime.gopark(0x80ffb6c, 0x818ba80, 0xc, 0x9, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x94497cc sp=0x94497b8 pc=0x807ff6c runtime.goparkunlock(...) /workdir/go/src/runtime/proc.go:408 runtime.bgsweep(0x941e040) /workdir/go/src/runtime/mgcsweep.go:277 +0x9b fp=0x94497e8 sp=0x94497cc pc=0x806b38b runtime.gcenable.gowrap1() /workdir/go/src/runtime/mgc.go:203 +0x27 fp=0x94497f0 sp=0x94497e8 pc=0x805cf27 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x94497f4 sp=0x94497f0 pc=0x80b08a1 created by runtime.gcenable in goroutine 1 /workdir/go/src/runtime/mgc.go:203 +0x77 goroutine 4 gp=0x9408908 m=nil [GC scavenge wait]: runtime.gopark(0x80ffb6c, 0x818bb60, 0xd, 0xa, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x9449fb8 sp=0x9449fa4 pc=0x807ff6c runtime.goparkunlock(...) /workdir/go/src/runtime/proc.go:408 runtime.(*scavengerState).park(0x818bb60) /workdir/go/src/runtime/mgcscavenge.go:425 +0x60 fp=0x9449fcc sp=0x9449fb8 pc=0x80688a0 runtime.bgscavenge(0x941e040) /workdir/go/src/runtime/mgcscavenge.go:653 +0x4b fp=0x9449fe8 sp=0x9449fcc pc=0x8068f0b runtime.gcenable.gowrap2() /workdir/go/src/runtime/mgc.go:204 +0x27 fp=0x9449ff0 sp=0x9449fe8 pc=0x805cee7 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x9449ff4 sp=0x9449ff0 pc=0x80b08a1 created by runtime.gcenable in goroutine 1 /workdir/go/src/runtime/mgc.go:204 +0xb7 goroutine 17 gp=0x9482248 m=nil [finalizer wait]: runtime.gopark(0x80ffb08, 0x81b75e4, 0x10, 0xa, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x9444798 sp=0x9444784 pc=0x807ff6c runtime.runfinq() /workdir/go/src/runtime/mfinal.go:194 +0xfc fp=0x94447f0 sp=0x9444798 pc=0x805c00c runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x94447f4 sp=0x94447f0 pc=0x80b08a1 created by runtime.createfing in goroutine 1 /workdir/go/src/runtime/mfinal.go:164 +0x60 goroutine 18 gp=0x9482368 m=nil [chan receive]: runtime.gopark(0x80ffaf0, 0x94940b0, 0xe, 0x7, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x9444f4c sp=0x9444f38 pc=0x807ff6c runtime.chanrecv(0x9494080, 0x0, 0x1) /workdir/go/src/runtime/chan.go:583 +0x3a3 fp=0x9444f88 sp=0x9444f4c pc=0x804d9f3 runtime.chanrecv1(0x9494080, 0x0) /workdir/go/src/runtime/chan.go:442 +0x1c fp=0x9444f9c sp=0x9444f88 pc=0x804d64c runtime.(*wakeableSleep).sleep(0x94b8000, 0x5f5e100) /workdir/go/src/runtime/trace2.go:936 +0xc3 fp=0x9444fd8 sp=0x9444f9c pc=0x809f393 runtime.traceStartReadCPU.func1() /workdir/go/src/runtime/trace2cpu.go:54 +0x50 fp=0x9444ff0 sp=0x9444fd8 pc=0x809fe20 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x9444ff4 sp=0x9444ff0 pc=0x80b08a1 created by runtime.traceStartReadCPU in goroutine 1 /workdir/go/src/runtime/trace2cpu.go:42 +0x161 goroutine 19 gp=0x9482488 m=3 mp=0x944c808 [trace proc status]: runtime.systemstack_switch() /workdir/go/src/runtime/asm_386.s:341 fp=0x9445718 sp=0x9445714 pc=0x80af4c0 runtime.forEachP(...) /workdir/go/src/runtime/proc.go:1911 runtime.traceAdvance(0x0) /workdir/go/src/runtime/trace2.go:548 +0x5c4 fp=0x94457d8 sp=0x9445718 pc=0x809e064 runtime.(*traceAdvancerState).start.func1() /workdir/go/src/runtime/trace2.go:882 +0x46 fp=0x94457f0 sp=0x94457d8 pc=0x809f226 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x94457f4 sp=0x94457f0 pc=0x80b08a1 created by runtime.(*traceAdvancerState).start in goroutine 1 /workdir/go/src/runtime/trace2.go:876 +0x165 goroutine 20 gp=0x94825a8 m=nil [trace reader (blocked)]: runtime.gopark(0x80ffae0, 0x0, 0x18, 0xa, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x9445f98 sp=0x9445f84 pc=0x807ff6c runtime.ReadTrace() /workdir/go/src/runtime/trace2.go:677 +0x40 fp=0x9445fc8 sp=0x9445f98 pc=0x809eaf0 runtime/trace.Start.func1() /workdir/go/src/runtime/trace/trace.go:130 +0x4e fp=0x9445ff0 sp=0x9445fc8 pc=0x80de3ae runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x9445ff4 sp=0x9445ff0 pc=0x80b08a1 created by runtime/trace.Start in goroutine 1 /workdir/go/src/runtime/trace/trace.go:128 +0xc9 goroutine 23 gp=0x9482908 m=nil [sleep]: runtime.gopark(0x80ffb8c, 0x94ba090, 0x13, 0xe, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x94476c8 sp=0x94476b4 pc=0x807ff6c time.Sleep(0x989680) /workdir/go/src/runtime/time.go:195 +0x128 fp=0x94476e4 sp=0x94476c8 pc=0x80ae848 main.do({0x811899c, 0x94a2060}, 0x0) /workdir/go/src/internal/trace/v2/testdata/testprog/annotations-stress.go:83 +0x170 fp=0x944772c sp=0x94476e4 pc=0x80de900 main.do.func1.1.1() /workdir/go/src/internal/trace/v2/testdata/testprog/annotations-stress.go:73 +0xcd fp=0x9447768 sp=0x944772c pc=0x80dec3d runtime/trace.WithRegion({0x811899c, 0x94a2060}, {0x949c047, 0x7}, 0x94477cc) /workdir/go/src/runtime/trace/annotation.go:141 +0xe8 fp=0x94477a8 sp=0x9447768 pc=0x80de188 main.do.func1.1(0x2) /workdir/go/src/internal/trace/v2/testdata/testprog/annotations-stress.go:70 +0xe9 fp=0x94477e8 sp=0x94477a8 pc=0x80deb59 main.do.func1.gowrap1() /workdir/go/src/internal/trace/v2/testdata/testprog/annotations-stress.go:77 +0x29 fp=0x94477f0 sp=0x94477e8 pc=0x80dea59 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x94477f4 sp=0x94477f0 pc=0x80b08a1 created by main.do.func1 in goroutine 1 /workdir/go/src/internal/trace/v2/testdata/testprog/annotations-stress.go:69 +0x44 eax 0x0 ebx 0x942c918 ecx 0x944c408 edx 0x942c008 edi 0x818d398 esi 0x1 ebp 0x1 esp 0x9465e84 eip 0x80a4347 eflags 0x10246 cs 0x23 fs 0x0 gs 0x63 exit status 2 trace_test.go:553: exit status 1 --- FAIL: TestTraceCPUProfile (1.12s) --- FAIL: TestTraceCPUProfile/Stress (0.21s) trace_test.go:551: stderr: SIGSEGV: segmentation violation PC=0x80a5a27 m=2 sigcode=1 addr=0x0 goroutine 0 gp=0x98086c8 m=2 mp=0x984c408 [idle]: runtime.readgstatus(...) /workdir/go/src/runtime/proc.go:1056 runtime.traceWriter.writeProcStatusForP({{0x984c408, 0x28}, 0x0}, 0x982f908, 0x0) /workdir/go/src/runtime/trace2status.go:91 +0x67 fp=0x9865ea8 sp=0x9865e80 pc=0x80a5a27 runtime.traceAdvance.func5(0x982f908) /workdir/go/src/runtime/trace2.go:551 +0x8f fp=0x9865ed4 sp=0x9865ea8 pc=0x80ad71f runtime.runSafePointFn() /workdir/go/src/runtime/proc.go:2044 +0x6a fp=0x9865eec sp=0x9865ed4 pc=0x808411a runtime.findRunnable() /workdir/go/src/runtime/proc.go:3169 +0x7e fp=0x9865f98 sp=0x9865eec pc=0x8085f6e runtime.schedule() /workdir/go/src/runtime/proc.go:3884 +0xbd fp=0x9865fb4 sp=0x9865f98 pc=0x8087fcd runtime.goschedImpl(0x9808128, 0x0) /workdir/go/src/runtime/proc.go:4081 +0x14d fp=0x9865fcc sp=0x9865fb4 pc=0x808887d runtime.gosched_m(0x9808128) /workdir/go/src/runtime/proc.go:4086 +0x26 fp=0x9865fd8 sp=0x9865fcc pc=0x80888d6 runtime.mcall(0x8086c8) /workdir/go/src/runtime/asm_386.s:329 +0x44 fp=0x9865fe0 sp=0x9865fd8 pc=0x80b0b44 goroutine 1 gp=0x9808128 m=nil [runnable]: runtime.Gosched(...) /workdir/go/src/runtime/proc.go:342 main.cpuHog0(0x0, 0x186a0) /workdir/go/src/internal/trace/v2/testdata/testprog/cpu-profile.go:128 +0x59 fp=0x990cd50 sp=0x990cd40 pc=0x812ffb9 main.cpuHog1(0x0) /workdir/go/src/internal/trace/v2/testdata/testprog/cpu-profile.go:120 +0x29 fp=0x990cd60 sp=0x990cd50 pc=0x812ff49 main.cpuHogger(0x815df44, 0x825345c, 0x5f5e100) /workdir/go/src/internal/trace/v2/testdata/testprog/cpu-profile.go:107 +0x3f fp=0x990cd9c sp=0x990cd60 pc=0x812fe9f main.main.func1.1({0x817edf4, 0x9910150}) /workdir/go/src/internal/trace/v2/testdata/testprog/cpu-profile.go:44 +0x3b fp=0x990cdb0 sp=0x990cd9c pc=0x812fddb runtime/pprof.Do({0x817edd4, 0x8253440}, {{0x9812080, 0x1, 0x1}}, 0x990ce20) /workdir/go/src/runtime/pprof/runtime.go:51 +0xbd fp=0x990cdec sp=0x990cdb0 pc=0x812eaad main.main.func1(0x5f5e100) /workdir/go/src/internal/trace/v2/testdata/testprog/cpu-profile.go:43 +0x220 fp=0x990ce54 sp=0x990cdec pc=0x812fd40 main.main() /workdir/go/src/internal/trace/v2/testdata/testprog/cpu-profile.go:50 +0x13f fp=0x990cfac sp=0x990ce54 pc=0x812f57f runtime.main() /workdir/go/src/runtime/proc.go:271 +0x2b6 fp=0x990cff0 sp=0x990cfac pc=0x80809a6 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x990cff4 sp=0x990cff0 pc=0x80b1f31 goroutine 2 gp=0x9808488 m=nil [force gc (idle)]: runtime.gopark(0x815e134, 0x8226f18, 0x11, 0xa, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x9848fdc sp=0x9848fc8 pc=0x8080e4c runtime.goparkunlock(...) /workdir/go/src/runtime/proc.go:408 runtime.forcegchelper() /workdir/go/src/runtime/proc.go:326 +0xd3 fp=0x9848ff0 sp=0x9848fdc pc=0x8080c93 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x9848ff4 sp=0x9848ff0 pc=0x80b1f31 created by runtime.init.5 in goroutine 1 /workdir/go/src/runtime/proc.go:314 +0x23 goroutine 3 gp=0x98087e8 m=nil [GC sweep wait]: runtime.gopark(0x815e134, 0x8227020, 0xc, 0x9, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x98497cc sp=0x98497b8 pc=0x8080e4c runtime.goparkunlock(...) /workdir/go/src/runtime/proc.go:408 runtime.bgsweep(0x981e040) /workdir/go/src/runtime/mgcsweep.go:277 +0x9b fp=0x98497e8 sp=0x98497cc pc=0x806c0eb runtime.gcenable.gowrap1() /workdir/go/src/runtime/mgc.go:203 +0x27 fp=0x98497f0 sp=0x98497e8 pc=0x805dc87 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x98497f4 sp=0x98497f0 pc=0x80b1f31 created by runtime.gcenable in goroutine 1 /workdir/go/src/runtime/mgc.go:203 +0x77 goroutine 4 gp=0x9808908 m=nil [GC scavenge wait]: runtime.gopark(0x815e134, 0x82271e0, 0xd, 0xa, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x9849fb8 sp=0x9849fa4 pc=0x8080e4c runtime.goparkunlock(...) /workdir/go/src/runtime/proc.go:408 runtime.(*scavengerState).park(0x82271e0) /workdir/go/src/runtime/mgcscavenge.go:425 +0x60 fp=0x9849fcc sp=0x9849fb8 pc=0x8069600 runtime.bgscavenge(0x981e040) /workdir/go/src/runtime/mgcscavenge.go:653 +0x4b fp=0x9849fe8 sp=0x9849fcc pc=0x8069c6b runtime.gcenable.gowrap2() /workdir/go/src/runtime/mgc.go:204 +0x27 fp=0x9849ff0 sp=0x9849fe8 pc=0x805dc47 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x9849ff4 sp=0x9849ff0 pc=0x80b1f31 created by runtime.gcenable in goroutine 1 /workdir/go/src/runtime/mgc.go:204 +0xb7 goroutine 5 gp=0x9808c68 m=nil [finalizer wait]: runtime.gopark(0x815e0d4, 0x825347c, 0x10, 0xa, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x984a798 sp=0x984a784 pc=0x8080e4c runtime.runfinq() /workdir/go/src/runtime/mfinal.go:194 +0xfc fp=0x984a7f0 sp=0x984a798 pc=0x805cd6c runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x984a7f4 sp=0x984a7f0 pc=0x80b1f31 created by runtime.createfing in goroutine 1 /workdir/go/src/runtime/mfinal.go:164 +0x60 goroutine 6 gp=0x9808d88 m=nil [sleep]: runtime.gopark(0x815e154, 0x9c00030, 0x13, 0xe, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x9848790 sp=0x984877c pc=0x8080e4c time.Sleep(0x5f5e100) /workdir/go/src/runtime/time.go:195 +0x128 fp=0x98487ac sp=0x9848790 pc=0x80b0318 runtime/pprof.profileWriter({0x817ea40, 0x9910138}) /workdir/go/src/runtime/pprof/pprof.go:809 +0x59 fp=0x98487e4 sp=0x98487ac pc=0x8125419 runtime/pprof.StartCPUProfile.gowrap2() /workdir/go/src/runtime/pprof/pprof.go:794 +0x2e fp=0x98487f0 sp=0x98487e4 pc=0x812535e runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x98487f4 sp=0x98487f0 pc=0x80b1f31 created by runtime/pprof.StartCPUProfile in goroutine 1 /workdir/go/src/runtime/pprof/pprof.go:794 +0x11f goroutine 7 gp=0x9808ea8 m=nil [chan receive]: runtime.gopark(0x815e0bc, 0x981e1b0, 0xe, 0x7, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x984af4c sp=0x984af38 pc=0x8080e4c runtime.chanrecv(0x981e180, 0x0, 0x1) /workdir/go/src/runtime/chan.go:583 +0x3a3 fp=0x984af88 sp=0x984af4c pc=0x804da23 runtime.chanrecv1(0x981e180, 0x0) /workdir/go/src/runtime/chan.go:442 +0x1c fp=0x984af9c sp=0x984af88 pc=0x804d67c runtime.(*wakeableSleep).sleep(0x9812040, 0x5f5e100) /workdir/go/src/runtime/trace2.go:936 +0xc3 fp=0x984afd8 sp=0x984af9c pc=0x80a0a73 runtime.traceStartReadCPU.func1() /workdir/go/src/runtime/trace2cpu.go:54 +0x50 fp=0x984aff0 sp=0x984afd8 pc=0x80a1500 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x984aff4 sp=0x984aff0 pc=0x80b1f31 created by runtime.traceStartReadCPU in goroutine 1 /workdir/go/src/runtime/trace2cpu.go:42 +0x161 goroutine 8 gp=0x9808fc8 m=3 mp=0x984c808 [trace proc status]: runtime.systemstack_switch() /workdir/go/src/runtime/asm_386.s:341 fp=0x984b718 sp=0x984b714 pc=0x80b0b50 runtime.forEachP(...) /workdir/go/src/runtime/proc.go:1911 runtime.traceAdvance(0x0) /workdir/go/src/runtime/trace2.go:548 +0x5c4 fp=0x984b7d8 sp=0x984b718 pc=0x809f744 runtime.(*traceAdvancerState).start.func1() /workdir/go/src/runtime/trace2.go:882 +0x46 fp=0x984b7f0 sp=0x984b7d8 pc=0x80a0906 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x984b7f4 sp=0x984b7f0 pc=0x80b1f31 created by runtime.(*traceAdvancerState).start in goroutine 1 /workdir/go/src/runtime/trace2.go:876 +0x165 goroutine 9 gp=0x98090e8 m=nil [trace reader (blocked)]: runtime.gopark(0x815e0ac, 0x0, 0x18, 0xa, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x984bf98 sp=0x984bf84 pc=0x8080e4c runtime.ReadTrace() /workdir/go/src/runtime/trace2.go:677 +0x40 fp=0x984bfc8 sp=0x984bf98 pc=0x80a01d0 runtime/trace.Start.func1() /workdir/go/src/runtime/trace/trace.go:130 +0x4e fp=0x984bff0 sp=0x984bfc8 pc=0x812f28e runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x984bff4 sp=0x984bff0 pc=0x80b1f31 created by runtime/trace.Start in goroutine 1 /workdir/go/src/runtime/trace/trace.go:128 +0xc9 eax 0x0 ebx 0x9830220 ecx 0x984c408 edx 0x982f908 edi 0x0 esi 0x0 ebp 0x0 esp 0x9865e80 eip 0x80a5a27 eflags 0x10246 cs 0x23 fs 0x0 gs 0x63 exit status 2 trace_test.go:553: exit status 1 --- FAIL: TestTraceStacks (0.66s) --- FAIL: TestTraceStacks/Stress (0.23s) trace_test.go:551: stderr: SIGSEGV: segmentation violation PC=0x80ab757 m=0 sigcode=1 addr=0x0 goroutine 0 gp=0x820e8c0 m=0 mp=0x820eca0 [idle]: runtime.readgstatus(...) /workdir/go/src/runtime/proc.go:1056 runtime.traceWriter.writeProcStatusForP({{0x820eca0, 0xf3}, 0x0}, 0x882f908, 0x0) /workdir/go/src/runtime/trace2status.go:91 +0x67 fp=0xffa4a5bc sp=0xffa4a594 pc=0x80ab757 runtime.traceAdvance.func5(0x882f908) /workdir/go/src/runtime/trace2.go:551 +0x8f fp=0xffa4a5e8 sp=0xffa4a5bc pc=0x80b344f runtime.runSafePointFn() /workdir/go/src/runtime/proc.go:2044 +0x6a fp=0xffa4a600 sp=0xffa4a5e8 pc=0x808893a runtime.findRunnable() /workdir/go/src/runtime/proc.go:3169 +0x7e fp=0xffa4a6ac sp=0xffa4a600 pc=0x808a80e runtime.schedule() /workdir/go/src/runtime/proc.go:3884 +0xbd fp=0xffa4a6c8 sp=0xffa4a6ac pc=0x808c86d runtime.park_m(0x88827e8) /workdir/go/src/runtime/proc.go:4052 +0x1b5 fp=0xffa4a6e8 sp=0xffa4a6c8 pc=0x808cfb5 runtime.mcall(0x20e8c0) /workdir/go/src/runtime/asm_386.s:329 +0x44 fp=0xffa4a6f0 sp=0xffa4a6e8 pc=0x80b8534 goroutine 1 gp=0x8808128 m=nil [sleep]: runtime.gopark(0x8151fac, 0x88bc060, 0x13, 0xe, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x8856ee4 sp=0x8856ed0 pc=0x808566c time.Sleep(0x5f5e100) /workdir/go/src/runtime/time.go:195 +0x128 fp=0x8856f00 sp=0x8856ee4 pc=0x80b7dc8 main.main() /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:101 +0x5ff fp=0x8856fac sp=0x8856f00 pc=0x811f43f runtime.main() /workdir/go/src/runtime/proc.go:271 +0x2b6 fp=0x8856ff0 sp=0x8856fac pc=0x80851c6 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x8856ff4 sp=0x8856ff0 pc=0x80b9a41 goroutine 2 gp=0x88087e8 m=nil [force gc (idle)]: runtime.gopark(0x8151f8c, 0x820e4a8, 0x11, 0xa, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x8848fdc sp=0x8848fc8 pc=0x808566c runtime.goparkunlock(...) /workdir/go/src/runtime/proc.go:408 runtime.forcegchelper() /workdir/go/src/runtime/proc.go:326 +0xd3 fp=0x8848ff0 sp=0x8848fdc pc=0x80854b3 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x8848ff4 sp=0x8848ff0 pc=0x80b9a41 created by runtime.init.5 in goroutine 1 /workdir/go/src/runtime/proc.go:314 +0x23 goroutine 3 gp=0x8808b48 m=nil [GC sweep wait]: runtime.gopark(0x8151f8c, 0x820e600, 0xc, 0x9, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x88497cc sp=0x88497b8 pc=0x808566c runtime.goparkunlock(...) /workdir/go/src/runtime/proc.go:408 runtime.bgsweep(0x8820040) /workdir/go/src/runtime/mgcsweep.go:277 +0x9b fp=0x88497e8 sp=0x88497cc pc=0x80700ab runtime.gcenable.gowrap1() /workdir/go/src/runtime/mgc.go:203 +0x27 fp=0x88497f0 sp=0x88497e8 pc=0x80619e7 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x88497f4 sp=0x88497f0 pc=0x80b9a41 created by runtime.gcenable in goroutine 1 /workdir/go/src/runtime/mgc.go:203 +0x77 goroutine 4 gp=0x8808c68 m=nil [GC scavenge wait]: runtime.gopark(0x8151f8c, 0x820e840, 0xd, 0xa, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x8849fb8 sp=0x8849fa4 pc=0x808566c runtime.goparkunlock(...) /workdir/go/src/runtime/proc.go:408 runtime.(*scavengerState).park(0x820e840) /workdir/go/src/runtime/mgcscavenge.go:425 +0x60 fp=0x8849fcc sp=0x8849fb8 pc=0x806d5c0 runtime.bgscavenge(0x8820040) /workdir/go/src/runtime/mgcscavenge.go:653 +0x4b fp=0x8849fe8 sp=0x8849fcc pc=0x806dc2b runtime.gcenable.gowrap2() /workdir/go/src/runtime/mgc.go:204 +0x27 fp=0x8849ff0 sp=0x8849fe8 pc=0x80619a7 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x8849ff4 sp=0x8849ff0 pc=0x80b9a41 created by runtime.gcenable in goroutine 1 /workdir/go/src/runtime/mgc.go:204 +0xb7 goroutine 18 gp=0x8882488 m=nil [finalizer wait]: runtime.gopark(0x8151e3c, 0x823a2b8, 0x10, 0xa, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x8848798 sp=0x8848784 pc=0x808566c runtime.runfinq() /workdir/go/src/runtime/mfinal.go:194 +0xfc fp=0x88487f0 sp=0x8848798 pc=0x8060acc runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x88487f4 sp=0x88487f0 pc=0x80b9a41 created by runtime.createfing in goroutine 1 /workdir/go/src/runtime/mfinal.go:164 +0x60 goroutine 19 gp=0x88825a8 m=nil [chan receive]: runtime.gopark(0x8151e20, 0x888e0b0, 0xe, 0x7, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x884474c sp=0x8844738 pc=0x808566c runtime.chanrecv(0x888e080, 0x0, 0x1) /workdir/go/src/runtime/chan.go:583 +0x3a3 fp=0x8844788 sp=0x884474c pc=0x804f1d3 runtime.chanrecv1(0x888e080, 0x0) /workdir/go/src/runtime/chan.go:442 +0x1c fp=0x884479c sp=0x8844788 pc=0x804ee2c runtime.(*wakeableSleep).sleep(0x889a030, 0x5f5e100) /workdir/go/src/runtime/trace2.go:936 +0xc3 fp=0x88447d8 sp=0x884479c pc=0x80a67a3 runtime.traceStartReadCPU.func1() /workdir/go/src/runtime/trace2cpu.go:54 +0x50 fp=0x88447f0 sp=0x88447d8 pc=0x80a7230 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x88447f4 sp=0x88447f0 pc=0x80b9a41 created by runtime.traceStartReadCPU in goroutine 1 /workdir/go/src/runtime/trace2cpu.go:42 +0x161 goroutine 20 gp=0x88826c8 m=5 mp=0x8880008 [trace proc status]: runtime.systemstack_switch() /workdir/go/src/runtime/asm_386.s:341 fp=0x8844f18 sp=0x8844f14 pc=0x80b8540 runtime.forEachP(...) /workdir/go/src/runtime/proc.go:1911 runtime.traceAdvance(0x0) /workdir/go/src/runtime/trace2.go:548 +0x5c4 fp=0x8844fd8 sp=0x8844f18 pc=0x80a5474 runtime.(*traceAdvancerState).start.func1() /workdir/go/src/runtime/trace2.go:882 +0x46 fp=0x8844ff0 sp=0x8844fd8 pc=0x80a6636 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x8844ff4 sp=0x8844ff0 pc=0x80b9a41 created by runtime.(*traceAdvancerState).start in goroutine 1 /workdir/go/src/runtime/trace2.go:876 +0x165 goroutine 21 gp=0x88827e8 m=nil [trace reader (blocked)]: runtime.gopark(0x8151e0c, 0x0, 0x18, 0xa, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x8845798 sp=0x8845784 pc=0x808566c runtime.ReadTrace() /workdir/go/src/runtime/trace2.go:677 +0x40 fp=0x88457c8 sp=0x8845798 pc=0x80a5f00 runtime/trace.Start.func1() /workdir/go/src/runtime/trace/trace.go:130 +0x4e fp=0x88457f0 sp=0x88457c8 pc=0x811ecfe runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x88457f4 sp=0x88457f0 pc=0x80b9a41 created by runtime/trace.Start in goroutine 1 /workdir/go/src/runtime/trace/trace.go:128 +0xc9 goroutine 22 gp=0x8882908 m=nil [select (no cases)]: runtime.gopark(0x0, 0x0, 0xa, 0x1, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x8845fd8 sp=0x8845fc4 pc=0x808566c runtime.block() /workdir/go/src/runtime/select.go:103 +0x38 fp=0x8845fec sp=0x8845fd8 pc=0x8096948 main.main.func1() /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:33 +0x17 fp=0x8845ff0 sp=0x8845fec pc=0x811fb27 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x8845ff4 sp=0x8845ff0 pc=0x80b9a41 created by main.main in goroutine 1 /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:32 +0xdd goroutine 23 gp=0x8882a28 m=nil [chan send (nil chan)]: runtime.gopark(0x0, 0x0, 0x4, 0x1, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x8846794 sp=0x8846780 pc=0x808566c runtime.chansend(0x0, 0x81719fc, 0x1, 0x811fb5b) /workdir/go/src/runtime/chan.go:165 +0x463 fp=0x88467cc sp=0x8846794 pc=0x804e7e3 runtime.chansend1(0x0, 0x81719fc) /workdir/go/src/runtime/chan.go:145 +0x24 fp=0x88467e4 sp=0x88467cc pc=0x804e374 main.main.func2() /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:37 +0x2b fp=0x88467f0 sp=0x88467e4 pc=0x811fb5b runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x88467f4 sp=0x88467f0 pc=0x80b9a41 created by main.main in goroutine 1 /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:35 +0xeb goroutine 24 gp=0x8882b48 m=nil [chan receive (nil chan)]: runtime.gopark(0x0, 0x0, 0x3, 0x1, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x8846f94 sp=0x8846f80 pc=0x808566c runtime.chanrecv(0x0, 0x0, 0x1) /workdir/go/src/runtime/chan.go:469 +0x513 fp=0x8846fd0 sp=0x8846f94 pc=0x804f343 runtime.chanrecv1(0x0, 0x0) /workdir/go/src/runtime/chan.go:442 +0x1c fp=0x8846fe4 sp=0x8846fd0 pc=0x804ee2c main.main.func3() /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:41 +0x29 fp=0x8846ff0 sp=0x8846fe4 pc=0x811fb99 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x8846ff4 sp=0x8846ff0 pc=0x80b9a41 created by main.main in goroutine 1 /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:39 +0xf9 goroutine 25 gp=0x8882c68 m=nil [chan receive]: runtime.gopark(0x8151e20, 0x888e1b0, 0xe, 0x7, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x8847794 sp=0x8847780 pc=0x808566c runtime.chanrecv(0x888e180, 0x0, 0x1) /workdir/go/src/runtime/chan.go:583 +0x3a3 fp=0x88477d0 sp=0x8847794 pc=0x804f1d3 runtime.chanrecv1(0x888e180, 0x0) /workdir/go/src/runtime/chan.go:442 +0x1c fp=0x88477e4 sp=0x88477d0 pc=0x804ee2c main.main.func4() /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:45 +0x28 fp=0x88477f0 sp=0x88477e4 pc=0x811faf8 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x88477f4 sp=0x88477f0 pc=0x80b9a41 created by main.main in goroutine 1 /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:44 +0x157 goroutine 26 gp=0x8882d88 m=nil [chan send]: runtime.gopark(0x8151e20, 0x888e1f0, 0xf, 0x6, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x8847f94 sp=0x8847f80 pc=0x808566c runtime.chansend(0x888e1c0, 0x8170c08, 0x1, 0x811faba) /workdir/go/src/runtime/chan.go:259 +0x2a5 fp=0x8847fcc sp=0x8847f94 pc=0x804e625 runtime.chansend1(0x888e1c0, 0x8170c08) /workdir/go/src/runtime/chan.go:145 +0x24 fp=0x8847fe4 sp=0x8847fcc pc=0x804e374 main.main.func5() /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:49 +0x2a fp=0x8847ff0 sp=0x8847fe4 pc=0x811faba runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x8847ff4 sp=0x8847ff0 pc=0x80b9a41 created by main.main in goroutine 1 /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:48 +0x1b5 goroutine 27 gp=0x8882ea8 m=nil [select]: runtime.gopark(0x8151fc0, 0x0, 0x9, 0x3, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x88c26d0 sp=0x88c26bc pc=0x808566c runtime.selectgo(0x88c27dc, 0x88c27d4, 0x0, 0x0, 0x2, 0x1) /workdir/go/src/runtime/select.go:327 +0xb27 fp=0x88c27b4 sp=0x88c26d0 pc=0x8097487 main.main.func6() /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:54 +0x74 fp=0x88c27f0 sp=0x88c27b4 pc=0x811fa84 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x88c27f4 sp=0x88c27f0 pc=0x80b9a41 created by main.main in goroutine 1 /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:53 +0x23f goroutine 28 gp=0x8882fc8 m=nil [sync.Mutex.Lock]: runtime.gopark(0x8151f8c, 0x8213fe0, 0x15, 0x5, 0x5) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x88c2f48 sp=0x88c2f34 pc=0x808566c runtime.goparkunlock(...) /workdir/go/src/runtime/proc.go:408 runtime.semacquire1(0x88a2064, 0x0, 0x3, 0x1, 0x15) /workdir/go/src/runtime/sema.go:160 +0x291 fp=0x88c2f78 sp=0x88c2f48 pc=0x8098021 sync.runtime_SemacquireMutex(0x88a2064, 0x0, 0x1) /workdir/go/src/runtime/sema.go:77 +0x3f fp=0x88c2f90 sp=0x88c2f78 pc=0x80b754f sync.(*Mutex).lockSlow(0x88a2060) /workdir/go/src/sync/mutex.go:171 +0x247 fp=0x88c2fd0 sp=0x88c2f90 pc=0x80c51d7 sync.(*Mutex).Lock(0x88a2060) /workdir/go/src/sync/mutex.go:90 +0x4c fp=0x88c2fe4 sp=0x88c2fd0 pc=0x80c4f7c main.main.func7() /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:62 +0x24 fp=0x88c2ff0 sp=0x88c2fe4 pc=0x811f9f4 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x88c2ff4 sp=0x88c2ff0 pc=0x80b9a41 created by main.main in goroutine 1 /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:61 +0x2a6 goroutine 29 gp=0x88830e8 m=nil [semacquire]: runtime.gopark(0x8151f8c, 0x82140a0, 0x12, 0x5, 0x4) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x88c3784 sp=0x88c3770 pc=0x808566c runtime.goparkunlock(...) /workdir/go/src/runtime/proc.go:408 runtime.semacquire1(0x88a2078, 0x0, 0x1, 0x0, 0x12) /workdir/go/src/runtime/sema.go:160 +0x291 fp=0x88c37b4 sp=0x88c3784 pc=0x8098021 sync.runtime_Semacquire(0x88a2078) /workdir/go/src/runtime/sema.go:62 +0x3b fp=0x88c37cc sp=0x88c37b4 pc=0x80b746b sync.(*WaitGroup).Wait(0x88a2070) /workdir/go/src/sync/waitgroup.go:116 +0x65 fp=0x88c37e8 sp=0x88c37cc pc=0x80c68f5 main.main.func8() /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:68 +0x20 fp=0x88c37f0 sp=0x88c37e8 pc=0x811f9c0 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x88c37f4 sp=0x88c37f0 pc=0x80b9a41 created by main.main in goroutine 1 /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:67 +0x315 goroutine 30 gp=0x8883208 m=nil [sync.Cond.Wait]: runtime.gopark(0x8151f8c, 0x889c270, 0x14, 0x4, 0x3) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x88c3f98 sp=0x88c3f84 pc=0x808566c runtime.goparkunlock(...) /workdir/go/src/runtime/proc.go:408 sync.runtime_notifyListWait(0x889c268, 0x0) /workdir/go/src/runtime/sema.go:569 +0x179 fp=0x88c3fc0 sp=0x88c3f98 pc=0x80b77f9 sync.(*Cond).Wait(0x889c260) /workdir/go/src/sync/cond.go:70 +0xa2 fp=0x88c3fe4 sp=0x88c3fc0 pc=0x80c42d2 main.main.func9() /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:73 +0x35 fp=0x88c3ff0 sp=0x88c3fe4 pc=0x811f975 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x88c3ff4 sp=0x88c3ff0 pc=0x80b9a41 created by main.main in goroutine 1 /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:71 +0x3a7 goroutine 31 gp=0x8883328 m=nil [IO wait]: runtime.gopark(0x8151f7c, 0xf6235760, 0x2, 0x2, 0x5) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x88c4690 sp=0x88c467c pc=0x808566c runtime.netpollblock(0xf6235750, 0x72, 0x0) /workdir/go/src/runtime/netpoll.go:573 +0xea fp=0x88c46a8 sp=0x88c4690 pc=0x807e08a internal/poll.runtime_pollWait(0xf6235750, 0x72) /workdir/go/src/runtime/netpoll.go:345 +0x52 fp=0x88c46bc sp=0x88c46a8 pc=0x80b60e2 internal/poll.(*pollDesc).wait(0x88ca018, 0x72, 0x0) /workdir/go/src/internal/poll/fd_poll_runtime.go:84 +0x37 fp=0x88c46d0 sp=0x88c46bc pc=0x80dfd27 internal/poll.(*pollDesc).waitRead(...) /workdir/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Accept(0x88ca000) /workdir/go/src/internal/poll/fd_unix.go:611 +0x28d fp=0x88c4718 sp=0x88c46d0 pc=0x80e0c9d net.(*netFD).accept(0x88ca000) /workdir/go/src/net/fd_unix.go:172 +0x27 fp=0x88c4780 sp=0x88c4718 pc=0x810cec7 net.(*TCPListener).accept(0x88c8060) /workdir/go/src/net/tcpsock_posix.go:159 +0x23 fp=0x88c4798 sp=0x88c4780 pc=0x811a923 net.(*TCPListener).Accept(0x88c8060) /workdir/go/src/net/tcpsock.go:327 +0x37 fp=0x88c47b4 sp=0x88c4798 pc=0x8119d77 main.main.func10() /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:81 +0x27 fp=0x88c47f0 sp=0x88c47b4 pc=0x811f807 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x88c47f4 sp=0x88c47f0 pc=0x80b9a41 created by main.main in goroutine 1 /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:80 +0x48c goroutine 32 gp=0x8883448 m=nil [IO wait]: runtime.gopark(0x8151f7c, 0xf62356c0, 0x2, 0x2, 0x5) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x88c4f1c sp=0x88c4f08 pc=0x808566c runtime.netpollblock(0xf62356b0, 0x72, 0x0) /workdir/go/src/runtime/netpoll.go:573 +0xea fp=0x88c4f34 sp=0x88c4f1c pc=0x807e08a internal/poll.runtime_pollWait(0xf62356b0, 0x72) /workdir/go/src/runtime/netpoll.go:345 +0x52 fp=0x88c4f48 sp=0x88c4f34 pc=0x80b60e2 internal/poll.(*pollDesc).wait(0x88a4158, 0x72, 0x1) /workdir/go/src/internal/poll/fd_poll_runtime.go:84 +0x37 fp=0x88c4f5c sp=0x88c4f48 pc=0x80dfd27 internal/poll.(*pollDesc).waitRead(...) /workdir/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0x88a4140, {0x88c4fe7, 0x1, 0x1}) /workdir/go/src/internal/poll/fd_unix.go:164 +0x20c fp=0x88c4fa4 sp=0x88c4f5c pc=0x80e052c os.(*File).read(...) /workdir/go/src/os/file_posix.go:29 os.(*File).Read(0x88980f0, {0x88c4fe7, 0x1, 0x1}) /workdir/go/src/os/file.go:118 +0x70 fp=0x88c4fc8 sp=0x88c4fa4 pc=0x80e19c0 main.main.func11() /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:97 +0x44 fp=0x88c4ff0 sp=0x88c4fc8 pc=0x811f714 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x88c4ff4 sp=0x88c4ff0 pc=0x80b9a41 created by main.main in goroutine 1 /workdir/go/src/internal/trace/v2/testdata/testprog/stacks.go:95 +0x5eb eax 0x0 ebx 0x883021c ecx 0x820eca0 edx 0x882f908 edi 0x8210078 esi 0x1 ebp 0x1 esp 0xffa4a594 eip 0x80ab757 eflags 0x10246 cs 0x23 fs 0x0 gs 0x63 exit status 2 trace_test.go:553: exit status 1 --- FAIL: TestTraceStress (0.67s) --- FAIL: TestTraceStress/Stress (0.22s) trace_test.go:551: stderr: SIGSEGV: segmentation violation PC=0x80ab7a7 m=7 sigcode=1 addr=0x0 goroutine 0 gp=0x9aa2128 m=7 mp=0x9aa0008 [idle]: runtime.readgstatus(...) /workdir/go/src/runtime/proc.go:1056 runtime.traceWriter.writeProcStatusForP({{0x9aa0008, 0x82}, 0x0}, 0x982c008, 0x0) /workdir/go/src/runtime/trace2status.go:91 +0x67 fp=0xf4aff1e8 sp=0xf4aff1c0 pc=0x80ab7a7 runtime.traceAdvance.func5(0x982c008) /workdir/go/src/runtime/trace2.go:551 +0x8f fp=0xf4aff214 sp=0xf4aff1e8 pc=0x80b349f runtime.runSafePointFn() /workdir/go/src/runtime/proc.go:2044 +0x6a fp=0xf4aff22c sp=0xf4aff214 pc=0x80888fa runtime.findRunnable() /workdir/go/src/runtime/proc.go:3169 +0x7e fp=0xf4aff2d8 sp=0xf4aff22c pc=0x808a7ce runtime.schedule() /workdir/go/src/runtime/proc.go:3884 +0xbd fp=0xf4aff2f4 sp=0xf4aff2d8 pc=0x808c82d runtime.park_m(0x991c248) /workdir/go/src/runtime/proc.go:4052 +0x1b5 fp=0xf4aff314 sp=0xf4aff2f4 pc=0x808cf75 runtime.mcall(0x1e72d00) /workdir/go/src/runtime/asm_386.s:329 +0x44 fp=0xf4aff31c sp=0xf4aff314 pc=0x80b81d4 goroutine 1 gp=0x9808128 m=nil [sleep]: runtime.gopark(0x8151ad0, 0x98aa000, 0x13, 0xe, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x9856f08 sp=0x9856ef4 pc=0x808562c time.Sleep(0x2faf080) /workdir/go/src/runtime/time.go:195 +0x128 fp=0x9856f24 sp=0x9856f08 pc=0x80b7a68 main.main() /workdir/go/src/internal/trace/v2/testdata/testprog/stress.go:57 +0x2a3 fp=0x9856fac sp=0x9856f24 pc=0x811e9f3 runtime.main() /workdir/go/src/runtime/proc.go:271 +0x2b6 fp=0x9856ff0 sp=0x9856fac pc=0x8085186 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x9856ff4 sp=0x9856ff0 pc=0x80b96e1 goroutine 2 gp=0x98087e8 m=nil [force gc (idle)]: runtime.gopark(0x8151ab0, 0x820d4a8, 0x11, 0xa, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x9848fdc sp=0x9848fc8 pc=0x808562c runtime.goparkunlock(...) /workdir/go/src/runtime/proc.go:408 runtime.forcegchelper() /workdir/go/src/runtime/proc.go:326 +0xd3 fp=0x9848ff0 sp=0x9848fdc pc=0x8085473 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x9848ff4 sp=0x9848ff0 pc=0x80b96e1 created by runtime.init.5 in goroutine 1 /workdir/go/src/runtime/proc.go:314 +0x23 goroutine 3 gp=0x9808b48 m=nil [GC sweep wait]: runtime.gopark(0x8151ab0, 0x820d600, 0xc, 0x9, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x98497cc sp=0x98497b8 pc=0x808562c runtime.goparkunlock(...) /workdir/go/src/runtime/proc.go:408 runtime.bgsweep(0x9820040) /workdir/go/src/runtime/mgcsweep.go:277 +0x9b fp=0x98497e8 sp=0x98497cc pc=0x807006b runtime.gcenable.gowrap1() /workdir/go/src/runtime/mgc.go:203 +0x27 fp=0x98497f0 sp=0x98497e8 pc=0x80619a7 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x98497f4 sp=0x98497f0 pc=0x80b96e1 created by runtime.gcenable in goroutine 1 /workdir/go/src/runtime/mgc.go:203 +0x77 goroutine 4 gp=0x9808c68 m=nil [GC scavenge wait]: runtime.gopark(0x8151ab0, 0x820d840, 0xd, 0xa, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x9849fb8 sp=0x9849fa4 pc=0x808562c runtime.goparkunlock(...) /workdir/go/src/runtime/proc.go:408 runtime.(*scavengerState).park(0x820d840) /workdir/go/src/runtime/mgcscavenge.go:425 +0x60 fp=0x9849fcc sp=0x9849fb8 pc=0x806d580 runtime.bgscavenge(0x9820040) /workdir/go/src/runtime/mgcscavenge.go:653 +0x4b fp=0x9849fe8 sp=0x9849fcc pc=0x806dbeb runtime.gcenable.gowrap2() /workdir/go/src/runtime/mgc.go:204 +0x27 fp=0x9849ff0 sp=0x9849fe8 pc=0x8061967 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x9849ff4 sp=0x9849ff0 pc=0x80b96e1 created by runtime.gcenable in goroutine 1 /workdir/go/src/runtime/mgc.go:204 +0xb7 goroutine 18 gp=0x9882488 m=nil [finalizer wait]: runtime.gopark(0x8151960, 0x82392b8, 0x10, 0xa, 0x1) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x9848798 sp=0x9848784 pc=0x808562c runtime.runfinq() /workdir/go/src/runtime/mfinal.go:194 +0xfc fp=0x98487f0 sp=0x9848798 pc=0x8060a8c runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x98487f4 sp=0x98487f0 pc=0x80b96e1 created by runtime.createfing in goroutine 1 /workdir/go/src/runtime/mfinal.go:164 +0x60 goroutine 19 gp=0x98825a8 m=nil [chan receive]: runtime.gopark(0x8151944, 0x988e0b0, 0xe, 0x7, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x9844790 sp=0x984477c pc=0x808562c runtime.chanrecv(0x988e080, 0x0, 0x1) /workdir/go/src/runtime/chan.go:583 +0x3a3 fp=0x98447cc sp=0x9844790 pc=0x804f193 runtime.chanrecv1(0x988e080, 0x0) /workdir/go/src/runtime/chan.go:442 +0x1c fp=0x98447e0 sp=0x98447cc pc=0x804edec main.main.func1() /workdir/go/src/internal/trace/v2/testdata/testprog/stress.go:29 +0x2f fp=0x98447f0 sp=0x98447e0 pc=0x811f25f runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x98447f4 sp=0x98447f0 pc=0x80b96e1 created by main.main in goroutine 1 /workdir/go/src/internal/trace/v2/testdata/testprog/stress.go:28 +0xcf goroutine 20 gp=0x98826c8 m=nil [IO wait]: runtime.gopark(0x8151aa0, 0xf56c3f08, 0x2, 0x2, 0x5) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x9844f18 sp=0x9844f04 pc=0x808562c runtime.netpollblock(0xf56c3ef8, 0x72, 0x0) /workdir/go/src/runtime/netpoll.go:573 +0xea fp=0x9844f30 sp=0x9844f18 pc=0x807e04a internal/poll.runtime_pollWait(0xf56c3ef8, 0x72) /workdir/go/src/runtime/netpoll.go:345 +0x52 fp=0x9844f44 sp=0x9844f30 pc=0x80b6132 internal/poll.(*pollDesc).wait(0x98a40d8, 0x72, 0x1) /workdir/go/src/internal/poll/fd_poll_runtime.go:84 +0x37 fp=0x9844f58 sp=0x9844f44 pc=0x80df747 internal/poll.(*pollDesc).waitRead(...) /workdir/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0x98a40c0, {0x9844fe3, 0x1, 0x1}) /workdir/go/src/internal/poll/fd_unix.go:164 +0x20c fp=0x9844fa0 sp=0x9844f58 pc=0x80dff4c os.(*File).read(...) /workdir/go/src/os/file_posix.go:29 os.(*File).Read(0x9898088, {0x9844fe3, 0x1, 0x1}) /workdir/go/src/os/file.go:118 +0x70 fp=0x9844fc4 sp=0x9844fa0 pc=0x80e13e0 main.main.func3() /workdir/go/src/internal/trace/v2/testdata/testprog/stress.go:45 +0x4b fp=0x9844ff0 sp=0x9844fc4 pc=0x811f1ab runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x9844ff4 sp=0x9844ff0 pc=0x80b96e1 created by main.main in goroutine 1 /workdir/go/src/internal/trace/v2/testdata/testprog/stress.go:43 +0x1e9 goroutine 34 gp=0x991c008 m=nil [chan receive]: runtime.gopark(0x8151944, 0x9916030, 0xe, 0x7, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x992274c sp=0x9922738 pc=0x808562c runtime.chanrecv(0x9916000, 0x0, 0x1) /workdir/go/src/runtime/chan.go:583 +0x3a3 fp=0x9922788 sp=0x992274c pc=0x804f193 runtime.chanrecv1(0x9916000, 0x0) /workdir/go/src/runtime/chan.go:442 +0x1c fp=0x992279c sp=0x9922788 pc=0x804edec runtime.(*wakeableSleep).sleep(0x9914000, 0x5f5e100) /workdir/go/src/runtime/trace2.go:936 +0xc3 fp=0x99227d8 sp=0x992279c pc=0x80a67f3 runtime.traceStartReadCPU.func1() /workdir/go/src/runtime/trace2cpu.go:54 +0x50 fp=0x99227f0 sp=0x99227d8 pc=0x80a7280 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x99227f4 sp=0x99227f0 pc=0x80b96e1 created by runtime.traceStartReadCPU in goroutine 1 /workdir/go/src/runtime/trace2cpu.go:42 +0x161 goroutine 35 gp=0x991c128 m=5 mp=0x9880008 [trace proc status]: runtime.systemstack_switch() /workdir/go/src/runtime/asm_386.s:341 fp=0x9922f18 sp=0x9922f14 pc=0x80b81e0 runtime.forEachP(...) /workdir/go/src/runtime/proc.go:1911 runtime.traceAdvance(0x0) /workdir/go/src/runtime/trace2.go:548 +0x5c4 fp=0x9922fd8 sp=0x9922f18 pc=0x80a54c4 runtime.(*traceAdvancerState).start.func1() /workdir/go/src/runtime/trace2.go:882 +0x46 fp=0x9922ff0 sp=0x9922fd8 pc=0x80a6686 runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x9922ff4 sp=0x9922ff0 pc=0x80b96e1 created by runtime.(*traceAdvancerState).start in goroutine 1 /workdir/go/src/runtime/trace2.go:876 +0x165 goroutine 36 gp=0x991c248 m=nil [trace reader (blocked)]: runtime.gopark(0x8151930, 0x0, 0x18, 0xa, 0x2) /workdir/go/src/runtime/proc.go:402 +0x10c fp=0x9923798 sp=0x9923784 pc=0x808562c runtime.ReadTrace() /workdir/go/src/runtime/trace2.go:677 +0x40 fp=0x99237c8 sp=0x9923798 pc=0x80a5f50 runtime/trace.Start.func1() /workdir/go/src/runtime/trace/trace.go:130 +0x4e fp=0x99237f0 sp=0x99237c8 pc=0x811e60e runtime.goexit({}) /workdir/go/src/runtime/asm_386.s:1363 +0x1 fp=0x99237f4 sp=0x99237f0 pc=0x80b96e1 created by runtime/trace.Start in goroutine 1 /workdir/go/src/runtime/trace/trace.go:128 +0xc9 eax 0x0 ebx 0x982c920 ecx 0x9aa0008 edx 0x982c008 edi 0x820f078 esi 0x1 ebp 0x1 esp 0xf4aff1c0 eip 0x80ab7a7 eflags 0x10246 cs 0x23 fs 0x0 gs 0x63 exit status 2 trace_test.go:553: exit status 1 FAIL FAIL internal/trace/v2 7.208s FAIL go tool dist: Failed: exit status 1