1737901 - Intermittent SUMMARY: ThreadSanitizer: data race (/builds/worker/workspace/build/application/firefox/libxul.so+0x68b1f0f)

Status: RESOLVED INCOMPLETE

(Core :: WebRTC, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2021-10-26T20:37:34.783Z] 20:37:34     INFO - TEST-START | /webrtc/protocol/ice-ufragpwd.html
[task 2021-10-26T20:37:34.791Z] 20:37:34     INFO - Clearing pref media.navigator.permission.disabled
[task 2021-10-26T20:37:34.908Z] 20:37:34     INFO - Clearing pref media.navigator.streams.fake
[task 2021-10-26T20:37:34.972Z] 20:37:34     INFO - Clearing pref privacy.resistFingerprinting.reduceTimerPrecision.jitter
[task 2021-10-26T20:37:35.032Z] 20:37:35     INFO - Clearing pref privacy.reduceTimerPrecision
[task 2021-10-26T20:37:35.076Z] 20:37:35     INFO - Clearing pref media.peerconnection.ice.trickle_grace_period
[task 2021-10-26T20:37:35.113Z] 20:37:35     INFO - Clearing pref media.peerconnection.ice.obfuscate_host_addresses
[task 2021-10-26T20:37:35.197Z] 20:37:35     INFO - Setting pref media.navigator.permission.disabled (true)
[task 2021-10-26T20:37:35.232Z] 20:37:35     INFO - PID 8964 | Timecard created 1635280627.449203
...
[task 2021-10-26T20:37:38.311Z] 20:37:38     INFO - PID 8964 |   Mutex M1146864724546814288 is already destroyed.
[task 2021-10-26T20:37:38.312Z] 20:37:38     INFO - PID 8964 |   Thread T24 'ModuleP~sThread' (tid=9871, running) created by main thread at:
[task 2021-10-26T20:37:38.312Z] 20:37:38     INFO - PID 8964 |     #0 <null> <null> (firefox+0x5916d)
[task 2021-10-26T20:37:38.312Z] 20:37:38     INFO - PID 8964 |     #1 <null> <null> (libxul.so+0x68bbb6d)
[task 2021-10-26T20:37:38.312Z] 20:37:38     INFO - PID 8964 |     #2 <null> <null> (libxul.so+0x685edc1)
[task 2021-10-26T20:37:38.312Z] 20:37:38     INFO - PID 8964 |     #3 <null> <null> (libxul.so+0x6672554)
[task 2021-10-26T20:37:38.313Z] 20:37:38     INFO - PID 8964 |     #4 <null> <null> (libxul.so+0x6671786)
[task 2021-10-26T20:37:38.313Z] 20:37:38     INFO - PID 8964 |     #5 <null> <null> (libxul.so+0x46fbc46)
[task 2021-10-26T20:37:38.313Z] 20:37:38     INFO - PID 8964 |     #6 <null> <null> (libxul.so+0x46d114d)
[task 2021-10-26T20:37:38.313Z] 20:37:38     INFO - PID 8964 |     #7 <null> <null> (libxul.so+0x46d4d2e)
[task 2021-10-26T20:37:38.313Z] 20:37:38     INFO - PID 8964 |     #8 <null> <null> (libxul.so+0x46d57e7)
[task 2021-10-26T20:37:38.313Z] 20:37:38     INFO - PID 8964 |     #9 <null> <null> (libxul.so+0x473a682)
[task 2021-10-26T20:37:38.314Z] 20:37:38     INFO - PID 8964 |     #10 <null> <null> (libxul.so+0x1956c2e)
[task 2021-10-26T20:37:38.314Z] 20:37:38     INFO - PID 8964 |     #11 <null> <null> (libxul.so+0x46cb3fc)
[task 2021-10-26T20:37:38.314Z] 20:37:38     INFO - PID 8964 |     #12 <null> <null> (libxul.so+0x46fafaa)
[task 2021-10-26T20:37:38.314Z] 20:37:38     INFO - PID 8964 |     #13 <null> <null> (libxul.so+0x20ed5c9)
[task 2021-10-26T20:37:38.314Z] 20:37:38     INFO - PID 8964 |     #14 <null> <null> (libxul.so+0x1956c2e)
[task 2021-10-26T20:37:38.314Z] 20:37:38     INFO - PID 8964 |     #15 <null> <null> (libxul.so+0x10a4917)
[task 2021-10-26T20:37:38.314Z] 20:37:38     INFO - PID 8964 |     #16 <null> <null> (libxul.so+0x107f96f)
[task 2021-10-26T20:37:38.315Z] 20:37:38     INFO - PID 8964 |     #17 <null> <null> (libxul.so+0x107dfe6)
[task 2021-10-26T20:37:38.315Z] 20:37:38     INFO - PID 8964 |     #18 <null> <null> (libxul.so+0x107e2c2)
[task 2021-10-26T20:37:38.315Z] 20:37:38     INFO - PID 8964 |     #19 <null> <null> (libxul.so+0x10a8677)
[task 2021-10-26T20:37:38.315Z] 20:37:38     INFO - PID 8964 |     #20 <null> <null> (libxul.so+0x10922a3)
[task 2021-10-26T20:37:38.315Z] 20:37:38     INFO - PID 8964 |     #21 <null> <null> (libxul.so+0x1099332)
[task 2021-10-26T20:37:38.315Z] 20:37:38     INFO - PID 8964 |     #22 <null> <null> (libxul.so+0x19f567d)
[task 2021-10-26T20:37:38.315Z] 20:37:38     INFO - PID 8964 |     #23 <null> <null> (libxul.so+0x19f612b)
[task 2021-10-26T20:37:38.315Z] 20:37:38     INFO - PID 8964 |     #24 <null> <null> (libxul.so+0x196e8dc)
[task 2021-10-26T20:37:38.315Z] 20:37:38     INFO - PID 8964 |     #25 <null> <null> (libxul.so+0x5084d46)
[task 2021-10-26T20:37:38.315Z] 20:37:38     INFO - PID 8964 |     #26 <null> <null> (libxul.so+0x6f40309)
[task 2021-10-26T20:37:38.315Z] 20:37:38     INFO - PID 8964 |     #27 <null> <null> (libxul.so+0x19f60dd)
[task 2021-10-26T20:37:38.315Z] 20:37:38     INFO - PID 8964 |     #28 <null> <null> (libxul.so+0x196e8dc)
[task 2021-10-26T20:37:38.315Z] 20:37:38     INFO - PID 8964 |     #29 <null> <null> (libxul.so+0x6f40056)
[task 2021-10-26T20:37:38.315Z] 20:37:38     INFO - PID 8964 |     #30 <null> <null> (libxul.so+0x6f47ea2)
[task 2021-10-26T20:37:38.316Z] 20:37:38     INFO - PID 8964 |     #31 <null> <null> (firefox+0xcf2a8)
[task 2021-10-26T20:37:38.316Z] 20:37:38     INFO - PID 8964 |     #32 <null> <null> (libc.so.6+0x21b96)
[task 2021-10-26T20:37:38.316Z] 20:37:38     INFO - PID 8964 | SUMMARY: ThreadSanitizer: data race (/builds/worker/workspace/build/application/firefox/libxul.so+0x68b1f0f)
[task 2021-10-26T20:37:38.316Z] 20:37:38     INFO - PID 8964 | ==================
[task 2021-10-26T20:37:38.350Z] 20:37:38     INFO - PID 8964 | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv
[task 2021-10-26T20:37:38.352Z] 20:37:38     INFO - PID 8964 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x23007C,name=PBrowser::Msg_ChildToParentMatrix) Channel error: cannot send/recv
[task 2021-10-26T20:37:38.392Z] 20:37:38     INFO - PID 8964 | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv
[task 2021-10-26T20:37:38.392Z] 20:37:38     INFO - PID 8964 | ###!!! [Parent][RunMessage] Error: Channel error: cannot send/recv
[task 2021-10-26T20:37:38.436Z] 20:37:38     INFO - PID 8964 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x39008C,name=PContent::Msg_FlushTabState) Channel error: cannot send/recv
[task 2021-10-26T20:37:38.441Z] 20:37:38     INFO - PID 8964 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x390145,name=PContent::Msg_CommitBrowsingContextTransaction) Channel error: cannot send/recv
[task 2021-10-26T20:37:38.446Z] 20:37:38     INFO - PID 8964 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x39014A,name=PContent::Msg_DiscardBrowsingContext) Channel error: cannot send/recv
[task 2021-10-26T20:37:38.666Z] 20:37:38     INFO - PID 8964 | A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
[task 2021-10-26T20:37:39.086Z] 20:37:39     INFO - PID 8964 | 1635280659084	Marionette	INFO	Stopped listening on port 36734
[task 2021-10-26T20:37:39.650Z] 20:37:39     INFO - PID 8964 | [Child 9311: Main Thread]: D/DataChannel Shutting down SCTP
[task 2021-10-26T20:37:39.650Z] 20:37:39     INFO - PID 8964 | [Child 9253: Main Thread]: D/DataChannel Shutting down SCTP
[task 2021-10-26T20:37:39.710Z] 20:37:39  WARNING - Traceback (most recent call last):
[task 2021-10-26T20:37:39.710Z] 20:37:39  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 797, in run_func
[task 2021-10-26T20:37:39.711Z] 20:37:39  WARNING -     self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2021-10-26T20:37:39.711Z] 20:37:39  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 921, in do_testharness
[task 2021-10-26T20:37:39.711Z] 20:37:39  WARNING -     self.script_resume % format_map, asynchronous=True)
[task 2021-10-26T20:37:39.711Z] 20:37:39  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 83, in execute_script
[task 2021-10-26T20:37:39.711Z] 20:37:39  WARNING -     return method(script, new_sandbox=False, sandbox=None)
[task 2021-10-26T20:37:39.711Z] 20:37:39  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1734, in execute_async_script
[task 2021-10-26T20:37:39.712Z] 20:37:39  WARNING -     rv = self._send_message("WebDriver:ExecuteAsyncScript", body, key="value")
[task 2021-10-26T20:37:39.712Z] 20:37:39  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2021-10-26T20:37:39.713Z] 20:37:39  WARNING -     return func(*args, **kwargs)
[task 2021-10-26T20:37:39.713Z] 20:37:39  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 630, in _send_message
[task 2021-10-26T20:37:39.713Z] 20:37:39  WARNING -     self._handle_error(err)
[task 2021-10-26T20:37:39.713Z] 20:37:39  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 652, in _handle_error
[task 2021-10-26T20:37:39.714Z] 20:37:39  WARNING -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2021-10-26T20:37:39.715Z] 20:37:39  WARNING - marionette_driver.errors.JavascriptException: TypeError: window.__wptrunner_process_next_event is not a function
[task 2021-10-26T20:37:39.715Z] 20:37:39  WARNING - stacktrace:
[task 2021-10-26T20:37:39.715Z] 20:37:39  WARNING - 	@tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py:88:8
[task 2021-10-26T20:37:39.716Z] 20:37:39  WARNING - 	@tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py:89:8
[task 2021-10-26T20:37:39.716Z] 20:37:39  WARNING - 
[task 2021-10-26T20:37:39.716Z] 20:37:39  WARNING - 
[task 2021-10-26T20:37:39.717Z] 20:37:39     INFO - TEST-UNEXPECTED-ERROR | /webrtc/protocol/ice-ufragpwd.html | TypeError: window.__wptrunner_process_next_event is not a function
[task 2021-10-26T20:37:39.718Z] 20:37:39     INFO - Traceback (most recent call last):
[task 2021-10-26T20:37:39.718Z] 20:37:39     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 797, in run_func
[task 2021-10-26T20:37:39.719Z] 20:37:39     INFO -     self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2021-10-26T20:37:39.719Z] 20:37:39     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 921, in do_testharness
[task 2021-10-26T20:37:39.720Z] 20:37:39     INFO -     self.script_resume % format_map, asynchronous=True)
[task 2021-10-26T20:37:39.720Z] 20:37:39     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 83, in execute_script
[task 2021-10-26T20:37:39.720Z] 20:37:39     INFO -     return method(script, new_sandbox=False, sandbox=None)
[task 2021-10-26T20:37:39.720Z] 20:37:39     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1734, in execute_async_script
[task 2021-10-26T20:37:39.721Z] 20:37:39     INFO -     rv = self._send_message("WebDriver:ExecuteAsyncScript", body, key="value")
[task 2021-10-26T20:37:39.722Z] 20:37:39     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2021-10-26T20:37:39.723Z] 20:37:39     INFO -     return func(*args, **kwargs)
[task 2021-10-26T20:37:39.723Z] 20:37:39     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 630, in _send_message
[task 2021-10-26T20:37:39.724Z] 20:37:39     INFO -     self._handle_error(err)
[task 2021-10-26T20:37:39.724Z] 20:37:39     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 652, in _handle_error
[task 2021-10-26T20:37:39.724Z] 20:37:39     INFO -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2021-10-26T20:37:39.725Z] 20:37:39     INFO - marionette_driver.errors.JavascriptException: TypeError: window.__wptrunner_process_next_event is not a function
[task 2021-10-26T20:37:39.725Z] 20:37:39     INFO - stacktrace:
[task 2021-10-26T20:37:39.725Z] 20:37:39     INFO - 	@tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py:88:8
[task 2021-10-26T20:37:39.726Z] 20:37:39     INFO - 	@tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py:89:8
[task 2021-10-26T20:37:39.727Z] 20:37:39     INFO - 
[task 2021-10-26T20:37:39.727Z] 20:37:39     INFO - TEST-INFO took 4924ms

