Closed Bug 1808984 Opened 2 years ago Closed 2 years ago

Intermittent toolkit/components/glean/tests/browser/browser_event_leak.js | application crashed [@ MOZ_Crash(char const*, int, char const*)]

Categories

(Toolkit :: Telemetry, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: crash, intermittent-failure)

Crash Data

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


INFO - TEST-START | toolkit/components/glean/tests/browser/browser_event_leak.js
[task 2023-01-06T21:20:34.383Z] 21:20:34     INFO - GECKO(7782) | ExceptionHandler::GenerateDump cloned child 8037
[task 2023-01-06T21:20:34.385Z] 21:20:34     INFO - GECKO(7782) | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2023-01-06T21:20:34.386Z] 21:20:34     INFO - GECKO(7782) | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2023-01-06T21:20:34.562Z] 21:20:34     INFO - GECKO(7782) | Exiting due to channel error.
[task 2023-01-06T21:20:34.563Z] 21:20:34     INFO - GECKO(7782) | Exiting due to channel error.
[task 2023-01-06T21:20:34.568Z] 21:20:34     INFO - GECKO(7782) | Exiting due to channel error.
[task 2023-01-06T21:20:34.569Z] 21:20:34     INFO - GECKO(7782) | Exiting due to channel error.
[task 2023-01-06T21:20:34.571Z] 21:20:34     INFO - GECKO(7782) | Exiting due to channel error.
[task 2023-01-06T21:20:34.573Z] 21:20:34     INFO - GECKO(7782) | Exiting due to channel error.
[task 2023-01-06T21:20:34.616Z] 21:20:34     INFO - TEST-INFO | Main app process: exit 11
[task 2023-01-06T21:20:34.617Z] 21:20:34     INFO - Buffered messages logged at 21:20:34
[task 2023-01-06T21:20:34.618Z] 21:20:34     INFO - Entering test bound 
[task 2023-01-06T21:20:34.618Z] 21:20:34     INFO - Buffered messages finished
[task 2023-01-06T21:20:34.619Z] 21:20:34    ERROR - TEST-UNEXPECTED-FAIL | toolkit/components/glean/tests/browser/browser_event_leak.js | application terminated with exit code 11
[task 2023-01-06T21:20:34.620Z] 21:20:34     INFO - runtests.py | Application ran for: 0:00:04.726776
[task 2023-01-06T21:20:34.620Z] 21:20:34     INFO - zombiecheck | Reading PID log: /tmp/tmp3o55f3yfpidlog
[task 2023-01-06T21:20:34.621Z] 21:20:34     INFO - ==> process 7782 launched child process 7804
[task 2023-01-06T21:20:34.621Z] 21:20:34     INFO - ==> process 7782 launched child process 7846
[task 2023-01-06T21:20:34.622Z] 21:20:34     INFO - ==> process 7782 launched child process 7876
[task 2023-01-06T21:20:34.622Z] 21:20:34     INFO - ==> process 7782 launched child process 7939
[task 2023-01-06T21:20:34.623Z] 21:20:34     INFO - ==> process 7782 launched child process 7942
[task 2023-01-06T21:20:34.623Z] 21:20:34     INFO - ==> process 7782 launched child process 7953
[task 2023-01-06T21:20:34.624Z] 21:20:34     INFO - ==> process 7782 launched child process 7971
[task 2023-01-06T21:20:34.626Z] 21:20:34     INFO - zombiecheck | Checking for orphan process with PID: 7939
[task 2023-01-06T21:20:34.627Z] 21:20:34     INFO - zombiecheck | Checking for orphan process with PID: 7876
[task 2023-01-06T21:20:34.627Z] 21:20:34     INFO - zombiecheck | Checking for orphan process with PID: 7971
[task 2023-01-06T21:20:34.629Z] 21:20:34     INFO - zombiecheck | Checking for orphan process with PID: 7846
[task 2023-01-06T21:20:34.630Z] 21:20:34     INFO - zombiecheck | Checking for orphan process with PID: 7942
[task 2023-01-06T21:20:34.630Z] 21:20:34     INFO - zombiecheck | Checking for orphan process with PID: 7953
[task 2023-01-06T21:20:34.631Z] 21:20:34     INFO - zombiecheck | Checking for orphan process with PID: 7804
[task 2023-01-06T21:20:34.631Z] 21:20:34     INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/KhSuFZQ_ST-2YIuBozjWiw/artifacts/public/build/target.crashreporter-symbols.zip
[task 2023-01-06T21:21:12.391Z] 21:21:12     INFO - mozcrash Copy/paste: /builds/worker/fetches/minidump-stackwalk/minidump-stackwalk --symbols-url=https://symbols.mozilla.org/ --human /tmp/tmp4mzyw4vh.mozrunner/minidumps/77d9b0ae-8366-e6a2-2c58-5629bab5b83a.dmp /tmp/tmpa8fgh29b
[task 2023-01-06T21:21:17.955Z] 21:21:17     INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/77d9b0ae-8366-e6a2-2c58-5629bab5b83a.dmp
[task 2023-01-06T21:21:17.957Z] 21:21:17     INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/77d9b0ae-8366-e6a2-2c58-5629bab5b83a.extra
[task 2023-01-06T21:21:18.307Z] 21:21:18     INFO - PROCESS-CRASH | toolkit/components/glean/tests/browser/browser_event_leak.js | application crashed [@ MOZ_Crash(char const*, int, char const*)]
[task 2023-01-06T21:21:18.307Z] 21:21:18     INFO - Mozilla crash reason: No database found
INFO - Operating system: Linux
[task 2023-01-06T21:21:18.308Z] 21:21:18     INFO -                   4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018
[task 2023-01-06T21:21:18.308Z] 21:21:18     INFO - CPU: amd64
[task 2023-01-06T21:21:18.308Z] 21:21:18     INFO -      family 6 model 85 stepping 7
[task 2023-01-06T21:21:18.308Z] 21:21:18     INFO -      2 CPUs
[task 2023-01-06T21:21:18.308Z] 21:21:18     INFO - Linux Ubuntu 18.04 - bionic (Ubuntu 18.04.6 LTS)
[task 2023-01-06T21:21:18.309Z] 21:21:18     INFO - 
[task 2023-01-06T21:21:18.309Z] 21:21:18     INFO - Crash reason:  SIGSEGV / SEGV_MAPERR
[task 2023-01-06T21:21:18.309Z] 21:21:18     INFO - Crash address: 0x0
[task 2023-01-06T21:21:18.309Z] 21:21:18     INFO - Crashing instruction: `mov dword [0x0], esi`
[task 2023-01-06T21:21:18.310Z] 21:21:18     INFO - Memory accessed by instruction:
[task 2023-01-06T21:21:18.311Z] 21:21:18     INFO -   0. Address: 0x0000000000000000
[task 2023-01-06T21:21:18.311Z] 21:21:18     INFO -      Size: 4
[task 2023-01-06T21:21:18.312Z] 21:21:18     INFO - Process uptime: not available
[task 2023-01-06T21:21:18.312Z] 21:21:18     INFO - 
[task 2023-01-06T21:21:18.313Z] 21:21:18     INFO - Thread 72 glean.upload (crashed)
[task 2023-01-06T21:21:18.313Z] 21:21:18     INFO -  0  libxul.so!MOZ_Crash(char const*, int, char const*) [Assertions.h:5d7bb031124916505b1bd27772a1a0d62142800e : 261]
[task 2023-01-06T21:21:18.314Z] 21:21:18     INFO -     Found by: inlining
[task 2023-01-06T21:21:18.314Z] 21:21:18     INFO -  1  libxul.so!RustMozCrash [wrappers.cpp:5d7bb031124916505b1bd27772a1a0d62142800e : 18 + 0xa]
[task 2023-01-06T21:21:18.315Z] 21:21:18     INFO -      rax = 0x0000558ffe03b708    rdx = 0x00007f31340f5fa4
[task 2023-01-06T21:21:18.316Z] 21:21:18     INFO -      rcx = 0x00007f31340f5ba4    rbx = 0x00007f3146d89d45
[task 2023-01-06T21:21:18.316Z] 21:21:18     INFO -      rsi = 0x000000000000020e    rdi = 0x00007f31340f5d9c
[task 2023-01-06T21:21:18.317Z] 21:21:18     INFO -      rbp = 0x00007f31340f5d80    rsp = 0x00007f31340f5d80
[task 2023-01-06T21:21:18.317Z] 21:21:18     INFO -       r8 = 0x000000000000001c     r9 = 0x00007f31340f5de0
[task 2023-01-06T21:21:18.318Z] 21:21:18     INFO -      r10 = 0x670222fa45c8d138    r11 = 0x00007f31340f5f7c
[task 2023-01-06T21:21:18.318Z] 21:21:18     INFO -      r12 = 0x0000000000000001    r13 = 0x00007f314e06ebc8
[task 2023-01-06T21:21:18.319Z] 21:21:18     INFO -      r14 = 0x000000000000020e    r15 = 0x000000000000002b
[task 2023-01-06T21:21:18.319Z] 21:21:18     INFO -      rip = 0x00007f314dbc632e
[task 2023-01-06T21:21:18.320Z] 21:21:18     INFO -     Found by: given as instruction pointer in context
[task 2023-01-06T21:21:18.320Z] 21:21:18     INFO -  2  libxul.so!mozglue_static::panic_hook [lib.rs:5d7bb031124916505b1bd27772a1a0d62142800e : 91 + 0x8]
[task 2023-01-06T21:21:18.321Z] 21:21:18     INFO -      rbx = 0x00007f3146d89d45    rbp = 0x00007f31340f61c0
[task 2023-01-06T21:21:18.321Z] 21:21:18     INFO -      rsp = 0x00007f31340f5d90    r12 = 0x0000000000000001
[task 2023-01-06T21:21:18.322Z] 21:21:18     INFO -      r13 = 0x00007f314e06ebc8    r14 = 0x000000000000020e
[task 2023-01-06T21:21:18.322Z] 21:21:18     INFO -      r15 = 0x000000000000002b    rip = 0x00007f314cef1d1a
[task 2023-01-06T21:21:18.323Z] 21:21:18     INFO -     Found by: call frame info
[task 2023-01-06T21:21:18.323Z] 21:21:18     INFO -  3  libxul.so!core::ops::function::Fn::call [function.rs:69f9c33d71c871fc16ac445211281c6e7a340943 : 78 + 0xb]
[task 2023-01-06T21:21:18.324Z] 21:21:18     INFO -      rbx = 0x00007f314e029f48    rbp = 0x00007f31340f61d0
<...>

No database found. Huh.

That means that glean.data_store is None which can happen if Glean::new fails (but if it did, the with_glean_opt earlier on the stack probably would've caught the problem), or destroy_db happened which only happens within glean_test_destroy_glean...

...maybe there was an outstanding task against the uploader which processed between glean_test_destroy_glean and initialize_internal within test_reset_glean?

Maybe. Either way this is definitely test-only, and has only one occurrence, so gonna backlog this.

Severity: -- → S4
Priority: -- → P5
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.