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

[task 2024-06-19T02:03:00.258Z] 02:03:00     INFO -  TEST-PASS | ImageDecoders.CorruptICOWithBadBppSingleChunk | test completed (time: 1ms)
[task 2024-06-19T02:03:00.258Z] 02:03:00     INFO -  TEST-START | ImageDecoders.CorruptAVIFSingleChunk
[task 2024-06-19T02:03:00.258Z] 02:03:00     INFO -  =================================================================
[task 2024-06-19T02:03:00.260Z] 02:03:00    ERROR -  ==3720==ERROR: AddressSanitizer: out of memory: allocator is trying to allocate 0xfffb0012 bytes
[task 2024-06-19T02:03:00.853Z] 02:03:00     INFO -      #0 0x7ff930ab630d in malloc /builds/worker/fetches/llvm-project/compiler-rt/lib/asan/asan_malloc_win.cpp:98
[task 2024-06-19T02:03:00.866Z] 02:03:00     INFO -      #1 0x7ff9252f43a0 in enum2$<core::result::Result<core::ptr::non_null::NonNull<slice2$<u8> >,core::alloc::AllocError> >::map_err /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081\library\core\src\result.rs:827
[task 2024-06-19T02:03:00.866Z] 02:03:00     INFO -      #2 0x7ff9252f43a0 in alloc::raw_vec::finish_grow<alloc::alloc::Global> /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081\library\alloc\src\raw_vec.rs:576
[task 2024-06-19T02:03:00.867Z] 02:03:00     INFO -      #3 0x7ff9259617f4 in alloc::raw_vec::RawVec<u8,alloc::alloc::Global>::grow_amortized /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081\library\alloc\src\raw_vec.rs:485
[task 2024-06-19T02:03:00.867Z] 02:03:00     INFO -      #4 0x7ff9259617f4 in alloc::raw_vec::RawVec<u8,alloc::alloc::Global>::try_reserve /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081\library\alloc\src\raw_vec.rs:372
[task 2024-06-19T02:03:00.868Z] 02:03:00     INFO -      #5 0x7ff9259617f4 in alloc::vec::Vec<u8,alloc::alloc::Global>::try_reserve /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081\library\alloc\src\vec\mod.rs:1039
[task 2024-06-19T02:03:00.868Z] 02:03:00     INFO -      #6 0x7ff9259617f4 in fallible_collections::vec::impl$19::try_reserve /builds/worker/checkouts/gecko/third_party/rust/fallible_collections/src/vec.rs:526
[task 2024-06-19T02:03:00.869Z] 02:03:00     INFO -      #7 0x7ff9259617f4 in fallible_collections::vec::TryVec<u8>::reserve /builds/worker/checkouts/gecko/third_party/rust/fallible_collections/src/vec.rs:157
[task 2024-06-19T02:03:00.869Z] 02:03:00     INFO -      #8 0x7ff9259617f4 in fallible_collections::vec::std_io::try_read_up_to /builds/worker/checkouts/gecko/third_party/rust/fallible_collections/src/vec.rs:260
[task 2024-06-19T02:03:00.870Z] 02:03:00     INFO -      #9 0x7ff9259617f4 in mp4parse::impl$41::try_read_to_end /builds/worker/checkouts/gecko/third_party/rust/mp4parse/src/lib.rs:2167
[task 2024-06-19T02:03:00.870Z] 02:03:00     INFO -      #10 0x7ff9259617f4 in fallible_collections::vec::std_io::TryRead::read_into_try_vec<mp4parse::BMFFBox<mp4parse::BMFFBox<mp4parse::OffsetReader<mp4parse_capi::Mp4parseIo> > > > /builds/worker/checkouts/gecko/third_party/rust/fallible_collections/src/vec.rs:225
[task 2024-06-19T02:03:00.870Z] 02:03:00     INFO -      #11 0x7ff92597b0e9 in mp4parse::read_iloc /builds/worker/checkouts/gecko/third_party/rust/mp4parse/src/lib.rs:3942
[task 2024-06-19T02:03:00.871Z] 02:03:00     INFO -      #12 0x7ff92597b0e9 in mp4parse::read_avif_meta /builds/worker/checkouts/gecko/third_party/rust/mp4parse/src/lib.rs:2772
[task 2024-06-19T02:03:00.871Z] 02:03:00     INFO -      #13 0x7ff92597b0e9 in mp4parse::read_avif<mp4parse_capi::Mp4parseIo> /builds/worker/checkouts/gecko/third_party/rust/mp4parse/src/lib.rs
[task 2024-06-19T02:03:00.871Z] 02:03:00     INFO -      #14 0x7ff9259a0fa3 in mp4parse_capi::impl$9::read /builds/worker/checkouts/gecko/third_party/rust/mp4parse_capi/src/lib.rs:422
[task 2024-06-19T02:03:00.872Z] 02:03:00     INFO -      #15 0x7ff9259a0fa3 in mp4parse_capi::mp4parse_new_common_safe /builds/worker/checkouts/gecko/third_party/rust/mp4parse_capi/src/lib.rs:533
[task 2024-06-19T02:03:00.872Z] 02:03:00     INFO -      #16 0x7ff9259a0fa3 in mp4parse_capi::mp4parse_new_common /builds/worker/checkouts/gecko/third_party/rust/mp4parse_capi/src/lib.rs:519
[task 2024-06-19T02:03:00.873Z] 02:03:00     INFO -      #17 0x7ff9259a0fa3 in mp4parse_avif_new /builds/worker/checkouts/gecko/third_party/rust/mp4parse_capi/src/lib.rs:502
[task 2024-06-19T02:03:00.873Z] 02:03:00     INFO -      #18 0x7ff91c504c3d in mozilla::image::AVIFParser::Init(class mozilla::ByteStream *, bool, bool) /builds/worker/checkouts/gecko/image/decoders/nsAVIFDecoder.cpp:333
[task 2024-06-19T02:03:00.874Z] 02:03:00     INFO -      #19 0x7ff91c50497a in mozilla::image::AVIFParser::Create(struct Mp4parseIo const *, class mozilla::ByteStream *, class mozilla::UniquePtr<class mozilla::image::AVIFParser, class mozilla::DefaultDelete<class mozilla::image::AVIFParser>> &, bool, bool) /builds/worker/checkouts/gecko/image/decoders/nsAVIFDecoder.cpp:212
[task 2024-06-19T02:03:00.875Z] 02:03:00     INFO -      #20 0x7ff91c51020f in mozilla::image::nsAVIFDecoder::CreateParser(void) /builds/worker/checkouts/gecko/image/decoders/nsAVIFDecoder.cpp:1262
[task 2024-06-19T02:03:00.875Z] 02:03:00     INFO -      #21 0x7ff91c509e7c in mozilla::image::nsAVIFDecoder::DoDecodeInternal(class mozilla::image::SourceBufferIterator &, struct mozilla::image::IResumable *) /builds/worker/checkouts/gecko/image/decoders/nsAVIFDecoder.cpp:1532
[task 2024-06-19T02:03:00.876Z] 02:03:00     INFO -      #22 0x7ff91c509960 in mozilla::image::nsAVIFDecoder::DoDecode(class mozilla::image::SourceBufferIterator &, struct mozilla::image::IResumable *) /builds/worker/checkouts/gecko/image/decoders/nsAVIFDecoder.cpp:1224
[task 2024-06-19T02:03:00.876Z] 02:03:00     INFO -      #23 0x7ff91c423cbc in mozilla::image::Decoder::Decode(struct mozilla::image::IResumable *) /builds/worker/checkouts/gecko/image/Decoder.cpp:177
[task 2024-06-19T02:03:00.876Z] 02:03:00     INFO -      #24 0x7ff916661c52 in MonitorAnonymousDecodingTask::Run /builds/worker/checkouts/gecko/image/test/gtest/TestDecoders.cpp:154
[task 2024-06-19T02:03:00.877Z] 02:03:00     INFO -      #25 0x7ff916661c52 in WithSingleChunkDecode /builds/worker/checkouts/gecko/image/test/gtest/TestDecoders.cpp:223
[task 2024-06-19T02:03:00.877Z] 02:03:00     INFO -      #26 0x7ff916661c52 in CheckDecoderSingleChunk /builds/worker/checkouts/gecko/image/test/gtest/TestDecoders.cpp:232
[task 2024-06-19T02:03:00.878Z] 02:03:00     INFO -      #27 0x7ff916672f25 in ImageDecoders_CorruptAVIFSingleChunk_Test::TestBody(void) /builds/worker/checkouts/gecko/image/test/gtest/TestDecoders.cpp:1041
[task 2024-06-19T02:03:00.878Z] 02:03:00     INFO -      #28 0x7ff915ffad56 in testing::internal::HandleSehExceptionsInMethodIfSupported<class testing::TestSuite, void>(class testing::TestSuite *, void (__cdecl testing::TestSuite::*)(void), char const *) /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:2628
[task 2024-06-19T02:03:00.879Z] 02:03:00     INFO -      #29 0x7ff915fa16ef in testing::internal::HandleExceptionsInMethodIfSupported /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:2690
[task 2024-06-19T02:03:00.879Z] 02:03:00     INFO -      #30 0x7ff915fa16ef in testing::Test::Run(void) /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:2710
[task 2024-06-19T02:03:00.879Z] 02:03:00     INFO -      #31 0x7ff915fa51bd in testing::TestInfo::Run(void) /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:2856
[task 2024-06-19T02:03:00.880Z] 02:03:00     INFO -      #32 0x7ff915fa84d8 in testing::TestSuite::Run(void) /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:3034
[task 2024-06-19T02:03:00.880Z] 02:03:00     INFO -      #33 0x7ff915fdd34a in testing::internal::UnitTestImpl::RunAllTests(void) /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:5964
[task 2024-06-19T02:03:00.881Z] 02:03:00     INFO -      #34 0x7ff915ffad96 in testing::internal::HandleSehExceptionsInMethodIfSupported<class testing::internal::UnitTestImpl, bool>(class testing::internal::UnitTestImpl *, bool (__cdecl testing::internal::UnitTestImpl::*)(void), char const *) /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:2628
[task 2024-06-19T02:03:00.881Z] 02:03:00     INFO -      #35 0x7ff915fdc52b in testing::internal::HandleExceptionsInMethodIfSupported /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:2690
[task 2024-06-19T02:03:00.882Z] 02:03:00     INFO -      #36 0x7ff915fdc52b in testing::UnitTest::Run(void) /builds/worker/checkouts/gecko/third_party/googletest/googletest/src/gtest.cc:5543
[task 2024-06-19T02:03:00.882Z] 02:03:00     INFO -      #37 0x7ff915f360e9 in RUN_ALL_TESTS /builds/worker/workspace/obj-build/dist/include/gtest/gtest.h:2336
[task 2024-06-19T02:03:00.883Z] 02:03:00     INFO -      #38 0x7ff915f360e9 in mozilla::RunGTestFunc(int *, char **) /builds/worker/checkouts/gecko/testing/gtest/mozilla/GTestRunner.cpp:172
[task 2024-06-19T02:03:00.883Z] 02:03:00     INFO -      #39 0x7ff924575661 in XREMain::XRE_mainStartup(bool *) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4717
[task 2024-06-19T02:03:00.883Z] 02:03:00     INFO -      #40 0x7ff9245863b8 in XREMain::XRE_main(int, char **const, struct mozilla::BootstrapConfig const &) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5980
[task 2024-06-19T02:03:00.884Z] 02:03:00     INFO -      #41 0x7ff9245871d7 in XRE_main(int, char **const, struct mozilla::BootstrapConfig const &) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:6049
[task 2024-06-19T02:03:00.884Z] 02:03:00     INFO -      #42 0x7ff62fab1efc in do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:230
[task 2024-06-19T02:03:00.884Z] 02:03:00     INFO -      #43 0x7ff62fab1efc in NS_internal_main(int, char **, char **) /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:448
[task 2024-06-19T02:03:00.885Z] 02:03:00     INFO -      #44 0x7ff62fab142b in wmain /builds/worker/checkouts/gecko/toolkit/xre/nsWindowsWMain.cpp:151
[task 2024-06-19T02:03:00.885Z] 02:03:00     INFO -      #45 0x7ff62fb7f7b7 in invoke_main D:\a\_work\1\s\src\vctools\crt\vcstartup\src\startup\exe_common.inl:90
[task 2024-06-19T02:03:00.885Z] 02:03:00     INFO -      #46 0x7ff62fb7f7b7 in __scrt_common_main_seh D:\a\_work\1\s\src\vctools\crt\vcstartup\src\startup\exe_common.inl:288
[task 2024-06-19T02:03:00.886Z] 02:03:00     INFO -      #47 0x7ff9679a257c  (C:\Windows\System32\KERNEL32.DLL+0x18001257c)
[task 2024-06-19T02:03:00.886Z] 02:03:00     INFO -  ==3720==HINT: if you don't care about these errors you may set allocator_may_return_null=1
[task 2024-06-19T02:03:00.886Z] 02:03:00     INFO -  SUMMARY: AddressSanitizer: out-of-memory /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081\library\core\src\result.rs:827 in enum2$<core::result::Result<core::ptr::non_null::NonNull<slice2$<u8> >,core::alloc::AllocError> >::map_err
[task 2024-06-19T02:03:00.887Z] 02:03:00     INFO -  ==3720==ABORTING
[task 2024-06-19T02:03:02.637Z] 02:03:02     INFO -  gtest INFO | gtest | process wait complete, returncode=1
[task 2024-06-19T02:03:02.644Z] 02:03:02     INFO -  mozcrash checking D:\task_171876115018949\build\tests\gtest for minidumps...
[task 2024-06-19T02:03:02.651Z] 02:03:02  WARNING -  gtest TEST-UNEXPECTED-FAIL | gtest | test failed with return code 1
[task 2024-06-19T02:03:02.652Z] 02:03:02     INFO -  gtest INFO | rungtests.py exits with code 1
[task 2024-06-19T02:03:02.700Z] 02:03:02     INFO - Return code: 1
[task 2024-06-19T02:03:02.715Z] 02:03:02    ERROR - No tests run or test summary not found
[task 2024-06-19T02:03:02.716Z] 02:03:02     INFO - TinderboxPrint: gtest-gtest<br/><em class="testfail">T-FAIL</em>
[task 2024-06-19T02:03:02.717Z] 02:03:02  WARNING - setting return code to 2
[task 2024-06-19T02:03:02.717Z] 02:03:02     INFO - The gtest suite: gtest ran with return status: FAILURE
[task 2024-06-19T02:03:02.719Z] 02:03:02     INFO - Running post-action listener: _package_coverage_data
[task 2024-06-19T02:03:02.719Z] 02:03:02     INFO - Running post-action listener: _resource_record_post_action
[task 2024-06-19T02:03:02.720Z] 02:03:02     INFO - Running post-action listener: process_java_coverage_data
[task 2024-06-19T02:03:02.720Z] 02:03:02     INFO - [mozharness: 2024-06-19 02:03:02.720323Z] Finished run-tests step (success)
[task 2024-06-19T02:03:02.720Z] 02:03:02     INFO - [mozharness: 2024-06-19 02:03:02.720323Z] Running uninstall step.
[task 2024-06-19T02:03:02.720Z] 02:03:02     INFO - Running pre-action listener: _resource_record_pre_action
[task 2024-06-19T02:03:02.720Z] 02:03:02     INFO - Running main action method: uninstall
[task 2024-06-19T02:03:02.720Z] 02:03:02     INFO - Skipping uninstall for non-MSIX test
[task 2024-06-19T02:03:02.720Z] 02:03:02     INFO - Running post-action listener: _resource_record_post_action
[task 2024-06-19T02:03:02.720Z] 02:03:02     INFO - [mozharness: 2024-06-19 02:03:02.720856Z] Finished uninstall step (success)
[task 2024-06-19T02:03:02.720Z] 02:03:02     INFO - Running post-run listener: _resource_record_post_run
[task 2024-06-19T02:03:03.216Z] 02:03:03     INFO - instance_metadata.json not found; unable to determine instance type
[task 2024-06-19T02:03:03.232Z] 02:03:03     INFO - Validating Perfherder data against D:\task_171876115018949\mozharness\external_tools\performance-artifact-schema.json
[task 2024-06-19T02:03:03.236Z] 02:03:03     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "gtest.gtest.overall", "extraOptions": ["buildbot-unknown"], "subtests": [{"name": "cpu_percent", "value": 45.27602610130021}, {"name": "io_write_bytes", "value": 3222038016}, {"name": "io.read_bytes", "value": 1174832128}, {"name": "io_write_time", "value": 3}, {"name": "io_read_time", "value": 198}]}, {"name": "gtest.gtest.start-pulseaudio", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}, {"name": "gtest.gtest.install", "subtests": [{"name": "time", "value": 10.609000000000037}, {"name": "cpu_percent", "value": 44.19962871287129}]}, {"name": "gtest.gtest.stage-files", "subtests": [{"name": "time", "value": 0.9219999999999686}, {"name": "cpu_percent", "value": 47.675000000000004}]}, {"name": "gtest.gtest.run-tests", "subtests": [{"name": "time", "value": 504.219}, {"name": "cpu_percent", "value": 45.29319466481556}]}, {"name": "gtest.gtest.uninstall", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}]}
[task 2024-06-19T02:03:03.236Z] 02:03:03     INFO - Total resource usage - Wall time: 516s; CPU: Can't collect data; Read bytes: 1174832128; Write bytes: 3222038016; Read time: 198; Write time: 3
[task 2024-06-19T02:03:03.236Z] 02:03:03     INFO - TinderboxPrint: I/O read bytes / time<br/>1,174,832,128 / 198
[task 2024-06-19T02:03:03.236Z] 02:03:03     INFO - TinderboxPrint: I/O write bytes / time<br/>3,222,038,016 / 3
[task 2024-06-19T02:03:03.239Z] 02:03:03     INFO - TinderboxPrint: CPU idle<br/>2,270.3 (54.9%)
[task 2024-06-19T02:03:03.239Z] 02:03:03     INFO - TinderboxPrint: CPU system<br/>721.0 (17.4%)
[task 2024-06-19T02:03:03.239Z] 02:03:03     INFO - TinderboxPrint: CPU user<br/>1,130.9 (27.3%)
[task 2024-06-19T02:03:03.240Z] 02:03:03     INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-06-19T02:03:03.242Z] 02:03:03     INFO - install - Wall time: 11s; CPU: 44%; Read bytes: 159744; Write bytes: 328720384; Read time: 0; Write time: 0
[task 2024-06-19T02:03:03.243Z] 02:03:03     INFO - stage-files - Wall time: 1s; CPU: 48%; Read bytes: 0; Write bytes: 134373376; Read time: 0; Write time: 0
[task 2024-06-19T02:03:03.262Z] 02:03:03     INFO - run-tests - Wall time: 504s; CPU: 45%; Read bytes: 1174672384; Write bytes: 2758940160; Read time: 198; Write time: 3
[task 2024-06-19T02:03:03.277Z] 02:03:03     INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-06-19T02:03:05.200Z] 02:03:05  WARNING - returning nonzero exit status 2
[taskcluster 2024-06-19T02:03:05.323Z]    Exit Code: 2
[taskcluster 2024-06-19T02:03:05.323Z]    User Time: 15.625ms
[taskcluster 2024-06-19T02:03:05.323Z]  Kernel Time: 31.25ms
[taskcluster 2024-06-19T02:03:05.323Z]    Wall Time: 12m43.5308205s
[taskcluster 2024-06-19T02:03:05.323Z]       Result: FAILED
[taskcluster 2024-06-19T02:03:05.324Z] === Task Finished ===
[taskcluster 2024-06-19T02:03:05.324Z] Task Duration: 12m43.5325953s
[taskcluster 2024-06-19T02:03:05.998Z] Uploading artifact public/logs/localconfig.json from file D:\task_171876115018949\logs\localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2025-06-19T01:03:10.824Z
[taskcluster 2024-06-19T02:03:06.725Z] Uploading artifact public/test_info/profile_resource-usage.json from file D:\task_171876115018949\build\blobber_upload_dir\profile_resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2025-06-19T01:03:10.824Z
[taskcluster 2024-06-19T02:03:08.253Z] Uploading artifact public/test_info/resource-usage.json from file D:\task_171876115018949\build\blobber_upload_dir\resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2025-06-19T01:03:10.824Z
[taskcluster 2024-06-19T02:03:10.380Z] Uploading artifact public/test_info/system-info.log from file D:\task_171876115018949\build\blobber_upload_dir\system-info.log with content encoding "gzip", mime type "text/plain" and expiry 2025-06-19T01:03:10.824Z
[taskcluster 2024-06-19T02:03:11.023Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2025-06-19T01:03:10.824Z
[taskcluster:error] exit status 2