1840863 - Intermittent layout/tables/crashtests/750147.html | single tracking bug

Status: RESOLVED INCOMPLETE

(Core :: Layout: Tables, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=420933005&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/S9ioHcgYStOi9XsqzeiaSQ/runs/2/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/S9ioHcgYStOi9XsqzeiaSQ/runs/2/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2023-06-28T13:16:57.620Z] 13:16:57     INFO - REFTEST TEST-START | layout/tables/crashtests/750147.html
[task 2023-06-28T13:16:57.651Z] 13:16:57     INFO - REFTEST INFO | SET PREFERENCE pref(font.size.inflation.minTwips,120)
[task 2023-06-28T13:16:57.652Z] 13:16:57     INFO - REFTEST TEST-LOAD | file:///builds/worker/workspace/build/tests/reftest/tests/layout/tables/crashtests/750147.html | 3781 / 3988 (94%)
[task 2023-06-28T13:17:12.692Z] 13:17:12     INFO - out of memory: 0x0000000000004000 bytes requested
[task 2023-06-28T13:17:12.693Z] 13:17:12     INFO - AddressSanitizer:DEADLYSIGNAL
[task 2023-06-28T13:17:12.693Z] 13:17:12     INFO - =================================================================
[task 2023-06-28T13:17:12.693Z] 13:17:12     INFO - ERROR: Failed to mmap
[task 2023-06-28T13:17:13.823Z] 13:17:13    ERROR - A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
[task 2023-06-28T13:17:13.920Z] 13:17:13     INFO - Crash Annotation GraphicsCriticalError: |[0][GFX1-]: Attempting to allocate a texture of size 10000x10000 above the limit, trimming (t=125.256) |[526]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.835) |[527]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.843) |[528]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.843) |[529]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.855) |[530]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.855) |[531]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.856) |[532]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.857) |[533]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.873) |[534]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.874) |[535]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.885) |[536]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.885) |[537][GFX1-]: wr_renderer_render: OutOfMemory (t=1172.03) |[523]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.798) |[524]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.798) |[525]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.835) [GFX1-]: wr_renderer_render: OutOfMemory
[task 2023-06-28T13:22:23.222Z] 13:22:23     INFO - out of memory: 0x0000000000004000 bytes requested
[task 2023-06-28T13:22:23.222Z] 13:22:23     INFO - AddressSanitizer:DEADLYSIGNAL
[task 2023-06-28T13:22:23.222Z] 13:22:23     INFO - =================================================================
[task 2023-06-28T13:22:23.222Z] 13:22:23     INFO - ERROR: Failed to mmap
[task 2023-06-28T13:22:25.819Z] 13:22:25     INFO - WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"SessionStore: flushing all windows","state":{"total":28,"current":0},"filename":"resource:///modules/sessionstore/SessionStore.sys.mjs","lineNumber":2279,"stack":["resource:///modules/sessionstore/SessionStore.sys.mjs:ssi_onQuitApplicationGranted:2279","resource:///modules/sessionstore/SessionStore.sys.mjs:ssi_observe:1172","resource:///modules/ContentCrashHandlers.sys.mjs:observe:165"]}] Barrier: quit-application-granted
[task 2023-06-28T13:22:25.877Z] 13:22:25     INFO - JavaScript error: resource:///modules/sessionstore/SessionStore.sys.mjs, line 2291: TypeError: subject.QueryInterface is not a function
[task 2023-06-28T13:22:25.885Z] 13:22:25     INFO - JavaScript error: resource:///modules/sessionstore/SessionStore.sys.mjs, line 2291: TypeError: subject.QueryInterface is not a function
[task 2023-06-28T13:22:25.889Z] 13:22:25     INFO - JavaScript error: resource:///modules/sessionstore/SessionStore.sys.mjs, line 2291: TypeError: subject.QueryInterface is not a function
[task 2023-06-28T13:22:25.894Z] 13:22:25    ERROR - A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
[task 2023-06-28T13:22:25.969Z] 13:22:25     INFO - 1687958545968	Marionette	TRACE	Received observer notification quit-application
[task 2023-06-28T13:22:25.972Z] 13:22:25     INFO - 1687958545970	Marionette	INFO	Stopped listening on port 2828
[task 2023-06-28T13:22:25.983Z] 13:22:25     INFO - 1687958545982	Marionette	DEBUG	Marionette stopped listening
[task 2023-06-28T13:22:26.105Z] 13:22:26     INFO - Crash Annotation GraphicsCriticalError: |[0][GFX1-]: Attempting to allocate a texture of size 10000x10000 above the limit, trimming (t=125.256) |[526]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.835) |[527]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.843) |[528]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.843) |[529]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.855) |[530]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.855) |[531]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.856) |[532]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.857) |[533]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.873) |[534]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.874) |[535]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.885) |[536]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.885) |[537][GFX1-]: wr_renderer_render: OutOfMemory (t=1172.03) |[538][GFX1-]: Handling webrender error 2 (t=1484.21) |[524]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.798) |[525]CP+[GFX1-]: Failed to allocate a surface due to invalid size (CDT) Size(53687092,96) (t=958.835) [GFX1-]: Handling webrender error 2
[task 2023-06-28T13:22:26.146Z] 13:22:26     INFO - REFTEST TEST-UNEXPECTED-FAIL | layout/tables/crashtests/750147.html | load failed: timed out while taking snapshot (bug in harness?)
[task 2023-06-28T13:22:26.149Z] 13:22:26     INFO - REFTEST INFO | Saved log: START file:///builds/worker/workspace/build/tests/reftest/tests/layout/tables/crashtests/750147.html
[task 2023-06-28T13:22:26.150Z] 13:22:26     INFO - REFTEST INFO | Saved log: [CONTENT] OnDocumentLoad triggering AfterOnLoadScripts
[task 2023-06-28T13:22:26.151Z] 13:22:26     INFO - REFTEST INFO | Saved log: Initializing canvas snapshot
[task 2023-06-28T13:22:26.152Z] 13:22:26     INFO - REFTEST INFO | Saved log: [CONTENT] AfterOnLoadScripts belatedly entering WaitForTestEnd
[task 2023-06-28T13:22:26.152Z] 13:22:26     INFO - REFTEST INFO | Saved log: [CONTENT] WaitForTestEnd: Adding listeners
[task 2023-06-28T13:22:26.153Z] 13:22:26     INFO - REFTEST INFO | Saved log: Initializing canvas snapshot
[task 2023-06-28T13:22:26.153Z] 13:22:26     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress
[task 2023-06-28T13:22:26.154Z] 13:22:26     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FIRE_INVALIDATE_EVENT
[task 2023-06-28T13:22:26.155Z] 13:22:26     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: dispatching MozReftestInvalidate
[task 2023-06-28T13:22:26.155Z] 13:22:26     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress
[task 2023-06-28T13:22:26.156Z] 13:22:26     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
[task 2023-06-28T13:22:26.156Z] 13:22:26     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress
[task 2023-06-28T13:22:26.157Z] 13:22:26     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_SPELL_CHECKS
[task 2023-06-28T13:22:26.158Z] 13:22:26     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_APZ_FLUSH
[task 2023-06-28T13:22:26.158Z] 13:22:26     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: APZ flush not required
[task 2023-06-28T13:22:26.159Z] 13:22:26     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress
[task 2023-06-28T13:22:26.162Z] 13:22:26     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FINISH
[task 2023-06-28T13:22:26.163Z] 13:22:26     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: Doing sync flush to compositor
[task 2023-06-28T13:22:26.163Z] 13:22:26     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: Completed
[task 2023-06-28T13:22:26.164Z] 13:22:26     INFO - REFTEST TEST-END | layout/tables/crashtests/750147.html
[task 2023-06-28T13:22:26.165Z] 13:22:26     INFO - REFTEST TEST-PASS | layout/tables/crashtests/750147.html | (LOAD ONLY)
[task 2023-06-28T13:22:26.165Z] 13:22:26     INFO - REFTEST TEST-END | layout/tables/crashtests/750147.html
[task 2023-06-28T13:22:26.192Z] 13:22:26     INFO - [Parent 1522, IPC I/O Parent] WARNING: process 1743 exited with status 1: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_posix.cc:271
[task 2023-06-28T13:22:27.371Z] 13:22:27     INFO - [Parent 1522, IPC I/O Parent] WARNING: process 1630 exited with status 1: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_posix.cc:271
[task 2023-06-28T13:22:27.544Z] 13:22:27     INFO - !!! error running onStopped callback: TypeError: callback is not a function
[task 2023-06-28T13:22:27.573Z] 13:22:27     INFO - console.error: ({})
[task 2023-06-28T13:22:32.028Z] 13:22:32    ERROR - TEST-UNEXPECTED-FAIL | layout/tables/crashtests/750147.html (finished) | application terminated with exit code 245
[task 2023-06-28T13:22:32.046Z] 13:22:32     INFO - REFTEST INFO | Process mode: e10s
[task 2023-06-28T13:22:32.072Z] 13:22:32     INFO - Return code: 245
[task 2023-06-28T13:22:32.073Z] 13:22:32  WARNING - Got 1 unexpected statuses
[task 2023-06-28T13:22:32.073Z] 13:22:32    ERROR - No suite end message was emitted by this harness.
[task 2023-06-28T13:22:32.073Z] 13:22:32     INFO - TinderboxPrint: reftest-crashtest<br/>7549/<em class="testfail">1</em>/0
[task 2023-06-28T13:22:32.073Z] 13:22:32  WARNING - setting return code to 2
[task 2023-06-28T13:22:32.074Z] 13:22:32     INFO - The reftest suite: crashtest ran with return status: FAILURE
[task 2023-06-28T13:22:32.074Z] 13:22:32     INFO - Running post-action listener: _package_coverage_data
[task 2023-06-28T13:22:32.074Z] 13:22:32     INFO - Running post-action listener: _resource_record_post_action
[task 2023-06-28T13:22:32.074Z] 13:22:32     INFO - Running post-action listener: process_java_coverage_data
[task 2023-06-28T13:22:32.074Z] 13:22:32     INFO - [mozharness: 2023-06-28 13:22:32.074552Z] Finished run-tests step (success)
[task 2023-06-28T13:22:32.074Z] 13:22:32     INFO - [mozharness: 2023-06-28 13:22:32.074655Z] Running uninstall step.
[task 2023-06-28T13:22:32.074Z] 13:22:32     INFO - Running pre-action listener: _resource_record_pre_action
[task 2023-06-28T13:22:32.074Z] 13:22:32     INFO - Running main action method: uninstall
[task 2023-06-28T13:22:32.075Z] 13:22:32     INFO - Skipping uninstall for non-MSIX test
[task 2023-06-28T13:22:32.075Z] 13:22:32     INFO - Running post-action listener: _resource_record_post_action
[task 2023-06-28T13:22:32.075Z] 13:22:32     INFO - [mozharness: 2023-06-28 13:22:32.075460Z] Finished uninstall step (success)
[task 2023-06-28T13:22:32.075Z] 13:22:32     INFO - Running post-run listener: _resource_record_post_run
[task 2023-06-28T13:22:32.201Z] 13:22:32     INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2023-06-28T13:22:32.205Z] 13:22:32     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "reftest.crashtest.overall", "extraOptions": ["e10s", "taskcluster-projects/887720501152/machineTypes/n2-standard-2"], "subtests": [{"name": "cpu_percent", "value": 74.59248601119103}, {"name": "io_write_bytes", "value": 4436688896}, {"name": "io.read_bytes", "value": 484738727936}, {"name": "io_write_time", "value": 2368092}, {"name": "io_read_time", "value": 14368636}]}, {"name": "reftest.crashtest.start-pulseaudio", "subtests": [{"name": "time", "value": 0.021184206008911133}, {"name": "cpu_percent", "value": 0}]}, {"name": "reftest.crashtest.install", "subtests": [{"name": "time", "value": 66.01674914360046}, {"name": "cpu_percent", "value": 50.487500000000004}]}, {"name": "reftest.crashtest.stage-files", "subtests": [{"name": "time", "value": 0.0009467601776123047}, {"name": "cpu_percent", "value": 0}]}, {"name": "reftest.crashtest.run-tests", "subtests": [{"name": "time", "value": 1490.5723850727081}, {"name": "cpu_percent", "value": 75.93417721518988}]}, {"name": "reftest.crashtest.uninstall", "subtests": [{"name": "time", "value": 0.0005328655242919922}, {"name": "cpu_percent", "value": 0}]}]}
[task 2023-06-28T13:22:32.205Z] 13:22:32     INFO - Total resource usage - Wall time: 1556s; CPU: Can't collect data; Read bytes: 484738727936; Write bytes: 4436688896; Read time: 14368636; Write time: 2368092
[task 2023-06-28T13:22:32.205Z] 13:22:32     INFO - TinderboxPrint: I/O read bytes / time<br/>484,738,727,936 / 14,368,636
[task 2023-06-28T13:22:32.205Z] 13:22:32     INFO - TinderboxPrint: I/O write bytes / time<br/>4,436,688,896 / 2,368,092
[task 2023-06-28T13:22:32.206Z] 13:22:32     INFO - TinderboxPrint: CPU idle<br/>611.5 (19.8%)
[task 2023-06-28T13:22:32.206Z] 13:22:32     INFO - TinderboxPrint: CPU iowait<br/>196.8 (6.4%)
[task 2023-06-28T13:22:32.206Z] 13:22:32     INFO - TinderboxPrint: CPU system<br/>649.3 (21.0%)
[task 2023-06-28T13:22:32.206Z] 13:22:32     INFO - TinderboxPrint: CPU user<br/>1,624.0 (52.6%)
[task 2023-06-28T13:22:32.207Z] 13:22:32     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2023-06-28T13:22:32.207Z] 13:22:32     INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2023-06-28T13:22:32.208Z] 13:22:32     INFO - install - Wall time: 66s; CPU: 50%; Read bytes: 0; Write bytes: 3732107264; Read time: 0; Write time: 2287556
[task 2023-06-28T13:22:32.209Z] 13:22:32     INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2023-06-28T13:22:32.214Z] 13:22:32     INFO - run-tests - Wall time: 1491s; CPU: 76%; Read bytes: 484738727936; Write bytes: 704581632; Read time: 14368636; Write time: 80536
[task 2023-06-28T13:22:32.215Z] 13:22:32     INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2023-06-28T13:22:32.329Z] 13:22:32  WARNING - returning nonzero exit status 2

Comment 1

[Iulian Moraru]

First occurrence, so far: backfill range and retriggers.

Comment 2

[Timothy Nikkel (:tnikkel)]

There's other new intermittent bugs filed lately for failures in asan crash test, I suspect they are all related.

Comment 3

[Intermittent Failures Robot]

8 failures in 3594 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 4
• mozilla-central: 4

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1840863&startday=2023-06-26&endday=2023-07-02&tree=all

Comment 4

[Intermittent Failures Robot]

10 failures in 2956 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 4
• autoland: 3
• mozilla-beta: 2
• try: 1

Platform and build breakdown:

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

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

Comment 5

[Intermittent Failures Robot]

4 failures in 3309 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2
• mozilla-central: 2

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1840863&startday=2023-07-10&endday=2023-07-16&tree=all

Comment 6

[Intermittent Failures Robot]

3 failures in 3520 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2
• mozilla-central: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1840863&startday=2023-07-17&endday=2023-07-23&tree=all

Comment 7

[Intermittent Failures Robot]

2 failures in 3726 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1
• autoland: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1840863&startday=2023-09-25&endday=2023-10-01&tree=all

Comment 8

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.