Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

x/build: android-amd64-emu builder timeouts in runtime tests #32109

Closed
FiloSottile opened this issue May 17, 2019 · 11 comments
Closed

x/build: android-amd64-emu builder timeouts in runtime tests #32109

FiloSottile opened this issue May 17, 2019 · 11 comments

Comments

@bradfitz
Copy link
Contributor

@bradfitz bradfitz commented May 17, 2019

I see no goroutines blocked for 1min+ (no substring "min" in either of those logs), and everything looks mostly in state running.

Maybe these are just legit timeouts after 3 minutes? Crank up GO_TEST_TIMEOUT_SCALE to perhaps just 2 or 3 on this builder?

@eliasnaur
Copy link
Contributor

@eliasnaur eliasnaur commented May 17, 2019

I find it odd that the timeout is in minutes but the reported time after "FAIL" is seconds, sometimes less than 10 seconds.

@eliasnaur
Copy link
Contributor

@eliasnaur eliasnaur commented May 25, 2019

I managed to reproduce the runtime crash with the odd timings. I had the following change applied to my local GOROOT:

$ git diff
diff --git a/src/cmd/go/internal/test/test.go b/src/cmd/go/internal/test/test.go
index 8440a83951..634cc6bd0e 100644
--- a/src/cmd/go/internal/test/test.go
+++ b/src/cmd/go/internal/test/test.go
@@ -1201,7 +1201,8 @@ func (c *runCache) builderRunTest(b *work.Builder, a *work.Action) error {
        }
        out := buf.Bytes()
        a.TestOutput = &buf
-       t := fmt.Sprintf("%.3fs", time.Since(t0).Seconds())
+       now := time.Now()
+       t := fmt.Sprintf("%.3fs t0 %v now %v", now.Sub(t0).Seconds(), t0, now)
 
        mergeCoverProfile(cmd.Stdout, a.Objdir+"_cover_.out")
 
diff --git a/src/testing/testing.go b/src/testing/testing.go
index 339df13f43..c500db5203 100644
--- a/src/testing/testing.go
+++ b/src/testing/testing.go
@@ -1376,10 +1376,12 @@ func toOutputDir(path string) string {
 // startAlarm starts an alarm if requested.
 func (m *M) startAlarm() {
        if *timeout > 0 {
+               t0 := time.Now()
                m.timer = time.AfterFunc(*timeout, func() {
+                       now := time.Now()
                        m.after()
                        debug.SetTraceback("all")
-                       panic(fmt.Sprintf("test timed out after %v", *timeout))
+                       panic(fmt.Sprintf("test timed out after %v t0 %v now %v", *timeout, t0, now))
                })
        }
 }

The crash was:

Building Go cmd/dist using /go1.4. Building Go toolchain1 using /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 host, linux/amd64. Building packages and commands for target, android/amd64.
Testing packages.

ok archive/tar 25.379s t0 2019-05-24 17:46:14.59233666 +0000 UTC m=+4.543144475 now 2019-05-24 17:46:39.971607355 +0000 UTC m=+29.922415187
ok archive/zip 24.076s t0 2019-05-24 17:46:14.59209504 +0000 UTC m=+4.542902843 now 2019-05-24 17:46:38.667828772 +0000 UTC m=+28.618636703
ok bufio 24.253s t0 2019-05-24 17:46:14.608764173 +0000 UTC m=+4.559571993 now 2019-05-24 17:46:38.861967175 +0000 UTC m=+28.812775023
ok bytes 23.144s t0 2019-05-24 17:46:16.144058785 +0000 UTC m=+6.094866593 now 2019-05-24 17:46:39.288131377 +0000 UTC m=+29.238939300
ok compress/bzip2 0.398s t0 2019-05-24 17:46:39.152403354 +0000 UTC m=+29.103211187 now 2019-05-24 17:46:39.550007152 +0000 UTC m=+29.500815049
ok compress/flate 1.420s t0 2019-05-24 17:46:40.56768845 +0000 UTC m=+30.518496258 now 2019-05-24 17:46:41.98817648 +0000 UTC m=+31.938984343
ok compress/gzip 0.246s t0 2019-05-24 17:46:40.38346001 +0000 UTC m=+30.334267817 now 2019-05-24 17:46:40.629082099 +0000 UTC m=+30.579889928
ok compress/lzw 1.370s t0 2019-05-24 17:46:40.726666592 +0000 UTC m=+30.677474410 now 2019-05-24 17:46:42.09678532 +0000 UTC m=+32.047593130
ok compress/zlib 1.435s t0 2019-05-24 17:46:40.941844661 +0000 UTC m=+30.892652584 now 2019-05-24 17:46:42.376787688 +0000 UTC m=+32.327595541
ok container/heap 1.415s t0 2019-05-24 17:46:41.227708261 +0000 UTC m=+31.178516083 now 2019-05-24 17:46:42.643090855 +0000 UTC m=+32.593898703
ok container/list 0.274s t0 2019-05-24 17:46:42.544020517 +0000 UTC m=+32.494828332 now 2019-05-24 17:46:42.818236535 +0000 UTC m=+32.769044388
ok container/ring 0.854s t0 2019-05-24 17:46:42.660674942 +0000 UTC m=+32.611482798 now 2019-05-24 17:46:43.515088095 +0000 UTC m=+33.465895902
ok context 1.511s t0 2019-05-24 17:46:46.286082499 +0000 UTC m=+36.236890326 now 2019-05-24 17:46:47.797101076 +0000 UTC m=+37.747908928
ok crypto 0.526s t0 2019-05-24 17:46:43.693605981 +0000 UTC m=+33.644413830 now 2019-05-24 17:46:44.21944296 +0000 UTC m=+34.170250857
ok crypto/aes 0.486s t0 2019-05-24 17:46:44.1661945 +0000 UTC m=+34.117002322 now 2019-05-24 17:46:44.651922412 +0000 UTC m=+34.602730248
ok crypto/cipher 0.376s t0 2019-05-24 17:46:44.867933701 +0000 UTC m=+34.818741542 now 2019-05-24 17:46:45.244216621 +0000 UTC m=+35.195024466
ok crypto/des 0.729s t0 2019-05-24 17:46:45.437050599 +0000 UTC m=+35.387858452 now 2019-05-24 17:46:46.166188437 +0000 UTC m=+36.116996264
ok crypto/dsa 0.199s t0 2019-05-24 17:46:45.385221302 +0000 UTC m=+35.336029110 now 2019-05-24 17:46:45.583851385 +0000 UTC m=+35.534659203
ok crypto/ecdsa 0.379s t0 2019-05-24 17:46:46.277910086 +0000 UTC m=+36.228717900 now 2019-05-24 17:46:46.656596332 +0000 UTC m=+36.607404157
ok crypto/ed25519 1.289s t0 2019-05-24 17:46:46.667018425 +0000 UTC m=+36.617826265 now 2019-05-24 17:46:47.955975954 +0000 UTC m=+37.906783794
ok crypto/elliptic 1.156s t0 2019-05-24 17:46:47.058255468 +0000 UTC m=+37.009063289 now 2019-05-24 17:46:48.213919361 +0000 UTC m=+38.164727180
ok crypto/hmac 1.296s t0 2019-05-24 17:46:47.135390304 +0000 UTC m=+37.086198113 now 2019-05-24 17:46:48.431829548 +0000 UTC m=+38.382637465
ok crypto/internal/subtle 0.470s t0 2019-05-24 17:46:48.171066792 +0000 UTC m=+38.121874616 now 2019-05-24 17:46:48.640579387 +0000 UTC m=+38.591387480
ok crypto/md5 0.355s t0 2019-05-24 17:46:48.543284437 +0000 UTC m=+38.494092340 now 2019-05-24 17:46:48.898392461 +0000 UTC m=+38.849200299
ok crypto/rand 0.454s t0 2019-05-24 17:46:48.89264945 +0000 UTC m=+38.843457298 now 2019-05-24 17:46:49.346573524 +0000 UTC m=+39.297381484
ok crypto/rc4 0.530s t0 2019-05-24 17:46:49.089047624 +0000 UTC m=+39.039855564 now 2019-05-24 17:46:49.61881626 +0000 UTC m=+39.569624092
ok crypto/rsa 0.368s t0 2019-05-24 17:46:49.587634599 +0000 UTC m=+39.538442521 now 2019-05-24 17:46:49.955362971 +0000 UTC m=+39.906170800
ok crypto/sha1 0.510s t0 2019-05-24 17:46:49.694997102 +0000 UTC m=+39.645804950 now 2019-05-24 17:46:50.205486119 +0000 UTC m=+40.156293936
ok crypto/sha256 0.288s t0 2019-05-24 17:46:50.170317391 +0000 UTC m=+40.121125223 now 2019-05-24 17:46:50.458068585 +0000 UTC m=+40.408876458
ok crypto/sha512 0.298s t0 2019-05-24 17:46:50.372752552 +0000 UTC m=+40.323560383 now 2019-05-24 17:46:50.671042808 +0000 UTC m=+40.621850696
ok crypto/subtle 0.577s t0 2019-05-24 17:46:50.599652781 +0000 UTC m=+40.550460604 now 2019-05-24 17:46:51.176790792 +0000 UTC m=+41.127598674
ok crypto/tls 2.322s t0 2019-05-24 17:46:58.224485494 +0000 UTC m=+48.175293336 now 2019-05-24 17:47:00.546546982 +0000 UTC m=+50.497354858
ok crypto/x509 1.619s t0 2019-05-24 17:46:53.638098995 +0000 UTC m=+43.588906848 now 2019-05-24 17:46:55.257057717 +0000 UTC m=+45.207865610
ok database/sql 1.644s t0 2019-05-24 17:46:54.698342532 +0000 UTC m=+44.649150380 now 2019-05-24 17:46:56.342063827 +0000 UTC m=+46.292871720
ok database/sql/driver 1.545s t0 2019-05-24 17:46:55.107724142 +0000 UTC m=+45.058531951 now 2019-05-24 17:46:56.652821828 +0000 UTC m=+46.603629663
ok debug/dwarf 0.569s t0 2019-05-24 17:46:57.19541355 +0000 UTC m=+47.146221358 now 2019-05-24 17:46:57.764550177 +0000 UTC m=+47.715358116
ok debug/elf 0.372s t0 2019-05-24 17:46:57.992212965 +0000 UTC m=+47.943020773 now 2019-05-24 17:46:58.363874554 +0000 UTC m=+48.314682394
ok debug/gosym 2.316s t0 2019-05-24 17:46:58.338552741 +0000 UTC m=+48.289360629 now 2019-05-24 17:47:00.654204414 +0000 UTC m=+50.605012299
ok debug/macho 2.236s t0 2019-05-24 17:46:58.849085779 +0000 UTC m=+48.799893622 now 2019-05-24 17:47:01.085316835 +0000 UTC m=+51.036124973
ok debug/pe 1.534s t0 2019-05-24 17:46:59.329379854 +0000 UTC m=+49.280187675 now 2019-05-24 17:47:00.863045704 +0000 UTC m=+50.813853536
ok debug/plan9obj 0.328s t0 2019-05-24 17:47:01.006711185 +0000 UTC m=+50.957519000 now 2019-05-24 17:47:01.335128944 +0000 UTC m=+51.285936773
ok encoding/ascii85 0.547s t0 2019-05-24 17:47:01.250295541 +0000 UTC m=+51.201103390 now 2019-05-24 17:47:01.796961071 +0000 UTC m=+51.747768887
ok encoding/asn1 0.346s t0 2019-05-24 17:47:01.972266018 +0000 UTC m=+51.923073906 now 2019-05-24 17:47:02.317768242 +0000 UTC m=+52.268576068
ok encoding/base32 0.227s t0 2019-05-24 17:47:01.836111502 +0000 UTC m=+51.786919310 now 2019-05-24 17:47:02.063360178 +0000 UTC m=+52.014168015
ok encoding/base64 0.264s t0 2019-05-24 17:47:02.278029811 +0000 UTC m=+52.228837627 now 2019-05-24 17:47:02.542366641 +0000 UTC m=+52.493174498
ok encoding/binary 0.379s t0 2019-05-24 17:47:03.042524977 +0000 UTC m=+52.993332804 now 2019-05-24 17:47:03.421422244 +0000 UTC m=+53.372230101
ok encoding/csv 0.325s t0 2019-05-24 17:47:02.821974539 +0000 UTC m=+52.772782388 now 2019-05-24 17:47:03.146698944 +0000 UTC m=+53.097506855
ok encoding/gob 0.508s t0 2019-05-24 17:47:04.904611449 +0000 UTC m=+54.855419302 now 2019-05-24 17:47:05.412746438 +0000 UTC m=+55.363554295
ok encoding/hex 0.419s t0 2019-05-24 17:47:03.243077183 +0000 UTC m=+53.193884996 now 2019-05-24 17:47:03.662037188 +0000 UTC m=+53.612845043
ok encoding/json 0.427s t0 2019-05-24 17:47:05.951335242 +0000 UTC m=+55.902143071 now 2019-05-24 17:47:06.378396493 +0000 UTC m=+56.329204516
ok encoding/pem 0.328s t0 2019-05-24 17:47:05.677482241 +0000 UTC m=+55.628290076 now 2019-05-24 17:47:06.005057488 +0000 UTC m=+55.955865346
ok encoding/xml 0.562s t0 2019-05-24 17:47:06.081379522 +0000 UTC m=+56.032187350 now 2019-05-24 17:47:06.643609246 +0000 UTC m=+56.594417058
ok errors 0.777s t0 2019-05-24 17:47:06.279832871 +0000 UTC m=+56.230640719 now 2019-05-24 17:47:07.056619087 +0000 UTC m=+57.007426915
ok expvar 0.590s t0 2019-05-24 17:47:07.175593673 +0000 UTC m=+57.126401554 now 2019-05-24 17:47:07.765380751 +0000 UTC m=+57.716188662
ok flag 0.518s t0 2019-05-24 17:47:07.720145575 +0000 UTC m=+57.670953409 now 2019-05-24 17:47:08.238474775 +0000 UTC m=+58.189282616
ok fmt 0.609s t0 2019-05-24 17:47:09.160642974 +0000 UTC m=+59.111450822 now 2019-05-24 17:47:09.769603343 +0000 UTC m=+59.720411204
ok go/ast 1.883s t0 2019-05-24 17:47:09.942703722 +0000 UTC m=+59.893511584 now 2019-05-24 17:47:11.825531592 +0000 UTC m=+61.776339440
ok go/build 1.944s t0 2019-05-24 17:47:09.755987131 +0000 UTC m=+59.706794997 now 2019-05-24 17:47:11.700114983 +0000 UTC m=+61.650922798
ok go/constant 1.547s t0 2019-05-24 17:47:10.479690519 +0000 UTC m=+60.430498364 now 2019-05-24 17:47:12.026792632 +0000 UTC m=+61.977600510
ok go/doc 1.554s t0 2019-05-24 17:47:11.048375252 +0000 UTC m=+60.999183060 now 2019-05-24 17:47:12.602494237 +0000 UTC m=+62.553302079
ok go/format 0.675s t0 2019-05-24 17:47:12.204952732 +0000 UTC m=+62.155760540 now 2019-05-24 17:47:12.879530931 +0000 UTC m=+62.830338748
ok go/importer 0.577s t0 2019-05-24 17:47:12.812509064 +0000 UTC m=+62.763316873 now 2019-05-24 17:47:13.389716235 +0000 UTC m=+63.340524039
ok go/internal/gccgoimporter 0.227s t0 2019-05-24 17:47:13.74195931 +0000 UTC m=+63.692767177 now 2019-05-24 17:47:13.968707594 +0000 UTC m=+63.919515423
ok go/internal/gcimporter 4.002s t0 2019-05-24 17:47:14.052737567 +0000 UTC m=+64.003545394 now 2019-05-24 17:47:18.054364408 +0000 UTC m=+68.005172235
ok go/internal/srcimporter 2.073s t0 2019-05-24 17:47:13.780882672 +0000 UTC m=+63.731690513 now 2019-05-24 17:47:15.853682695 +0000 UTC m=+65.804490520
ok go/parser 1.365s t0 2019-05-24 17:47:14.637151341 +0000 UTC m=+64.587959228 now 2019-05-24 17:47:16.002363002 +0000 UTC m=+65.953170862
ok go/printer 1.633s t0 2019-05-24 17:47:15.121616573 +0000 UTC m=+65.072424380 now 2019-05-24 17:47:16.754965583 +0000 UTC m=+66.705773396
ok go/scanner 0.442s t0 2019-05-24 17:47:16.504821328 +0000 UTC m=+66.455629158 now 2019-05-24 17:47:16.947266518 +0000 UTC m=+66.898074370
ok go/token 0.643s t0 2019-05-24 17:47:17.914705928 +0000 UTC m=+67.865513772 now 2019-05-24 17:47:18.557938928 +0000 UTC m=+68.508746748
ok go/types 1.450s t0 2019-05-24 17:47:20.961714606 +0000 UTC m=+70.912522455 now 2019-05-24 17:47:22.412144886 +0000 UTC m=+72.362952701
ok hash 0.449s t0 2019-05-24 17:47:17.734796108 +0000 UTC m=+67.685603917 now 2019-05-24 17:47:18.183381086 +0000 UTC m=+68.134188941
ok hash/adler32 0.638s t0 2019-05-24 17:47:18.577211643 +0000 UTC m=+68.528019595 now 2019-05-24 17:47:19.215025311 +0000 UTC m=+69.165833161
ok hash/crc32 0.702s t0 2019-05-24 17:47:19.681687837 +0000 UTC m=+69.632495675 now 2019-05-24 17:47:20.383280498 +0000 UTC m=+70.334088305
ok hash/crc64 0.494s t0 2019-05-24 17:47:19.259346262 +0000 UTC m=+69.210154070 now 2019-05-24 17:47:19.753582601 +0000 UTC m=+69.704390449
ok hash/fnv 0.320s t0 2019-05-24 17:47:20.383878459 +0000 UTC m=+70.334686290 now 2019-05-24 17:47:20.703441781 +0000 UTC m=+70.654249644
ok html 0.400s t0 2019-05-24 17:47:20.571665374 +0000 UTC m=+70.522473185 now 2019-05-24 17:47:20.971756899 +0000 UTC m=+70.922564741
ok html/template 0.477s t0 2019-05-24 17:47:22.222779346 +0000 UTC m=+72.173587185 now 2019-05-24 17:47:22.699961459 +0000 UTC m=+72.650769307
ok image 0.802s t0 2019-05-24 17:47:22.738524386 +0000 UTC m=+72.689332235 now 2019-05-24 17:47:23.540083642 +0000 UTC m=+73.490891502
ok image/color 0.504s t0 2019-05-24 17:47:22.405882465 +0000 UTC m=+72.356690277 now 2019-05-24 17:47:22.910150446 +0000 UTC m=+72.860958331
ok image/draw 0.529s t0 2019-05-24 17:47:23.332506094 +0000 UTC m=+73.283313922 now 2019-05-24 17:47:23.861024758 +0000 UTC m=+73.811832700
ok image/gif 1.474s t0 2019-05-24 17:47:23.634323201 +0000 UTC m=+73.585131031 now 2019-05-24 17:47:25.107841288 +0000 UTC m=+75.058649152
ok image/jpeg 2.027s t0 2019-05-24 17:47:23.991868902 +0000 UTC m=+73.942676773 now 2019-05-24 17:47:26.018628339 +0000 UTC m=+75.969436160
ok image/png 2.546s t0 2019-05-24 17:47:24.792779977 +0000 UTC m=+74.743587790 now 2019-05-24 17:47:27.338859222 +0000 UTC m=+77.289667070
ok index/suffixarray 0.650s t0 2019-05-24 17:47:24.830839745 +0000 UTC m=+74.781647552 now 2019-05-24 17:47:25.480488693 +0000 UTC m=+75.431296518
ok internal/cpu 0.665s t0 2019-05-24 17:47:32.089848627 +0000 UTC m=+82.040656453 now 2019-05-24 17:47:32.755142518 +0000 UTC m=+82.705950368
ok internal/fmtsort 0.270s t0 2019-05-24 17:47:27.50264568 +0000 UTC m=+77.453453501 now 2019-05-24 17:47:27.772957935 +0000 UTC m=+77.723765766
ok internal/oserror 0.294s t0 2019-05-24 17:47:27.294446581 +0000 UTC m=+77.245254515 now 2019-05-24 17:47:27.588673554 +0000 UTC m=+77.539481444
ok internal/poll 1.068s t0 2019-05-24 17:47:34.895386217 +0000 UTC m=+84.846194020 now 2019-05-24 17:47:35.963203925 +0000 UTC m=+85.914011907
ok internal/reflectlite 0.406s t0 2019-05-24 17:47:36.280523028 +0000 UTC m=+86.231330885 now 2019-05-24 17:47:36.6867954 +0000 UTC m=+86.637603219
ok internal/singleflight 0.186s t0 2019-05-24 17:47:34.861526014 +0000 UTC m=+84.812333845 now 2019-05-24 17:47:35.047716663 +0000 UTC m=+84.998524501
ok internal/trace 0.298s t0 2019-05-24 17:47:35.925806198 +0000 UTC m=+85.876614005 now 2019-05-24 17:47:36.223782535 +0000 UTC m=+86.174590365
ok internal/xcoff 0.416s t0 2019-05-24 17:47:36.052671863 +0000 UTC m=+86.003479683 now 2019-05-24 17:47:36.468859466 +0000 UTC m=+86.419667289
ok io 0.398s t0 2019-05-24 17:47:36.966380008 +0000 UTC m=+86.917187876 now 2019-05-24 17:47:37.364308733 +0000 UTC m=+87.315116570
ok io/ioutil 0.441s t0 2019-05-24 17:47:37.093109854 +0000 UTC m=+87.043917689 now 2019-05-24 17:47:37.534084133 +0000 UTC m=+87.484892006
ok log 0.496s t0 2019-05-24 17:47:37.266586475 +0000 UTC m=+87.217394431 now 2019-05-24 17:47:37.762800098 +0000 UTC m=+87.713608046
ok log/syslog 2.508s t0 2019-05-24 17:47:37.535698182 +0000 UTC m=+87.486506049 now 2019-05-24 17:47:40.043513388 +0000 UTC m=+89.994321229
ok math 0.427s t0 2019-05-24 17:47:40.592967542 +0000 UTC m=+90.543775387 now 2019-05-24 17:47:41.019957681 +0000 UTC m=+90.970765509
ok math/big 0.819s t0 2019-05-24 17:47:40.927928947 +0000 UTC m=+90.878736796 now 2019-05-24 17:47:41.747415168 +0000 UTC m=+91.698222983
ok math/bits 0.948s t0 2019-05-24 17:47:40.984564211 +0000 UTC m=+90.935372044 now 2019-05-24 17:47:41.932391274 +0000 UTC m=+91.883199092
ok math/cmplx 0.550s t0 2019-05-24 17:47:41.524687416 +0000 UTC m=+91.475495291 now 2019-05-24 17:47:42.075116043 +0000 UTC m=+92.025923893
ok math/rand 0.929s t0 2019-05-24 17:47:41.877941883 +0000 UTC m=+91.828749694 now 2019-05-24 17:47:42.807387596 +0000 UTC m=+92.758195441
ok mime 0.324s t0 2019-05-24 17:47:42.681149715 +0000 UTC m=+92.631957588 now 2019-05-24 17:47:43.005631468 +0000 UTC m=+92.956439299
ok mime/multipart 0.535s t0 2019-05-24 17:47:42.931646603 +0000 UTC m=+92.882454461 now 2019-05-24 17:47:43.466291348 +0000 UTC m=+93.417099179
ok mime/quotedprintable 1.333s t0 2019-05-24 17:47:43.077543246 +0000 UTC m=+93.028351058 now 2019-05-24 17:47:44.410838352 +0000 UTC m=+94.361646185
ok net 9.227s t0 2019-05-24 17:47:49.834199005 +0000 UTC m=+99.785006816 now 2019-05-24 17:47:59.061575418 +0000 UTC m=+109.012383264
ok net/http 15.904s t0 2019-05-24 17:47:53.132057603 +0000 UTC m=+103.082865446 now 2019-05-24 17:48:09.035718037 +0000 UTC m=+118.986525871
ok net/http/cgi 1.322s t0 2019-05-24 17:47:44.838586881 +0000 UTC m=+94.789394735 now 2019-05-24 17:47:46.160183048 +0000 UTC m=+96.110990873
ok net/http/cookiejar 0.944s t0 2019-05-24 17:47:46.070810312 +0000 UTC m=+96.021618126 now 2019-05-24 17:47:47.015267859 +0000 UTC m=+96.966075778
ok net/http/fcgi 0.772s t0 2019-05-24 17:47:47.820962452 +0000 UTC m=+97.771770260 now 2019-05-24 17:47:48.593219459 +0000 UTC m=+98.544027271
ok net/http/httptest 0.621s t0 2019-05-24 17:47:49.655937984 +0000 UTC m=+99.606745800 now 2019-05-24 17:47:50.277295365 +0000 UTC m=+100.228103213
ok net/http/httptrace 15.701s t0 2019-05-24 17:47:53.629548186 +0000 UTC m=+103.580355997 now 2019-05-24 17:48:09.330881059 +0000 UTC m=+119.281689016
ok net/http/httputil 5.947s t0 2019-05-24 17:47:53.458927148 +0000 UTC m=+103.409734956 now 2019-05-24 17:47:59.405966914 +0000 UTC m=+109.356774762
ok net/http/internal 9.634s t0 2019-05-24 17:47:59.499006454 +0000 UTC m=+109.449814285 now 2019-05-24 17:48:09.132525294 +0000 UTC m=+119.083333133
ok net/http/pprof 11.160s t0 2019-05-24 17:48:00.516989417 +0000 UTC m=+110.467797230 now 2019-05-24 17:48:11.677417662 +0000 UTC m=+121.628225566
ok net/internal/socktest 2.316s t0 2019-05-24 17:48:09.451689813 +0000 UTC m=+119.402497628 now 2019-05-24 17:48:11.767859219 +0000 UTC m=+121.718667058
ok net/mail 2.008s t0 2019-05-24 17:48:09.891336951 +0000 UTC m=+119.842144772 now 2019-05-24 17:48:11.899294424 +0000 UTC m=+121.850102313
ok net/rpc 2.164s t0 2019-05-24 17:48:10.559458606 +0000 UTC m=+120.510266414 now 2019-05-24 17:48:12.723204951 +0000 UTC m=+122.674013010
ok net/rpc/jsonrpc 0.354s t0 2019-05-24 17:48:12.911591687 +0000 UTC m=+122.862399513 now 2019-05-24 17:48:13.265350879 +0000 UTC m=+123.216158707
ok net/smtp 0.843s t0 2019-05-24 17:48:12.985947194 +0000 UTC m=+122.936755022 now 2019-05-24 17:48:13.828997722 +0000 UTC m=+123.779805595
ok net/textproto 0.125s t0 2019-05-24 17:48:12.908362603 +0000 UTC m=+122.859170431 now 2019-05-24 17:48:13.033102339 +0000 UTC m=+122.983910228
ok net/url 0.421s t0 2019-05-24 17:48:13.924365422 +0000 UTC m=+123.875173391 now 2019-05-24 17:48:14.345684021 +0000 UTC m=+124.296491847
ok os 4.075s t0 2019-05-24 17:48:16.001698128 +0000 UTC m=+125.952505983 now 2019-05-24 17:48:20.07632269 +0000 UTC m=+130.027130512
ok os/exec 5.102s t0 2019-05-24 17:48:16.027150098 +0000 UTC m=+125.977957919 now 2019-05-24 17:48:21.129027302 +0000 UTC m=+131.079835204
ok os/signal 3.336s t0 2019-05-24 17:48:15.451757341 +0000 UTC m=+125.402565198 now 2019-05-24 17:48:18.78817597 +0000 UTC m=+128.738983784
ok os/user 3.074s t0 2019-05-24 17:48:15.803526754 +0000 UTC m=+125.754334561 now 2019-05-24 17:48:18.877895656 +0000 UTC m=+128.828703479
ok path 1.947s t0 2019-05-24 17:48:19.304130121 +0000 UTC m=+129.254937963 now 2019-05-24 17:48:21.251101382 +0000 UTC m=+131.201909203
ok path/filepath 1.253s t0 2019-05-24 17:48:20.144293788 +0000 UTC m=+130.095101600 now 2019-05-24 17:48:21.397593415 +0000 UTC m=+131.348401234
ok plugin 1.456s t0 2019-05-24 17:48:20.773630744 +0000 UTC m=+130.724438551 now 2019-05-24 17:48:22.229334359 +0000 UTC m=+132.180142189
ok reflect 2.158s t0 2019-05-24 17:48:26.952956951 +0000 UTC m=+136.903764803 now 2019-05-24 17:48:29.11083183 +0000 UTC m=+139.061639654
ok regexp 0.407s t0 2019-05-24 17:48:22.991991758 +0000 UTC m=+132.942799577 now 2019-05-24 17:48:23.398830072 +0000 UTC m=+133.349637961
ok regexp/syntax 1.206s t0 2019-05-24 17:48:23.174439925 +0000 UTC m=+133.125247733 now 2019-05-24 17:48:24.380118456 +0000 UTC m=+134.330926311
panic: test timed out after 9m0s t0 2019-05-24 17:48:37.59701419 +0000 UTC m=+0.000534711 now 2019-05-24 17:48:45.909217 +0000 UTC m=+8.312724651

goroutine 24266 [running]:
panic(0x75a3154c58a0, 0xc0006ec010)
/workdir/go/src/runtime/panic.go:662 +0x2c6 fp=0xc00004af10 sp=0xc00004ae80 pc=0x75a315248676
testing.(*M).startAlarm.func1()
/workdir/go/src/testing/testing.go:1384 +0x1e8 fp=0xc00004afe0 sp=0xc00004af10 pc=0x75a3152fb468
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc00004afe8 sp=0xc00004afe0 pc=0x75a31527cb51
created by time.goFunc
/workdir/go/src/time/sleep.go:169 +0x46

goroutine 1 [chan receive, locked to thread]:
runtime.gopark(0x75a315520d48, 0xc00037f138, 0xc00035170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000049be8 sp=0xc000049bc8 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc00037f0e0, 0x0, 0xc0002e2b01, 0xc0000701d8)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000049c78 sp=0xc000049be8 pc=0x75a31521e23c
runtime.chanrecv1(0xc00037f0e0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000049ca8 sp=0xc000049c78 pc=0x75a31521defb
testing.tRunner.func1(0xc0000ac000)
/workdir/go/src/testing/testing.go:885 +0x204 fp=0xc000049d28 sp=0xc000049ca8 pc=0x75a3152faf74
testing.tRunner(0xc0000ac000, 0xc000049de0)
/workdir/go/src/testing/testing.go:913 +0xcb fp=0xc000049d50 sp=0xc000049d28 pc=0x75a3152f747b
testing.runTests(0xc00000e0e0, 0x75a315691c40, 0x11e, 0x11e, 0x0)
/workdir/go/src/testing/testing.go:1205 +0x2a9 fp=0xc000049e10 sp=0xc000049d50 pc=0x75a3152f8d29
testing.(*M).Run(0xc0000a6000, 0x0)
/workdir/go/src/testing/testing.go:1122 +0x162 fp=0xc000049ee0 sp=0xc000049e10 pc=0x75a3152f7ca2
runtime_test.TestMain(0xc0000a6000)
/workdir/go/src/runtime/crash_test.go:28 +0x31 fp=0xc000049f30 sp=0xc000049ee0 pc=0x75a315390a01
main.main()
_testmain.go:1082 +0x137 fp=0xc000049f98 sp=0xc000049f30 pc=0x75a3153f6717
runtime.main()
/workdir/go/src/runtime/proc.go:203 +0x21c fp=0xc000049fe0 sp=0xc000049f98 pc=0x75a31524a3dc
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000049fe8 sp=0xc000049fe0 pc=0x75a31527cb51

goroutine 2 [force gc (idle)]:
runtime.gopark(0x75a315520d48, 0x75a315694530, 0x1411, 0x1)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000036fb0 sp=0xc000036f90 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.forcegchelper()
/workdir/go/src/runtime/proc.go:253 +0xbb fp=0xc000036fe0 sp=0xc000036fb0 pc=0x75a31524a66b
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000036fe8 sp=0xc000036fe0 pc=0x75a31527cb51
created by runtime.init.5
/workdir/go/src/runtime/proc.go:242 +0x37

goroutine 3 [GC sweep wait]:
runtime.gopark(0x75a315520d48, 0x75a315694920, 0x140c, 0x1)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0000377a8 sp=0xc000037788 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.bgsweep(0xc00005c000)
/workdir/go/src/runtime/mgcsweep.go:89 +0x135 fp=0xc0000377d8 sp=0xc0000377a8 pc=0x75a31523c055
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0000377e0 sp=0xc0000377d8 pc=0x75a31527cb51
created by runtime.gcenable
/workdir/go/src/runtime/mgc.go:210 +0x5e

goroutine 4 [runnable]:
runtime.gopark(0x75a315520d48, 0x75a315694ae0, 0x75a315241313, 0x2)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000037f00 sp=0xc000037ee0 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.scavengeSleep(0xc000000067, 0xd9bc5, 0x1401)
/workdir/go/src/runtime/mgcscavenge.go:232 +0xc4 fp=0xc000037f38 sp=0xc000037f00 pc=0x75a31523b4e4
runtime.bgscavenge(0xc00005c000)
/workdir/go/src/runtime/mgcscavenge.go:358 +0x312 fp=0xc000037fd8 sp=0xc000037f38 pc=0x75a31523b832
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000037fe0 sp=0xc000037fd8 pc=0x75a31527cb51
created by runtime.gcenable
/workdir/go/src/runtime/mgc.go:211 +0x80

goroutine 5 [finalizer wait]:
runtime.gopark(0x75a315520d48, 0x75a3156b0c58, 0xc000391410, 0x1)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000033758 sp=0xc000033738 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.runfinq()
/workdir/go/src/runtime/mfinal.go:175 +0xa7 fp=0xc0000337e0 sp=0xc000033758 pc=0x75a315231887
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0000337e8 sp=0xc0000337e0 pc=0x75a31527cb51
created by runtime.createfing
/workdir/go/src/runtime/mfinal.go:156 +0x63

goroutine 6 [timer goroutine (idle)]:
runtime.gopark(0x75a315520d48, 0x75a315698460, 0x1415, 0x1)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000038760 sp=0xc000038740 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.timerproc(0x75a315698460)
/workdir/go/src/runtime/time.go:303 +0x27f fp=0xc0000387d8 sp=0xc000038760 pc=0x75a3152683df
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0000387e0 sp=0xc0000387d8 pc=0x75a31527cb51
created by runtime.(*timersBucket).addtimerLocked
/workdir/go/src/runtime/time.go:169 +0x110

goroutine 22510 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001e0580 sp=0xc0001e0560 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xaa01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001e0610 sp=0xc0001e0580 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001e0640 sp=0xc0001e0610 pc=0x75a31521defb
testing.(*T).Parallel(0xc0000ac800)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001e06c8 sp=0xc0001e0640 pc=0x75a3152f71c7
runtime_test.TestCgoExternalThreadSIGPROF(0xc0000ac800)
/workdir/go/src/runtime/crash_cgo_test.go:87 +0x42 fp=0xc0001e07a8 sp=0xc0001e06c8 pc=0x75a31538be32
testing.tRunner(0xc0000ac800, 0x75a315521a20)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001e07d0 sp=0xc0001e07a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001e07d8 sp=0xc0001e07d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 19 [syscall]:
runtime.notetsleepg(0x75a315698500, 0x43c64b104, 0x0)
/workdir/go/src/runtime/lock_futex.go:227 +0x38 fp=0xc000032f60 sp=0xc000032f30 pc=0x75a315223c38
runtime.timerproc(0x75a3156984e0)
/workdir/go/src/runtime/time.go:311 +0x2f5 fp=0xc000032fd8 sp=0xc000032f60 pc=0x75a315268455
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000032fe0 sp=0xc000032fd8 pc=0x75a31527cb51
created by runtime.(*timersBucket).addtimerLocked
/workdir/go/src/runtime/time.go:169 +0x110

goroutine 35 [timer goroutine (idle)]:
runtime.gopark(0x75a315520d48, 0x75a315698560, 0x1415, 0x1)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0000def60 sp=0xc0000def40 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.timerproc(0x75a315698560)
/workdir/go/src/runtime/time.go:303 +0x27f fp=0xc0000defd8 sp=0xc0000def60 pc=0x75a3152683df
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0000defe0 sp=0xc0000defd8 pc=0x75a31527cb51
created by runtime.(*timersBucket).addtimerLocked
/workdir/go/src/runtime/time.go:169 +0x110

goroutine 62 [timer goroutine (idle)]:
runtime.gopark(0x75a315520d48, 0x75a3156985e0, 0x1415, 0x1)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0000ddf60 sp=0xc0000ddf40 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.timerproc(0x75a3156985e0)
/workdir/go/src/runtime/time.go:303 +0x27f fp=0xc0000ddfd8 sp=0xc0000ddf60 pc=0x75a3152683df
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0000ddfe0 sp=0xc0000ddfd8 pc=0x75a31527cb51
created by runtime.(*timersBucket).addtimerLocked
/workdir/go/src/runtime/time.go:169 +0x110

goroutine 22695 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001ecdd0 sp=0xc0001ecdb0 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xa601)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001ece60 sp=0xc0001ecdd0 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001ece90 sp=0xc0001ece60 pc=0x75a31521defb
testing.(*T).Parallel(0xc0000ac400)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001ecf18 sp=0xc0001ece90 pc=0x75a3152f71c7
runtime_test.testConcurrentReadsAfterGrowth(0xc0000ac400, 0x54cd7ff400)
/workdir/go/src/runtime/map_test.go:315 +0x31 fp=0xc0001ecf88 sp=0xc0001ecf18 pc=0x75a3153b3431
runtime_test.TestConcurrentReadsAfterGrowth(0xc0000ac400)
/workdir/go/src/runtime/map_test.go:361 +0x32 fp=0xc0001ecfa8 sp=0xc0001ecf88 pc=0x75a3153b3712
testing.tRunner(0xc0000ac400, 0x75a315521b30)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001ecfd0 sp=0xc0001ecfa8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001ecfd8 sp=0xc0001ecfd0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22696 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0002dd5d0 sp=0xc0002dd5b0 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xaa01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0002dd660 sp=0xc0002dd5d0 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0002dd690 sp=0xc0002dd660 pc=0x75a31521defb
testing.(*T).Parallel(0xc0000aca00)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0002dd718 sp=0xc0002dd690 pc=0x75a3152f71c7
runtime_test.testConcurrentReadsAfterGrowth(0xc0000aca00, 0x54cd825601)
/workdir/go/src/runtime/map_test.go:315 +0x31 fp=0xc0002dd788 sp=0xc0002dd718 pc=0x75a3153b3431
runtime_test.TestConcurrentReadsAfterGrowthReflect(0xc0000aca00)
/workdir/go/src/runtime/map_test.go:365 +0x32 fp=0xc0002dd7a8 sp=0xc0002dd788 pc=0x75a3153b3762
testing.tRunner(0xc0000aca00, 0x75a315521b28)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0002dd7d0 sp=0xc0002dd7a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0002dd7d8 sp=0xc0002dd7d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22744 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001f6600 sp=0xc0001f65e0 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0x8101)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001f6690 sp=0xc0001f6600 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001f66c0 sp=0xc0001f6690 pc=0x75a31521defb
testing.(*T).Parallel(0xc0000ad400)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001f6748 sp=0xc0001f66c0 pc=0x75a3152f71c7
runtime_test.TestDeferKeepAlive(0xc0000ad400)
/workdir/go/src/runtime/mfinal_test.go:251 +0x41 fp=0xc0001f67a8 sp=0xc0001f6748 pc=0x75a3153bde41
testing.tRunner(0xc0000ad400, 0x75a315521be8)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001f67d0 sp=0xc0001f67a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001f67d8 sp=0xc0001f67d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22718 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001f15a0 sp=0xc0001f1580 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xa401)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001f1630 sp=0xc0001f15a0 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001f1660 sp=0xc0001f1630 pc=0x75a31521defb
testing.(*T).Parallel(0xc000393400)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001f16e8 sp=0xc0001f1660 pc=0x75a3152f71c7
runtime_test.TestMemmoveAlias(0xc000393400)
/workdir/go/src/runtime/memmove_test.go:63 +0x52 fp=0xc0001f17a8 sp=0xc0001f16e8 pc=0x75a3153ba6a2
testing.tRunner(0xc000393400, 0x75a315521f98)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001f17d0 sp=0xc0001f17a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001f17d8 sp=0xc0001f17d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22717 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001f2da8 sp=0xc0001f2d88 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xa601)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001f2e38 sp=0xc0001f2da8 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001f2e68 sp=0xc0001f2e38 pc=0x75a31521defb
testing.(*T).Parallel(0xc000393300)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001f2ef0 sp=0xc0001f2e68 pc=0x75a3152f71c7
runtime_test.TestMemmove(0xc000393300)
/workdir/go/src/runtime/memmove_test.go:21 +0x52 fp=0xc0001f2fa8 sp=0xc0001f2ef0 pc=0x75a3153ba072
testing.tRunner(0xc000393300, 0x75a315521fb8)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001f2fd0 sp=0xc0001f2fa8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001f2fd8 sp=0xc0001f2fd0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22544 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001edd28 sp=0xc0001edd08 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0x9f01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001eddb8 sp=0xc0001edd28 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001edde8 sp=0xc0001eddb8 pc=0x75a31521defb
testing.(*T).Parallel(0xc000393a00)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001ede70 sp=0xc0001edde8 pc=0x75a3152f71c7
runtime_test.TestPanicTraceback(0xc000393a00)
/workdir/go/src/runtime/crash_test.go:414 +0x45 fp=0xc0001edfa8 sp=0xc0001ede70 pc=0x75a315393e25
testing.tRunner(0xc000393a00, 0x75a3155220e8)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001edfd0 sp=0xc0001edfa8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001edfd8 sp=0xc0001edfd0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22505 [runnable]:
runtime_test.testCrashHandler(0xc0000ac300, 0x544e2f0401)
/workdir/go/src/runtime/crash_test.go:174 +0x1f5 fp=0xc0001dc788 sp=0xc0001dc780 pc=0x75a3153920e5
runtime_test.TestCgoCrashHandler(0xc0000ac300)
/workdir/go/src/runtime/crash_cgo_test.go:24 +0x40 fp=0xc0001dc7a8 sp=0xc0001dc788 pc=0x75a31538b6a0
testing.tRunner(0xc0000ac300, 0x75a3155219f8)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001dc7d0 sp=0xc0001dc7a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001dc7d8 sp=0xc0001dc7d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22029 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001e7580 sp=0xc0001e7560 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xab01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001e7610 sp=0xc0001e7580 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001e7640 sp=0xc0001e7610 pc=0x75a31521defb
testing.(*T).Parallel(0xc000392800)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001e76c8 sp=0xc0001e7640 pc=0x75a3152f71c7
runtime_test.TestCatchPanic(0xc000392800)
/workdir/go/src/runtime/crash_cgo_test.go:416 +0x42 fp=0xc0001e77a8 sp=0xc0001e76c8 pc=0x75a31538f7e2
testing.tRunner(0xc000392800, 0x75a3155219d8)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001e77d0 sp=0xc0001e77a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001e77d8 sp=0xc0001e77d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22023 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001df440 sp=0xc0001df420 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0x13601)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001df4d0 sp=0xc0001df440 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001df500 sp=0xc0001df4d0 pc=0x75a31521defb
testing.(*T).Parallel(0xc000392100)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001df588 sp=0xc0001df500 pc=0x75a3152f71c7
runtime_test.testCgoPprof(0xc000392100, 0x0, 0x0, 0x75a3153f8668, 0x8, 0x75a3153f8072, 0x6, 0x75a3153fa086, 0xc)
/workdir/go/src/runtime/crash_cgo_test.go:267 +0x45 fp=0xc0001df750 sp=0xc0001df588 pc=0x75a31538da85
runtime_test.TestCgoPprof(0xc000392100)
/workdir/go/src/runtime/crash_cgo_test.go:329 +0x74 fp=0xc0001df7a8 sp=0xc0001df750 pc=0x75a31538ea04
testing.tRunner(0xc000392100, 0x75a315521a60)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001df7d0 sp=0xc0001df7a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001df7d8 sp=0xc0001df7d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22517 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001e85c8 sp=0xc0001e85a8 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xb201)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001e8658 sp=0xc0001e85c8 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001e8688 sp=0xc0001e8658 pc=0x75a31521defb
testing.(*T).Parallel(0xc0000acf00)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001e8710 sp=0xc0001e8688 pc=0x75a3152f71c7
runtime_test.TestCgoCCodeSIGPROF(0xc0000acf00)
/workdir/go/src/runtime/crash_cgo_test.go:232 +0x42 fp=0xc0001e87a8 sp=0xc0001e8710 pc=0x75a31538d452
testing.tRunner(0xc0000acf00, 0x75a3155219e0)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001e87d0 sp=0xc0001e87a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001e87d8 sp=0xc0001e87d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22030 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001e8e38 sp=0xc0001e8e18 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xab01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001e8ec8 sp=0xc0001e8e38 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001e8ef8 sp=0xc0001e8ec8 pc=0x75a31521defb
testing.(*T).Parallel(0xc000392900)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001e8f80 sp=0xc0001e8ef8 pc=0x75a3152f71c7
runtime_test.TestCgoLockOSThreadExit(0xc000392900)
/workdir/go/src/runtime/crash_cgo_test.go:452 +0x2d fp=0xc0001e8fa8 sp=0xc0001e8f80 pc=0x75a31538fc4d
testing.tRunner(0xc000392900, 0x75a315521a30)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001e8fd0 sp=0xc0001e8fa8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001e8fd8 sp=0xc0001e8fd0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22508 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001e95c8 sp=0xc0001e95a8 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xd701)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001e9658 sp=0xc0001e95c8 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001e9688 sp=0xc0001e9658 pc=0x75a31521defb
testing.(*T).Parallel(0xc0000ac600)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001e9710 sp=0xc0001e9688 pc=0x75a3152f71c7
runtime_test.TestCgoCallbackGC(0xc0000ac600)
/workdir/go/src/runtime/crash_cgo_test.go:52 +0x42 fp=0xc0001e97a8 sp=0xc0001e9710 pc=0x75a31538ba92
testing.tRunner(0xc0000ac600, 0x75a3155219e8)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001e97d0 sp=0xc0001e97a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001e97d8 sp=0xc0001e97d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22507 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001e9dc8 sp=0xc0001e9da8 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xaa01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001e9e58 sp=0xc0001e9dc8 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001e9e88 sp=0xc0001e9e58 pc=0x75a31521defb
testing.(*T).Parallel(0xc0000ac500)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001e9f10 sp=0xc0001e9e88 pc=0x75a3152f71c7
runtime_test.TestCgoTraceback(0xc0000ac500)
/workdir/go/src/runtime/crash_cgo_test.go:43 +0x42 fp=0xc0001e9fa8 sp=0xc0001e9f10 pc=0x75a31538b8f2
testing.tRunner(0xc0000ac500, 0x75a315521a80)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001e9fd0 sp=0xc0001e9fa8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001e9fd8 sp=0xc0001e9fd0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22513 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001ea5c8 sp=0xc0001ea5a8 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xad01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001ea658 sp=0xc0001ea5c8 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001ea688 sp=0xc0001ea658 pc=0x75a31521defb
testing.(*T).Parallel(0xc0000acb00)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001ea710 sp=0xc0001ea688 pc=0x75a3152f71c7
runtime_test.TestCgoExecSignalMask(0xc0000acb00)
/workdir/go/src/runtime/crash_cgo_test.go:151 +0x42 fp=0xc0001ea7a8 sp=0xc0001ea710 pc=0x75a31538c7f2
testing.tRunner(0xc0000acb00, 0x75a315521a10)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001ea7d0 sp=0xc0001ea7a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001ea7d8 sp=0xc0001ea7d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22022 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001eadc8 sp=0xc0001eada8 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xaa01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001eae58 sp=0xc0001eadc8 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001eae88 sp=0xc0001eae58 pc=0x75a31521defb
testing.(*T).Parallel(0xc000392000)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001eaf10 sp=0xc0001eae88 pc=0x75a3152f71c7
runtime_test.TestCgoTracebackContext(0xc000392000)
/workdir/go/src/runtime/crash_cgo_test.go:258 +0x42 fp=0xc0001eafa8 sp=0xc0001eaf10 pc=0x75a31538d8e2
testing.tRunner(0xc000392000, 0x75a315521a70)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001eafd0 sp=0xc0001eafa8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001eafd8 sp=0xc0001eafd0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22028 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001f55c8 sp=0xc0001f55a8 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xbe01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001f5658 sp=0xc0001f55c8 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001f5688 sp=0xc0001f5658 pc=0x75a31521defb
testing.(*T).Parallel(0xc000392700)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001f5710 sp=0xc0001f5688 pc=0x75a3152f71c7
runtime_test.TestCgoNumGoroutine(0xc000392700)
/workdir/go/src/runtime/crash_cgo_test.go:407 +0x42 fp=0xc0001f57a8 sp=0xc0001f5710 pc=0x75a31538f642
testing.tRunner(0xc000392700, 0x75a315521a38)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001f57d0 sp=0xc0001f57a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001f57d8 sp=0xc0001f57d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22511 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001f5d78 sp=0xc0001f5d58 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xa901)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001f5e08 sp=0xc0001f5d78 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001f5e38 sp=0xc0001f5e08 pc=0x75a31521defb
testing.(*T).Parallel(0xc0000ac900)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001f5ec0 sp=0xc0001f5e38 pc=0x75a3152f71c7
runtime_test.TestCgoExternalThreadSignal(0xc0000ac900)
/workdir/go/src/runtime/crash_cgo_test.go:115 +0x42 fp=0xc0001f5fa8 sp=0xc0001f5ec0 pc=0x75a31538c202
testing.tRunner(0xc0000ac900, 0x75a315521a28)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001f5fd0 sp=0xc0001f5fa8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001f5fd8 sp=0xc0001f5fd0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 23225 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc00052f630 sp=0xc00052f610 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0x8201)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc00052f6c0 sp=0xc00052f630 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc00052f6f0 sp=0xc00052f6c0 pc=0x75a31521defb
testing.(*T).Parallel(0xc000392a00)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc00052f778 sp=0xc00052f6f0 pc=0x75a3152f71c7
runtime_test.TestStackGrowthCallback(0xc000392a00)
/workdir/go/src/runtime/stack_test.go:200 +0x31 fp=0xc00052f7a8 sp=0xc00052f778 pc=0x75a3153d1471
testing.tRunner(0xc000392a00, 0x75a3155223f0)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc00052f7d0 sp=0xc00052f7a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc00052f7d8 sp=0xc00052f7d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22026 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001f6c40 sp=0xc0001f6c20 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xa901)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001f6cd0 sp=0xc0001f6c40 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001f6d00 sp=0xc0001f6cd0 pc=0x75a31521defb
testing.(*T).Parallel(0xc000392400)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001f6d88 sp=0xc0001f6d00 pc=0x75a3152f71c7
runtime_test.testCgoPprof(0xc000392400, 0x0, 0x0, 0x75a315400618, 0x19, 0x75a3153f9f4e, 0xc, 0x75a3153fe8d8, 0x15)
/workdir/go/src/runtime/crash_cgo_test.go:267 +0x45 fp=0xc0001f6f50 sp=0xc0001f6d88 pc=0x75a31538da85
runtime_test.TestCgoPprofThreadNoTraceback(0xc000392400)
/workdir/go/src/runtime/crash_cgo_test.go:341 +0x74 fp=0xc0001f6fa8 sp=0xc0001f6f50 pc=0x75a31538ebc4
testing.tRunner(0xc000392400, 0x75a315521a50)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001f6fd0 sp=0xc0001f6fa8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001f6fd8 sp=0xc0001f6fd0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22543 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001f75c8 sp=0xc0001f75a8 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xa701)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001f7658 sp=0xc0001f75c8 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001f7688 sp=0xc0001f7658 pc=0x75a31521defb
testing.(*T).Parallel(0xc000393900)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001f7710 sp=0xc0001f7688 pc=0x75a3152f71c7
runtime_test.TestNetpollDeadlock(0xc000393900)
/workdir/go/src/runtime/crash_test.go:405 +0x42 fp=0xc0001f77a8 sp=0xc0001f7710 pc=0x75a315393c32
testing.tRunner(0xc000393900, 0x75a315521ff0)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001f77d0 sp=0xc0001f77a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001f77d8 sp=0xc0001f77d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22509 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001f7dc8 sp=0xc0001f7da8 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xac01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001f7e58 sp=0xc0001f7dc8 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001f7e88 sp=0xc0001f7e58 pc=0x75a31521defb
testing.(*T).Parallel(0xc0000ac700)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001f7f10 sp=0xc0001f7e88 pc=0x75a3152f71c7
runtime_test.TestCgoExternalThreadPanic(0xc0000ac700)
/workdir/go/src/runtime/crash_cgo_test.go:75 +0x42 fp=0xc0001f7fa8 sp=0xc0001f7f10 pc=0x75a31538bc92
testing.tRunner(0xc0000ac700, 0x75a315521a18)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001f7fd0 sp=0xc0001f7fa8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001f7fd8 sp=0xc0001f7fd0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22032 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001e65c8 sp=0xc0001e65a8 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xa901)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001e6658 sp=0xc0001e65c8 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001e6688 sp=0xc0001e6658 pc=0x75a31521defb
testing.(*T).Parallel(0xc000392b00)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001e6710 sp=0xc0001e6688 pc=0x75a3152f71c7
runtime_test.TestSigStackSwapping(0xc000392b00)
/workdir/go/src/runtime/crash_cgo_test.go:476 +0x42 fp=0xc0001e67a8 sp=0xc0001e6710 pc=0x75a31538ff22
testing.tRunner(0xc000392b00, 0x75a3155222e8)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001e67d0 sp=0xc0001e67a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001e67d8 sp=0xc0001e67d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22025 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001e6c40 sp=0xc0001e6c20 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xaa01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001e6cd0 sp=0xc0001e6c40 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001e6d00 sp=0xc0001e6cd0 pc=0x75a31521defb
testing.(*T).Parallel(0xc000392300)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001e6d88 sp=0xc0001e6d00 pc=0x75a3152f71c7
runtime_test.testCgoPprof(0xc000392300, 0x0, 0x0, 0x75a3153fa8c8, 0xe, 0x75a3153f9f4e, 0xc, 0x75a3153fa556, 0xd)
/workdir/go/src/runtime/crash_cgo_test.go:267 +0x45 fp=0xc0001e6f50 sp=0xc0001e6d88 pc=0x75a31538da85
runtime_test.TestCgoPprofThread(0xc000392300)
/workdir/go/src/runtime/crash_cgo_test.go:337 +0x74 fp=0xc0001e6fa8 sp=0xc0001e6f50 pc=0x75a31538eb34
testing.tRunner(0xc000392300, 0x75a315521a58)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001e6fd0 sp=0xc0001e6fa8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001e6fd8 sp=0xc0001e6fd0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22518 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001dfdd8 sp=0xc0001dfdb8 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xa901)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001dfe68 sp=0xc0001dfdd8 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001dfe98 sp=0xc0001dfe68 pc=0x75a31521defb
testing.(*T).Parallel(0xc0000ad000)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001dff20 sp=0xc0001dfe98 pc=0x75a3152f71c7
runtime_test.TestCgoCrashTraceback(0xc0000ad000)
/workdir/go/src/runtime/crash_cgo_test.go:241 +0x34 fp=0xc0001dffa8 sp=0xc0001dff20 pc=0x75a31538d5e4
testing.tRunner(0xc0000ad000, 0x75a315521a00)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001dffd0 sp=0xc0001dffa8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001dffd8 sp=0xc0001dffd0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22515 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001f1d50 sp=0xc0001f1d30 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xb801)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001f1de0 sp=0xc0001f1d50 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001f1e10 sp=0xc0001f1de0 pc=0x75a31521defb
testing.(*T).Parallel(0xc0000acd00)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001f1e98 sp=0xc0001f1e10 pc=0x75a3152f71c7
runtime_test.TestCgoCheckBytes(0xc0000acd00)
/workdir/go/src/runtime/crash_cgo_test.go:182 +0x45 fp=0xc0001f1fa8 sp=0xc0001f1e98 pc=0x75a31538cb35
testing.tRunner(0xc0000acd00, 0x75a3155219f0)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001f1fd0 sp=0xc0001f1fa8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001f1fd8 sp=0xc0001f1fd0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22519 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001f2580 sp=0xc0001f2560 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xac01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001f2610 sp=0xc0001f2580 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001f2640 sp=0xc0001f2610 pc=0x75a31521defb
testing.(*T).Parallel(0xc0000ad100)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001f26c8 sp=0xc0001f2640 pc=0x75a3152f71c7
runtime_test.TestRaceSignal(0xc0000ad100)
/workdir/go/src/runtime/crash_cgo_test.go:373 +0x42 fp=0xc0001f27a8 sp=0xc0001f26c8 pc=0x75a31538f0d2
testing.tRunner(0xc0000ad100, 0x75a3155221c8)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001f27d0 sp=0xc0001f27a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001f27d8 sp=0xc0001f27d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22516 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001f3dc8 sp=0xc0001f3da8 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xaf01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001f3e58 sp=0xc0001f3dc8 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001f3e88 sp=0xc0001f3e58 pc=0x75a31521defb
testing.(*T).Parallel(0xc0000ace00)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001f3f10 sp=0xc0001f3e88 pc=0x75a3152f71c7
runtime_test.TestCgoPanicDeadlock(0xc0000ace00)
/workdir/go/src/runtime/crash_cgo_test.go:222 +0x42 fp=0xc0001f3fa8 sp=0xc0001f3f10 pc=0x75a31538d292
testing.tRunner(0xc0000ace00, 0x75a315521a40)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001f3fd0 sp=0xc0001f3fa8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001f3fd8 sp=0xc0001f3fd0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22024 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001f4440 sp=0xc0001f4420 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xad01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001f44d0 sp=0xc0001f4440 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001f4500 sp=0xc0001f44d0 pc=0x75a31521defb
testing.(*T).Parallel(0xc000392200)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001f4588 sp=0xc0001f4500 pc=0x75a3152f71c7
runtime_test.testCgoPprof(0xc000392200, 0x75a3153fa812, 0xe, 0x75a3153f8668, 0x8, 0x75a3153f8072, 0x6, 0x75a3153fa086, 0xc)
/workdir/go/src/runtime/crash_cgo_test.go:267 +0x45 fp=0xc0001f4750 sp=0xc0001f4588 pc=0x75a31538da85
runtime_test.TestCgoPprofPIE(0xc000392200)
/workdir/go/src/runtime/crash_cgo_test.go:333 +0x81 fp=0xc0001f47a8 sp=0xc0001f4750 pc=0x75a31538eaa1
testing.tRunner(0xc000392200, 0x75a315521a48)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001f47d0 sp=0xc0001f47a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001f47d8 sp=0xc0001f47d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22514 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001ec5c8 sp=0xc0001ec5a8 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xa901)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001ec658 sp=0xc0001ec5c8 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001ec688 sp=0xc0001ec658 pc=0x75a31521defb
testing.(*T).Parallel(0xc0000acc00)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001ec710 sp=0xc0001ec688 pc=0x75a3152f71c7
runtime_test.TestEnsureDropM(0xc0000acc00)
/workdir/go/src/runtime/crash_cgo_test.go:165 +0x42 fp=0xc0001ec7a8 sp=0xc0001ec710 pc=0x75a31538c992
testing.tRunner(0xc0000acc00, 0x75a315521c48)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001ec7d0 sp=0xc0001ec7a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001ec7d8 sp=0xc0001ec7d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22033 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001ff5c8 sp=0xc0001ff5a8 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xa301)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001ff658 sp=0xc0001ff5c8 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001ff688 sp=0xc0001ff658 pc=0x75a31521defb
testing.(*T).Parallel(0xc000392c00)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001ff710 sp=0xc0001ff688 pc=0x75a3152f71c7
runtime_test.TestCgoTracebackSigpanic(0xc000392c00)
/workdir/go/src/runtime/crash_cgo_test.go:493 +0x42 fp=0xc0001ff7a8 sp=0xc0001ff710 pc=0x75a3153900c2
testing.tRunner(0xc000392c00, 0x75a315521a78)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001ff7d0 sp=0xc0001ff7a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001ff7d8 sp=0xc0001ff7d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 22716 [runnable]:
runtime.gopark(0x75a315520d48, 0xc0000701d8, 0xc00005170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0001eb580 sp=0xc0001eb560 pc=0x75a31524a7c6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc000070180, 0x0, 0x75a3152f7101, 0xaf01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc0001eb610 sp=0xc0001eb580 pc=0x75a31521e23c
runtime.chanrecv1(0xc000070180, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc0001eb640 sp=0xc0001eb610 pc=0x75a31521defb
testing.(*T).Parallel(0xc000393200)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0001eb6c8 sp=0xc0001eb640 pc=0x75a3152f71c7
runtime_test.TestMemmoveOverflow(0xc000393200)
/workdir/go/src/runtime/memmove_linux_amd64_test.go:19 +0x42 fp=0xc0001eb7a8 sp=0xc0001eb6c8 pc=0x75a3153b9912
testing.tRunner(0xc000393200, 0x75a315521fa8)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0001eb7d0 sp=0xc0001eb7a8 pc=0x75a3152f7471
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001eb7d8 sp=0xc0001eb7d0 pc=0x75a31527cb51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 23226 [GC worker (idle)]:
runtime.gopark(0x75a315520b98, 0xc000674010, 0x75a3154b1418, 0x0)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0002d7760 sp=0xc0002d7740 pc=0x75a31524a7c6
runtime.gcBgMarkWorker(0xc000024000)
/workdir/go/src/runtime/mgc.go:1834 +0x105 fp=0xc0002d77d8 sp=0xc0002d7760 pc=0x75a315235355
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0002d77e0 sp=0xc0002d77d8 pc=0x75a31527cb51
created by runtime.gcBgMarkStartWorkers
/workdir/go/src/runtime/mgc.go:1782 +0x79

goroutine 24344 [runnable]:
testing.runTests.func1.1(0xc0000ac000)
/workdir/go/src/testing/testing.go:1212 fp=0xc0005247d8 sp=0xc0005247d0 pc=0x75a3152fb120
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0005247e0 sp=0xc0005247d8 pc=0x75a31527cb51
created by testing.runTests.func1
/workdir/go/src/testing/testing.go:1212 +0xae

goroutine 24276 [GC worker (idle)]:
runtime.gopark(0x75a315520b98, 0xc000019f10, 0xc000ab1418, 0x0)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000abff60 sp=0xc000abff40 pc=0x75a31524a7c6
runtime.gcBgMarkWorker(0xc000026500)
/workdir/go/src/runtime/mgc.go:1834 +0x105 fp=0xc000abffd8 sp=0xc000abff60 pc=0x75a315235355
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000abffe0 sp=0xc000abffd8 pc=0x75a31527cb51
created by runtime.gcBgMarkStartWorkers
/workdir/go/src/runtime/mgc.go:1782 +0x79
exitcode=2FAIL runtime 11.631s t0 2019-05-24 17:48:34.831875164 +0000 UTC m=+144.782683032 now 2019-05-24 17:48:46.463003474 +0000 UTC m=+156.413811365

Notice the first line from the panic:

panic: test timed out after 9m0s t0 2019-05-24 17:48:37.59701419 +0000 UTC m=+0.000534711 now 2019-05-24 17:48:45.909217 +0000 UTC m=+8.312724651

As far as I can tell, time.AfterFunc was fired after ~10 seconds, way before the 9 minutes were up. That only 10 seconds in real time has passed is confirmed by the FAIL line printed by the go tool (the go tool run on the host on the android emu builders):

FAIL runtime 11.631s t0 2019-05-24 17:48:34.831875164 +0000 UTC m=+144.782683032 now 2019-05-24 17:48:46.463003474 +0000 UTC m=+156.413811365

@eliasnaur
Copy link
Contributor

@eliasnaur eliasnaur commented May 25, 2019

With the revised debug change:

$ git diff
diff --git a/src/cmd/go/internal/test/test.go b/src/cmd/go/internal/test/test.go
index 8440a83951..634cc6bd0e 100644
--- a/src/cmd/go/internal/test/test.go
+++ b/src/cmd/go/internal/test/test.go
@@ -1201,7 +1201,8 @@ func (c *runCache) builderRunTest(b *work.Builder, a *work.Action) error {
        }
        out := buf.Bytes()
        a.TestOutput = &buf
-       t := fmt.Sprintf("%.3fs", time.Since(t0).Seconds())
+       now := time.Now()
+       t := fmt.Sprintf("%.3fs t0 %v now %v", now.Sub(t0).Seconds(), t0, now)
 
        mergeCoverProfile(cmd.Stdout, a.Objdir+"_cover_.out")
 
diff --git a/src/runtime/time.go b/src/runtime/time.go
index 28a4722866..c7b1eafd12 100644
--- a/src/runtime/time.go
+++ b/src/runtime/time.go
@@ -27,6 +27,7 @@ type timer struct {
        f      func(interface{}, uintptr)
        arg    interface{}
        seq    uintptr
+       debug  bool
 }
 
 // timersLen is the length of timers array.
@@ -294,6 +295,9 @@ func timerproc(tb *timersBucket) {
                        if raceenabled {
                                raceacquire(unsafe.Pointer(t))
                        }
+                       if t.debug {
+                               println("debug timer timeout, t.when: ", t.when, " now ", now, " delta ", delta)
+                       }
                        f(arg, seq)
                        lock(&tb.lock)
                }
diff --git a/src/testing/testing.go b/src/testing/testing.go
index 339df13f43..3f67b53864 100644
--- a/src/testing/testing.go
+++ b/src/testing/testing.go
@@ -1376,10 +1376,12 @@ func toOutputDir(path string) string {
 // startAlarm starts an alarm if requested.
 func (m *M) startAlarm() {
        if *timeout > 0 {
-               m.timer = time.AfterFunc(*timeout, func() {
+               t0 := time.Now()
+               m.timer = time.AfterFuncDebug(*timeout, func() {
+                       now := time.Now()
                        m.after()
                        debug.SetTraceback("all")
-                       panic(fmt.Sprintf("test timed out after %v", *timeout))
+                       panic(fmt.Sprintf("test timed out after %v t0 %v now %v", *timeout, t0, now))
                })
        }
 }
diff --git a/src/time/sleep.go b/src/time/sleep.go
index 10edf6fe0e..adfe5f7ba5 100644
--- a/src/time/sleep.go
+++ b/src/time/sleep.go
@@ -19,6 +19,7 @@ type runtimeTimer struct {
        f      func(interface{}, uintptr) // NOTE: must not be closure
        arg    interface{}
        seq    uintptr
+       debug  bool
 }
 
 // when is a helper function for setting the 'when' field of a runtimeTimer.
@@ -165,6 +166,19 @@ func AfterFunc(d Duration, f func()) *Timer {
        return t
 }
 
+func AfterFuncDebug(d Duration, f func()) *Timer {
+       t := &Timer{
+               r: runtimeTimer{
+                       when:  when(d),
+                       f:     goFunc,
+                       arg:   f,
+                       debug: true,
+               },
+       }
+       startTimer(&t.r)
+       return t
+}
+
 func goFunc(arg interface{}, seq uintptr) {
        go arg.(func())()
 }

I managed to reproduce a crash with bogus monotonic time readings:

Building Go cmd/dist using /go1.4.
Building Go toolchain1 using /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 host, linux/amd64.
Building packages and commands for target, android/amd64.
Testing packages.

ok archive/tar 24.967s t0 2019-05-25 21:20:12.09920545 +0000 UTC m=+4.361639504 now 2019-05-25 21:20:37.066085887 +0000 UTC m=+29.328519992
ok archive/zip 25.712s t0 2019-05-25 21:20:12.097896834 +0000 UTC m=+4.360330899 now 2019-05-25 21:20:37.810286582 +0000 UTC m=+30.072720657
ok bufio 24.934s t0 2019-05-25 21:20:11.904423722 +0000 UTC m=+4.166857806 now 2019-05-25 21:20:36.838631946 +0000 UTC m=+29.101066051
ok bytes 23.546s t0 2019-05-25 21:20:13.99862574 +0000 UTC m=+6.261059797 now 2019-05-25 21:20:37.544338874 +0000 UTC m=+29.806772943
ok compress/bzip2 0.910s t0 2019-05-25 21:20:37.319529617 +0000 UTC m=+29.581963714 now 2019-05-25 21:20:38.229108726 +0000 UTC m=+30.491542823
ok compress/flate 1.236s t0 2019-05-25 21:20:38.673686818 +0000 UTC m=+30.936120903 now 2019-05-25 21:20:39.909672229 +0000 UTC m=+32.172106301
ok compress/gzip 1.830s t0 2019-05-25 21:20:38.712046291 +0000 UTC m=+30.974480372 now 2019-05-25 21:20:40.541979546 +0000 UTC m=+32.804413661
ok compress/lzw 1.161s t0 2019-05-25 21:20:38.850948371 +0000 UTC m=+31.113382451 now 2019-05-25 21:20:40.01220092 +0000 UTC m=+32.274635032
ok compress/zlib 1.153s t0 2019-05-25 21:20:39.080098511 +0000 UTC m=+31.342532569 now 2019-05-25 21:20:40.233305693 +0000 UTC m=+32.495739782
ok container/heap 0.269s t0 2019-05-25 21:20:40.472783818 +0000 UTC m=+32.735217904 now 2019-05-25 21:20:40.741561744 +0000 UTC m=+33.003995854
ok container/list 0.356s t0 2019-05-25 21:20:40.636738482 +0000 UTC m=+32.899172593 now 2019-05-25 21:20:40.992884875 +0000 UTC m=+33.255318970
ok container/ring 0.653s t0 2019-05-25 21:20:40.827838612 +0000 UTC m=+33.090272684 now 2019-05-25 21:20:41.480987163 +0000 UTC m=+33.743421248
ok context 1.405s t0 2019-05-25 21:20:44.346432005 +0000 UTC m=+36.608866121 now 2019-05-25 21:20:45.751287615 +0000 UTC m=+38.013721757
ok crypto 0.420s t0 2019-05-25 21:20:41.734559484 +0000 UTC m=+33.996993579 now 2019-05-25 21:20:42.154610862 +0000 UTC m=+34.417044918
ok crypto/aes 0.277s t0 2019-05-25 21:20:42.163182848 +0000 UTC m=+34.425616916 now 2019-05-25 21:20:42.439846757 +0000 UTC m=+34.702280915
ok crypto/cipher 0.376s t0 2019-05-25 21:20:42.872489917 +0000 UTC m=+35.134924028 now 2019-05-25 21:20:43.24808747 +0000 UTC m=+35.510521560
ok crypto/des 0.313s t0 2019-05-25 21:20:43.177035926 +0000 UTC m=+35.439470030 now 2019-05-25 21:20:43.490490587 +0000 UTC m=+35.752924659
ok crypto/dsa 0.802s t0 2019-05-25 21:20:43.3758827 +0000 UTC m=+35.638316770 now 2019-05-25 21:20:44.178023335 +0000 UTC m=+36.440457406
ok crypto/ecdsa 0.440s t0 2019-05-25 21:20:44.142267313 +0000 UTC m=+36.404701370 now 2019-05-25 21:20:44.582378494 +0000 UTC m=+36.844812599
ok crypto/ed25519 1.232s t0 2019-05-25 21:20:44.710489757 +0000 UTC m=+36.972923850 now 2019-05-25 21:20:45.942380358 +0000 UTC m=+38.204814459
ok crypto/elliptic 1.157s t0 2019-05-25 21:20:45.170246025 +0000 UTC m=+37.432680083 now 2019-05-25 21:20:46.327637327 +0000 UTC m=+38.590071407
ok crypto/hmac 0.979s t0 2019-05-25 21:20:45.117513022 +0000 UTC m=+37.379947080 now 2019-05-25 21:20:46.096661659 +0000 UTC m=+38.359095768
ok crypto/internal/subtle 0.530s t0 2019-05-25 21:20:46.144406098 +0000 UTC m=+38.406840198 now 2019-05-25 21:20:46.674192784 +0000 UTC m=+38.936626893
ok crypto/md5 0.342s t0 2019-05-25 21:20:46.614528672 +0000 UTC m=+38.876962773 now 2019-05-25 21:20:46.956074612 +0000 UTC m=+39.218508700
ok crypto/rand 0.393s t0 2019-05-25 21:20:46.808832558 +0000 UTC m=+39.071266644 now 2019-05-25 21:20:47.201494317 +0000 UTC m=+39.463928414
ok crypto/rc4 0.575s t0 2019-05-25 21:20:46.96173579 +0000 UTC m=+39.224169891 now 2019-05-25 21:20:47.536281886 +0000 UTC m=+39.798715991
ok crypto/rsa 0.451s t0 2019-05-25 21:20:47.481682776 +0000 UTC m=+39.744116847 now 2019-05-25 21:20:47.932780561 +0000 UTC m=+40.195214680
ok crypto/sha1 0.544s t0 2019-05-25 21:20:47.757645989 +0000 UTC m=+40.020080121 now 2019-05-25 21:20:48.30130812 +0000 UTC m=+40.563742224
ok crypto/sha256 0.291s t0 2019-05-25 21:20:48.148635829 +0000 UTC m=+40.411069918 now 2019-05-25 21:20:48.439140438 +0000 UTC m=+40.701574540
ok crypto/sha512 0.292s t0 2019-05-25 21:20:48.302740333 +0000 UTC m=+40.565174437 now 2019-05-25 21:20:48.594742572 +0000 UTC m=+40.857176647
ok crypto/subtle 0.305s t0 2019-05-25 21:20:48.501674344 +0000 UTC m=+40.764108410 now 2019-05-25 21:20:48.806530138 +0000 UTC m=+41.068964250
ok crypto/tls 2.013s t0 2019-05-25 21:20:56.965928875 +0000 UTC m=+49.228363003 now 2019-05-25 21:20:58.979040208 +0000 UTC m=+51.241474312
ok crypto/x509 1.129s t0 2019-05-25 21:20:51.668835436 +0000 UTC m=+43.931269751 now 2019-05-25 21:20:52.797719559 +0000 UTC m=+45.060153650
ok database/sql 2.688s t0 2019-05-25 21:20:52.207283257 +0000 UTC m=+44.469717344 now 2019-05-25 21:20:54.895371636 +0000 UTC m=+47.157805726
ok database/sql/driver 0.613s t0 2019-05-25 21:20:52.30203818 +0000 UTC m=+44.564472250 now 2019-05-25 21:20:52.915424826 +0000 UTC m=+45.177858903
ok debug/dwarf 0.371s t0 2019-05-25 21:20:54.875319574 +0000 UTC m=+47.137753682 now 2019-05-25 21:20:55.246697036 +0000 UTC m=+47.509131124
ok debug/elf 0.587s t0 2019-05-25 21:20:55.186030538 +0000 UTC m=+47.448464614 now 2019-05-25 21:20:55.772914565 +0000 UTC m=+48.035348628
ok debug/gosym 0.541s t0 2019-05-25 21:20:55.704299997 +0000 UTC m=+47.966734068 now 2019-05-25 21:20:56.245765077 +0000 UTC m=+48.508199181
ok debug/macho 0.374s t0 2019-05-25 21:20:56.332000502 +0000 UTC m=+48.594434559 now 2019-05-25 21:20:56.706209396 +0000 UTC m=+48.968643504
ok debug/pe 2.033s t0 2019-05-25 21:20:57.120934228 +0000 UTC m=+49.383368299 now 2019-05-25 21:20:59.154054644 +0000 UTC m=+51.416488762
ok debug/plan9obj 2.612s t0 2019-05-25 21:20:57.289438062 +0000 UTC m=+49.551872153 now 2019-05-25 21:20:59.901660793 +0000 UTC m=+52.164094896
ok encoding/ascii85 1.790s t0 2019-05-25 21:20:57.518216549 +0000 UTC m=+49.780650617 now 2019-05-25 21:20:59.307753235 +0000 UTC m=+51.570187335
ok encoding/asn1 0.215s t0 2019-05-25 21:20:59.911528438 +0000 UTC m=+52.173962570 now 2019-05-25 21:21:00.126242078 +0000 UTC m=+52.388676176
ok encoding/base32 0.333s t0 2019-05-25 21:21:00.022968039 +0000 UTC m=+52.285402104 now 2019-05-25 21:21:00.356306732 +0000 UTC m=+52.618740793
ok encoding/base64 0.848s t0 2019-05-25 21:21:00.111473359 +0000 UTC m=+52.373907461 now 2019-05-25 21:21:00.959548569 +0000 UTC m=+53.221982709
ok encoding/binary 0.500s t0 2019-05-25 21:21:01.135278594 +0000 UTC m=+53.397712681 now 2019-05-25 21:21:01.635687738 +0000 UTC m=+53.898121862
ok encoding/csv 0.558s t0 2019-05-25 21:21:00.827803678 +0000 UTC m=+53.090237728 now 2019-05-25 21:21:01.386055521 +0000 UTC m=+53.648489630
ok encoding/gob 0.369s t0 2019-05-25 21:21:02.866462278 +0000 UTC m=+55.128896374 now 2019-05-25 21:21:03.235058455 +0000 UTC m=+55.497492554
ok encoding/hex 0.614s t0 2019-05-25 21:21:01.504616931 +0000 UTC m=+53.767051003 now 2019-05-25 21:21:02.118303039 +0000 UTC m=+54.380737127
ok encoding/json 0.282s t0 2019-05-25 21:21:04.17515918 +0000 UTC m=+56.437593238 now 2019-05-25 21:21:04.457176087 +0000 UTC m=+56.719610140
ok encoding/pem 0.440s t0 2019-05-25 21:21:03.734127274 +0000 UTC m=+55.996561333 now 2019-05-25 21:21:04.173781651 +0000 UTC m=+56.436215741
ok encoding/xml 0.919s t0 2019-05-25 21:21:04.319010835 +0000 UTC m=+56.581444920 now 2019-05-25 21:21:05.237956037 +0000 UTC m=+57.500390115
ok errors 0.370s t0 2019-05-25 21:21:04.217429421 +0000 UTC m=+56.479863479 now 2019-05-25 21:21:04.587102872 +0000 UTC m=+56.849536950
ok expvar 0.489s t0 2019-05-25 21:21:05.42612514 +0000 UTC m=+57.688559233 now 2019-05-25 21:21:05.915509637 +0000 UTC m=+58.177943724
ok flag 0.358s t0 2019-05-25 21:21:05.773675701 +0000 UTC m=+58.036109776 now 2019-05-25 21:21:06.132045907 +0000 UTC m=+58.394479971
ok fmt 0.520s t0 2019-05-25 21:21:07.171719585 +0000 UTC m=+59.434153671 now 2019-05-25 21:21:07.691556729 +0000 UTC m=+59.953990843
ok go/ast 2.502s t0 2019-05-25 21:21:07.977177034 +0000 UTC m=+60.239611091 now 2019-05-25 21:21:10.479057356 +0000 UTC m=+62.741491424
ok go/build 1.975s t0 2019-05-25 21:21:07.771594449 +0000 UTC m=+60.034028531 now 2019-05-25 21:21:09.746119358 +0000 UTC m=+62.008553451
ok go/constant 1.363s t0 2019-05-25 21:21:08.497923771 +0000 UTC m=+60.760357842 now 2019-05-25 21:21:09.860467243 +0000 UTC m=+62.122901332
ok go/doc 1.226s t0 2019-05-25 21:21:09.041981302 +0000 UTC m=+61.304415385 now 2019-05-25 21:21:10.268450527 +0000 UTC m=+62.530884695
ok go/format 0.679s t0 2019-05-25 21:21:10.253507527 +0000 UTC m=+62.515941598 now 2019-05-25 21:21:10.932399462 +0000 UTC m=+63.194833543
ok go/importer 0.595s t0 2019-05-25 21:21:10.899061282 +0000 UTC m=+63.161495337 now 2019-05-25 21:21:11.493855286 +0000 UTC m=+63.756289376
ok go/internal/gccgoimporter 0.276s t0 2019-05-25 21:21:11.639782151 +0000 UTC m=+63.902216249 now 2019-05-25 21:21:11.915993543 +0000 UTC m=+64.178427645
ok go/internal/gcimporter 2.701s t0 2019-05-25 21:21:11.970470382 +0000 UTC m=+64.232904479 now 2019-05-25 21:21:14.671756027 +0000 UTC m=+66.934190138
ok go/internal/srcimporter 2.345s t0 2019-05-25 21:21:11.850024647 +0000 UTC m=+64.112458750 now 2019-05-25 21:21:14.194927822 +0000 UTC m=+66.457361951
ok go/parser 2.152s t0 2019-05-25 21:21:12.699083435 +0000 UTC m=+64.961517567 now 2019-05-25 21:21:14.851456872 +0000 UTC m=+67.113890951
ok go/printer 2.527s t0 2019-05-25 21:21:13.097950509 +0000 UTC m=+65.360384568 now 2019-05-25 21:21:15.62488283 +0000 UTC m=+67.887316913
ok go/scanner 1.450s t0 2019-05-25 21:21:14.771398336 +0000 UTC m=+67.033832393 now 2019-05-25 21:21:16.221690057 +0000 UTC m=+68.484124155
ok go/token 0.739s t0 2019-05-25 21:21:16.493956574 +0000 UTC m=+68.756390679 now 2019-05-25 21:21:17.23325654 +0000 UTC m=+69.495690623
ok go/types 0.844s t0 2019-05-25 21:21:18.905865029 +0000 UTC m=+71.168299313 now 2019-05-25 21:21:19.75016535 +0000 UTC m=+72.012599455
ok hash 0.243s t0 2019-05-25 21:21:16.40931692 +0000 UTC m=+68.671750978 now 2019-05-25 21:21:16.652025564 +0000 UTC m=+68.914459664
ok hash/adler32 0.611s t0 2019-05-25 21:21:17.184035719 +0000 UTC m=+69.446469776 now 2019-05-25 21:21:17.795052481 +0000 UTC m=+70.057486597
ok hash/crc32 0.351s t0 2019-05-25 21:21:18.33411708 +0000 UTC m=+70.596551173 now 2019-05-25 21:21:18.684998269 +0000 UTC m=+70.947432370
ok hash/crc64 0.132s t0 2019-05-25 21:21:18.310205295 +0000 UTC m=+70.572639370 now 2019-05-25 21:21:18.441933954 +0000 UTC m=+70.704368028
ok hash/fnv 0.539s t0 2019-05-25 21:21:18.478019387 +0000 UTC m=+70.740453443 now 2019-05-25 21:21:19.017071586 +0000 UTC m=+71.279505759
ok html 1.011s t0 2019-05-25 21:21:19.032550386 +0000 UTC m=+71.294984501 now 2019-05-25 21:21:20.043306269 +0000 UTC m=+72.305740336
ok html/template 0.553s t0 2019-05-25 21:21:20.314937558 +0000 UTC m=+72.577371646 now 2019-05-25 21:21:20.867530875 +0000 UTC m=+73.129964943
ok image 0.810s t0 2019-05-25 21:21:20.895841255 +0000 UTC m=+73.158275334 now 2019-05-25 21:21:21.70611937 +0000 UTC m=+73.968553528
ok image/color 0.285s t0 2019-05-25 21:21:20.856696221 +0000 UTC m=+73.119130293 now 2019-05-25 21:21:21.14135666 +0000 UTC m=+73.403790725
ok image/draw 0.661s t0 2019-05-25 21:21:21.449218152 +0000 UTC m=+73.711652234 now 2019-05-25 21:21:22.110218859 +0000 UTC m=+74.372652930
ok image/gif 1.316s t0 2019-05-25 21:21:21.914838592 +0000 UTC m=+74.177272667 now 2019-05-25 21:21:23.230374292 +0000 UTC m=+75.492808376
ok image/jpeg 1.571s t0 2019-05-25 21:21:22.101047221 +0000 UTC m=+74.363481313 now 2019-05-25 21:21:23.67237588 +0000 UTC m=+75.934809975
ok image/png 1.186s t0 2019-05-25 21:21:22.785757809 +0000 UTC m=+75.048191890 now 2019-05-25 21:21:23.971790849 +0000 UTC m=+76.234225144
ok index/suffixarray 2.176s t0 2019-05-25 21:21:22.998749602 +0000 UTC m=+75.261183658 now 2019-05-25 21:21:25.174332801 +0000 UTC m=+77.436766912
ok internal/cpu 0.674s t0 2019-05-25 21:21:30.339198367 +0000 UTC m=+82.601632444 now 2019-05-25 21:21:31.012821334 +0000 UTC m=+83.275255438
ok internal/fmtsort 0.830s t0 2019-05-25 21:21:25.704272934 +0000 UTC m=+77.966707012 now 2019-05-25 21:21:26.533852134 +0000 UTC m=+78.796286213
ok internal/oserror 0.340s t0 2019-05-25 21:21:25.208385836 +0000 UTC m=+77.470819894 now 2019-05-25 21:21:25.54793716 +0000 UTC m=+77.810371260
ok internal/poll 0.587s t0 2019-05-25 21:21:32.937721078 +0000 UTC m=+85.200155304 now 2019-05-25 21:21:33.524478509 +0000 UTC m=+85.786912598
ok internal/reflectlite 0.333s t0 2019-05-25 21:21:34.351320856 +0000 UTC m=+86.613754969 now 2019-05-25 21:21:34.684319129 +0000 UTC m=+86.946753219
ok internal/singleflight 0.416s t0 2019-05-25 21:21:33.490817812 +0000 UTC m=+85.753251869 now 2019-05-25 21:21:33.907242386 +0000 UTC m=+86.169676467
ok internal/trace 0.562s t0 2019-05-25 21:21:33.965556518 +0000 UTC m=+86.227990593 now 2019-05-25 21:21:34.527439302 +0000 UTC m=+86.789873393
ok internal/xcoff 0.576s t0 2019-05-25 21:21:34.407144754 +0000 UTC m=+86.669578821 now 2019-05-25 21:21:34.98282201 +0000 UTC m=+87.245256069
ok io 0.602s t0 2019-05-25 21:21:34.904306075 +0000 UTC m=+87.166740166 now 2019-05-25 21:21:35.506804866 +0000 UTC m=+87.769238975
ok io/ioutil 0.468s t0 2019-05-25 21:21:35.466257051 +0000 UTC m=+87.728691177 now 2019-05-25 21:21:35.934138424 +0000 UTC m=+88.196572482
ok log 0.297s t0 2019-05-25 21:21:35.381236744 +0000 UTC m=+87.643670855 now 2019-05-25 21:21:35.67848096 +0000 UTC m=+87.940915039
ok log/syslog 2.621s t0 2019-05-25 21:21:35.704512682 +0000 UTC m=+87.966946769 now 2019-05-25 21:21:38.325366969 +0000 UTC m=+90.587801068
ok math 0.344s t0 2019-05-25 21:21:38.865332137 +0000 UTC m=+91.127766221 now 2019-05-25 21:21:39.208875069 +0000 UTC m=+91.471309140
ok math/big 1.106s t0 2019-05-25 21:21:39.221610031 +0000 UTC m=+91.484044130 now 2019-05-25 21:21:40.327898886 +0000 UTC m=+92.590332964
ok math/bits 0.178s t0 2019-05-25 21:21:39.164451579 +0000 UTC m=+91.426885640 now 2019-05-25 21:21:39.342691524 +0000 UTC m=+91.605125652
ok math/cmplx 0.616s t0 2019-05-25 21:21:39.847851309 +0000 UTC m=+92.110285408 now 2019-05-25 21:21:40.463500534 +0000 UTC m=+92.725934662
ok math/rand 0.697s t0 2019-05-25 21:21:40.128475645 +0000 UTC m=+92.390909776 now 2019-05-25 21:21:40.82502484 +0000 UTC m=+93.087458943
ok mime 0.638s t0 2019-05-25 21:21:40.429106713 +0000 UTC m=+92.691540770 now 2019-05-25 21:21:41.067401744 +0000 UTC m=+93.329835837
ok mime/multipart 1.123s t0 2019-05-25 21:21:41.354816316 +0000 UTC m=+93.617250411 now 2019-05-25 21:21:42.47748656 +0000 UTC m=+94.739920659
ok mime/quotedprintable 0.232s t0 2019-05-25 21:21:41.166483174 +0000 UTC m=+93.428917252 now 2019-05-25 21:21:41.398266975 +0000 UTC m=+93.660701081
ok net 8.214s t0 2019-05-25 21:21:48.600725448 +0000 UTC m=+100.863159504 now 2019-05-25 21:21:56.814253968 +0000 UTC m=+109.076688109
ok net/http 14.794s t0 2019-05-25 21:21:51.255108906 +0000 UTC m=+103.517543048 now 2019-05-25 21:22:06.048851081 +0000 UTC m=+118.311285197
ok net/http/cgi 1.297s t0 2019-05-25 21:21:42.901276161 +0000 UTC m=+95.163710249 now 2019-05-25 21:21:44.19781231 +0000 UTC m=+96.460246421
ok net/http/cookiejar 0.555s t0 2019-05-25 21:21:44.053214336 +0000 UTC m=+96.315648394 now 2019-05-25 21:21:44.608510577 +0000 UTC m=+96.870944687
ok net/http/fcgi 0.829s t0 2019-05-25 21:21:45.542497283 +0000 UTC m=+97.804931356 now 2019-05-25 21:21:46.371618041 +0000 UTC m=+98.634052130
ok net/http/httptest 0.645s t0 2019-05-25 21:21:48.145196991 +0000 UTC m=+100.407631072 now 2019-05-25 21:21:48.790341913 +0000 UTC m=+101.052776015
ok net/http/httptrace 14.538s t0 2019-05-25 21:21:51.667595074 +0000 UTC m=+103.930029201 now 2019-05-25 21:22:06.205436029 +0000 UTC m=+118.467870126
ok net/http/httputil 15.190s t0 2019-05-25 21:21:51.594742567 +0000 UTC m=+103.857176627 now 2019-05-25 21:22:06.785069545 +0000 UTC m=+119.047503763
ok net/http/internal 9.792s t0 2019-05-25 21:21:57.250573795 +0000 UTC m=+109.513007851 now 2019-05-25 21:22:07.042525797 +0000 UTC m=+119.304959921
ok net/http/pprof 2.422s t0 2019-05-25 21:22:07.088114153 +0000 UTC m=+119.350548231 now 2019-05-25 21:22:09.510024249 +0000 UTC m=+121.772458357
ok net/internal/socktest 0.253s t0 2019-05-25 21:22:06.94833013 +0000 UTC m=+119.210764262 now 2019-05-25 21:22:07.201182858 +0000 UTC m=+119.463616951
ok net/mail 1.922s t0 2019-05-25 21:22:07.70112788 +0000 UTC m=+119.963561977 now 2019-05-25 21:22:09.623244023 +0000 UTC m=+121.885678130
ok net/rpc 1.577s t0 2019-05-25 21:22:08.316250348 +0000 UTC m=+120.578684433 now 2019-05-25 21:22:09.892796298 +0000 UTC m=+122.155230363
ok net/rpc/jsonrpc 1.997s t0 2019-05-25 21:22:08.534292789 +0000 UTC m=+120.796726846 now 2019-05-25 21:22:10.531543288 +0000 UTC m=+122.793977376
ok net/smtp 0.625s t0 2019-05-25 21:22:10.530317835 +0000 UTC m=+122.792751956 now 2019-05-25 21:22:11.155351843 +0000 UTC m=+123.417785947
ok net/textproto 0.388s t0 2019-05-25 21:22:10.366568239 +0000 UTC m=+122.629002334 now 2019-05-25 21:22:10.754149749 +0000 UTC m=+123.016583858
ok net/url 0.475s t0 2019-05-25 21:22:11.169495983 +0000 UTC m=+123.431930069 now 2019-05-25 21:22:11.644154809 +0000 UTC m=+123.906588946
ok os 5.152s t0 2019-05-25 21:22:13.540203147 +0000 UTC m=+125.802637226 now 2019-05-25 21:22:18.691754933 +0000 UTC m=+130.954189028
ok os/exec 3.916s t0 2019-05-25 21:22:13.292186581 +0000 UTC m=+125.554620709 now 2019-05-25 21:22:17.207796421 +0000 UTC m=+129.470230513
ok os/signal 3.495s t0 2019-05-25 21:22:12.709588504 +0000 UTC m=+124.972022605 now 2019-05-25 21:22:16.204443367 +0000 UTC m=+128.466878220
ok os/user 3.954s t0 2019-05-25 21:22:13.371381416 +0000 UTC m=+125.633815473 now 2019-05-25 21:22:17.325761775 +0000 UTC m=+129.588195839
ok path 2.141s t0 2019-05-25 21:22:16.666613204 +0000 UTC m=+128.929047261 now 2019-05-25 21:22:18.807930708 +0000 UTC m=+131.070364797
ok path/filepath 0.767s t0 2019-05-25 21:22:18.315029289 +0000 UTC m=+130.577463384 now 2019-05-25 21:22:19.082108219 +0000 UTC m=+131.344542312
ok plugin 1.217s t0 2019-05-25 21:22:18.452968573 +0000 UTC m=+130.715402630 now 2019-05-25 21:22:19.670052911 +0000 UTC m=+131.932487047
ok reflect 2.499s t0 2019-05-25 21:22:24.397278674 +0000 UTC m=+136.659712731 now 2019-05-25 21:22:26.896062228 +0000 UTC m=+139.158496346
ok regexp 0.309s t0 2019-05-25 21:22:20.630752369 +0000 UTC m=+132.893186456 now 2019-05-25 21:22:20.939642406 +0000 UTC m=+133.202076530
ok regexp/syntax 1.210s t0 2019-05-25 21:22:20.720551781 +0000 UTC m=+132.982985838 now 2019-05-25 21:22:21.930844636 +0000 UTC m=+134.193278710
ok runtime 15.585s t0 2019-05-25 21:22:32.319889316 +0000 UTC m=+144.582323370 now 2019-05-25 21:22:47.905255944 +0000 UTC m=+160.167690055
ok runtime/debug 0.730s t0 2019-05-25 21:22:21.756148767 +0000 UTC m=+134.018582823 now 2019-05-25 21:22:22.48599473 +0000 UTC m=+134.748428814
ok runtime/internal/atomic 0.418s t0 2019-05-25 21:22:22.456859878 +0000 UTC m=+134.719293935 now 2019-05-25 21:22:22.874580295 +0000 UTC m=+135.137014378
ok runtime/internal/math 0.454s t0 2019-05-25 21:22:23.083151476 +0000 UTC m=+135.345585612 now 2019-05-25 21:22:23.537440968 +0000 UTC m=+135.799875052
ok runtime/internal/sys 0.781s t0 2019-05-25 21:22:23.481840651 +0000 UTC m=+135.744274715 now 2019-05-25 21:22:24.262907362 +0000 UTC m=+136.525341462
ok runtime/pprof 6.592s t0 2019-05-25 21:22:26.504329774 +0000 UTC m=+138.766763832 now 2019-05-25 21:22:33.095884611 +0000 UTC m=+145.358318694
ok runtime/pprof/internal/profile 3.278s t0 2019-05-25 21:22:29.919946833 +0000 UTC m=+142.182380893 now 2019-05-25 21:22:33.197460681 +0000 UTC m=+145.459894836
ok runtime/trace 20.467s t0 2019-05-25 21:22:30.292038863 +0000 UTC m=+142.554472922 now 2019-05-25 21:22:50.758684171 +0000 UTC m=+163.021118260
ok sort 16.029s t0 2019-05-25 21:22:35.013555671 +0000 UTC m=+147.275989798 now 2019-05-25 21:22:51.042095328 +0000 UTC m=+163.304529418
ok strconv 12.919s t0 2019-05-25 21:22:38.88663357 +0000 UTC m=+151.149067651 now 2019-05-25 21:22:51.805682135 +0000 UTC m=+164.068116235
ok strings 2.205s t0 2019-05-25 21:22:51.084264557 +0000 UTC m=+163.346698619 now 2019-05-25 21:22:53.289059592 +0000 UTC m=+165.551493704
ok sync 1.677s t0 2019-05-25 21:22:54.7841766 +0000 UTC m=+167.046610727 now 2019-05-25 21:22:56.461313304 +0000 UTC m=+168.723747389
ok sync/atomic 1.106s t0 2019-05-25 21:22:53.606982854 +0000 UTC m=+165.869416912 now 2019-05-25 21:22:54.713357764 +0000 UTC m=+166.975791890
ok syscall 0.409s t0 2019-05-25 21:22:58.668219489 +0000 UTC m=+170.930653573 now 2019-05-25 21:22:59.077540837 +0000 UTC m=+171.339974942
ok testing 1.181s t0 2019-05-25 21:22:55.885130606 +0000 UTC m=+168.147564664 now 2019-05-25 21:22:57.065843834 +0000 UTC m=+169.328277927
ok testing/quick 1.006s t0 2019-05-25 21:22:56.358611938 +0000 UTC m=+168.621045997 now 2019-05-25 21:22:57.364507102 +0000 UTC m=+169.626941209
ok text/scanner 0.503s t0 2019-05-25 21:22:57.190145804 +0000 UTC m=+169.452579861 now 2019-05-25 21:22:57.693129522 +0000 UTC m=+169.955563629
ok text/tabwriter 0.837s t0 2019-05-25 21:22:57.628365024 +0000 UTC m=+169.890799121 now 2019-05-25 21:22:58.465533475 +0000 UTC m=+170.727967586
ok text/template 0.433s t0 2019-05-25 21:22:59.045999388 +0000 UTC m=+171.308433543 now 2019-05-25 21:22:59.478563332 +0000 UTC m=+171.740997435
ok text/template/parse 0.624s t0 2019-05-25 21:22:59.218352394 +0000 UTC m=+171.480786486 now 2019-05-25 21:22:59.842715206 +0000 UTC m=+172.105149292
ok time 3.767s t0 2019-05-25 21:23:02.496840563 +0000 UTC m=+174.759274688 now 2019-05-25 21:23:06.263954152 +0000 UTC m=+178.526388232
ok unicode 0.705s t0 2019-05-25 21:22:59.76992687 +0000 UTC m=+172.032360928 now 2019-05-25 21:23:00.475019862 +0000 UTC m=+172.737453964
ok unicode/utf16 0.187s t0 2019-05-25 21:23:01.108894577 +0000 UTC m=+173.371328653 now 2019-05-25 21:23:01.295456974 +0000 UTC m=+173.557891063
ok unicode/utf8 0.420s t0 2019-05-25 21:23:01.546109563 +0000 UTC m=+173.808543640 now 2019-05-25 21:23:01.965710639 +0000 UTC m=+174.228144721
ok cmd/addr2line 3.781s t0 2019-05-25 21:23:02.603359864 +0000 UTC m=+174.865793922 now 2019-05-25 21:23:06.384375624 +0000 UTC m=+178.646809733
ok cmd/api 3.598s t0 2019-05-25 21:23:03.071838104 +0000 UTC m=+175.334272162 now 2019-05-25 21:23:06.670224132 +0000 UTC m=+178.932658184
ok cmd/asm/internal/asm 4.082s t0 2019-05-25 21:23:04.343289681 +0000 UTC m=+176.605723737 now 2019-05-25 21:23:08.425254444 +0000 UTC m=+180.687688560
ok cmd/asm/internal/lex 2.173s t0 2019-05-25 21:23:06.921113147 +0000 UTC m=+179.183547212 now 2019-05-25 21:23:09.09431484 +0000 UTC m=+181.356748956
ok cmd/compile 1.195s t0 2019-05-25 21:23:16.968950334 +0000 UTC m=+189.231384392 now 2019-05-25 21:23:18.1642533 +0000 UTC m=+190.426687387
ok cmd/compile/internal/gc 1.257s t0 2019-05-25 21:23:21.832201529 +0000 UTC m=+194.094635591 now 2019-05-25 21:23:23.089016462 +0000 UTC m=+195.351450592
ok cmd/compile/internal/ssa 1.353s t0 2019-05-25 21:23:45.412863759 +0000 UTC m=+217.675297814 now 2019-05-25 21:23:46.765446027 +0000 UTC m=+219.027880154
ok cmd/compile/internal/syntax 0.692s t0 2019-05-25 21:23:12.243733728 +0000 UTC m=+184.506167834 now 2019-05-25 21:23:12.935660831 +0000 UTC m=+185.198095099
ok cmd/compile/internal/test 0.460s t0 2019-05-25 21:23:13.642850762 +0000 UTC m=+185.905284845 now 2019-05-25 21:23:14.102974699 +0000 UTC m=+186.365408789 [no tests to run]
ok cmd/compile/internal/types 0.737s t0 2019-05-25 21:23:15.253913596 +0000 UTC m=+187.516347653 now 2019-05-25 21:23:15.990796254 +0000 UTC m=+188.253230319
ok cmd/cover 0.581s t0 2019-05-25 21:23:18.241388389 +0000 UTC m=+190.503822440 now 2019-05-25 21:23:18.821960385 +0000 UTC m=+191.084394476
ok cmd/doc 0.456s t0 2019-05-25 21:23:19.352239612 +0000 UTC m=+191.614673700 now 2019-05-25 21:23:19.807847907 +0000 UTC m=+192.070282016
ok cmd/fix 0.753s t0 2019-05-25 21:23:20.381355278 +0000 UTC m=+192.643789342 now 2019-05-25 21:23:21.134811707 +0000 UTC m=+193.397245910
ok cmd/go 0.817s t0 2019-05-25 21:23:24.530705496 +0000 UTC m=+196.793139559 now 2019-05-25 21:23:25.34791131 +0000 UTC m=+197.610345396
ok cmd/go/internal/auth 0.307s t0 2019-05-25 21:23:25.290933908 +0000 UTC m=+197.553367994 now 2019-05-25 21:23:25.597513323 +0000 UTC m=+197.859947398
ok cmd/go/internal/cache 1.288s t0 2019-05-25 21:23:25.511497346 +0000 UTC m=+197.773931551 now 2019-05-25 21:23:26.799322521 +0000 UTC m=+199.061756605
ok cmd/go/internal/dirhash 1.142s t0 2019-05-25 21:23:26.250311559 +0000 UTC m=+198.512745642 now 2019-05-25 21:23:27.392126099 +0000 UTC m=+199.654560343
ok cmd/go/internal/generate 1.236s t0 2019-05-25 21:23:27.269501993 +0000 UTC m=+199.531936099 now 2019-05-25 21:23:28.50559642 +0000 UTC m=+200.768030507
ok cmd/go/internal/get 0.942s t0 2019-05-25 21:23:28.547441084 +0000 UTC m=+200.809875192 now 2019-05-25 21:23:29.489742156 +0000 UTC m=+201.752176239
ok cmd/go/internal/imports 0.272s t0 2019-05-25 21:23:28.475853474 +0000 UTC m=+200.738287530 now 2019-05-25 21:23:28.747945503 +0000 UTC m=+201.010379605
ok cmd/go/internal/load 0.488s t0 2019-05-25 21:23:29.629014559 +0000 UTC m=+201.891448643 now 2019-05-25 21:23:30.117465959 +0000 UTC m=+202.379900380
ok cmd/go/internal/lockedfile 0.240s t0 2019-05-25 21:23:29.548198601 +0000 UTC m=+201.810632669 now 2019-05-25 21:23:29.788316527 +0000 UTC m=+202.050750626
ok cmd/go/internal/lockedfile/internal/filelock 0.430s t0 2019-05-25 21:23:30.004568306 +0000 UTC m=+202.267002418 now 2019-05-25 21:23:30.43504296 +0000 UTC m=+202.697477057
ok cmd/go/internal/modconv 0.715s t0 2019-05-25 21:23:31.75130724 +0000 UTC m=+204.013741326 now 2019-05-25 21:23:32.466043463 +0000 UTC m=+204.728477549
ok cmd/go/internal/modfetch 0.765s t0 2019-05-25 21:23:32.432906696 +0000 UTC m=+204.695340784 now 2019-05-25 21:23:33.197449677 +0000 UTC m=+205.459883760
ok cmd/go/internal/modfetch/codehost 0.343s t0 2019-05-25 21:23:32.275613684 +0000 UTC m=+204.538047743 now 2019-05-25 21:23:32.618261212 +0000 UTC m=+204.880695315
ok cmd/go/internal/modfile 0.497s t0 2019-05-25 21:23:33.467536665 +0000 UTC m=+205.729970758 now 2019-05-25 21:23:33.964194273 +0000 UTC m=+206.226628367
ok cmd/go/internal/modload 0.641s t0 2019-05-25 21:23:34.807164001 +0000 UTC m=+207.069598087 now 2019-05-25 21:23:35.447707522 +0000 UTC m=+207.710141614
ok cmd/go/internal/module 0.371s t0 2019-05-25 21:23:34.096589652 +0000 UTC m=+206.359023744 now 2019-05-25 21:23:34.467406679 +0000 UTC m=+206.729840772
ok cmd/go/internal/mvs 0.234s t0 2019-05-25 21:23:34.671151049 +0000 UTC m=+206.933585123 now 2019-05-25 21:23:34.904895593 +0000 UTC m=+207.167329724
ok cmd/go/internal/note 0.384s t0 2019-05-25 21:23:35.256076688 +0000 UTC m=+207.518510758 now 2019-05-25 21:23:35.639641894 +0000 UTC m=+207.902075984
ok cmd/go/internal/par 0.675s t0 2019-05-25 21:23:35.537521211 +0000 UTC m=+207.799955289 now 2019-05-25 21:23:36.212753233 +0000 UTC m=+208.475187334
ok cmd/go/internal/renameio 0.490s t0 2019-05-25 21:23:35.985542813 +0000 UTC m=+208.247976919 now 2019-05-25 21:23:36.475595076 +0000 UTC m=+208.738029170
ok cmd/go/internal/search 0.480s t0 2019-05-25 21:23:36.379873159 +0000 UTC m=+208.642307245 now 2019-05-25 21:23:36.860056212 +0000 UTC m=+209.122490270
ok cmd/go/internal/semver 0.783s t0 2019-05-25 21:23:36.867072637 +0000 UTC m=+209.129506786 now 2019-05-25 21:23:37.650204973 +0000 UTC m=+209.912639060
ok cmd/go/internal/sumweb 0.696s t0 2019-05-25 21:23:37.645628047 +0000 UTC m=+209.908062176 now 2019-05-25 21:23:38.341989024 +0000 UTC m=+210.604423112
ok cmd/go/internal/tlog 1.626s t0 2019-05-25 21:23:38.316762025 +0000 UTC m=+210.579196109 now 2019-05-25 21:23:39.943064151 +0000 UTC m=+212.205498247
ok cmd/go/internal/txtar 2.243s t0 2019-05-25 21:23:38.39014802 +0000 UTC m=+210.652582078 now 2019-05-25 21:23:40.633412902 +0000 UTC m=+212.895847023
ok cmd/go/internal/work 0.802s t0 2019-05-25 21:23:42.180817637 +0000 UTC m=+214.443251714 now 2019-05-25 21:23:42.983135215 +0000 UTC m=+215.245569347
ok cmd/gofmt 1.381s t0 2019-05-25 21:23:40.742745364 +0000 UTC m=+213.005179421 now 2019-05-25 21:23:42.12326808 +0000 UTC m=+214.385702193
ok cmd/internal/buildid 0.437s t0 2019-05-25 21:23:42.073780647 +0000 UTC m=+214.336214712 now 2019-05-25 21:23:42.510477707 +0000 UTC m=+214.772911904
ok cmd/internal/dwarf 0.921s t0 2019-05-25 21:23:42.734009466 +0000 UTC m=+214.996443582 now 2019-05-25 21:23:43.654924934 +0000 UTC m=+215.917359057
ok cmd/internal/edit 0.909s t0 2019-05-25 21:23:43.096614116 +0000 UTC m=+215.359048217 now 2019-05-25 21:23:44.005435144 +0000 UTC m=+216.267869241
ok cmd/internal/goobj 1.179s t0 2019-05-25 21:23:43.919425215 +0000 UTC m=+216.181859304 now 2019-05-25 21:23:45.098653108 +0000 UTC m=+217.361087200
ok cmd/internal/obj 0.342s t0 2019-05-25 21:23:45.179618623 +0000 UTC m=+217.442052683 now 2019-05-25 21:23:45.521768834 +0000 UTC m=+217.784202903
ok cmd/internal/obj/arm64 1.031s t0 2019-05-25 21:23:45.872094582 +0000 UTC m=+218.134528658 now 2019-05-25 21:23:46.903455283 +0000 UTC m=+219.165889418
ok cmd/internal/obj/x86 0.672s t0 2019-05-25 21:23:46.601362878 +0000 UTC m=+218.863796946 now 2019-05-25 21:23:47.273099218 +0000 UTC m=+219.535533370
ok cmd/internal/objabi 0.243s t0 2019-05-25 21:23:46.826583229 +0000 UTC m=+219.089017327 now 2019-05-25 21:23:47.069742874 +0000 UTC m=+219.332177012
ok cmd/internal/src 0.912s t0 2019-05-25 21:23:47.586355357 +0000 UTC m=+219.848789436 now 2019-05-25 21:23:48.497883127 +0000 UTC m=+220.760317200
ok cmd/internal/test2json 0.509s t0 2019-05-25 21:23:47.485974381 +0000 UTC m=+219.748408525 now 2019-05-25 21:23:47.995396305 +0000 UTC m=+220.257830419
ok cmd/link 0.455s t0 2019-05-25 21:23:48.630546497 +0000 UTC m=+220.892980543 now 2019-05-25 21:23:49.085889264 +0000 UTC m=+221.348323352
ok cmd/link/internal/ld 0.324s t0 2019-05-25 21:23:52.023073458 +0000 UTC m=+224.285507544 now 2019-05-25 21:23:52.346818375 +0000 UTC m=+224.609252494
ok cmd/link/internal/sym 0.410s t0 2019-05-25 21:23:49.151344776 +0000 UTC m=+221.413779027 now 2019-05-25 21:23:49.561617875 +0000 UTC m=+221.824051996
ok cmd/nm 0.682s t0 2019-05-25 21:23:49.399850973 +0000 UTC m=+221.662285047 now 2019-05-25 21:23:50.081465566 +0000 UTC m=+222.343899699
ok cmd/objdump 0.342s t0 2019-05-25 21:23:50.039160656 +0000 UTC m=+222.301594788 now 2019-05-25 21:23:50.38086912 +0000 UTC m=+222.643303211
ok cmd/pack 0.389s t0 2019-05-25 21:23:50.340171153 +0000 UTC m=+222.602605295 now 2019-05-25 21:23:50.729113658 +0000 UTC m=+222.991547746
ok cmd/trace 0.253s t0 2019-05-25 21:23:52.406069693 +0000 UTC m=+224.668503757 now 2019-05-25 21:23:52.659218053 +0000 UTC m=+224.921652300
ok cmd/vet 0.196s t0 2019-05-25 21:23:52.590199748 +0000 UTC m=+224.852633836 now 2019-05-25 21:23:52.785850458 +0000 UTC m=+225.048284555

os/user with tag osusergo

ok os/user 0.197s t0 2019-05-25 21:23:53.849009607 +0000 UTC m=+0.757881239 now 2019-05-25 21:23:54.04579113 +0000 UTC m=+0.954662791

GOMAXPROCS=2 runtime -cpu=1,2,4 -quick

debug timer timeout, t.when: 737357704510now1558819458027234000delta-1558818720669529490panic: test timed out after 5m0s t0 2019-05-25 21:23:54.71644559 +0000 UTC m=+0.002343221 now 2019-05-25 21:24:18.027735 +0000 UTC m=+1558819020.672441481

goroutine 106074 [running]:
panic(0x7f8237b20f40, 0xc000084200)
/workdir/go/src/runtime/panic.go:662 +0x2c6 fp=0xc000045f10 sp=0xc000045e80 pc=0x7f82378a40a6
testing.(*M).startAlarm.func1()
/workdir/go/src/testing/testing.go:1384 +0x1e8 fp=0xc000045fe0 sp=0xc000045f10 pc=0x7f8237957438
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000045fe8 sp=0xc000045fe0 pc=0x7f82378d8a51
created by time.goFunc
/workdir/go/src/time/sleep.go:183 +0x46

goroutine 1 [chan receive, locked to thread]:
runtime.gopark(0x7f8237b7c6d0, 0xc00014ab38, 0x170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000044b68 sp=0xc000044b48 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc00014aae0, 0xc000044c7f, 0xc000000301, 0x7f82379537f2)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000044bf8 sp=0xc000044b68 pc=0x7f8237879c8c
runtime.chanrecv1(0xc00014aae0, 0xc000044c7f)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000044c28 sp=0xc000044bf8 pc=0x7f823787994b
testing.(*T).Run(0xc0000ece00, 0x7f8237a5e4c9, 0x1d, 0x7f8237b7d470, 0x7f8237924301)
/workdir/go/src/testing/testing.go:961 +0x379 fp=0xc000044cd8 sp=0xc000044c28 pc=0x7f8237953819
testing.runTests.func1(0xc00027b700)
/workdir/go/src/testing/testing.go:1207 +0x7a fp=0xc000044d28 sp=0xc000044cd8 pc=0x7f82379571ba
testing.tRunner(0xc00027b700, 0xc000044de0)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000044d50 sp=0xc000044d28 pc=0x7f8237953441
testing.runTests(0xc0000920a0, 0x7f8237cedc40, 0x11e, 0x11e, 0x0)
/workdir/go/src/testing/testing.go:1205 +0x2a9 fp=0xc000044e10 sp=0xc000044d50 pc=0x7f8237954cf9
testing.(*M).Run(0xc0000a0080, 0x0)
/workdir/go/src/testing/testing.go:1122 +0x162 fp=0xc000044ee0 sp=0xc000044e10 pc=0x7f8237953c72
runtime_test.TestMain(0xc0000a0080)
/workdir/go/src/runtime/crash_test.go:28 +0x31 fp=0xc000044f30 sp=0xc000044ee0 pc=0x7f82379ec9d1
main.main()
_testmain.go:1082 +0x137 fp=0xc000044f98 sp=0xc000044f30 pc=0x7f8237a526e7
runtime.main()
/workdir/go/src/runtime/proc.go:203 +0x21c fp=0xc000044fe0 sp=0xc000044f98 pc=0x7f82378a5e0c
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000044fe8 sp=0xc000044fe0 pc=0x7f82378d8a51

goroutine 2 [force gc (idle)]:
runtime.gopark(0x7f8237b7c6d0, 0x7f8237cf0530, 0x1411, 0x1)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000036fb0 sp=0xc000036f90 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.forcegchelper()
/workdir/go/src/runtime/proc.go:253 +0xbb fp=0xc000036fe0 sp=0xc000036fb0 pc=0x7f82378a609b
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000036fe8 sp=0xc000036fe0 pc=0x7f82378d8a51
created by runtime.init.5
/workdir/go/src/runtime/proc.go:242 +0x37

goroutine 3 [GC sweep wait]:
runtime.gopark(0x7f8237b7c6d0, 0x7f8237cf0920, 0x140c, 0x1)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0000377a8 sp=0xc000037788 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.bgsweep(0xc00005e000)
/workdir/go/src/runtime/mgcsweep.go:89 +0x135 fp=0xc0000377d8 sp=0xc0000377a8 pc=0x7f8237897a75
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0000377e0 sp=0xc0000377d8 pc=0x7f82378d8a51
created by runtime.gcenable
/workdir/go/src/runtime/mgc.go:210 +0x5e

goroutine 4 [sleep]:
runtime.gopark(0x7f8237b7c6d0, 0x7f8237cf0ae0, 0x7f82378a1313, 0x2)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000037f00 sp=0xc000037ee0 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.scavengeSleep(0xc00000024b, 0x64138, 0x1401)
/workdir/go/src/runtime/mgcscavenge.go:232 +0xc4 fp=0xc000037f38 sp=0xc000037f00 pc=0x7f8237896f04
runtime.bgscavenge(0xc00005e000)
/workdir/go/src/runtime/mgcscavenge.go:358 +0x312 fp=0xc000037fd8 sp=0xc000037f38 pc=0x7f8237897252
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000037fe0 sp=0xc000037fd8 pc=0x7f82378d8a51
created by runtime.gcenable
/workdir/go/src/runtime/mgc.go:211 +0x80

goroutine 18 [finalizer wait]:
runtime.gopark(0x7f8237b7c6d0, 0x7f8237d0cc58, 0xc000011410, 0x1)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000036758 sp=0xc000036738 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.runfinq()
/workdir/go/src/runtime/mfinal.go:175 +0xa7 fp=0xc0000367e0 sp=0xc000036758 pc=0x7f823788d2d7
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0000367e8 sp=0xc0000367e0 pc=0x7f82378d8a51
created by runtime.createfing
/workdir/go/src/runtime/mfinal.go:156 +0x63

goroutine 19 [timer goroutine (idle)]:
runtime.gopark(0x7f8237b7c6d0, 0x7f8237cf44e0, 0x1415, 0x1)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000032758 sp=0xc000032738 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.timerproc(0x7f8237cf44e0)
/workdir/go/src/runtime/time.go:307 +0x336 fp=0xc0000327d8 sp=0xc000032758 pc=0x7f82378c3ed6
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0000327e0 sp=0xc0000327d8 pc=0x7f82378d8a51
created by runtime.(*timersBucket).addtimerLocked
/workdir/go/src/runtime/time.go:170 +0x110

goroutine 10 [syscall]:
runtime.notetsleepg(0x7f8237cf4480, 0x2f15be66, 0x0)
/workdir/go/src/runtime/lock_futex.go:227 +0x38 fp=0xc000038758 sp=0xc000038728 pc=0x7f823787f688
runtime.timerproc(0x7f8237cf4460)
/workdir/go/src/runtime/time.go:315 +0x3b8 fp=0xc0000387d8 sp=0xc000038758 pc=0x7f82378c3f58
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0000387e0 sp=0xc0000387d8 pc=0x7f82378d8a51
created by runtime.(*timersBucket).addtimerLocked
/workdir/go/src/runtime/time.go:170 +0x110

goroutine 35 [runnable]:
runtime.notetsleepg(0x7f8237cf4580, 0x2dd47ace, 0x0)
/workdir/go/src/runtime/lock_futex.go:227 +0x38 fp=0xc000032f58 sp=0xc000032f28 pc=0x7f823787f688
runtime.timerproc(0x7f8237cf4560)
/workdir/go/src/runtime/time.go:315 +0x3b8 fp=0xc000032fd8 sp=0xc000032f58 pc=0x7f82378c3f58
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000032fe0 sp=0xc000032fd8 pc=0x7f82378d8a51
created by runtime.(*timersBucket).addtimerLocked
/workdir/go/src/runtime/time.go:170 +0x110

goroutine 105141 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc00042f5d0 sp=0xc00042f5b0 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x8601)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc00042f660 sp=0xc00042f5d0 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc00042f690 sp=0xc00042f660 pc=0x7f823787994b
testing.(*T).Parallel(0xc000104100)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc00042f718 sp=0xc00042f690 pc=0x7f8237953197
runtime_test.testConcurrentReadsAfterGrowth(0xc000104100, 0x6a77f98c01)
/workdir/go/src/runtime/map_test.go:315 +0x31 fp=0xc00042f788 sp=0xc00042f718 pc=0x7f8237a0f401
runtime_test.TestConcurrentReadsAfterGrowthReflect(0xc000104100)
/workdir/go/src/runtime/map_test.go:365 +0x32 fp=0xc00042f7a8 sp=0xc00042f788 pc=0x7f8237a0f732
testing.tRunner(0xc000104100, 0x7f8237b7d4b0)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc00042f7d0 sp=0xc00042f7a8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc00042f7d8 sp=0xc00042f7d0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 51 [timer goroutine (idle)]:
runtime.gopark(0x7f8237b7c6d0, 0x7f8237cf45e0, 0x1415, 0x1)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000100758 sp=0xc000100738 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.timerproc(0x7f8237cf45e0)
/workdir/go/src/runtime/time.go:307 +0x336 fp=0xc0001007d8 sp=0xc000100758 pc=0x7f82378c3ed6
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001007e0 sp=0xc0001007d8 pc=0x7f82378d8a51
created by runtime.(*timersBucket).addtimerLocked
/workdir/go/src/runtime/time.go:170 +0x110

goroutine 105040 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000a825c8 sp=0xc000a825a8 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9401)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000a82658 sp=0xc000a825c8 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000a82688 sp=0xc000a82658 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027b100)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000a82710 sp=0xc000a82688 pc=0x7f8237953197
runtime_test.TestSigStackSwapping(0xc00027b100)
/workdir/go/src/runtime/crash_cgo_test.go:476 +0x42 fp=0xc000a827a8 sp=0xc000a82710 pc=0x7f82379ebef2
testing.tRunner(0xc00027b100, 0x7f8237b7dc70)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000a827d0 sp=0xc000a827a8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000a827d8 sp=0xc000a827d0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 104345 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000790d50 sp=0xc000790d30 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9901)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000790de0 sp=0xc000790d50 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000790e10 sp=0xc000790de0 pc=0x7f823787994b
testing.(*T).Parallel(0xc0000ec500)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000790e98 sp=0xc000790e10 pc=0x7f8237953197
runtime_test.TestCgoCheckBytes(0xc0000ec500)
/workdir/go/src/runtime/crash_cgo_test.go:182 +0x45 fp=0xc000790fa8 sp=0xc000790e98 pc=0x7f82379e8b05
testing.tRunner(0xc0000ec500, 0x7f8237b7d378)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000790fd0 sp=0xc000790fa8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000790fd8 sp=0xc000790fd0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 105025 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000b06dc8 sp=0xc000b06da8 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0xbb01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000b06e58 sp=0xc000b06dc8 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000b06e88 sp=0xc000b06e58 pc=0x7f823787994b
testing.(*T).Parallel(0xc000104200)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000b06f10 sp=0xc000b06e88 pc=0x7f8237953197
runtime_test.TestCgoTraceback(0xc000104200)
/workdir/go/src/runtime/crash_cgo_test.go:43 +0x42 fp=0xc000b06fa8 sp=0xc000b06f10 pc=0x7f82379e78c2
testing.tRunner(0xc000104200, 0x7f8237b7d408)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000b06fd0 sp=0xc000b06fa8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000b06fd8 sp=0xc000b06fd0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 29015 [timer goroutine (idle)]:
runtime.gopark(0x7f8237b7c6d0, 0x7f8237cf47e0, 0x1415, 0x1)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000161758 sp=0xc000161738 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.timerproc(0x7f8237cf47e0)
/workdir/go/src/runtime/time.go:307 +0x336 fp=0xc0001617d8 sp=0xc000161758 pc=0x7f82378c3ed6
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0001617e0 sp=0xc0001617d8 pc=0x7f82378d8a51
created by runtime.(*timersBucket).addtimerLocked
/workdir/go/src/runtime/time.go:170 +0x110

goroutine 25573 [timer goroutine (idle)]:
runtime.gopark(0x7f8237b7c6d0, 0x7f8237cf46e0, 0x1415, 0x1)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0000f4f58 sp=0xc0000f4f38 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.timerproc(0x7f8237cf46e0)
/workdir/go/src/runtime/time.go:307 +0x336 fp=0xc0000f4fd8 sp=0xc0000f4f58 pc=0x7f82378c3ed6
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0000f4fe0 sp=0xc0000f4fd8 pc=0x7f82378d8a51
created by runtime.(*timersBucket).addtimerLocked
/workdir/go/src/runtime/time.go:170 +0x110

goroutine 105670 [GC worker (idle)]:
runtime.gopark(0x7f8237b7c518, 0xc00001a050, 0x1418, 0x0)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000abcf60 sp=0xc000abcf40 pc=0x7f82378a61f6
runtime.gcBgMarkWorker(0xc000024000)
/workdir/go/src/runtime/mgc.go:1837 +0x105 fp=0xc000abcfd8 sp=0xc000abcf60 pc=0x7f8237890d75
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000abcfe0 sp=0xc000abcfd8 pc=0x7f82378d8a51
created by runtime.gcBgMarkStartWorkers
/workdir/go/src/runtime/mgc.go:1785 +0x79

goroutine 105027 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000aca5c8 sp=0xc000aca5a8 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9101)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000aca658 sp=0xc000aca5c8 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000aca688 sp=0xc000aca658 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027a300)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000aca710 sp=0xc000aca688 pc=0x7f8237953197
runtime_test.TestCgoCCodeSIGPROF(0xc00027a300)
/workdir/go/src/runtime/crash_cgo_test.go:232 +0x42 fp=0xc000aca7a8 sp=0xc000aca710 pc=0x7f82379e9422
testing.tRunner(0xc00027a300, 0x7f8237b7d368)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000aca7d0 sp=0xc000aca7a8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000aca7d8 sp=0xc000aca7d0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 103725 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc0007915c8 sp=0xc0007915a8 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0xb701)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000791658 sp=0xc0007915c8 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000791688 sp=0xc000791658 pc=0x7f823787994b
testing.(*T).Parallel(0xc00010c000)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000791710 sp=0xc000791688 pc=0x7f8237953197
runtime_test.TestEnsureDropM(0xc00010c000)
/workdir/go/src/runtime/crash_cgo_test.go:165 +0x42 fp=0xc0007917a8 sp=0xc000791710 pc=0x7f82379e8962
testing.tRunner(0xc00010c000, 0x7f8237b7d5d0)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0007917d0 sp=0xc0007917a8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0007917d8 sp=0xc0007917d0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 105026 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000acadc8 sp=0xc000acada8 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x8b01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000acae58 sp=0xc000acadc8 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000acae88 sp=0xc000acae58 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027a200)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000acaf10 sp=0xc000acae88 pc=0x7f8237953197
runtime_test.TestCgoPanicDeadlock(0xc00027a200)
/workdir/go/src/runtime/crash_cgo_test.go:222 +0x42 fp=0xc000acafa8 sp=0xc000acaf10 pc=0x7f82379e9262
testing.tRunner(0xc00027a200, 0x7f8237b7d3c8)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000acafd0 sp=0xc000acafa8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000acafd8 sp=0xc000acafd0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 105033 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000034c40 sp=0xc000034c20 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0xaa01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000034cd0 sp=0xc000034c40 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000034d00 sp=0xc000034cd0 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027a900)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000034d88 sp=0xc000034d00 pc=0x7f8237953197
runtime_test.testCgoPprof(0xc00027a900, 0x0, 0x0, 0x7f8237a5c5e0, 0x19, 0x7f8237a55f16, 0xc, 0x7f8237a5a8a0, 0x15)
/workdir/go/src/runtime/crash_cgo_test.go:267 +0x45 fp=0xc000034f50 sp=0xc000034d88 pc=0x7f82379e9a55
runtime_test.TestCgoPprofThreadNoTraceback(0xc00027a900)
/workdir/go/src/runtime/crash_cgo_test.go:341 +0x74 fp=0xc000034fa8 sp=0xc000034f50 pc=0x7f82379eab94
testing.tRunner(0xc00027a900, 0x7f8237b7d3d8)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000034fd0 sp=0xc000034fa8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000034fd8 sp=0xc000034fd0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 105030 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc00042a440 sp=0xc00042a420 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9001)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc00042a4d0 sp=0xc00042a440 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc00042a500 sp=0xc00042a4d0 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027a600)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc00042a588 sp=0xc00042a500 pc=0x7f8237953197
runtime_test.testCgoPprof(0xc00027a600, 0x0, 0x0, 0x7f8237a54630, 0x8, 0x7f8237a5403a, 0x6, 0x7f8237a5604e, 0xc)
/workdir/go/src/runtime/crash_cgo_test.go:267 +0x45 fp=0xc00042a750 sp=0xc00042a588 pc=0x7f82379e9a55
runtime_test.TestCgoPprof(0xc00027a600)
/workdir/go/src/runtime/crash_cgo_test.go:329 +0x74 fp=0xc00042a7a8 sp=0xc00042a750 pc=0x7f82379ea9d4
testing.tRunner(0xc00027a600, 0x7f8237b7d3e8)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc00042a7d0 sp=0xc00042a7a8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc00042a7d8 sp=0xc00042a7d0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 104342 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc00078c580 sp=0xc00078c560 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9d01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc00078c610 sp=0xc00078c580 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc00078c640 sp=0xc00078c610 pc=0x7f823787994b
testing.(*T).Parallel(0xc0000ec200)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc00078c6c8 sp=0xc00078c640 pc=0x7f8237953197
runtime_test.TestCgoExternalThreadSIGPROF(0xc0000ec200)
/workdir/go/src/runtime/crash_cgo_test.go:87 +0x42 fp=0xc00078c7a8 sp=0xc00078c6c8 pc=0x7f82379e7e02
testing.tRunner(0xc0000ec200, 0x7f8237b7d3a8)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc00078c7d0 sp=0xc00078c7a8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc00078c7d8 sp=0xc00078c7d0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 105041 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000aff5c8 sp=0xc000aff5a8 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9001)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000aff658 sp=0xc000aff5c8 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000aff688 sp=0xc000aff658 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027b200)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000aff710 sp=0xc000aff688 pc=0x7f8237953197
runtime_test.TestCgoTracebackSigpanic(0xc00027b200)
/workdir/go/src/runtime/crash_cgo_test.go:493 +0x42 fp=0xc000aff7a8 sp=0xc000aff710 pc=0x7f82379ec092
testing.tRunner(0xc00027b200, 0x7f8237b7d400)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000aff7d0 sp=0xc000aff7a8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000aff7d8 sp=0xc000aff7d0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 105655 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000abd630 sp=0xc000abd610 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x8401)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000abd6c0 sp=0xc000abd630 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000abd6f0 sp=0xc000abd6c0 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027be00)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000abd778 sp=0xc000abd6f0 pc=0x7f8237953197
runtime_test.TestStackGrowthCallback(0xc00027be00)
/workdir/go/src/runtime/stack_test.go:200 +0x31 fp=0xc000abd7a8 sp=0xc000abd778 pc=0x7f8237a2d441
testing.tRunner(0xc00027be00, 0x7f8237b7dd78)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000abd7d0 sp=0xc000abd7a8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000abd7d8 sp=0xc000abd7d0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 28402 [timer goroutine (idle)]:
runtime.gopark(0x7f8237b7c6d0, 0x7f8237cf4760, 0x1415, 0x1)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000a82f58 sp=0xc000a82f38 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.timerproc(0x7f8237cf4760)
/workdir/go/src/runtime/time.go:307 +0x336 fp=0xc000a82fd8 sp=0xc000a82f58 pc=0x7f82378c3ed6
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000a82fe0 sp=0xc000a82fd8 pc=0x7f82378d8a51
created by runtime.(*timersBucket).addtimerLocked
/workdir/go/src/runtime/time.go:170 +0x110