Comment 1

[Andrew McCreight [:mccr8]]

This stack failed to symbolicate for some reason, so odds are the race here is something covered by the allow list, so I'll unhide this.

Comment 2

[Intermittent Failures Robot]

1 failures in 2845 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1737901&startday=2021-10-25&endday=2021-10-31&tree=all

Comment 3

[Intermittent Failures Robot]

1 failures in 3319 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-esr91: 1

Platform and build breakdown:

• linux1804-64-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1737901&startday=2021-12-06&endday=2021-12-12&tree=all

Comment 4

[Intermittent Failures Robot]

1 failures in 2801 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1737901&startday=2022-01-03&endday=2022-01-09&tree=all

Comment 5

[Intermittent Failures Robot]

1 failures in 3445 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1737901&startday=2022-01-24&endday=2022-01-30&tree=all

Comment 6

[Intermittent Failures Robot]

1 failures in 3711 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1737901&startday=2022-02-07&endday=2022-02-13&tree=all

Comment 7

[Intermittent Failures Robot]

1 failures in 3304 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1737901&startday=2022-03-14&endday=2022-03-20&tree=all

Comment 8

[Iulian Moraru]

This started to fail on tier 1: https://treeherder.mozilla.org/logviewer?job_id=371875192&repo=autoland&lineNumber=3769

Comment 9

[Intermittent Failures Robot]

1 failures in 3341 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1737901&startday=2022-03-21&endday=2022-03-27&tree=all

Comment 10

[Intermittent Failures Robot]

1 failures in 3323 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1737901&startday=2022-03-28&endday=2022-04-03&tree=all

Comment 11

[Intermittent Failures Robot]

1 failures in 2922 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1737901&startday=2022-04-04&endday=2022-04-10&tree=all