[Bug go/64999] New: s390x libgo test failure in TestMemoryProfiler

["vogt at linux dot vnet.ibm.com" <gcc-bugzilla at gcc dot gnu dot org>]

https://gcc.gnu.org/bugzilla/show_bug.cgi?id=64999

            Bug ID: 64999
           Summary: s390x libgo test failure in TestMemoryProfiler
           Product: gcc
           Version: 5.0
            Status: UNCONFIRMED
          Severity: normal
          Priority: P3
         Component: go
          Assignee: ian at airs dot com
          Reporter: vogt at linux dot vnet.ibm.com
                CC: cmang at google dot com

With the current trunk I get a test failure in the runtime/pprof test.  I'll
start investigating what's going on now but wanted to report it beforehand. 
Any hints on debugging this?

--

$ make -k check-go RUNTESTFLAGS="go-test.exp"
...
--- FAIL: TestMemoryProfiler (0.16s)
    testing.go:278: The entry did not match:
        32: 1024 \[32: 1024\] @ 0x[0-9,a-f x]+
        #    0x[0-9,a-f]+    pprof_test\.allocatePersistent1K\+0x[0-9,a-f]+   
.*/mprof_test\.go:43
        #    0x[0-9,a-f]+   
runtime_pprof_test\.TestMemoryProfiler\+0x[0-9,a-f]+    .*/mprof_test\.go:66


        Profile:
        heap profile: 37: 3544 [3111: 4229640] @ heap/2
        1: 1408 [1: 1408] @ 0x800250a9 0x800250a9 0x8001e5c7 0x8001e5c7
0x80021d3f 0x8001bf73 0x8001d54b 0x800069b1 0x3fff74ac92b 0x80006a15
        #    0x8001bf73    mcommoninit+0x93   
../../../libgo/runtime/proc.c:739
        #    0x8001d54b    runtime_schedinit+0x8b   
../../../libgo/runtime/proc.c:459
        #    0x800069b1    main+0x31        ../../../libgo/runtime/go-main.c:40
        #    0x3fff74ac92b
        #    0x80006a15

        1: 1024 [1024: 1048576] @ 0x800250a9 0x800250a9 0x8001163f 0x80011687
0x8000c551 0x800ad279 0x8001e229
        #    0x800ad279    testing.$thunk15+0xc9   
../../../libgo/go/testing/testing.go:555
        #    0x8001e229    kickoff+0x41       
../../../libgo/runtime/proc.c:235

        32: 1024 [32: 1024] @ 0x800250a9 0x800250a9 0x8000c44b 0x8000c595
0x800ad279 0x8001e229
        #    0x8000c44b    pprof_test.allocatePersistent1K+0x23   
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/mprof_test.go:43
        #    0x8000c595    runtime_pprof_test.TestMemoryProfiler+0x11d   
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/mprof_test.go:65
        #    0x800ad279    testing.$thunk15+0xc9           
../../../libgo/go/testing/testing.go:555
        #    0x8001e229    kickoff+0x41               
../../../libgo/runtime/proc.c:235

        1: 64 [1: 64] @ 0x800250a9 0x800250a9 0x8001134d 0x8000c533 0x800ad279
0x8001e229
        #    0x800ad279    testing.$thunk15+0xc9   
../../../libgo/go/testing/testing.go:555
        #    0x8001e229    kickoff+0x41       
../../../libgo/runtime/proc.c:235

        1: 16 [1: 16] @ 0x800250a9 0x800250a9 0x8000c4df 0x800ad279 0x8001e229
        #    0x800ad279    testing.$thunk15+0xc9   
../../../libgo/go/testing/testing.go:555
        #    0x8001e229    kickoff+0x41       
../../../libgo/runtime/proc.c:235

        1: 8 [1: 8] @ 0x800250a9 0x800250a9 0x8000c50f 0x800ad279 0x8001e229
        #    0x800ad279    testing.$thunk15+0xc9   