goroutine 106730 [runnable]:
testing.tRunner.func1(0xc0000ece00)
/workdir/go/src/testing/testing.go:847 +0x3ab fp=0xc0000f27a8 sp=0xc0000f27a0 pc=0x7f82379570eb
testing.tRunner(0xc0000ece00, 0x7f8237b7d470)
/workdir/go/src/testing/testing.go:913 +0xcb fp=0xc0000f27d0 sp=0xc0000f27a8 pc=0x7f823795344b
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0000f27d8 sp=0xc0000f27d0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 25592 [timer goroutine (idle)]:
runtime.gopark(0x7f8237b7c6d0, 0x7f8237cf4860, 0x1415, 0x1)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000aa3f58 sp=0xc000aa3f38 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.timerproc(0x7f8237cf4860)
/workdir/go/src/runtime/time.go:307 +0x336 fp=0xc000aa3fd8 sp=0xc000aa3f58 pc=0x7f82378c3ed6
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000aa3fe0 sp=0xc000aa3fd8 pc=0x7f82378d8a51
created by runtime.(*timersBucket).addtimerLocked
/workdir/go/src/runtime/time.go:170 +0x110

goroutine 32755 [timer goroutine (idle)]:
runtime.gopark(0x7f8237b7c6d0, 0x7f8237cf4660, 0x1415, 0x1)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000abc758 sp=0xc000abc738 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.timerproc(0x7f8237cf4660)
/workdir/go/src/runtime/time.go:307 +0x336 fp=0xc000abc7d8 sp=0xc000abc758 pc=0x7f82378c3ed6
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000abc7e0 sp=0xc000abc7d8 pc=0x7f82378d8a51
created by runtime.(*timersBucket).addtimerLocked
/workdir/go/src/runtime/time.go:170 +0x110

