Closed Bug 1700775 Opened 4 years ago Closed 4 years ago

Intermittent org.mozilla.geckoview.test.<random_test> | java.lang.NullPointerException: NullHandle at org.mozilla.gecko.mozglue.GeckoLoader.nativeRun(Native Method)

Categories

(GeckoView :: General, defect, P1)

Unspecified
All

Tracking

(firefox89+ fixed, firefox90 fixed)

RESOLVED FIXED
90 Branch
Tracking Status
firefox89 + fixed
firefox90 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: agi)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [geckoview:m90])

Crash Data

Attachments

(1 file)

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


[task 2021-03-24T21:10:34.372Z] 21:10:34     INFO -  TEST-START | org.mozilla.geckoview.test.OpenWindowTest.openWindowSameSession
[task 2021-03-24T21:10:34.980Z] 21:10:34     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_RESULT: shortMsg=Process crashed.
[task 2021-03-24T21:10:34.980Z] 21:10:34     INFO -  org.mozilla.geckoview.test | INSTRUMENTATION_CODE: 0
[task 2021-03-24T21:10:34.992Z] 21:10:34     INFO -  org.mozilla.geckoview.test | adb_returncode=0
[task 2021-03-24T21:10:34.994Z] 21:10:34     INFO -  Passed: 376
[task 2021-03-24T21:10:34.994Z] 21:10:34     INFO -  Failed: 0
[task 2021-03-24T21:10:34.994Z] 21:10:34     INFO -  Todo: 24
[task 2021-03-24T21:10:34.994Z] 21:10:34     INFO -  SUITE-END | took 154s
[task 2021-03-24T21:10:35.107Z] 21:10:35     INFO -  mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/bdzQbTRpRm-tkabf4dHuhA/artifacts/public/build/target.crashreporter-symbols.zip
[task 2021-03-24T21:10:39.483Z] 21:10:39     INFO -  mozcrash Copy/paste: /builds/worker/fetches/minidump_stackwalk/minidump_stackwalk /tmp/tmpNUNL2R/638495e5-7db0-370c-8715-8e351f499119.dmp /tmp/tmpX3vmRH
[task 2021-03-24T21:10:43.747Z] 21:10:43     INFO -  mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/638495e5-7db0-370c-8715-8e351f499119.dmp
[task 2021-03-24T21:10:43.747Z] 21:10:43     INFO -  mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/638495e5-7db0-370c-8715-8e351f499119.extra
[task 2021-03-24T21:10:43.759Z] 21:10:43  WARNING -  PROCESS-CRASH | org.mozilla.geckoview.test.OpenWindowTest.openWindowSameSession | java.lang.NullPointerException: NullHandle 	at org.mozilla.gecko.mozglue.GeckoLoader.nativeRun(Native Method)
[task 2021-03-24T21:10:43.759Z] 21:10:43     INFO -  [java.lang.NullPointerException: NullHandle
[task 2021-03-24T21:10:43.759Z] 21:10:43     INFO -  	at org.mozilla.gecko.mozglue.GeckoLoader.nativeRun(Native Method)
[task 2021-03-24T21:10:43.760Z] 21:10:43     INFO -  	at org.mozilla.gecko.GeckoThread.run(GeckoThread.java:469)
[task 2021-03-24T21:10:43.760Z] 21:10:43     INFO -  ]
[task 2021-03-24T21:10:43.760Z] 21:10:43     INFO -  Mozilla crash reason: MOZ_CRASH(JNI exception)
[task 2021-03-24T21:10:43.760Z] 21:10:43     INFO -  Crash dump filename: /tmp/tmpNUNL2R/638495e5-7db0-370c-8715-8e351f499119.dmp
[task 2021-03-24T21:10:43.798Z] 21:10:43     INFO -  Stopping web server
[task 2021-03-24T21:10:43.802Z] 21:10:43     INFO -  Server shut down.
[task 2021-03-24T21:10:43.824Z] 21:10:43     INFO -  Web server killed.
[task 2021-03-24T21:10:43.824Z] 21:10:43     INFO -  Stopping web socket server
[task 2021-03-24T21:10:43.844Z] 21:10:43     INFO -  Stopping ssltunnel
[task 2021-03-24T21:10:44.263Z] 21:10:44    ERROR - Return code: 1
[task 2021-03-24T21:10:44.263Z] 21:10:44     INFO - TinderboxPrint: geckoview-junit<br/>376/0
[task 2021-03-24T21:10:44.263Z] 21:10:44     INFO - ##### geckoview-junit log ends
[task 2021-03-24T21:10:44.263Z] 21:10:44  WARNING - # TBPL WARNING #
[task 2021-03-24T21:10:44.263Z] 21:10:44  WARNING - setting return code to 1
[task 2021-03-24T21:10:44.263Z] 21:10:44  WARNING - The geckoview-junit suite: geckoview-junit ran with return status: WARNING
[task 2021-03-24T21:10:44.264Z] 21:10:44     INFO - Running post-action listener: _package_coverage_data
[task 2021-03-24T21:10:44.264Z] 21:10:44     INFO - Running post-action listener: _resource_record_post_action
[task 2021-03-24T21:10:44.264Z] 21:10:44     INFO - Running post-action listener: process_java_coverage_data
[task 2021-03-24T21:10:44.264Z] 21:10:44     INFO - Running post-action listener: stop_device
[task 2021-03-24T21:10:44.761Z] 21:10:44     INFO - Killing logcat pid 1750.
[task 2021-03-24T21:10:44.761Z] 21:10:44     INFO - Killing every process called qemu-system-x86_64
[task 2021-03-24T21:10:44.771Z] 21:10:44     INFO - [mozharness: 2021-03-24 21:10:44.770727Z] Finished run-tests step (success)
[task 2021-03-24T21:10:44.771Z] 21:10:44     INFO - Running post-run listener: _resource_record_post_run
[task 2021-03-24T21:10:44.906Z] 21:10:44     INFO - Total resource usage - Wall time: 197s; CPU: 34.0%; Read bytes: 4096; Write bytes: 62640128; Read time: 0; Write time: 4136
[task 2021-03-24T21:10:44.906Z] 21:10:44     INFO - TinderboxPrint: CPU usage<br/>33.7%
[task 2021-03-24T21:10:44.906Z] 21:10:44     INFO - TinderboxPrint: I/O read bytes / time<br/>4,096 / 0
[task 2021-03-24T21:10:44.906Z] 21:10:44     INFO - TinderboxPrint: I/O write bytes / time<br/>62,640,128 / 4,136
[task 2021-03-24T21:10:44.907Z] 21:10:44     INFO - TinderboxPrint: CPU guest<br/>1,588.8 (7.8%)
[task 2021-03-24T21:10:44.907Z] 21:10:44     INFO - TinderboxPrint: CPU idle<br/>12,404.7 (61.1%)
[task 2021-03-24T21:10:44.907Z] 21:10:44     INFO - TinderboxPrint: CPU system<br/>1,090.3 (5.4%)
[task 2021-03-24T21:10:44.907Z] 21:10:44     INFO - TinderboxPrint: CPU user<br/>5,162.0 (25.4%)
[task 2021-03-24T21:10:44.907Z] 21:10:44     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2021-03-24T21:10:44.907Z] 21:10:44     INFO - start-emulator - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2021-03-24T21:10:44.911Z] 21:10:44     INFO - verify-device - Wall time: 27s; CPU: 10.0%; Read bytes: 0; Write bytes: 2367488; Read time: 0; Write time: 474
[task 2021-03-24T21:10:44.912Z] 21:10:44     INFO - install - Wall time: 3s; CPU: 8.0%; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2021-03-24T21:10:44.936Z] 21:10:44     INFO - run-tests - Wall time: 167s; CPU: 38.0%; Read bytes: 4096; Write bytes: 60268544; Read time: 0; Write time: 3661
[task 2021-03-24T21:10:45.511Z] 21:10:45  WARNING - returning nonzero exit status 1
[task 2021-03-24T21:10:45.541Z] cleanup
[task 2021-03-24T21:10:45.541Z] + cleanup
[task 2021-03-24T21:10:45.541Z] + local rv=1
[task 2021-03-24T21:10:45.541Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2021-03-24T21:10:45.541Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2021-03-24T21:10:45.542Z] + '[' ']'
[task 2021-03-24T21:10:45.542Z] + true
[task 2021-03-24T21:10:45.542Z] + cleanup_xvfb
[task 2021-03-24T21:10:45.543Z] ++ pidof Xvfb
[task 2021-03-24T21:10:45.547Z] + local xvfb_pid=50
[task 2021-03-24T21:10:45.547Z] + local vnc=false
[task 2021-03-24T21:10:45.547Z] + local interactive=false
[task 2021-03-24T21:10:45.547Z] + '[' -n 50 ']'
[task 2021-03-24T21:10:45.547Z] + [[ false == false ]]
[task 2021-03-24T21:10:45.547Z] + [[ false == false ]]
[task 2021-03-24T21:10:45.547Z] + kill 50
[task 2021-03-24T21:10:45.547Z] + screen -XS xvfb quit
[task 2021-03-24T21:10:45.561Z] + exit 1
[taskcluster 2021-03-24 21:10:46.226Z] === Task Finished ===
[taskcluster 2021-03-24 21:10:52.626Z] Unsuccessful task run with exit code: 1 completed in 289.657 seconds```

Emily, should we modify this bug's summary to include other Geckoview failures with: java.lang.NullPointerException: NullHandle at org.mozilla.gecko.mozglue.GeckoLoader.nativeRun(Native Method) like Bug 1672167, 1550894 and 1700851?

Yes please.

Flags: needinfo?(etoop)
Summary: Intermittent org.mozilla.geckoview.test.OpenWindowTest.openWindowSameSession | java.lang.NullPointerException: NullHandle at org.mozilla.gecko.mozglue.GeckoLoader.nativeRun(Native Method) → Intermittent org.mozilla.geckoview.test.<random_test> | java.lang.NullPointerException: NullHandle at org.mozilla.gecko.mozglue.GeckoLoader.nativeRun(Native Method)

I've encountered this crash while scrolling through https://www.zdnet.com/google-amp/article/firefox-to-ship-network-partitioning-as-a-new-anti-tracking-defense/ o the 3/31 Nightly build with Google Pixel (Android 10).

d699a65a-27ba-4710-8150-c282dba9d601
<native crash>


<native crash>

Priority: P5 → P2
Whiteboard: [geckoview:m90]

This looks pretty scary. I'm gonna look at it now.

Assignee: nobody → agi
Severity: S4 → S3
Priority: P2 → P1
Crash Signature: [@ java.lang.NullPointerException: at org.mozilla.gecko.mozglue.GeckoLoader.nativeRun(Native Method)]

[Tracking Requested - why for this release]: Currently our top 2 crash (#1 crash is not actionable), new in 89

Confirmed on try that Bug 1567341 is causing this.

Regressed by: 1567341
Has Regression Range: --- → yes
Keywords: regression

This reverts commit e542e8826b92f70dbabaf577d80a32273c684d1b.

In hindsight, this was a pretty bad change. It introduces a third place where
the EventDispatcher can be shutdown (JavaScript) and doesn't do a good job at
communicating this across all consumers.

I introduced this change in Bug 1567341 to fix some leaks in xpcshell tests at
xpcom shutdown, and it was never meant to affect a normal browser use (turns
out, it does).

I ran the tests again in try and they are still green when reverting this
change, I suspect that the actual failures I was seeing were due to a separate
leak that I fixed while developing the patches.

Pushed by asferro@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/2b1ff6cb75dc Revert "Bug 1567341 - Release EventDispatcher listeners on xpcom-shutdown" r=aklotz
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → FIXED
Target Milestone: --- → 90 Branch
Whiteboard: [geckoview:m90]

Does this need a Beta uplift request?

Flags: needinfo?(agi)

Comment on attachment 9216861 [details]
Bug 1700775 - Revert "Bug 1567341 - Release EventDispatcher listeners on xpcom-shutdown"

Beta/Release Uplift Approval Request

  • User impact if declined: Random crashes when opening a new tab
  • Is this code covered by automated tests?: Yes
  • Has the fix been verified in Nightly?: Yes
  • Needs manual test from QE?: No
  • If yes, steps to reproduce:
  • List of other uplifts needed: None
  • Risk to taking this patch: Medium
  • Why is the change risky/not risky? (and alternatives if risky): The change is a little involved, however the code reverts back to what it was in Gecko 88, so it should be pretty safe.
  • String changes made/needed:
Flags: needinfo?(agi)
Attachment #9216861 - Flags: approval-mozilla-beta?

Comment on attachment 9216861 [details]
Bug 1700775 - Revert "Bug 1567341 - Release EventDispatcher listeners on xpcom-shutdown"

Approved for Fenix 89.0.0-beta.2.

Attachment #9216861 - Flags: approval-mozilla-beta? → approval-mozilla-beta+
Whiteboard: [geckoview:m90]
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: