cmd/pprof: TestDisasm failures #56574

gopherbot · 2022-11-04T17:59:20Z

#!watchflakes
post <- pkg == "cmd/pprof" && test == "TestDisasm"

Issue created automatically to collect these failures.

Example (log):

--- FAIL: TestDisasm (1.41s)
    pprof_test.go:118: pprof failed: exit status 2
        no matches found for regexp: main.main

— watchflakes

The text was updated successfully, but these errors were encountered:

gopherbot · 2022-11-04T18:00:00Z

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "cmd/pprof" && test == "TestDisasm"

2022-10-24 16:31 linux-arm64 go@65f86352 cmd/pprof.TestDisasm (log)

--- FAIL: TestDisasm (1.41s)
    pprof_test.go:118: pprof failed: exit status 2
        no matches found for regexp: main.main

2022-11-03 19:34 linux-arm64 go@3511c822 cmd/pprof.TestDisasm (log)

--- FAIL: TestDisasm (1.42s)
    pprof_test.go:118: pprof failed: exit status 2
        no matches found for regexp: main.main

2022-11-04 14:19 linux-arm64 go@7ddbadb3 cmd/pprof.TestDisasm (log)

--- FAIL: TestDisasm (1.41s)
    pprof_test.go:118: pprof failed: exit status 2
        no matches found for regexp: main.main

— watchflakes

cherrymui · 2022-11-07T23:50:45Z

This seems on the new linux-arm64 builder (#53851). @cagedmantis do you think the new builder is stable and reliable, or there is still some issue? Thanks.

cagedmantis · 2022-11-08T20:03:44Z

@cherrymui The builder should be stable and reliable enough where any issue should be investigated.

gopherbot · 2022-11-14T21:37:34Z

Change https://go.dev/cl/450281 mentions this issue: cmd/pprof: debug TestDisasm

If pprof -disasm fails, print the profile content for debugging. For #56574. Change-Id: I5d9377b7fb80f6b85317bc53f3ebb18f70c2f06d Reviewed-on: https://go-review.googlesource.com/c/go/+/450281 TryBot-Result: Gopher Robot <gobot@golang.org> Run-TryBot: Cherry Mui <cherryyz@google.com> Reviewed-by: Than McIntosh <thanm@google.com>

gopherbot · 2022-11-17T16:07:17Z

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "cmd/pprof" && test == "TestDisasm"

2022-11-15 00:02 linux-arm64 go@e6ebbefa cmd/pprof.TestDisasm (log)

--- FAIL: TestDisasm (1.41s)
    pprof_test.go:118: pprof failed: exit status 2
        no matches found for regexp: main.main

— watchflakes

cherrymui · 2022-11-17T16:50:57Z

Unfortunately the failure on 11-15 is still before my debugging CL landed. We'll see if we can get some information if a new failure occurs.

gopherbot · 2022-11-21T17:14:29Z

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "cmd/pprof" && test == "TestDisasm"

2022-11-17 17:24 linux-arm64 go@5c834a2e cmd/pprof.TestDisasm (log)

--- FAIL: TestDisasm (1.43s)
    pprof_test.go:110: pprof -disasm failed: exit status 2
        no matches found for regexp: main.main
    pprof_test.go:118: profile content:
        PeriodType: cpu nanoseconds
        Period: 10000000
        Time: 2022-11-17 17:34:46.01668506 +0000 UTC
        Duration: 1.10
        Samples:
        samples/count cpu/nanoseconds
                 97  970000000: 1 
                  1   10000000: 2 
        Locations
             1: 0x6fa94 M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:377 s=311
             2: 0x6fa98 M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:378 s=311
        Mappings
        1: 0x10000/0xb8000/0x0 /tmp/workdir/tmp/TestDisasm2297534319/001/cpu.exe  [FN]

2022-11-17 21:15 linux-arm64 go@1f4394a0 cmd/pprof.TestDisasm (log)

--- FAIL: TestDisasm (1.50s)
    pprof_test.go:110: pprof -disasm failed: exit status 2
        no matches found for regexp: main.main
    pprof_test.go:118: profile content:
        PeriodType: cpu nanoseconds
        Period: 10000000
        Time: 2022-11-17 21:25:17.962118619 +0000 UTC
        Duration: 1.10
        Samples:
        samples/count cpu/nanoseconds
                 93  930000000: 1 
                  2   20000000: 2 
                  1   10000000: 3 
                  1   10000000: 4 
                  1   10000000: 5 
        Locations
             1: 0x6fa94 M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:377 s=311
             2: 0x6fa98 M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:378 s=311
             3: 0x6fa30 M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:333 s=311
             4: 0x6fa08 M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:320 s=311
             5: 0x6fa28 M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:330 s=311
        Mappings
        1: 0x10000/0xb8000/0x0 /tmp/workdir/tmp/TestDisasm4084982061/001/cpu.exe  [FN]

— watchflakes

gopherbot · 2022-11-21T17:36:06Z

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "cmd/pprof" && test == "TestDisasm"

2022-11-18 17:59 linux-arm64 go@b2faff18 cmd/pprof.TestDisasm (log)

--- FAIL: TestDisasm (1.42s)
    pprof_test.go:110: pprof -disasm failed: exit status 2
        no matches found for regexp: main.main
    pprof_test.go:118: profile content:
        PeriodType: cpu nanoseconds
        Period: 10000000
        Time: 2022-11-18 18:09:26.035842499 +0000 UTC
        Duration: 1.10
        Samples:
        samples/count cpu/nanoseconds
                 94  940000000: 1 
                  1   10000000: 2 
                  1   10000000: 3 
                  2   20000000: 4 
                  1   10000000: 5 
                  1   10000000: 6 
        Locations
             1: 0x6f974 M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:377 s=311
             2: 0x6f8d8 M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:311 s=311
             3: 0x6f984 M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:387 s=311
             4: 0x6f978 M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:378 s=311
             5: 0x6f8ec M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:321 s=311
             6: 0x6f8e8 M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:320 s=311
        Mappings
        1: 0x10000/0xb8000/0x0 /tmp/workdir/tmp/TestDisasm2137471865/001/cpu.exe  [FN]

— watchflakes

gopherbot · 2022-12-05T19:59:56Z

Change https://go.dev/cl/455166 mentions this issue: runtime: prioritize VDSO and libcall unwinding in profiler

gopherbot · 2023-01-04T21:17:07Z

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "cmd/pprof" && test == "TestDisasm"

2022-11-10 18:45 linux-arm64 go@531ba0c8 cmd/pprof.TestDisasm (log)

--- FAIL: TestDisasm (1.44s)
    pprof_test.go:118: pprof failed: exit status 2
        no matches found for regexp: main.main

2022-11-15 20:17 linux-arm64 go@9754bc7b cmd/pprof.TestDisasm (log)

--- FAIL: TestDisasm (1.40s)
    pprof_test.go:118: pprof -disasm failed: exit status 2
        no matches found for regexp: main.main
    pprof_test.go:126: profile content:
        PeriodType: cpu nanoseconds
        Period: 10000000
        Time: 2022-11-15 20:27:39.220352422 +0000 UTC
        Duration: 1.10
        Samples:
        samples/count cpu/nanoseconds
                 97  970000000: 1 
                  1   10000000: 2 
                  1   10000000: 3 
                  1   10000000: 4 
        Locations
             1: 0x6fa84 M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:377 s=311
             2: 0x6f9fc M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:321 s=311
             3: 0x6fa88 M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:378 s=311
             4: 0x6fa20 M=1 runtime.nanotime1 /tmp/workdir/go/src/runtime/sys_linux_arm64.s:333 s=311
        Mappings
        1: 0x10000/0xba000/0x0 /tmp/workdir/tmp/TestDisasm2593135869/001/cpu.exe  [FN]

— watchflakes

cherrymui · 2023-01-04T21:33:15Z

The failures are before the fix.

gopherbot · 2024-04-18T21:52:47Z

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "cmd/pprof" && test == "TestDisasm"

2024-04-03 15:35 go1.21-openbsd-amd64 release-branch.go1.21@d8392e69 cmd/pprof.TestDisasm (log)

=== RUN   TestDisasm
    pprof_test.go:112: pprof -disasm failed: exit status 2
        no matches found for regexp: main.main
    pprof_test.go:120: profile content:
        PeriodType: cpu nanoseconds
        Period: 10000000
        Time: 2024-04-03 08:53:45.525865952 -0700 PDT
        Duration: 2.44
        Samples:
        samples/count cpu/nanoseconds
                  1   10000000: 1 2 3 4 5 6 
        Locations
             1: 0x44e52d M=1 runtime.kevent /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/sys_openbsd2.go:219 s=218
             2: 0x42dd1a M=1 runtime.netpoll /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/netpoll_kqueue.go:141 s=121
             3: 0x438bf1 M=1 runtime.findRunnable /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:3186 s=2886
             4: 0x439d50 M=1 runtime.schedule /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:3577 s=3548
             5: 0x43a25e M=1 runtime.park_m /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:3740 s=3716
             6: 0x45c78d M=1 runtime.mcall /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_amd64.s:458 s=428
        Mappings
        1: 0x0/0x0/0x0 /home/swarming/.swarming/w/ir/x/t/TestDisasm2561604156/001/cpu.exe  [FN]
--- FAIL: TestDisasm (23.03s)

2024-04-10 20:23 gotip-openbsd-amd64 go@c23579f0 cmd/pprof.TestDisasm (log)

=== RUN   TestDisasm
    pprof_test.go:111: pprof -disasm failed: exit status 2
        no matches found for regexp main.main
    pprof_test.go:119: profile content:
        PeriodType: cpu nanoseconds
        Period: 10000000
        Time: 2024-04-10 13:40:34.632853971 -0700 PDT
        Duration: 1.00
        Samples:
        samples/count cpu/nanoseconds
        Locations
        Mappings
        1: 0x0/0x0/0x0 /home/swarming/.swarming/w/ir/x/t/TestDisasm2990457179/001/cpu.exe  
--- FAIL: TestDisasm (18.24s)

2024-04-18 15:39 gotip-openbsd-amd64 go@84fbdf01 cmd/pprof.TestDisasm (log)

=== RUN   TestDisasm
    pprof_test.go:111: pprof -disasm failed: exit status 2
        no matches found for regexp main.main
    pprof_test.go:119: profile content:
        PeriodType: cpu nanoseconds
        Period: 10000000
        Time: 2024-04-18 08:57:54.068820717 -0700 PDT
        Duration: 1.12
        Samples:
        samples/count cpu/nanoseconds
        Locations
        Mappings
        1: 0x0/0x0/0x0 /home/swarming/.swarming/w/ir/x/t/TestDisasm808078515/001/cpu.exe  
--- FAIL: TestDisasm (21.20s)

— watchflakes

mknyszek · 2024-04-24T20:48:58Z

CC @golang/openbsd

gopherbot · 2024-05-13T21:31:16Z

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "cmd/pprof" && test == "TestDisasm"

2024-05-13 18:57 gotip-openbsd-amd64 go@c98867d2 cmd/pprof.TestDisasm (log)

=== RUN   TestDisasm
    pprof_test.go:111: pprof -disasm failed: exit status 2
        no matches found for regexp main.main
    pprof_test.go:119: profile content:
        PeriodType: cpu nanoseconds
        Period: 10000000
        Time: 2024-05-13 12:14:06.085111305 -0700 PDT
        Duration: 1.14
        Samples:
        samples/count cpu/nanoseconds
...
             9: 0x4b1384 M=1 runtime/pprof.profileWriter /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/pprof/pprof.go:847:0 s=846
            10: 0x454fad M=1 runtime.kevent /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/sys_openbsd2.go:219:0 s=218
            11: 0x432376 M=1 runtime.netpoll /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/netpoll_kqueue.go:107:0 s=87
            12: 0x43e512 M=1 runtime.findRunnable /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:3499:0 s=3168
            13: 0x43fab0 M=1 runtime.schedule /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:3914:0 s=3885
            14: 0x43feca M=1 runtime.park_m /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:4021:0 s=3987
            15: 0x469d2d M=1 runtime.mcall /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_amd64.s:459:0 s=429
        Mappings
        1: 0x0/0x0/0x0 /home/swarming/.swarming/w/ir/x/t/TestDisasm2506162594/001/cpu.exe  [FN]
--- FAIL: TestDisasm (52.24s)

— watchflakes

gopherbot · 2024-08-09T23:37:12Z

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "cmd/pprof" && test == "TestDisasm"

2024-08-09 20:07 gotip-openbsd-amd64 go@1cf6e31f cmd/pprof.TestDisasm (log)

=== RUN   TestDisasm
    pprof_test.go:111: pprof -disasm failed: exit status 2
        no matches found for regexp main.main
    pprof_test.go:119: profile content:
        PeriodType: cpu nanoseconds
        Period: 10000000
        Time: 2024-08-09 13:24:13.033417768 -0700 PDT
        Duration: 3.19
        Samples:
        samples/count cpu/nanoseconds
...
                     os.openFileNolog /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file_unix.go:278:0 s=264
            11: 0x495f3d M=1 os.OpenFile /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file.go:385:0 s=383
            12: 0x496235 M=1 os.Open /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file.go:365:0 s=364
                     os.ReadFile /home/swarming/.swarming/w/ir/x/w/goroot/src/os/file.go:789:0 s=788
            13: 0x4b9452 M=1 runtime/pprof.(*profileBuilder).readMapping /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/pprof/proto_other.go:18:0 s=17
            14: 0x4b587b M=1 runtime/pprof.newProfileBuilder /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/pprof/proto.go:270:0 s=259
            15: 0x4b29c4 M=1 runtime/pprof.profileWriter /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/pprof/pprof.go:865:0 s=864
        Mappings
        1: 0x0/0x0/0x0 /home/swarming/.swarming/w/ir/x/t/TestDisasm1237673866/001/cpu.exe  [FN]
--- FAIL: TestDisasm (20.83s)

— watchflakes

gopherbot · 2024-08-15T21:41:52Z

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "cmd/pprof" && test == "TestDisasm"

2024-08-15 20:16 gotip-openbsd-amd64 go@209ed1a9 cmd/pprof.TestDisasm (log)

=== RUN   TestDisasm
    pprof_test.go:111: pprof -disasm failed: exit status 2
        no matches found for regexp main.main
    pprof_test.go:119: profile content:
        PeriodType: cpu nanoseconds
        Period: 10000000
        Time: 2024-08-15 13:30:27.858320073 -0700 PDT
        Duration: 1.25
        Samples:
        samples/count cpu/nanoseconds
        Locations
        Mappings
        1: 0x0/0x0/0x0 /home/swarming/.swarming/w/ir/x/t/TestDisasm3402569774/001/cpu.exe  
--- FAIL: TestDisasm (14.81s)

— watchflakes

gopherbot added NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one. compiler/runtime Issues related to the Go compiler and/or runtime. labels Nov 4, 2022

cherrymui self-assigned this Nov 7, 2022

mknyszek added this to the Go1.20 milestone Nov 9, 2022

cherrymui mentioned this issue Nov 23, 2022

x/build: add longtest builders for linux-arm64 #49649

Closed

gopherbot closed this as completed in 185e1a7 Dec 5, 2022

gopherbot reopened this Jan 4, 2023

cherrymui closed this as completed Jan 4, 2023

mknyszek mentioned this issue Jun 7, 2023

cmd/pprof: skip TestDisasm flaky failures on linux/arm64 [1.19 backport] #60637

Closed

cherrymui mentioned this issue Jun 8, 2023

cmd/pprof: TestDisasm fails on raspbian #47024

Closed

dmitshur added NeedsFix The path to resolution is known, but the work has not been done. OS-Linux and removed NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one. labels Jun 19, 2023

gopherbot reopened this Apr 18, 2024

mknyszek added the help wanted label Apr 24, 2024

mknyszek unassigned cherrymui Apr 24, 2024

dmitshur modified the milestones: Go1.20, Backlog May 30, 2024

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

cmd/pprof: TestDisasm failures #56574

cmd/pprof: TestDisasm failures #56574

gopherbot commented Nov 4, 2022

gopherbot commented Nov 4, 2022

cherrymui commented Nov 7, 2022

cagedmantis commented Nov 8, 2022

gopherbot commented Nov 14, 2022

gopherbot commented Nov 17, 2022

cherrymui commented Nov 17, 2022

gopherbot commented Nov 21, 2022

gopherbot commented Nov 21, 2022

gopherbot commented Dec 5, 2022

gopherbot commented Jan 4, 2023

cherrymui commented Jan 4, 2023

gopherbot commented Apr 18, 2024

mknyszek commented Apr 24, 2024

gopherbot commented May 13, 2024

gopherbot commented Aug 9, 2024

gopherbot commented Aug 15, 2024

cmd/pprof: TestDisasm failures #56574

cmd/pprof: TestDisasm failures #56574

Comments

gopherbot commented Nov 4, 2022

gopherbot commented Nov 4, 2022

cherrymui commented Nov 7, 2022

cagedmantis commented Nov 8, 2022

gopherbot commented Nov 14, 2022

gopherbot commented Nov 17, 2022

cherrymui commented Nov 17, 2022

gopherbot commented Nov 21, 2022

gopherbot commented Nov 21, 2022

gopherbot commented Dec 5, 2022

gopherbot commented Jan 4, 2023

cherrymui commented Jan 4, 2023

gopherbot commented Apr 18, 2024

mknyszek commented Apr 24, 2024

gopherbot commented May 13, 2024

gopherbot commented Aug 9, 2024

gopherbot commented Aug 15, 2024