goroutine 105038 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000b07e38 sp=0xc000b07e18 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x8b01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000b07ec8 sp=0xc000b07e38 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000b07ef8 sp=0xc000b07ec8 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027af00)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000b07f80 sp=0xc000b07ef8 pc=0x7f8237953197
runtime_test.TestCgoLockOSThreadExit(0xc00027af00)
/workdir/go/src/runtime/crash_cgo_test.go:452 +0x2d fp=0xc000b07fa8 sp=0xc000b07f80 pc=0x7f82379ebc1d
testing.tRunner(0xc00027af00, 0x7f8237b7d3b8)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000b07fd0 sp=0xc000b07fa8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000b07fd8 sp=0xc000b07fd0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 106073 [GC worker (idle)]:
runtime.gopark(0x7f8237b7c518, 0xc000b400e0, 0xc000781418, 0x0)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc00078ef60 sp=0xc00078ef40 pc=0x7f82378a61f6
runtime.gcBgMarkWorker(0xc00002af00)
/workdir/go/src/runtime/mgc.go:1837 +0x105 fp=0xc00078efd8 sp=0xc00078ef60 pc=0x7f8237890d75
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc00078efe0 sp=0xc00078efd8 pc=0x7f82378d8a51
created by runtime.gcBgMarkStartWorkers
/workdir/go/src/runtime/mgc.go:1785 +0x79

