Closed Bug 1629080 Opened 2 years ago Closed 2 years ago

Intermittent GECKO(4719) | SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/toolkit/components/extensions/webrequest/StreamFilterParent.cpp:321:29 in <something>

Categories

(WebExtensions :: Request Handling, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED FIXED

People

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

References

(Blocks 1 open bug)

Details

(Keywords: intermittent-failure)

Filed by: shindli [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=297134084&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/WSh_Uid-RH2zohOjVDcxkA/runs/0/artifacts/public/logs/live_backing.log


task 2020-04-10T12:10:34.256Z] 12:10:34 INFO - GECKO(4719) | #37 mozilla::dom::ContentProcess::Init(int, char**) /builds/worker/checkouts/gecko/dom/ipc/ContentProcess.cpp:181:13 (libxul.so+0x417efcb)
[task 2020-04-10T12:10:34.258Z] 12:10:34 INFO - GECKO(4719) | #38 XRE_InitChildProcess(int, char**, XREChildData const*) /builds/worker/checkouts/gecko/toolkit/xre/nsEmbedFunctions.cpp:706:21 (libxul.so+0x611559f)
[task 2020-04-10T12:10:34.260Z] 12:10:34 INFO - GECKO(4719) | #39 mozilla::BootstrapImpl::XRE_InitChildProcess(int, char**, XREChildData const*) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:67:12 (libxul.so+0x611d202)
[task 2020-04-10T12:10:34.260Z] 12:10:34 INFO - GECKO(4719) | #40 content_process_main /builds/worker/checkouts/gecko/browser/app/../../ipc/contentproc/plugin-container.cpp:56:28 (firefox+0xc9067)
[task 2020-04-10T12:10:34.262Z] 12:10:34 INFO - GECKO(4719) | #41 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:303:18 (firefox+0xc9067)
[task 2020-04-10T12:10:34.263Z] 12:10:34 INFO - GECKO(4719) | SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/toolkit/components/extensions/webrequest/StreamFilterParent.cpp:321:29 in operator()
[task 2020-04-10T12:10:34.263Z] 12:10:34 INFO - GECKO(4719) | ==================
[task 2020-04-10T12:10:34.265Z] 12:10:34 INFO - GECKO(4719) | ###!!! [Parent][MessageChannel] Error: (msgtype=0xB8000A,name=PWindowGlobal::Msg_RawMessage) Channel error: cannot send/recv
[task 2020-04-10T12:10:34.483Z] 12:10:34 ERROR - GECKO(4719) | A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
[task 2020-04-10T12:10:46.145Z] 12:10:46 INFO - GECKO(4719) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x38012C,name=PContent::Msg_AsyncMessage) Closed channel: cannot send/recv
[task 2020-04-10T12:10:46.147Z] 12:10:46 INFO - GECKO(4719) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x38012C,name=PContent::Msg_AsyncMessage) Closed channel: cannot send/recv
[task 2020-04-10T12:10:47.617Z] 12:10:47 INFO - GECKO(4719) | 1586520647614 Marionette TRACE Received observer notification xpcom-will-shutdown
[task 2020-04-10T12:10:47.617Z] 12:10:47 INFO - GECKO(4719) | 1586520647615 Marionette INFO Stopped listening on port 2828
[task 2020-04-10T12:10:47.618Z] 12:10:47 INFO - GECKO(4719) | 1586520647616 Marionette DEBUG Marionette stopped listening
[task 2020-04-10T12:10:47.641Z] 12:10:47 INFO - GECKO(4719) | *** WIFI GEO: shutdown called
[task 2020-04-10T12:10:49.175Z] 12:10:49 INFO - TEST-INFO | Main app process: exit 0
[task 2020-04-10T12:10:49.176Z] 12:10:49 INFO - runtests.py | Application ran for: 0:04:17.309758
[task 2020-04-10T12:10:49.176Z] 12:10:49 INFO - zombiecheck | Reading PID log: /tmp/tmpPe46W3pidlog

