Open
Bug 1942479
Opened 24 days ago
Updated 15 days ago
Intermittent ImageDecoders.AVIFMultiLayerSingleChunk | Expected equality of these values:
Categories
(Testing :: GTest, defect, P5)
Testing
GTest
Tracking
(Not tracked)
NEW
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: agoloman [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=490785926&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/YL7pfbj1TRK1e-YYbi6ang/runs/0/artifacts/public/logs/live_backing.log
[task 2025-01-18T22:46:24.380Z] 22:46:24 INFO - TEST-PASS | ImageDecoders.AVIFSingleChunkGray12bitFullRangeGrayscale | test completed (time: 4ms)
[task 2025-01-18T22:46:24.380Z] 22:46:24 INFO - TEST-START | ImageDecoders.AVIFMultiLayerSingleChunk
[task 2025-01-18T22:46:24.396Z] 22:46:24 WARNING - TEST-UNEXPECTED-FAIL | ImageDecoders.AVIFMultiLayerSingleChunk | Expected equality of these values:
[task 2025-01-18T22:46:24.405Z] 22:46:24 INFO - bool(aTestCase.mFlags & TEST_CASE_HAS_ERROR)
[task 2025-01-18T22:46:24.405Z] 22:46:24 INFO - Which is: false
[task 2025-01-18T22:46:24.405Z] 22:46:24 INFO - aDecoder->HasError()
[task 2025-01-18T22:46:24.405Z] 22:46:24 INFO - Which is: true
[task 2025-01-18T22:46:24.406Z] 22:46:24 INFO - @ /builds/worker/checkouts/gecko/image/test/gtest/TestDecoders.cpp:42
[task 2025-01-18T22:46:24.406Z] 22:46:24 WARNING - TEST-UNEXPECTED-FAIL | ImageDecoders.AVIFMultiLayerSingleChunk | Expected equality of these values:
[task 2025-01-18T22:46:24.406Z] 22:46:24 INFO - bool(aTestCase.mFlags & TEST_CASE_HAS_ERROR)
[task 2025-01-18T22:46:24.406Z] 22:46:24 INFO - Which is: false
[task 2025-01-18T22:46:24.407Z] 22:46:24 INFO - bool(progress & FLAG_HAS_ERROR)
[task 2025-01-18T22:46:24.407Z] 22:46:24 INFO - Which is: true
[task 2025-01-18T22:46:24.407Z] 22:46:24 INFO - @ /builds/worker/checkouts/gecko/image/test/gtest/TestDecoders.cpp:47
[task 2025-01-18T22:46:24.407Z] 22:46:24 WARNING - TEST-UNEXPECTED-FAIL | ImageDecoders.AVIFMultiLayerSingleChunk | Value of: bool(progress & FLAG_FRAME_COMPLETE)
[task 2025-01-18T22:46:24.408Z] 22:46:24 INFO - Actual: false
[task 2025-01-18T22:46:24.408Z] 22:46:24 INFO - Expected: true
[task 2025-01-18T22:46:24.409Z] 22:46:24 INFO - @ /builds/worker/checkouts/gecko/image/test/gtest/TestDecoders.cpp:55
[task 2025-01-18T22:46:24.409Z] 22:46:24 WARNING - TEST-UNEXPECTED-FAIL | ImageDecoders.AVIFMultiLayerSingleChunk | SEH exception with code 0xc0000005 thrown in the test body. @ (null):-1
[task 2025-01-18T22:46:24.409Z] 22:46:24 WARNING - TEST-UNEXPECTED-FAIL | ImageDecoders.AVIFMultiLayerSingleChunk | test completed (time: 24ms)
[task 2025-01-18T22:46:24.409Z] 22:46:24 INFO - TEST-START | ImageDecoders.AVIFStackCheck
[task 2025-01-18T22:46:24.414Z] 22:46:24 INFO - =================================================================
[task 2025-01-18T22:46:24.414Z] 22:46:24 ERROR - ==4212==ERROR: AddressSanitizer: out of memory: allocator is trying to allocate 0x119c087 bytes
[task 2025-01-18T22:46:24.416Z] 22:46:24 INFO - ==4212==FATAL: AddressSanitizer: internal allocator is out of memory trying to allocate 0x3e bytes
[task 2025-01-18T22:46:25.765Z] 22:46:25 INFO - gtest INFO | gtest | process wait complete, returncode=1
[task 2025-01-18T22:46:25.773Z] 22:46:25 INFO - mozcrash checking D:\task_173723962844430\build\tests\gtest for minidumps...
[task 2025-01-18T22:46:25.776Z] 22:46:25 WARNING - gtest TEST-UNEXPECTED-FAIL | gtest | test failed with return code 1
[task 2025-01-18T22:46:25.777Z] 22:46:25 INFO - gtest INFO | rungtests.py exits with code 1
[task 2025-01-18T22:46:25.828Z] 22:46:25 INFO - Return code: 1
[task 2025-01-18T22:46:25.841Z] 22:46:25 ERROR - No tests run or test summary not found
[task 2025-01-18T22:46:25.841Z] 22:46:25 INFO - TinderboxPrint: gtest-gtest<br/><em class="testfail">T-FAIL</em>
[task 2025-01-18T22:46:25.841Z] 22:46:25 WARNING - setting return code to 2
[task 2025-01-18T22:46:25.841Z] 22:46:25 INFO - The gtest suite: gtest ran with return status: FAILURE
[task 2025-01-18T22:46:25.842Z] 22:46:25 INFO - Running post-action listener: _package_coverage_data
[task 2025-01-18T22:46:25.842Z] 22:46:25 INFO - Running post-action listener: _resource_record_post_action
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - Running post-action listener: process_java_coverage_data
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - [mozharness: 2025-01-18 22:46:25.843383Z] Finished run-tests step (success)
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - [mozharness: 2025-01-18 22:46:25.843383Z] Running uninstall step.
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - Running pre-action listener: _resource_record_pre_action
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - Running main action method: uninstall
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - Skipping uninstall for non-MSIX test
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - Running post-action listener: _resource_record_post_action
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - [mozharness: 2025-01-18 22:46:25.843890Z] Finished uninstall step (success)
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - Running post-run listener: _resource_record_post_run
[task 2025-01-18T22:46:26.359Z] 22:46:26 INFO - Validating Perfherder data against D:\task_173723962844430\mozharness\external_tools\performance-artifact-schema.json
[task 2025-01-18T22:46:26.373Z] 22:46:26 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "gtest.gtest.overall", "extraOptions": ["taskcluster-Standard_F8s_v2"], "subtests": [{"name": "cpu_percent", "value": 51.19300692574074}, {"name": "io_write_bytes", "value": 9763760128}, {"name": "io.read_bytes", "value": 4410621440}, {"name": "io_write_time", "value": 27}, {"name": "io_read_time", "value": 311}]}, {"name": "gtest.gtest.start-pulseaudio", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}, {"name": "gtest.gtest.install", "subtests": [{"name": "time", "value": 11.85899999999998}, {"name": "cpu_percent", "value": 47.55962389380531}]}, {"name": "gtest.gtest.stage-files", "subtests": [{"name": "time", "value": 1.1100000000000136}, {"name": "cpu_percent", "value": 48.14}]}, {"name": "gtest.gtest.run-tests", "subtests": [{"name": "time", "value": 507.265}, {"name": "cpu_percent", "value": 51.28486102897698}]}, {"name": "gtest.gtest.uninstall", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}]}
[task 2025-01-18T22:46:26.373Z] 22:46:26 INFO - Total resource usage - Wall time: 521s; CPU: Can't collect data; Read bytes: 4410621440; Write bytes: 9763760128; Read time: 311; Write time: 27
[task 2025-01-18T22:46:26.373Z] 22:46:26 INFO - TinderboxPrint: I/O read bytes / time<br/>4,410,621,440 / 311
[task 2025-01-18T22:46:26.373Z] 22:46:26 INFO - TinderboxPrint: I/O write bytes / time<br/>9,763,760,128 / 27
[task 2025-01-18T22:46:26.373Z] 22:46:26 INFO - TinderboxPrint: CPU idle<br/>2,043.8 (49.0%)
[task 2025-01-18T22:46:26.373Z] 22:46:26 INFO - TinderboxPrint: CPU system<br/>766.7 (18.4%)
[task 2025-01-18T22:46:26.373Z] 22:46:26 INFO - TinderboxPrint: CPU user<br/>1,346.8 (32.3%)
[task 2025-01-18T22:46:26.376Z] 22:46:26 INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-01-18T22:46:26.378Z] 22:46:26 INFO - install - Wall time: 12s; CPU: 48%; Read bytes: 57344; Write bytes: 362471424; Read time: 0; Write time: 0
[task 2025-01-18T22:46:26.380Z] 22:46:26 INFO - stage-files - Wall time: 1s; CPU: 48%; Read bytes: 0; Write bytes: 134230016; Read time: 0; Write time: 0
[task 2025-01-18T22:46:26.406Z] 22:46:26 INFO - run-tests - Wall time: 507s; CPU: 51%; Read bytes: 4410564096; Write bytes: 9267054592; Read time: 311; Write time: 27
[task 2025-01-18T22:46:26.416Z] 22:46:26 INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-01-18T22:46:28.298Z] 22:46:28 WARNING - returning nonzero exit status 2
[taskcluster 2025-01-18T22:46:28.436Z] Exit Code: 2[task 2025-01-18T22:46:24.380Z] 22:46:24 INFO - TEST-PASS | ImageDecoders.AVIFSingleChunkGray12bitFullRangeGrayscale | test completed (time: 4ms)
[task 2025-01-18T22:46:24.380Z] 22:46:24 INFO - TEST-START | ImageDecoders.AVIFMultiLayerSingleChunk
[task 2025-01-18T22:46:24.396Z] 22:46:24 WARNING - TEST-UNEXPECTED-FAIL | ImageDecoders.AVIFMultiLayerSingleChunk | Expected equality of these values:
[task 2025-01-18T22:46:24.405Z] 22:46:24 INFO - bool(aTestCase.mFlags & TEST_CASE_HAS_ERROR)
[task 2025-01-18T22:46:24.405Z] 22:46:24 INFO - Which is: false
[task 2025-01-18T22:46:24.405Z] 22:46:24 INFO - aDecoder->HasError()
[task 2025-01-18T22:46:24.405Z] 22:46:24 INFO - Which is: true
[task 2025-01-18T22:46:24.406Z] 22:46:24 INFO - @ /builds/worker/checkouts/gecko/image/test/gtest/TestDecoders.cpp:42
[task 2025-01-18T22:46:24.406Z] 22:46:24 WARNING - TEST-UNEXPECTED-FAIL | ImageDecoders.AVIFMultiLayerSingleChunk | Expected equality of these values:
[task 2025-01-18T22:46:24.406Z] 22:46:24 INFO - bool(aTestCase.mFlags & TEST_CASE_HAS_ERROR)
[task 2025-01-18T22:46:24.406Z] 22:46:24 INFO - Which is: false
[task 2025-01-18T22:46:24.407Z] 22:46:24 INFO - bool(progress & FLAG_HAS_ERROR)
[task 2025-01-18T22:46:24.407Z] 22:46:24 INFO - Which is: true
[task 2025-01-18T22:46:24.407Z] 22:46:24 INFO - @ /builds/worker/checkouts/gecko/image/test/gtest/TestDecoders.cpp:47
[task 2025-01-18T22:46:24.407Z] 22:46:24 WARNING - TEST-UNEXPECTED-FAIL | ImageDecoders.AVIFMultiLayerSingleChunk | Value of: bool(progress & FLAG_FRAME_COMPLETE)
[task 2025-01-18T22:46:24.408Z] 22:46:24 INFO - Actual: false
[task 2025-01-18T22:46:24.408Z] 22:46:24 INFO - Expected: true
[task 2025-01-18T22:46:24.409Z] 22:46:24 INFO - @ /builds/worker/checkouts/gecko/image/test/gtest/TestDecoders.cpp:55
[task 2025-01-18T22:46:24.409Z] 22:46:24 WARNING - TEST-UNEXPECTED-FAIL | ImageDecoders.AVIFMultiLayerSingleChunk | SEH exception with code 0xc0000005 thrown in the test body. @ (null):-1
[task 2025-01-18T22:46:24.409Z] 22:46:24 WARNING - TEST-UNEXPECTED-FAIL | ImageDecoders.AVIFMultiLayerSingleChunk | test completed (time: 24ms)
[task 2025-01-18T22:46:24.409Z] 22:46:24 INFO - TEST-START | ImageDecoders.AVIFStackCheck
[task 2025-01-18T22:46:24.414Z] 22:46:24 INFO - =================================================================
[task 2025-01-18T22:46:24.414Z] 22:46:24 ERROR - ==4212==ERROR: AddressSanitizer: out of memory: allocator is trying to allocate 0x119c087 bytes
[task 2025-01-18T22:46:24.416Z] 22:46:24 INFO - ==4212==FATAL: AddressSanitizer: internal allocator is out of memory trying to allocate 0x3e bytes
[task 2025-01-18T22:46:25.765Z] 22:46:25 INFO - gtest INFO | gtest | process wait complete, returncode=1
[task 2025-01-18T22:46:25.773Z] 22:46:25 INFO - mozcrash checking D:\task_173723962844430\build\tests\gtest for minidumps...
[task 2025-01-18T22:46:25.776Z] 22:46:25 WARNING - gtest TEST-UNEXPECTED-FAIL | gtest | test failed with return code 1
[task 2025-01-18T22:46:25.777Z] 22:46:25 INFO - gtest INFO | rungtests.py exits with code 1
[task 2025-01-18T22:46:25.828Z] 22:46:25 INFO - Return code: 1
[task 2025-01-18T22:46:25.841Z] 22:46:25 ERROR - No tests run or test summary not found
[task 2025-01-18T22:46:25.841Z] 22:46:25 INFO - TinderboxPrint: gtest-gtest<br/><em class="testfail">T-FAIL</em>
[task 2025-01-18T22:46:25.841Z] 22:46:25 WARNING - setting return code to 2
[task 2025-01-18T22:46:25.841Z] 22:46:25 INFO - The gtest suite: gtest ran with return status: FAILURE
[task 2025-01-18T22:46:25.842Z] 22:46:25 INFO - Running post-action listener: _package_coverage_data
[task 2025-01-18T22:46:25.842Z] 22:46:25 INFO - Running post-action listener: _resource_record_post_action
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - Running post-action listener: process_java_coverage_data
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - [mozharness: 2025-01-18 22:46:25.843383Z] Finished run-tests step (success)
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - [mozharness: 2025-01-18 22:46:25.843383Z] Running uninstall step.
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - Running pre-action listener: _resource_record_pre_action
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - Running main action method: uninstall
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - Skipping uninstall for non-MSIX test
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - Running post-action listener: _resource_record_post_action
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - [mozharness: 2025-01-18 22:46:25.843890Z] Finished uninstall step (success)
[task 2025-01-18T22:46:25.843Z] 22:46:25 INFO - Running post-run listener: _resource_record_post_run
[task 2025-01-18T22:46:26.359Z] 22:46:26 INFO - Validating Perfherder data against D:\task_173723962844430\mozharness\external_tools\performance-artifact-schema.json
[task 2025-01-18T22:46:26.373Z] 22:46:26 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "gtest.gtest.overall", "extraOptions": ["taskcluster-Standard_F8s_v2"], "subtests": [{"name": "cpu_percent", "value": 51.19300692574074}, {"name": "io_write_bytes", "value": 9763760128}, {"name": "io.read_bytes", "value": 4410621440}, {"name": "io_write_time", "value": 27}, {"name": "io_read_time", "value": 311}]}, {"name": "gtest.gtest.start-pulseaudio", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}, {"name": "gtest.gtest.install", "subtests": [{"name": "time", "value": 11.85899999999998}, {"name": "cpu_percent", "value": 47.55962389380531}]}, {"name": "gtest.gtest.stage-files", "subtests": [{"name": "time", "value": 1.1100000000000136}, {"name": "cpu_percent", "value": 48.14}]}, {"name": "gtest.gtest.run-tests", "subtests": [{"name": "time", "value": 507.265}, {"name": "cpu_percent", "value": 51.28486102897698}]}, {"name": "gtest.gtest.uninstall", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}]}
[task 2025-01-18T22:46:26.373Z] 22:46:26 INFO - Total resource usage - Wall time: 521s; CPU: Can't collect data; Read bytes: 4410621440; Write bytes: 9763760128; Read time: 311; Write time: 27
[task 2025-01-18T22:46:26.373Z] 22:46:26 INFO - TinderboxPrint: I/O read bytes / time<br/>4,410,621,440 / 311
[task 2025-01-18T22:46:26.373Z] 22:46:26 INFO - TinderboxPrint: I/O write bytes / time<br/>9,763,760,128 / 27
[task 2025-01-18T22:46:26.373Z] 22:46:26 INFO - TinderboxPrint: CPU idle<br/>2,043.8 (49.0%)
[task 2025-01-18T22:46:26.373Z] 22:46:26 INFO - TinderboxPrint: CPU system<br/>766.7 (18.4%)
[task 2025-01-18T22:46:26.373Z] 22:46:26 INFO - TinderboxPrint: CPU user<br/>1,346.8 (32.3%)
[task 2025-01-18T22:46:26.376Z] 22:46:26 INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-01-18T22:46:26.378Z] 22:46:26 INFO - install - Wall time: 12s; CPU: 48%; Read bytes: 57344; Write bytes: 362471424; Read time: 0; Write time: 0
[task 2025-01-18T22:46:26.380Z] 22:46:26 INFO - stage-files - Wall time: 1s; CPU: 48%; Read bytes: 0; Write bytes: 134230016; Read time: 0; Write time: 0
[task 2025-01-18T22:46:26.406Z] 22:46:26 INFO - run-tests - Wall time: 507s; CPU: 51%; Read bytes: 4410564096; Write bytes: 9267054592; Read time: 311; Write time: 27
[task 2025-01-18T22:46:26.416Z] 22:46:26 INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-01-18T22:46:28.298Z] 22:46:28 WARNING - returning nonzero exit status 2
[taskcluster 2025-01-18T22:46:28.436Z] Exit Code: 2
[taskcluster 2025-01-18T22:46:28.436Z] User Time: 0s
[taskcluster 2025-01-18T22:46:28.436Z] Kernel Time: 15.625ms
[taskcluster 2025-01-18T22:46:28.436Z] Wall Time: 10m46.9840425s
[taskcluster 2025-01-18T22:46:28.436Z] Result: FAILED
[taskcluster 2025-01-18T22:46:28.438Z] === Task Finished ===
[taskcluster 2025-01-18T22:46:28.438Z] Task Duration: 10m46.9888617s
[taskcluster 2025-01-18T22:46:29.556Z] Uploading artifact public/test_info/system-info.log from file D:\task_173723962844430\build\blobber_upload_dir\system-info.log with content encoding "gzip", mime type "text/plain" and expiry 2025-04-18T21:44:01.441Z
[taskcluster 2025-01-18T22:46:29.557Z] Uploading artifact public/test_info/resource-usage.json from file D:\task_173723962844430\build\blobber_upload_dir\resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2025-04-18T21:44:01.441Z
[taskcluster 2025-01-18T22:46:29.558Z] Uploading artifact public/logs/localconfig.json from file D:\task_173723962844430\logs\localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2025-04-18T21:44:01.441Z
[taskcluster 2025-01-18T22:46:29.560Z] Uploading artifact public/test_info/profile_resource-usage.json from file D:\task_173723962844430\build\blobber_upload_dir\profile_resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2025-04-18T21:44:01.441Z
[taskcluster 2025-01-18T22:46:29.955Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2025-04-18T21:44:01.441Z
[taskcluster:error] exit status 2
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
You need to log in
before you can comment on or make changes to this bug.
Description
•