goroutine 105031 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000aea440 sp=0xc000aea420 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9301)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000aea4d0 sp=0xc000aea440 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000aea500 sp=0xc000aea4d0 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027a700)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000aea588 sp=0xc000aea500 pc=0x7f8237953197
runtime_test.testCgoPprof(0xc00027a700, 0x7f8237a567da, 0xe, 0x7f8237a54630, 0x8, 0x7f8237a5403a, 0x6, 0x7f8237a5604e, 0xc)
/workdir/go/src/runtime/crash_cgo_test.go:267 +0x45 fp=0xc000aea750 sp=0xc000aea588 pc=0x7f82379e9a55
runtime_test.TestCgoPprofPIE(0xc00027a700)
/workdir/go/src/runtime/crash_cgo_test.go:333 +0x81 fp=0xc000aea7a8 sp=0xc000aea750 pc=0x7f82379eaa71
testing.tRunner(0xc00027a700, 0x7f8237b7d3d0)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000aea7d0 sp=0xc000aea7a8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000aea7d8 sp=0xc000aea7d0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 104341 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000ad1dc8 sp=0xc000ad1da8 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x7c01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000ad1e58 sp=0xc000ad1dc8 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000ad1e88 sp=0xc000ad1e58 pc=0x7f823787994b
testing.(*T).Parallel(0xc0000ec100)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000ad1f10 sp=0xc000ad1e88 pc=0x7f8237953197
runtime_test.TestCgoExternalThreadPanic(0xc0000ec100)
/workdir/go/src/runtime/crash_cgo_test.go:75 +0x42 fp=0xc000ad1fa8 sp=0xc000ad1f10 pc=0x7f82379e7c62
testing.tRunner(0xc0000ec100, 0x7f8237b7d3a0)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000ad1fd0 sp=0xc000ad1fa8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000ad1fd8 sp=0xc000ad1fd0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 105036 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc00078bdc8 sp=0xc00078bda8 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9401)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc00078be58 sp=0xc00078bdc8 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc00078be88 sp=0xc00078be58 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027ad00)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc00078bf10 sp=0xc00078be88 pc=0x7f8237953197
runtime_test.TestCgoNumGoroutine(0xc00027ad00)
/workdir/go/src/runtime/crash_cgo_test.go:407 +0x42 fp=0xc00078bfa8 sp=0xc00078bf10 pc=0x7f82379eb612
testing.tRunner(0xc00027ad00, 0x7f8237b7d3c0)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc00078bfd0 sp=0xc00078bfa8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc00078bfd8 sp=0xc00078bfd0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 105035 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000ad0d80 sp=0xc000ad0d60 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9001)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000ad0e10 sp=0xc000ad0d80 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000ad0e40 sp=0xc000ad0e10 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027ac00)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000ad0ec8 sp=0xc000ad0e40 pc=0x7f8237953197
runtime_test.TestRaceSignal(0xc00027ac00)
/workdir/go/src/runtime/crash_cgo_test.go:373 +0x42 fp=0xc000ad0fa8 sp=0xc000ad0ec8 pc=0x7f82379eb0a2
testing.tRunner(0xc00027ac00, 0x7f8237b7db50)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000ad0fd0 sp=0xc000ad0fa8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000ad0fd8 sp=0xc000ad0fd0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 105065 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000aedd28 sp=0xc000aedd08 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9001)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000aeddb8 sp=0xc000aedd28 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000aedde8 sp=0xc000aeddb8 pc=0x7f823787994b
testing.(*T).Parallel(0xc000118a00)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000aede70 sp=0xc000aedde8 pc=0x7f8237953197
runtime_test.TestPanicTraceback(0xc000118a00)
/workdir/go/src/runtime/crash_test.go:414 +0x45 fp=0xc000aedfa8 sp=0xc000aede70 pc=0x7f82379efdf5
testing.tRunner(0xc000118a00, 0x7f8237b7da70)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000aedfd0 sp=0xc000aedfa8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000aedfd8 sp=0xc000aedfd0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 105064 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000ad85c8 sp=0xc000ad85a8 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9001)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000ad8658 sp=0xc000ad85c8 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000ad8688 sp=0xc000ad8658 pc=0x7f823787994b
testing.(*T).Parallel(0xc000118900)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000ad8710 sp=0xc000ad8688 pc=0x7f8237953197
runtime_test.TestNetpollDeadlock(0xc000118900)
/workdir/go/src/runtime/crash_test.go:405 +0x42 fp=0xc000ad87a8 sp=0xc000ad8710 pc=0x7f82379efc02
testing.tRunner(0xc000118900, 0x7f8237b7d978)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000ad87d0 sp=0xc000ad87a8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000ad87d8 sp=0xc000ad87d0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 104977 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000dfddc8 sp=0xc000dfdda8 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9701)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000dfde58 sp=0xc000dfddc8 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000dfde88 sp=0xc000dfde58 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027a100)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000dfdf10 sp=0xc000dfde88 pc=0x7f8237953197
runtime_test.TestCgoExecSignalMask(0xc00027a100)
/workdir/go/src/runtime/crash_cgo_test.go:151 +0x42 fp=0xc000dfdfa8 sp=0xc000dfdf10 pc=0x7f82379e87c2
testing.tRunner(0xc00027a100, 0x7f8237b7d398)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000dfdfd0 sp=0xc000dfdfa8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000dfdfd8 sp=0xc000dfdfd0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 106693 [GC worker (idle)]:
runtime.gopark(0x7f8237b7c518, 0xc000b400c0, 0xc000161418, 0x0)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000161f60 sp=0xc000161f40 pc=0x7f82378a61f6
runtime.gcBgMarkWorker(0xc000026500)
/workdir/go/src/runtime/mgc.go:1837 +0x105 fp=0xc000161fd8 sp=0xc000161f60 pc=0x7f8237890d75
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000161fe0 sp=0xc000161fd8 pc=0x7f82378d8a51
created by runtime.gcBgMarkStartWorkers
/workdir/go/src/runtime/mgc.go:1785 +0x79