../../../libgo/go/testing/testing.go:555
        #    0x8001e229    kickoff+0x41       
../../../libgo/runtime/proc.c:235

        0: 0 [0: 0] @ 0x800250a9 0x800250a9 0x8000c5bb 0x800ad279 0x8001e229
        #    0x800ad279    testing.$thunk15+0xc9   
../../../libgo/go/testing/testing.go:555
        #    0x8001e229    kickoff+0x41       
../../../libgo/runtime/proc.c:235

        0: 0 [1: 32] @ 0x800250a9 0x800250a9 0x8000c3b7 0x8000c58f 0x800ad279
0x8001e229
        #    0x8000c3b7    pprof_test.allocateTransient2M+0x47   
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/mprof_test.go:30
        #    0x8000c58f    runtime_pprof_test.TestMemoryProfiler+0x117   
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/mprof_test.go:64
        #    0x800ad279    testing.$thunk15+0xc9           
../../../libgo/go/testing/testing.go:555
        #    0x8001e229    kickoff+0x41               
../../../libgo/runtime/proc.c:235

        0: 0 [1: 2097152] @ 0x800250a9 0x800250a9 0x8001163f 0x80011687
0x8000c3a7 0x8000c58f 0x800ad279 0x8001e229
        #    0x8000c3a7    pprof_test.allocateTransient2M+0x37   
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/mprof_test.go:30
        #    0x8000c58f    runtime_pprof_test.TestMemoryProfiler+0x117   
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/mprof_test.go:64
        #    0x800ad279    testing.$thunk15+0xc9           
../../../libgo/go/testing/testing.go:555
        #    0x8001e229    kickoff+0x41               
../../../libgo/runtime/proc.c:235

        0: 0 [1024: 1048576] @ 0x800250a9 0x800250a9 0x8000c32b 0x8000c589
0x800ad279 0x8001e229
        #    0x8000c32b    pprof_test.allocateTransient1M+0x23   
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/mprof_test.go:21
        #    0x8000c589    runtime_pprof_test.TestMemoryProfiler+0x111   
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/mprof_test.go:59
        #    0x800ad279    testing.$thunk15+0xc9           
../../../libgo/go/testing/testing.go:555
        #    0x8001e229    kickoff+0x41               
../../../libgo/runtime/proc.c:235

        0: 0 [1024: 32768] @ 0x800250a9 0x800250a9 0x8000c561 0x800ad279
0x8001e229
        #    0x800ad279    testing.$thunk15+0xc9   
../../../libgo/go/testing/testing.go:555
        #    0x8001e229    kickoff+0x41       
../../../libgo/runtime/proc.c:235

        0: 0 [1: 16] @ 0x800250a9 0x800250a9 0x80025b41 0x80025b41 0x8001d531
0x800069b1 0x3fff74ac92b 0x80006a15
        #    0x800069b1    main+0x31    ../../../libgo/runtime/go-main.c:40
        #    0x3fff74ac92b
        #    0x80006a15

        0: 0 [0: 0] @ 0x800250a9 0x800250a9 0x8001163f 0x80011687 0x80009025
0x80007949 0x8000c609 0x800ad279 0x8001e229
        #    0x80009025    pprof.writeHeap+0x8d           
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/pprof.go:401
        #    0x80007949   
runtime_pprof.WriteTo.pN21_runtime_pprof.Profile+0x99   
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/pprof.go:229
        #    0x8000c609    runtime_pprof_test.TestMemoryProfiler+0x191       
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/mprof_test.go:71
        #    0x800ad279    testing.$thunk15+0xc9           
../../../libgo/go/testing/testing.go:555
        #    0x8001e229    kickoff+0x41               
../../../libgo/runtime/proc.c:235

        0: 0 [0: 0] @ 0x800250a9 0x800250a9 0x80008fd3 0x80007949 0x8000c609
0x800ad279 0x8001e229
        #    0x80008fd3    pprof.writeHeap+0x3b           
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/pprof.go:388
        #    0x80007949   
