Closed Bug 1747488 Opened 2 years ago Closed 2 days ago

Intermittent damp | Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))]

Categories

(Testing :: Talos, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2021-12-23T21:04:34.226Z] 21:04:34     INFO -   '_': '/usr/local/bin/start-worker'}
[task 2021-12-23T21:04:34.226Z] 21:04:34     INFO -  TEST-INFO | started process 4656 (/home/cltbld/tasks/task_164029104946221/build/application/firefox/firefox -profile /tmp/tmphyeis8n5/profile)
[task 2021-12-23T21:04:35.015Z] 21:04:35     INFO -  PID 4656 | Inconsistency detected by ld.so: ../elf/dl-tls.c: 481: _dl_allocate_tls_init: Assertion `listp->slotinfo[cnt].gen <= GL(dl_tls_generation)' failed!
[task 2021-12-23T21:04:35.023Z] 21:04:35     INFO -  PID 4656 | Exiting due to channel error.
[task 2021-12-23T21:04:35.024Z] 21:04:35     INFO -  PID 4656 | Exiting due to channel error.
[task 2021-12-23T21:04:35.069Z] 21:04:35     INFO -  Terminating psutil.Process(pid=4656, name='GeckoMain', started='21:04:33')
[task 2021-12-23T21:04:35.069Z] 21:04:35     INFO -  TEST-INFO | 4656: exit 127
[task 2021-12-23T21:04:35.108Z] 21:04:35     INFO -  mozcrash checking /tmp/tmphyeis8n5/profile/minidumps for minidumps...
[task 2021-12-23T21:04:35.108Z] 21:04:35     INFO -  TEST-UNEXPECTED-ERROR | damp | Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))]
[task 2021-12-23T21:04:35.108Z] 21:04:35    ERROR -  Traceback (most recent call last):
[task 2021-12-23T21:04:35.108Z] 21:04:35     INFO -    File "/home/cltbld/tasks/task_164029104946221/build/tests/talos/talos/run_tests.py", line 336, in run_tests
[task 2021-12-23T21:04:35.109Z] 21:04:35     INFO -      talos_results.add(mytest.runTest(browser_config, test))
[task 2021-12-23T21:04:35.109Z] 21:04:35     INFO -    File "/home/cltbld/tasks/task_164029104946221/build/tests/talos/talos/ttest.py", line 65, in runTest
[task 2021-12-23T21:04:35.109Z] 21:04:35     INFO -      return self._runTest(browser_config, test_config, setup)
[task 2021-12-23T21:04:35.109Z] 21:04:35     INFO -    File "/home/cltbld/tasks/task_164029104946221/build/tests/talos/talos/ttest.py", line 274, in _runTest
[task 2021-12-23T21:04:35.109Z] 21:04:35     INFO -      counter_management.results() if counter_management else None
[task 2021-12-23T21:04:35.110Z] 21:04:35     INFO -    File "/home/cltbld/tasks/task_164029104946221/build/tests/talos/talos/results.py", line 95, in add
[task 2021-12-23T21:04:35.110Z] 21:04:35     INFO -      global_counters=self.global_counters,
[task 2021-12-23T21:04:35.110Z] 21:04:35     INFO -    File "/home/cltbld/tasks/task_164029104946221/build/tests/talos/talos/results.py", line 346, in __init__
[task 2021-12-23T21:04:35.110Z] 21:04:35     INFO -      self.parse()
[task 2021-12-23T21:04:35.110Z] 21:04:35     INFO -    File "/home/cltbld/tasks/task_164029104946221/build/tests/talos/talos/results.py", line 373, in parse
[task 2021-12-23T21:04:35.110Z] 21:04:35     INFO -      "Could not find report in browser output: %s" % self.report_tokens
[task 2021-12-23T21:04:35.111Z] 21:04:35     INFO -    File "/home/cltbld/tasks/task_164029104946221/build/tests/talos/talos/results.py", line 357, in error
[task 2021-12-23T21:04:35.111Z] 21:04:35     INFO -      raise utils.TalosError(message)
[task 2021-12-23T21:04:35.111Z] 21:04:35     INFO -  talos.utils.TalosError: Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))]
[task 2021-12-23T21:04:35.111Z] 21:04:35     INFO -  TEST-INFO took 2383782ms
[task 2021-12-23T21:04:35.111Z] 21:04:35     INFO -  SUITE-END | took 2383s
[task 2021-12-23T21:04:35.495Z] 21:04:35    ERROR - Return code: 2
[task 2021-12-23T21:04:35.495Z] 21:04:35  WARNING - setting return code to 2
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

So the error message here is just a sign that we crashed or aborted at a particular time, which means the log parser doesn't find some expected output in the browser output, which then produces this error message.

The crash/abort itself is the real issue, and that may be happening for different reasons in different cases.

In comment 0, the crash/abort was likely associated with this fatal assertion, near the start of the quoted log snippet:

PID 4656 | Inconsistency detected by ld.so: ../elf/dl-tls.c: 481: _dl_allocate_tls_init: Assertion `listp->slotinfo[cnt].gen <= GL(dl_tls_generation)' failed
That's a known Linux-specific glibc bug, and I've filed bug 1819763 on that specific issue.

The failures that we still have available in our Orange Factor chart are all for Windows, though, so they're a different sort of crash. They all just say TEST-INFO | 5980: exit 1 (where 5980 is the PID) just before the issue, without much of a hint about why we exited, unfortunately.

Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago11 months ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 11 months ago8 months ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 8 months ago2 days ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.