goroutine 104343 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000df9d78 sp=0xc000df9d58 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9601)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000df9e08 sp=0xc000df9d78 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000df9e38 sp=0xc000df9e08 pc=0x7f823787994b
testing.(*T).Parallel(0xc0000ec300)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000df9ec0 sp=0xc000df9e38 pc=0x7f8237953197
runtime_test.TestCgoExternalThreadSignal(0xc0000ec300)
/workdir/go/src/runtime/crash_cgo_test.go:115 +0x42 fp=0xc000df9fa8 sp=0xc000df9ec0 pc=0x7f82379e81d2
testing.tRunner(0xc0000ec300, 0x7f8237b7d3b0)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000df9fd0 sp=0xc000df9fa8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000df9fd8 sp=0xc000df9fd0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 105032 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000ae7c40 sp=0xc000ae7c20 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9001)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000ae7cd0 sp=0xc000ae7c40 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000ae7d00 sp=0xc000ae7cd0 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027a800)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000ae7d88 sp=0xc000ae7d00 pc=0x7f8237953197
runtime_test.testCgoPprof(0xc00027a800, 0x0, 0x0, 0x7f8237a56890, 0xe, 0x7f8237a55f16, 0xc, 0x7f8237a5651e, 0xd)
/workdir/go/src/runtime/crash_cgo_test.go:267 +0x45 fp=0xc000ae7f50 sp=0xc000ae7d88 pc=0x7f82379e9a55
runtime_test.TestCgoPprofThread(0xc00027a800)
/workdir/go/src/runtime/crash_cgo_test.go:337 +0x74 fp=0xc000ae7fa8 sp=0xc000ae7f50 pc=0x7f82379eab04
testing.tRunner(0xc00027a800, 0x7f8237b7d3e0)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000ae7fd0 sp=0xc000ae7fa8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000ae7fd8 sp=0xc000ae7fd0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 106072 [GC worker (idle)]:
runtime.gopark(0x7f8237b7c518, 0xc000b400d0, 0xc000781418, 0x0)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc00078e760 sp=0xc00078e740 pc=0x7f82378a61f6
runtime.gcBgMarkWorker(0xc000028a00)
/workdir/go/src/runtime/mgc.go:1837 +0x105 fp=0xc00078e7d8 sp=0xc00078e760 pc=0x7f8237890d75
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc00078e7e0 sp=0xc00078e7d8 pc=0x7f82378d8a51
created by runtime.gcBgMarkStartWorkers
/workdir/go/src/runtime/mgc.go:1785 +0x79