runtime_pprof.WriteTo.pN21_runtime_pprof.Profile+0x99   
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/pprof.go:229
        #    0x8000c609    runtime_pprof_test.TestMemoryProfiler+0x191       
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/mprof_test.go:71
        #    0x800ad279    testing.$thunk15+0xc9           
../../../libgo/go/testing/testing.go:555
        #    0x8001e229    kickoff+0x41               
../../../libgo/runtime/proc.c:235

        0: 0 [0: 0] @ 0x800250a9 0x800250a9 0x8001134d 0x80006ecf 0x8000c5e1
0x800ad279 0x8001e229
        #    0x800ad279    testing.$thunk15+0xc9   
../../../libgo/go/testing/testing.go:555
        #    0x8001e229    kickoff+0x41       
../../../libgo/runtime/proc.c:235

        0: 0 [0: 0] @ 0x800250a9 0x800250a9 0x80011821 0x800112cf 0x80006c03
0x80006ea3 0x8000c5e1 0x800ad279 0x8001e229
        #    0x80006c03    pprof.lockProfiles+0x103       
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/pprof.go:90
        #    0x80006ea3    runtime_pprof.Lookup+0x23       
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/pprof.go:127
        #    0x8000c5e1    runtime_pprof_test.TestMemoryProfiler+0x169   
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/mprof_test.go:71
        #    0x800ad279    testing.$thunk15+0xc9           
../../../libgo/go/testing/testing.go:555
        #    0x8001e229    kickoff+0x41               
../../../libgo/runtime/proc.c:235

        0: 0 [0: 0] @ 0x800250a9 0x800250a9 0x80011c07 0x800112a9 0x80006c03
0x80006ea3 0x8000c5e1 0x800ad279 0x8001e229
        #    0x80006c03    pprof.lockProfiles+0x103       
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/pprof.go:90
        #    0x80006ea3    runtime_pprof.Lookup+0x23       
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/pprof.go:127
        #    0x8000c5e1    runtime_pprof_test.TestMemoryProfiler+0x169   
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/mprof_test.go:71
        #    0x800ad279    testing.$thunk15+0xc9           
../../../libgo/go/testing/testing.go:555
        #    0x8001e229    kickoff+0x41               
../../../libgo/runtime/proc.c:235

        0: 0 [0: 0] @ 0x800250a9 0x800250a9 0x80011be3 0x800112a9 0x80006c03
0x80006ea3 0x8000c5e1 0x800ad279 0x8001e229
        #    0x80006c03    pprof.lockProfiles+0x103       
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/pprof.go:90
        #    0x80006ea3    runtime_pprof.Lookup+0x23       
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/pprof.go:127
        #    0x8000c5e1    runtime_pprof_test.TestMemoryProfiler+0x169   
/home/vogt/src/git/gcc/build-go/s390x-ibm-linux-gnu/libgo/gotest81795/test/mprof_test.go:71
        #    0x800ad279    testing.$thunk15+0xc9           
../../../libgo/go/testing/testing.go:555
        #    0x8001e229    kickoff+0x41               
../../../libgo/runtime/proc.c:235


        # runtime.MemStats
        # Alloc = 25574936
        # TotalAlloc = 29809488
        # Sys = 35420408
        # Lookups = 11
        # Mallocs = 7276
        # Frees = 3243
        # HeapAlloc = 25574936
        # HeapSys = 30408704
        # HeapIdle = 4521984
        # HeapInuse = 25886720
        # HeapReleased = 0
        # HeapObjects = 4033
        # Stack = 0 / 0
        # MSpan = 9472 / 49152
        # MCache = 2200 / 16384
        # BuckHashSys = 1540888
        # NextGC = 50917776
        # PauseNs = [85000 94000 92000 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0]
        # NumGC = 3
        # EnableGC = true
        # DebugGC = false

FAIL
FAIL: runtime/pprof
make[3]: *** [runtime/pprof/check] Error 1