[task 2020-04-10T12:11:25.418Z] 12:11:25 INFO - SUITE-END | took 1104s
[task 2020-04-10T12:11:25.472Z] 12:11:25 INFO - Return code: 0
[task 2020-04-10T12:11:25.473Z] 12:11:25 INFO - TinderboxPrint: mochitest-mochitest-plain<br/>241/0/0
[task 2020-04-10T12:11:25.473Z] 12:11:25 ERROR - # TBPL FAILURE #
[task 2020-04-10T12:11:25.473Z] 12:11:25 WARNING - setting return code to 2
[task 2020-04-10T12:11:25.473Z] 12:11:25 ERROR - The mochitest suite: mochitest-plain ran with return status: FAILURE
[task 2020-04-10T12:11:25.474Z] 12:11:25 INFO - Running post-action listener: _package_coverage_data
[task 2020-04-10T12:11:25.474Z] 12:11:25 INFO - Running post-action listener: _resource_record_post_action
[task 2020-04-10T12:11:25.474Z] 12:11:25 INFO - Running post-action listener: process_java_coverage_data
[task 2020-04-10T12:11:25.474Z] 12:11:25 INFO - [mozharness: 2020-04-10 12:11:25.474521Z] Finished run-tests step (success)
[task 2020-04-10T12:11:25.474Z] 12:11:25 INFO - Running post-run listener: _resource_record_post_run
[task 2020-04-10T12:11:25.635Z] 12:11:25 INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2020-04-10T12:11:25.641Z] 12:11:25 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"subtests": [{"name": "cpu_percent", "value": 44.612114537444924}, {"name": "io_write_bytes", "value": 2806861824}, {"name": "io.read_bytes", "value": 678723584}, {"name": "io_write_time", "value": 224728}, {"name": "io_read_time", "value": 9828}], "extraOptions": ["e10s", "taskcluster-c3.xlarge"], "name": "mochitest.mochitest-plain.overall"}, {"subtests": [{"name": "time", "value": 0.024671077728271484}], "name": "mochitest.mochitest-plain.start-pulseaudio"}, {"subtests": [{"name": "time", "value": 30.446955919265747}, {"name": "cpu_percent", "value": 25.129310344827587}], "name": "mochitest.mochitest-plain.install"}, {"subtests": [{"name": "time", "value": 0.0003948211669921875}], "name": "mochitest.mochitest-plain.stage-files"}, {"subtests": [{"name": "time", "value": 1106.6718780994415}, {"name": "cpu_percent", "value": 45.156657608695646}], "name": "mochitest.mochitest-plain.run-tests"}]}
[task 2020-04-10T12:11:25.641Z] 12:11:25 INFO - Total resource usage - Wall time: 1137s; CPU: 45.0%; Read bytes: 678723584; Write bytes: 2806861824; Read time: 9828; Write time: 224728
[task 2020-04-10T12:11:25.641Z] 12:11:25 INFO - TinderboxPrint: CPU usage<br/>45.2%
[task 2020-04-10T12:11:25.641Z] 12:11:25 INFO - TinderboxPrint: I/O read bytes / time<br/>678,723,584 / 9,828
[task 2020-04-10T12:11:25.641Z] 12:11:25 INFO - TinderboxPrint: I/O write bytes / time<br/>2,806,861,824 / 224,728
[task 2020-04-10T12:11:25.641Z] 12:11:25 INFO - TinderboxPrint: CPU idle<br/>2,453.9 (55.1%)
[task 2020-04-10T12:11:25.641Z] 12:11:25 INFO - TinderboxPrint: CPU system<br/>192.3 (4.3%)
[task 2020-04-10T12:11:25.642Z] 12:11:25 INFO - TinderboxPrint: CPU user<br/>1,795.0 (40.3%)
[task 2020-04-10T12:11:25.642Z] 12:11:25 INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2020-04-10T12:11:25.642Z] 12:11:25 INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2020-04-10T12:11:25.643Z] 12:11:25 INFO - install - Wall time: 30s; CPU: 25.0%; Read bytes: 0; Write bytes: 646037504; Read time: 0; Write time: 69412
[task 2020-04-10T12:11:25.644Z] 12:11:25 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2020-04-10T12:11:25.654Z] 12:11:25 INFO - run-tests - Wall time: 1107s; CPU: 45.0%; Read bytes: 678723584; Write bytes: 1808502784; Read time: 9828; Write time: 118276
[task 2020-04-10T12:11:25.990Z] 12:11:25 WARNING - returning nonzero exit status 2
[task 2020-04-10T12:11:26.017Z] cleanup
[task 2020-04-10T12:11:26.018Z] + cleanup
[task 2020-04-10T12:11:26.018Z] + local rv=2
[task 2020-04-10T12:11:26.018Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2020-04-10T12:11:26.018Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2020-04-10T12:11:26.022Z] + true
[task 2020-04-10T12:11:26.022Z] + cleanup_xvfb
[task 2020-04-10T12:11:26.022Z] ++ pidof Xvfb
[task 2020-04-10T12:11:26.029Z] + local xvfb_pid=40
[task 2020-04-10T12:11:26.029Z] + local vnc=false
[task 2020-04-10T12:11:26.029Z] + local interactive=false
[task 2020-04-10T12:11:26.029Z] + '[' -n 40 ']'
[task 2020-04-10T12:11:26.030Z] + [[ false == false ]]
[task 2020-04-10T12:11:26.030Z] + [[ false == false ]]
[task 2020-04-10T12:11:26.030Z] + kill 40
[task 2020-04-10T12:11:26.030Z] + screen -XS xvfb quit
[task 2020-04-10T12:11:26.057Z] + exit 2
[fetches 2020-04-10T12:11:26.057Z] removing /builds/worker/fetches
[fetches 2020-04-10T12:11:26.059Z] finished

Fix for this coming in Bug 1530408

Depends on: 1530408

This should be fixed by Bug 1530408

Assignee: nobody → mixedpuppy
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → FIXED
Blocks: tsan
You need to log in before you can comment on or make changes to this bug.