goroutine 105037 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000afed80 sp=0xc000afed60 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9201)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000afee10 sp=0xc000afed80 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000afee40 sp=0xc000afee10 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027ae00)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000afeec8 sp=0xc000afee40 pc=0x7f8237953197
runtime_test.TestCatchPanic(0xc00027ae00)
/workdir/go/src/runtime/crash_cgo_test.go:416 +0x42 fp=0xc000afefa8 sp=0xc000afeec8 pc=0x7f82379eb7b2
testing.tRunner(0xc00027ae00, 0x7f8237b7d360)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000afefd0 sp=0xc000afefa8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000afefd8 sp=0xc000afefd0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 105226 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc00042fdd0 sp=0xc00042fdb0 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x8301)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc00042fe60 sp=0xc00042fdd0 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc00042fe90 sp=0xc00042fe60 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027a000)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc00042ff18 sp=0xc00042fe90 pc=0x7f8237953197
runtime_test.testConcurrentReadsAfterGrowth(0xc00027a000, 0x6a77f06300)
/workdir/go/src/runtime/map_test.go:315 +0x31 fp=0xc00042ff88 sp=0xc00042ff18 pc=0x7f8237a0f401
runtime_test.TestConcurrentReadsAfterGrowth(0xc00027a000)
/workdir/go/src/runtime/map_test.go:361 +0x32 fp=0xc00042ffa8 sp=0xc00042ff88 pc=0x7f8237a0f6e2
testing.tRunner(0xc00027a000, 0x7f8237b7d4b8)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc00042ffd0 sp=0xc00042ffa8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc00042ffd8 sp=0xc00042ffd0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 104340 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000afe5c8 sp=0xc000afe5a8 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x8d01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000afe658 sp=0xc000afe5c8 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000afe688 sp=0xc000afe658 pc=0x7f823787994b
testing.(*T).Parallel(0xc0000ec000)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000afe710 sp=0xc000afe688 pc=0x7f8237953197
runtime_test.TestCgoCallbackGC(0xc0000ec000)
/workdir/go/src/runtime/crash_cgo_test.go:52 +0x42 fp=0xc000afe7a8 sp=0xc000afe710 pc=0x7f82379e7a62
testing.tRunner(0xc0000ec000, 0x7f8237b7d370)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000afe7d0 sp=0xc000afe7a8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000afe7d8 sp=0xc000afe7d0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 105028 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000abb5d8 sp=0xc000abb5b8 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9301)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000abb668 sp=0xc000abb5d8 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000abb698 sp=0xc000abb668 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027a400)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000abb720 sp=0xc000abb698 pc=0x7f8237953197
runtime_test.TestCgoCrashTraceback(0xc00027a400)
/workdir/go/src/runtime/crash_cgo_test.go:241 +0x34 fp=0xc000abb7a8 sp=0xc000abb720 pc=0x7f82379e95b4
testing.tRunner(0xc00027a400, 0x7f8237b7d388)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000abb7d0 sp=0xc000abb7a8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000abb7d8 sp=0xc000abb7d0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 105024 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000afc640 sp=0xc000afc620 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x8a01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000afc6d0 sp=0xc000afc640 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000afc700 sp=0xc000afc6d0 pc=0x7f823787994b
testing.(*T).Parallel(0xc000104000)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000afc788 sp=0xc000afc700 pc=0x7f8237953197
runtime_test.TestCgoCrashHandler(0xc000104000)
/workdir/go/src/runtime/crash_cgo_test.go:23 +0x2d fp=0xc000afc7a8 sp=0xc000afc788 pc=0x7f82379e765d
testing.tRunner(0xc000104000, 0x7f8237b7d380)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000afc7d0 sp=0xc000afc7a8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000afc7d8 sp=0xc000afc7d0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 106729 [runnable]:
runtime.goexit1()
/workdir/go/src/runtime/proc.go:2657 +0x6d fp=0xc000b26fd0 sp=0xc000b26fc8 pc=0x7f82378ac56d
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1358 +0x6 fp=0xc000b26fd8 sp=0xc000b26fd0 pc=0x7f82378d8a56
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 105257 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000428580 sp=0xc000428560 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x8b01)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000428610 sp=0xc000428580 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000428640 sp=0xc000428610 pc=0x7f823787994b
testing.(*T).Parallel(0xc000105100)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc0004286c8 sp=0xc000428640 pc=0x7f8237953197
runtime_test.TestMemmoveOverflow(0xc000105100)
/workdir/go/src/runtime/memmove_linux_amd64_test.go:19 +0x42 fp=0xc0004287a8 sp=0xc0004286c8 pc=0x7f8237a158e2
testing.tRunner(0xc000105100, 0x7f8237b7d930)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc0004287d0 sp=0xc0004287a8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc0004287d8 sp=0xc0004287d0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352

