Closed Bug 1720915 Opened 3 years ago Closed 1 month ago

Intermittent gtest | application crashed [@ testing::internal::TestEventRepeater::OnTestEnd]

Categories

(Testing :: GTest, defect)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: crash, intermittent-failure)

Crash Data

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=345447252&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/PJnL_4LuTgibWRgbuv1Q4w/runs/0/artifacts/public/logs/live_backing.log


[task 2021-07-16T15:31:48.316Z] 15:31:48     INFO -  TEST-START | DelayedRunnable.TimerFiresBeforeRunnableRuns
[task 2021-07-16T15:31:48.316Z] 15:31:48     INFO -  TEST-PASS | DelayedRunnable.TimerFiresBeforeRunnableRuns | test completed (time: 1ms)
[task 2021-07-16T15:31:48.654Z] 15:31:48     INFO -  gtest INFO | gtest | process wait complete, returncode=1
[task 2021-07-16T15:31:48.655Z] 15:31:48     INFO -  mozcrash checking /opt/worker/tasks/task_162644669724332/build/tests/gtest for minidumps...
[task 2021-07-16T15:31:48.656Z] 15:31:48     INFO -  mozcrash INFO | Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/VrOvbtcIScqMWi5O3wrFVQ/artifacts/public/build/target.crashreporter-symbols.zip
[task 2021-07-16T15:31:50.963Z] 15:31:50     INFO -  mozcrash INFO | Copy/paste: /opt/worker/tasks/task_162644669724332/fetches/minidump_stackwalk/minidump_stackwalk /opt/worker/tasks/task_162644669724332/build/tests/gtest/F9FF9A4F-F12E-4845-9EF3-54FDBA1FC84B.dmp /var/folders/ss/fzy6v4s53sz2tpsl2fcw0r9w000014/T/tmpny5pa7yt
[task 2021-07-16T15:31:55.853Z] 15:31:55     INFO -  mozcrash INFO | Saved minidump as /opt/worker/tasks/task_162644669724332/build/blobber_upload_dir/F9FF9A4F-F12E-4845-9EF3-54FDBA1FC84B.dmp
[task 2021-07-16T15:31:55.853Z] 15:31:55     INFO -  mozcrash INFO | Saved app info as /opt/worker/tasks/task_162644669724332/build/blobber_upload_dir/F9FF9A4F-F12E-4845-9EF3-54FDBA1FC84B.extra
[task 2021-07-16T15:31:55.854Z] 15:31:55  WARNING -  PROCESS-CRASH | gtest | application crashed [@ testing::internal::TestEventRepeater::OnTestEnd(testing::TestInfo const&)]
[task 2021-07-16T15:31:55.854Z] 15:31:55     INFO -  Crash dump filename: /opt/worker/tasks/task_162644669724332/build/tests/gtest/F9FF9A4F-F12E-4845-9EF3-54FDBA1FC84B.dmp
[task 2021-07-16T15:31:55.854Z] 15:31:55     INFO -  Operating system: Mac OS X
[task 2021-07-16T15:31:55.854Z] 15:31:55     INFO -                    10.15.7 19H524
[task 2021-07-16T15:31:55.854Z] 15:31:55     INFO -  CPU: amd64
[task 2021-07-16T15:31:55.855Z] 15:31:55     INFO -       family 6 model 158 stepping 10
[task 2021-07-16T15:31:55.855Z] 15:31:55     INFO -       12 CPUs
[task 2021-07-16T15:31:55.855Z] 15:31:55     INFO -  GPU: UNKNOWN
[task 2021-07-16T15:31:55.855Z] 15:31:55     INFO -  Crash reason:  EXC_BAD_ACCESS / KERN_INVALID_ADDRESS
[task 2021-07-16T15:31:55.855Z] 15:31:55     INFO -  Crash address: 0x1010aaf00e1
[task 2021-07-16T15:31:55.856Z] 15:31:55     INFO -  Process uptime: 56 seconds
[task 2021-07-16T15:31:55.856Z] 15:31:55     INFO -  Thread 0 (crashed) - MainThread 0  0x1010aaf00e1
[task 2021-07-16T15:31:55.856Z] 15:31:55     INFO -      rax = 0x0000000000000056   rdx = 0x0000000000000000
[task 2021-07-16T15:31:55.856Z] 15:31:55     INFO -      rcx = 0x44c29bd64b140051   rbx = 0x0000000109ad8890
[task 2021-07-16T15:31:55.857Z] 15:31:55     INFO -      rsi = 0x00000000000120a8   rdi = 0x00007fff929fcbd8
[task 2021-07-16T15:31:55.857Z] 15:31:55     INFO -      rbp = 0x00007ffee63ec520   rsp = 0x00007ffee63ec4e0
[task 2021-07-16T15:31:55.857Z] 15:31:55     INFO -       r8 = 0x00000000000130a8    r9 = 0x0000000000000000
[task 2021-07-16T15:31:55.857Z] 15:31:55     INFO -      r10 = 0x00007fff929fcbf8   r11 = 0x00007fff929fcbf0
[task 2021-07-16T15:31:55.857Z] 15:31:55     INFO -      r12 = 0x0000000109f68620   r13 = 0x0000000109ad88a9
[task 2021-07-16T15:31:55.858Z] 15:31:55     INFO -      r14 = 0x0000000109ad8908   r15 = 0x000000011293d3e1
[task 2021-07-16T15:31:55.858Z] 15:31:55     INFO -      rip = 0x000001010aaf00e1
[task 2021-07-16T15:31:55.858Z] 15:31:55     INFO -      Found by: given as instruction pointer in context
[task 2021-07-16T15:31:55.858Z] 15:31:55     INFO -   1  XUL!testing::internal::TestEventRepeater::OnTestEnd(testing::TestInfo const&) [gtest.cc:5278e88c6d34f40c17a54ba973120d09aa0070b7 : 3392 + 0x3f]
[task 2021-07-16T15:31:55.859Z] 15:31:55     INFO -      rbp = 0x00007ffee63ec550   rsp = 0x00007ffee63ec530
[task 2021-07-16T15:31:55.859Z] 15:31:55     INFO -      rip = 0x000000010aad68ff
[task 2021-07-16T15:31:55.859Z] 15:31:55     INFO -      Found by: previous frame's frame pointer
[task 2021-07-16T15:31:55.859Z] 15:31:55     INFO -   2  XUL!testing::TestInfo::Run() [gtest.cc:5278e88c6d34f40c17a54ba973120d09aa0070b7 : 2706 + 0x9]
[task 2021-07-16T15:31:55.860Z] 15:31:55     INFO -      rbx = 0x0000000000000000   rbp = 0x00007ffee63ec5b0
[task 2021-07-16T15:31:55.860Z] 15:31:55     INFO -      rsp = 0x00007ffee63ec560   r15 = 0x00007fff00036f2a
[task 2021-07-16T15:31:55.860Z] 15:31:55     INFO -      rip = 0x000000010aad331c
[task 2021-07-16T15:31:55.860Z] 15:31:55     INFO -      Found by: call frame info
[task 2021-07-16T15:31:55.861Z] 15:31:55     INFO -   3  XUL!testing::TestCase::Run() [gtest.cc:5278e88c6d34f40c17a54ba973120d09aa0070b7 : 2813 + 0xf]
[task 2021-07-16T15:31:55.861Z] 15:31:55     INFO -      rbx = 0x44c29bd64b140051   rbp = 0x00007ffee63ec610
[task 2021-07-16T15:31:55.861Z] 15:31:55     INFO -      rsp = 0x00007ffee63ec5c0   r12 = 0x0000000109a54ea0
[task 2021-07-16T15:31:55.861Z] 15:31:55     INFO -      r14 = 0x0000000109814d11   r15 = 0x0002000100000007
[task 2021-07-16T15:31:55.861Z] 15:31:55     INFO -      rip = 0x000000010aad3a97
[task 2021-07-16T15:31:55.862Z] 15:31:55     INFO -      Found by: call frame info
[task 2021-07-16T15:31:55.862Z] 15:31:55     INFO -   4  XUL!testing::internal::UnitTestImpl::RunAllTests() [gtest.cc:5278e88c6d34f40c17a54ba973120d09aa0070b7 : 5179 + 0xf]
[task 2021-07-16T15:31:55.862Z] 15:31:55     INFO -      rbx = 0x0000000000000000   rbp = 0x00007ffee63ec6c0
[task 2021-07-16T15:31:55.862Z] 15:31:55     INFO -      rsp = 0x00007ffee63ec620   r12 = 0x0000000000000000
[task 2021-07-16T15:31:55.863Z] 15:31:55     INFO -      r14 = 0x0000000000000000   r15 = 0x0000000000000000
[task 2021-07-16T15:31:55.863Z] 15:31:55     INFO -      rip = 0x000000010aadf8b7
[task 2021-07-16T15:31:55.863Z] 15:31:55     INFO -      Found by: call frame info
[task 2021-07-16T15:31:55.863Z] 15:31:55     INFO -   5  XUL!testing::UnitTest::Run() [gtest.cc:5278e88c6d34f40c17a54ba973120d09aa0070b7 : 4788 + 0x12]
[task 2021-07-16T15:31:55.864Z] 15:31:55     INFO -      rbx = 0x00007ffee63ecdb0   rbp = 0x00007ffee63ec700
[task 2021-07-16T15:31:55.864Z] 15:31:55     INFO -      rsp = 0x00007ffee63ec6d0   r12 = 0x0000000000000075
[task 2021-07-16T15:31:55.864Z] 15:31:55     INFO -      r14 = 0x0000000000000000   r15 = 0x00007ffee63ed240
[task 2021-07-16T15:31:55.864Z] 15:31:55     INFO -      rip = 0x000000010aadf21d
[task 2021-07-16T15:31:55.864Z] 15:31:55     INFO -      Found by: call frame info
[task 2021-07-16T15:31:55.865Z] 15:31:55     INFO -   6  XUL!mozilla::RunGTestFunc(int*, char**) [GTestRunner.cpp:5278e88c6d34f40c17a54ba973120d09aa0070b7 : 156 + 0xd]
[task 2021-07-16T15:31:55.865Z] 15:31:55     INFO -      rbx = 0x00007ffee63ecdb0   rbp = 0x00007ffee63ec810
[task 2021-07-16T15:31:55.865Z] 15:31:55     INFO -      rsp = 0x00007ffee63ec710   r12 = 0x0000000000000075
[task 2021-07-16T15:31:55.865Z] 15:31:55     INFO -      r14 = 0x000000010f2357b4   r15 = 0x00007ffee63ed240
[task 2021-07-16T15:31:55.866Z] 15:31:55     INFO -      rip = 0x000000010aaef8a8
[task 2021-07-16T15:31:55.866Z] 15:31:55     INFO -      Found by: call frame info
[task 2021-07-16T15:31:55.866Z] 15:31:55     INFO -   7  XUL!XREMain::XRE_mainStartup(bool*) [nsAppRunner.cpp:5278e88c6d34f40c17a54ba973120d09aa0070b7 : 4302 + 0x10]
[task 2021-07-16T15:31:55.866Z] 15:31:55     INFO -      rbx = 0x00007ffee63ecdb0   rbp = 0x00007ffee63ecc00
[task 2021-07-16T15:31:55.867Z] 15:31:55     INFO -      rsp = 0x00007ffee63ec820   r12 = 0x0000000000000075
[task 2021-07-16T15:31:55.867Z] 15:31:55     INFO -      r14 = 0x000000010a100180   r15 = 0x00007ffee63ed240
[task 2021-07-16T15:31:55.867Z] 15:31:55     INFO -      rip = 0x000000010f2357b4
[task 2021-07-16T15:31:55.867Z] 15:31:55     INFO -      Found by: call frame info
[task 2021-07-16T15:31:55.868Z] 15:31:55     INFO -   8  XUL!XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:5278e88c6d34f40c17a54ba973120d09aa0070b7 : 5421 + 0x8]
[task 2021-07-16T15:31:55.868Z] 15:31:55     INFO -      rbx = 0x00007ffee63ed65f   rbp = 0x00007ffee63ecc80
[task 2021-07-16T15:31:55.868Z] 15:31:55     INFO -      rsp = 0x00007ffee63ecc10   r12 = 0x00007fbb8b40a5e0
[task 2021-07-16T15:31:55.868Z] 15:31:55     INFO -      r14 = 0x00007ffee63ed5e8   r15 = 0x00007ffee63ed606
[task 2021-07-16T15:31:55.868Z] 15:31:55     INFO -      rip = 0x000000010f23cf56
[task 2021-07-16T15:31:55.869Z] 15:31:55     INFO -      Found by: call frame info
[task 2021-07-16T15:31:55.869Z] 15:31:55     INFO -   9  XUL!XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:5278e88c6d34f40c17a54ba973120d09aa0070b7 : 5492 + 0xd]
[task 2021-07-16T15:31:55.869Z] 15:31:55     INFO -      rbx = 0x00007ffee63eda4e   rbp = 0x00007ffee63ecdb0
[task 2021-07-16T15:31:55.869Z] 15:31:55     INFO -      rsp = 0x00007ffee63ecc90   r12 = 0x00007ffee63eda3b
[task 2021-07-16T15:31:55.870Z] 15:31:55     INFO -      r14 = 0x00007ffee63ed8eb   r15 = 0x00007ffee63eda2b
[task 2021-07-16T15:31:55.870Z] 15:31:55     INFO -      rip = 0x000000010f23d35d
[task 2021-07-16T15:31:55.870Z] 15:31:55     INFO -      Found by: call frame info
[task 2021-07-16T15:31:55.870Z] 15:31:55     INFO -  10  firefox!main [nsBrowserApp.cpp:5278e88c6d34f40c17a54ba973120d09aa0070b7 : 378 + 0x2a0]
[task 2021-07-16T15:31:55.871Z] 15:31:55     INFO -      rbx = 0x00007ffee63eda4e   rbp = 0x00007ffee63ed3e0
[task 2021-07-16T15:31:55.871Z] 15:31:55     INFO -      rsp = 0x00007ffee63ecdc0   r12 = 0x00007ffee63eda3b
[task 2021-07-16T15:31:55.871Z] 15:31:55     INFO -      r14 = 0x00007ffee63ed8eb   r15 = 0x00007ffee63eda2b
[task 2021-07-16T15:31:55.871Z] 15:31:55     INFO -      rip = 0x000000010981368d
[task 2021-07-16T15:31:55.872Z] 15:31:55     INFO -      Found by: call frame info
[task 2021-07-16T15:31:55.872Z] 15:31:55     INFO -  11  0x7ffee63edef0
[task 2021-07-16T15:31:55.872Z] 15:31:55     INFO -      rbx = 0x656369767265732e   rbp = 0x00007ffee63ed428
[task 2021-07-16T15:31:55.872Z] 15:31:55     INFO -      rsp = 0x00007ffee63ed3f0   r12 = 0x63742d69632d786f
[task 2021-07-16T15:31:55.872Z] 15:31:55     INFO -      r14 = 0x73707474683d4c52   r15 = 0x66657269662f2f3a
[task 2021-07-16T15:31:55.873Z] 15:31:55     INFO -      rip = 0x00007ffee63edef0
[task 2021-07-16T15:31:55.873Z] 15:31:55     INFO -      Found by: call frame info
[task 2021-07-16T15:31:55.873Z] 15:31:55     INFO -  Thread 1
[task 2021-07-16T15:31:55.873Z] 15:31:55     INFO -   0  libsystem_kernel.dylib!__workq_kernreturn + 0xa
[task 2021-07-16T15:31:55.873Z] 15:31:55     INFO -      rax = 0x0000000002000170   rdx = 0x0000000000000001
[task 2021-07-16T15:31:55.874Z] 15:31:55     INFO -      rcx = 0x0000700002565b18   rbx = 0x00000000004f4004
[task 2021-07-16T15:31:55.874Z] 15:31:55     INFO -      rsi = 0x0000700002565b80   rdi = 0x0000000000000100
[task 2021-07-16T15:31:55.874Z] 15:31:55     INFO -      rbp = 0x0000700002565b40   rsp = 0x0000700002565b18
[task 2021-07-16T15:31:55.874Z] 15:31:55     INFO -       r8 = 0x0000000000000000    r9 = 0x0000000000000000
[task 2021-07-16T15:31:55.875Z] 15:31:55     INFO -      r10 = 0x0000000000000000   r11 = 0x0000000000000246
[task 2021-07-16T15:31:55.875Z] 15:31:55     INFO -      r12 = 0x0000700002566000   r13 = 0x0000000000000000
[task 2021-07-16T15:31:55.875Z] 15:31:55     INFO -      r14 = 0x0000000000000001   r15 = 0x0000700002565b78
[task 2021-07-16T15:31:55.875Z] 15:31:55     INFO -      rip = 0x00007fff6c2934ce
[task 2021-07-16T15:31:55.875Z] 15:31:55     INFO -      Found by: given as instruction pointer in context
[task 2021-07-16T15:31:55.876Z] 15:31:55     INFO -   1  libsystem_pthread.dylib!start_wqthread + 0xf
[task 2021-07-16T15:31:55.876Z] 15:31:55     INFO -      rbp = 0x0000700002565b60   rsp = 0x0000700002565b50
[task 2021-07-16T15:31:55.876Z] 15:31:55     INFO -      rip = 0x00007fff6c350b77
[task 2021-07-16T15:31:55.876Z] 15:31:55     INFO -      Found by: previous frame's frame pointer
[task 2021-07-16T15:31:55.877Z] 15:31:55     INFO -   2  0x700002565b70
[task 2021-07-16T15:31:55.877Z] 15:31:55     INFO -      rbp = 0x0000700002565b60   rsp = 0x0000700002565b58
[task 2021-07-16T15:31:55.877Z] 15:31:55     INFO -      rip = 0x0000700002565b70
[task 2021-07-16T15:31:55.877Z] 15:31:55     INFO -      Found by: call frame info
[task 2021-07-16T15:31:55.877Z] 15:31:55     INFO -   3  libsystem_pthread.dylib!start_wqthread + 0xf
[task 2021-07-16T15:31:55.878Z] 15:31:55     INFO -      rbp = 0x0000700002565b60   rsp = 0x0000700002565b60
[task 2021-07-16T15:31:55.878Z] 15:31:55     INFO -      rip = 0x00007fff6c350b77
[task 2021-07-16T15:31:55.878Z] 15:31:55     INFO -      Found by: stack scanning
Crash Signature: [@ testing::internal::TestEventRepeater::OnTestEnd(testing::TestInfo const&)] → [@ testing::internal::TestEventRepeater::OnTestEnd]
Summary: Intermittent gtest | application crashed [@ testing::internal::TestEventRepeater::OnTestEnd(testing::TestInfo const&)] → Intermittent gtest | application crashed [@ testing::internal::TestEventRepeater::OnTestEnd]
Status: NEW → RESOLVED
Closed: 1 month ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.