Closed Bug 1665952 Opened 5 years ago Closed 5 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /svg/painting/reftests/mask-print.svg | expected PASS

Categories

(Core :: SVG, defect, P5)

Product:
Core
Component:
SVG
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=316079853&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/KD7iTouoQ9qFoi1jOCFNpw/runs/0/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/KD7iTouoQ9qFoi1jOCFNpw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2020-09-18T13:51:46.511Z] 13:51:46 INFO - TEST-START | /infrastructure/reftest/reftest_mismatch_page_margins-print.html
[task 2020-09-18T13:51:46.513Z] 13:51:46 INFO - PID 1816 | 1600437106510 Marionette INFO Testing http://web-platform.test:8000/infrastructure/reftest/reftest_mismatch_page_margins-print.html != http://web-platform.test:8000/infrastructure/reftest/reftest_match-print-ref.html
[task 2020-09-18T13:51:49.593Z] 13:51:49 INFO - PID 1816 | 1600437109590 Marionette INFO Rendering page 1/2
[task 2020-09-18T13:51:49.651Z] 13:51:49 INFO - PID 1816 | 1600437109640 Marionette INFO Rendering page 1/2
[task 2020-09-18T13:51:49.692Z] 13:51:49 INFO - PID 1816 | 1600437109689 Marionette INFO No differences allowed
[task 2020-09-18T13:51:49.702Z] 13:51:49 INFO - TEST-PASS | /infrastructure/reftest/reftest_mismatch_page_margins-print.html | took 3194ms
[task 2020-09-18T13:51:49.703Z] 13:51:49 INFO - No more tests
[task 2020-09-18T13:51:49.754Z] 13:51:49 INFO - PID 1816 | 1600437109742 Marionette INFO Stopped listening on port 50241
[task 2020-09-18T13:51:50.280Z] 13:51:50 INFO - Browser exited with return code 0
[task 2020-09-18T13:51:50.280Z] 13:51:50 INFO - PROCESS LEAKS None
[task 2020-09-18T13:51:50.280Z] 13:51:50 INFO - Closing logging queue
[task 2020-09-18T13:51:50.280Z] 13:51:50 INFO - queue closed
[task 2020-09-18T13:51:50.280Z] 13:51:50 INFO - PROCESS LEAKS None
[task 2020-09-18T13:51:56.540Z] 13:51:56 INFO - PID 9612 | [GFX1-]: Killing GPU process due to IPC reply timeout
[task 2020-09-18T13:51:56.540Z] 13:51:56 INFO - PID 9612 | [GFX1-]: Failed as lost WebRenderBridgeChild.
[task 2020-09-18T13:51:56.540Z] 13:51:56 INFO - PID 9612 | [GFX1-]: Failed to create remote compositor
[task 2020-09-18T13:51:56.540Z] 13:51:56 INFO - PID 9612 | [Parent 512, Main Thread] WARNING: base::KillProcess refusing to terminate process handle 0: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc, line 401
[task 2020-09-18T13:51:56.542Z] 13:51:56 INFO - PID 9612 | [GFX1-]: Compositors might be mixed (5,1)
[task 2020-09-18T13:51:56.632Z] 13:51:56 INFO - TEST-UNEXPECTED-TIMEOUT | /svg/painting/reftests/mask-print.svg | expected PASS
[task 2020-09-18T13:51:56.632Z] 13:51:56 INFO - TEST-INFO took 11170ms
[task 2020-09-18T13:51:56.632Z] 13:51:56 INFO - No more tests
[task 2020-09-18T13:51:56.773Z] 13:51:56 INFO - PID 9612 | 1600437116765 Marionette INFO Stopped listening on port 50277
[task 2020-09-18T13:51:58.475Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv
[task 2020-09-18T13:51:58.475Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv
[task 2020-09-18T13:51:58.476Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel] Error: (msgtype=0x610020,name=PLayerTransaction::Msg_Shutdown) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.476Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv
[task 2020-09-18T13:51:58.476Z] 13:51:58 INFO - PID 9612 | [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2020-09-18T13:51:58.476Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv
[task 2020-09-18T13:51:58.476Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel] Error: (msgtype=0x360033,name=PCompositorBridge::Msg_PLayerTransactionConstructor) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.477Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel::SendAndWait] Error: (msgtype=0x61001D,name=PLayerTransaction::Msg_GetTextureFactoryIdentifier) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.477Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel] Error: (msgtype=0x360033,name=PCompositorBridge::Msg_PLayerTransactionConstructor) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.477Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel::SendAndWait] Error: (msgtype=0x61001D,name=PLayerTransaction::Msg_GetTextureFactoryIdentifier) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.477Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel] Error: (msgtype=0x610020,name=PLayerTransaction::Msg_Shutdown) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.477Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel] Error: (msgtype=0x610020,name=PLayerTransaction::Msg_Shutdown) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.478Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel] Error: (msgtype=0x360033,name=PCompositorBridge::Msg_PLayerTransactionConstructor) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.478Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel::SendAndWait] Error: (msgtype=0x61001D,name=PLayerTransaction::Msg_GetTextureFactoryIdentifier) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.478Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel] Error: (msgtype=0x610020,name=PLayerTransaction::Msg_Shutdown) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.478Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel] Error: (msgtype=0xD,name=PAPZ::Msg_Destroy) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.478Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel::SendAndWait] Error: (msgtype=0x360019,name=PCompositorBridge::Msg_WillClose) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.478Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel] Error: (msgtype=0x360033,name=PCompositorBridge::Msg_PLayerTransactionConstructor) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.479Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel::SendAndWait] Error: (msgtype=0x61001D,name=PLayerTransaction::Msg_GetTextureFactoryIdentifier) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.479Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel] Error: (msgtype=0x610020,name=PLayerTransaction::Msg_Shutdown) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.479Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel] Error: (msgtype=0xD,name=PAPZ::Msg_Destroy) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.479Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel::SendAndWait] Error: (msgtype=0x360019,name=PCompositorBridge::Msg_WillClose) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.479Z] 13:51:58 INFO - PID 9612 | ###!!! [Child][MessageChannel::SendAndWait] Error: (msgtype=0x360019,name=PCompositorBridge::Msg_WillClose) Channel error: cannot send/recv
[task 2020-09-18T13:51:58.480Z] 13:51:58 INFO - PID 9612 | IPDL protocol error: Handler returned error code!
[task 2020-09-18T13:51:58.480Z] 13:51:58 INFO - PID 9612 | ###!!! [Parent][DispatchSyncMessage] Error: PCompositorBridge::Msg_NotifyChildRecreated Processing error: message was deserialized, but the handler returned false (indicating failure)
[task 2020-09-18T13:51:58.480Z] 13:51:58 INFO - PID 9612 | [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2020-09-18T13:51:58.480Z] 13:51:58 INFO - PID 9612 | [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2020-09-18T13:51:58.498Z] 13:51:58 INFO - PID 9612 | [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2020-09-18T13:51:58.498Z] 13:51:58 INFO - PID 9612 | [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2020-09-18T13:51:58.801Z] 13:51:58 INFO - Browser exited with return code 0
[task 2020-09-18T13:51:58.801Z] 13:51:58 INFO - PROCESS LEAKS None
[task 2020-09-18T13:51:58.801Z] 13:51:58 INFO - Closing logging queue
[task 2020-09-18T13:51:58.801Z] 13:51:58 INFO - queue closed
[task 2020-09-18T13:51:58.802Z] 13:51:58 INFO - PROCESS LEAKS None
[task 2020-09-18T13:51:58.840Z] 13:51:58 INFO - Got 1 unexpected results
[task 2020-09-18T13:51:58.840Z] 13:51:58 INFO - SUITE-END | took 76s
[task 2020-09-18T13:51:58.878Z] 13:51:58 INFO - Removed font: Ahem.ttf
[task 2020-09-18T13:51:58.888Z] 13:51:58 INFO - Closing logging queue
[task 2020-09-18T13:51:58.890Z] 13:51:58 INFO - queue closed
[task 2020-09-18T13:51:59.038Z] 13:51:59 ERROR - Return code: 1
[task 2020-09-18T13:51:59.039Z] 13:51:59 ERROR - Got 1 unexpected statuses
[task 2020-09-18T13:51:59.039Z] 13:51:59 WARNING - # TBPL WARNING #
[task 2020-09-18T13:51:59.039Z] 13:51:59 WARNING - setting return code to 1
[task 2020-09-18T13:51:59.039Z] 13:51:59 INFO - Running post-action listener: _package_coverage_data
[task 2020-09-18T13:51:59.039Z] 13:51:59 INFO - Running post-action listener: _resource_record_post_action
[task 2020-09-18T13:51:59.039Z] 13:51:59 INFO - Running post-action listener: process_java_coverage_data
[task 2020-09-18T13:51:59.039Z] 13:51:59 INFO - Running post-action listener: stop_device
[task 2020-09-18T13:51:59.039Z] 13:51:59 INFO - [mozharness: 2020-09-18 13:51:59.039000Z] Finished run-tests step (success)
[task 2020-09-18T13:51:59.039Z] 13:51:59 INFO - Running post-run listener: _resource_record_post_run
[task 2020-09-18T13:51:59.125Z] 13:51:59 INFO - Total resource usage - Wall time: 102s; CPU: 29.0%; Read bytes: 128208896; Write bytes: 495677440; Read time: 5; Write time: 14
[task 2020-09-18T13:51:59.125Z] 13:51:59 INFO - TinderboxPrint: CPU usage<br/>28.9%
[task 2020-09-18T13:51:59.125Z] 13:51:59 INFO - TinderboxPrint: I/O read bytes / time<br/>128,208,896 / 5
[task 2020-09-18T13:51:59.125Z] 13:51:59 INFO - TinderboxPrint: I/O write bytes / time<br/>495,677,440 / 14
[task 2020-09-18T13:51:59.125Z] 13:51:59 INFO - TinderboxPrint: CPU idle<br/>578.6 (71.1%)
[task 2020-09-18T13:51:59.125Z] 13:51:59 INFO - TinderboxPrint: CPU system<br/>83.6 (10.3%)
[task 2020-09-18T13:51:59.125Z] 13:51:59 INFO - TinderboxPrint: CPU user<br/>150.3 (18.5%)
[task 2020-09-18T13:51:59.126Z] 13:51:59 INFO - pull - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2020-09-18T13:51:59.126Z] 13:51:59 INFO - start-emulator - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2020-09-18T13:51:59.126Z] 13:51:59 INFO - verify-device - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2020-09-18T13:51:59.126Z] 13:51:59 INFO - install - Wall time: 2s; CPU: 15.0%; Read bytes: 0; Write bytes: 7204864; Read time: 0; Write time: 0
[task 2020-09-18T13:51:59.127Z] 13:51:59 INFO - run-tests - Wall time: 100s; CPU: 29.0%; Read bytes: 128163840; Write bytes: 488460288; Read time: 5; Write time: 14
[task 2020-09-18T13:51:59.154Z] 13:51:59 WARNING - returning nonzero exit status 1
[taskcluster 2020-09-18T13:51:59.194Z] Exit Code: 1
[taskcluster 2020-09-18T13:51:59.194Z] User Time: 0s
[taskcluster 2020-09-18T13:51:59.194Z] Kernel Time: 15.625ms
[taskcluster 2020-09-18T13:51:59.194Z] Wall Time: 3m25.6032783s
[taskcluster 2020-09-18T13:51:59.194Z] Result: FAILED
[taskcluster 2020-09-18T13:51:59.194Z] === Task Finished ===
[taskcluster 2020-09-18T13:51:59.194Z] Task Duration: 3m25.6042537s
[taskcluster 2020-09-18T13:51:59.381Z] Uploading artifact public/logs/localconfig.json from file logs\localconfig.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2021-09-18T12:59:51.957Z
[taskcluster 2020-09-18T13:52:00.288Z] Uploading artifact public/test_info/resource-usage.json from file build\blobber_upload_dir\resource-usage.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2021-09-18T12:59:51.957Z
[taskcluster 2020-09-18T13:52:00.862Z] Uploading artifact public/test_info/wpt_errorsummary.log from file build\blobber_upload_dir\wpt_errorsummary.log with content encoding "gzip", mime type "text/plain" and expiry 2021-09-18T12:59:51.957Z
[taskcluster 2020-09-18T13:52:01.438Z] Uploading artifact public/test_info/wpt_instruments.txt from file build\blobber_upload_dir\wpt_instruments.txt with content encoding "gzip", mime type "text/plain; charset=utf-8" and expiry 2021-09-18T12:59:51.957Z
[taskcluster 2020-09-18T13:52:02.023Z] Uploading artifact public/test_info/wpt_raw.log from file build\blobber_upload_dir\wpt_raw.log with content encoding "gzip", mime type "text/plain" and expiry 2021-09-18T12:59:51.957Z
[taskcluster 2020-09-18T13:52:02.390Z] Uploading artifact public/test_info/wptreport.json from file build\blobber_upload_dir\wptreport.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2021-09-18T12:59:51.957Z
[taskcluster 2020-09-18T13:52:02.772Z] Uploading redirect artifact public/logs/live.log to URL https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/KD7iTouoQ9qFoi1jOCFNpw/runs/0/artifacts/public/logs/live_backing.log with mime type "text/plain; charset=utf-8" and expiry 2021-09-18T12:59:51.957Z
[taskcluster:error] exit status 1

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

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.