goroutine 105029 [chan receive]:
runtime.gopark(0x7f8237b7c6d0, 0xc0002e2118, 0xc00006170e, 0x3)
/workdir/go/src/runtime/proc.go:304 +0xe6 fp=0xc000df75c8 sp=0xc000df75a8 pc=0x7f82378a61f6
runtime.goparkunlock(...)
/workdir/go/src/runtime/proc.go:310
runtime.chanrecv(0xc0002e20c0, 0x0, 0x7f8237953101, 0x9201)
/workdir/go/src/runtime/chan.go:524 +0x2ec fp=0xc000df7658 sp=0xc000df75c8 pc=0x7f8237879c8c
runtime.chanrecv1(0xc0002e20c0, 0x0)
/workdir/go/src/runtime/chan.go:406 +0x2b fp=0xc000df7688 sp=0xc000df7658 pc=0x7f823787994b
testing.(*T).Parallel(0xc00027a500)
/workdir/go/src/testing/testing.go:814 +0x1d7 fp=0xc000df7710 sp=0xc000df7688 pc=0x7f8237953197
runtime_test.TestCgoTracebackContext(0xc00027a500)
/workdir/go/src/runtime/crash_cgo_test.go:258 +0x42 fp=0xc000df77a8 sp=0xc000df7710 pc=0x7f82379e98b2
testing.tRunner(0xc00027a500, 0x7f8237b7d3f8)
/workdir/go/src/testing/testing.go:909 +0xc1 fp=0xc000df77d0 sp=0xc000df77a8 pc=0x7f8237953441
runtime.goexit()
/workdir/go/src/runtime/asm_amd64.s:1357 +0x1 fp=0xc000df77d8 sp=0xc000df77d0 pc=0x7f82378d8a51
created by testing.(*T).Run
/workdir/go/src/testing/testing.go:960 +0x352
exitcode=2FAIL runtime 23.961s t0 2019-05-25 21:23:55.235229917 +0000 UTC m=+2.144128165 now 2019-05-25 21:24:19.19591965 +0000 UTC m=+26.104817947
FAIL
2019/05/25 21:24:19 Failed: exit status 1

sync -cpu=10

ok sync 1.218s t0 2019-05-25 21:23:53.865341667 +0000 UTC m=+0.774180387 now 2019-05-25 21:23:55.082898075 +0000 UTC m=+1.991736809
2019/05/25 21:24:19 FAILED
Error running run: exit status 1

Adding proper spacing, the interesting lines are:

debug timer timeout, t.when: 737357704510 now 1558819458027234000 delta -1558818720669529490
panic: test timed out after 5m0s t0 2019-05-25 21:23:54.71644559 +0000 UTC m=+0.002343221 now 2019-05-25 21:24:18.027735 +0000 UTC m=+1558819020.672441481

It's interesting that both the timerProc and startAlarm readings produced similarly bogus values.

@bradfitz
Copy link
Contributor

@bradfitz bradfitz commented May 26, 2019

/cc @aclements @cherrymui @ianlancetaylor for timer debugging help

@eliasnaur
Copy link
Contributor

@eliasnaur eliasnaur commented May 26, 2019

Note that the bogus values are curiously similar to the unix time since the 1970 epoch (currently 1558861037 seconds).

@eliasnaur
Copy link
Contributor

@eliasnaur eliasnaur commented May 26, 2019

I think I got it. The nanotime implementation for android/amd64 calls clock_gettime(CLOCK_MONOTONIC, ...) if it is found in the vDSO, and falls back to gettimeofday if not.

However, there is a runtime test, TestClockVDSOAndFallbackPaths that tests the fallback by temporarily disabling clock_gettime.

I believe what happens is that TestClockVDSOAndFallbackPaths disables clock_gettime just as timerproc is calling nanotime. Since clock_gettime(CLOCK_MONOTONIC) and gettimeofday use different zero times, computing the elapsed time results in bogus values.

That theory explains why the early timeouts only happen during the runtime tests and also explains the "bogus" nanotime readings: the readings are not wrong in themselves, but the switch of zero time basis invalidates any time deltas computed during the switch.

I don't have an obvious fix other than disabling TestClockVDSOAndFallbackPaths.

@ianlancetaylor
Copy link
Contributor

@ianlancetaylor ianlancetaylor commented May 27, 2019

I agree and that is what I did in https://golang.org/cl/174679 which is part of my timer rewrite series. Perhaps we should submit just that CL for 1.13.

@ianlancetaylor
Copy link
Contributor

@ianlancetaylor ianlancetaylor commented May 27, 2019

I have a +2 for that CL, and I think it's the right thing to do, so I will submit it.

@gopherbot
Copy link

@gopherbot gopherbot commented May 27, 2019

Change https://golang.org/cl/174679 mentions this issue: runtime: remove VDSO fallback test and benchmarks

@gopherbot gopherbot closed this in 1531192 May 27, 2019
@golang golang locked and limited conversation to collaborators May 26, 2020
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Projects
None yet
Linked pull requests

Successfully merging a pull request may close this issue.

None yet
6 participants
You can’t perform that action at this time.