Closed Bug 1884270 Opened 11 months ago Closed 8 months ago

Intermittent gtest TEST-UNEXPECTED-FAIL | gtest | test failed with return code 2147483651

Categories

(Core :: Audio/Video, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2024-03-07T23:21:05.052Z] 23:21:05     INFO -  TEST-START | OpusAudioTrackEncoder.DefaultInitDuration
[task 2024-03-07T23:21:05.052Z] 23:21:05     INFO -  #05: mozilla::ThreadedDriver::RunThread() [dom/media/GraphDriver.cpp:241]
[task 2024-03-07T23:21:05.052Z] 23:21:05     INFO -  #06: mozilla::MediaTrackGraphInitThreadRunnable::Run() [dom/media/GraphDriver.cpp:140]
[task 2024-03-07T23:21:05.053Z] 23:21:05     INFO -  #07: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1194]
[task 2024-03-07T23:21:05.053Z] 23:21:05     INFO -  #08: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:480]
[task 2024-03-07T23:21:05.053Z] 23:21:05     INFO -  #09: mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:300]
[task 2024-03-07T23:21:05.054Z] 23:21:05     INFO -  #10: MessageLoop::RunInternal() [ipc/chromium/src/base/message_loop.cc:370]
[task 2024-03-07T23:21:05.054Z] 23:21:05     INFO -  #11: MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:364]
[task 2024-03-07T23:21:05.054Z] 23:21:05     INFO -  #12: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:346]
[task 2024-03-07T23:21:05.054Z] 23:21:05     INFO -  #13: nsThread::ThreadFunc(void*) [xpcom/threads/nsThread.cpp:372]
[task 2024-03-07T23:21:05.238Z] 23:21:05     INFO -  #14: _PR_NativeRunThread(void*) [nsprpub/pr/src/threads/combined/pruthr.c:399]
[task 2024-03-07T23:21:05.245Z] 23:21:05     INFO -  #15: pr_root(void*) [nsprpub/pr/src/md/windows/w95thred.c:139]
[task 2024-03-07T23:21:05.248Z] 23:21:05     INFO -  fix-stacks: error: failed to read debug info file `ucrtbase.pdb` for `C:\Windows\System32\ucrtbase.dll`
[task 2024-03-07T23:21:05.248Z] 23:21:05     INFO -  fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2024-03-07T23:21:05.248Z] 23:21:05     INFO -  fix-stacks: The system cannot find the file specified. (os error 2)
[task 2024-03-07T23:21:05.249Z] 23:21:05     INFO -  #16: time32 [C:\Windows\System32\ucrtbase.dll + 0x459e3]
[task 2024-03-07T23:21:05.252Z] 23:21:05     INFO -  fix-stacks: error: failed to read debug info file `wkernel32.pdb` for `C:\Windows\System32\KERNEL32.DLL`
[task 2024-03-07T23:21:05.252Z] 23:21:05     INFO -  fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2024-03-07T23:21:05.252Z] 23:21:05     INFO -  fix-stacks: The system cannot find the file specified. (os error 2)
[task 2024-03-07T23:21:05.252Z] 23:21:05     INFO -  #17: BaseThreadInitThunk [C:\Windows\System32\KERNEL32.DLL + 0x17ba9]
[task 2024-03-07T23:21:05.347Z] 23:21:05     INFO -  #18: DllBlocklist_Initialize [Z:\task_170985109417884\build\application\firefox\mozglue.dll + 0x7ec07]
[task 2024-03-07T23:21:05.357Z] 23:21:05     INFO -  fix-stacks: error: failed to read debug info file `wntdll.pdb` for `C:\Windows\SYSTEM32\ntdll.dll`
[task 2024-03-07T23:21:05.357Z] 23:21:05     INFO -  fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2024-03-07T23:21:05.357Z] 23:21:05     INFO -  fix-stacks: The system cannot find the file specified. (os error 2)
[task 2024-03-07T23:21:05.357Z] 23:21:05     INFO -  #19: RtlInitializeExceptionChain [C:\Windows\SYSTEM32\ntdll.dll + 0x6bd2b]
[task 2024-03-07T23:21:05.358Z] 23:21:05     INFO -  #20: RtlClearBits [C:\Windows\SYSTEM32\ntdll.dll + 0x6bcaf]
[task 2024-03-07T23:21:05.358Z] 23:21:05     INFO -  gtest INFO | gtest | process wait complete, returncode=2147483651
[task 2024-03-07T23:21:05.358Z] 23:21:05     INFO -  mozcrash checking Z:\task_170985109417884\build\tests\gtest for minidumps...
[task 2024-03-07T23:21:05.359Z] 23:21:05  WARNING -  gtest TEST-UNEXPECTED-FAIL | gtest | test failed with return code 2147483651
[task 2024-03-07T23:21:05.359Z] 23:21:05     INFO -  gtest INFO | rungtests.py exits with code 1
[task 2024-03-07T23:21:05.394Z] 23:21:05     INFO - Return code: 1
[task 2024-03-07T23:21:05.395Z] 23:21:05    ERROR - No tests run or test summary not found
[task 2024-03-07T23:21:05.395Z] 23:21:05     INFO - TinderboxPrint: gtest-gtest<br/><em class="testfail">T-FAIL</em>
[task 2024-03-07T23:21:05.395Z] 23:21:05  WARNING - setting return code to 2
[task 2024-03-07T23:21:05.395Z] 23:21:05     INFO - The gtest suite: gtest ran with return status: FAILURE
[task 2024-03-07T23:21:05.396Z] 23:21:05     INFO - Running post-action listener: _package_coverage_data
[task 2024-03-07T23:21:05.396Z] 23:21:05     INFO - Running post-action listener: _resource_record_post_action
[task 2024-03-07T23:21:05.396Z] 23:21:05     INFO - Running post-action listener: process_java_coverage_data
[task 2024-03-07T23:21:05.396Z] 23:21:05     INFO - [mozharness: 2024-03-07 23:21:05.396075Z] Finished run-tests step (success)
[task 2024-03-07T23:21:05.396Z] 23:21:05     INFO - [mozharness: 2024-03-07 23:21:05.396075Z] Running uninstall step.
[task 2024-03-07T23:21:05.396Z] 23:21:05     INFO - Running pre-action listener: _resource_record_pre_action
[task 2024-03-07T23:21:05.396Z] 23:21:05     INFO - Running main action method: uninstall
[task 2024-03-07T23:21:05.396Z] 23:21:05     INFO - Skipping uninstall for non-MSIX test
[task 2024-03-07T23:21:05.396Z] 23:21:05     INFO - Running post-action listener: _resource_record_post_action
[task 2024-03-07T23:21:05.396Z] 23:21:05     INFO - [mozharness: 2024-03-07 23:21:05.396075Z] Finished uninstall step (success)
[task 2024-03-07T23:21:05.396Z] 23:21:05     INFO - Running post-run listener: _resource_record_post_run
[task 2024-03-07T23:21:05.801Z] 23:21:05     INFO - instance_metadata.json not found; unable to determine instance type
[task 2024-03-07T23:21:05.814Z] 23:21:05     INFO - Validating Perfherder data against Z:\task_170985109417884\mozharness\external_tools\performance-artifact-schema.json
[task 2024-03-07T23:21:05.818Z] 23:21:05     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "gtest.gtest.overall", "extraOptions": ["buildbot-unknown"], "subtests": [{"name": "cpu_percent", "value": 28.196105673523576}, {"name": "io_write_bytes", "value": 628813824}, {"name": "io.read_bytes", "value": 606863360}, {"name": "io_write_time", "value": 13}, {"name": "io_read_time", "value": 145}]}, {"name": "gtest.gtest.start-pulseaudio", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}, {"name": "gtest.gtest.install", "subtests": [{"name": "time", "value": 4.75}, {"name": "cpu_percent", "value": 27.985511363636363}]}, {"name": "gtest.gtest.stage-files", "subtests": [{"name": "time", "value": 0.13999999999998636}, {"name": "cpu_percent", "value": 0}]}, {"name": "gtest.gtest.run-tests", "subtests": [{"name": "time", "value": 598.969}, {"name": "cpu_percent", "value": 28.19648319959881}]}, {"name": "gtest.gtest.uninstall", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}]}
[task 2024-03-07T23:21:05.818Z] 23:21:05     INFO - Total resource usage - Wall time: 604s; CPU: Can't collect data; Read bytes: 606863360; Write bytes: 628813824; Read time: 145; Write time: 13
[task 2024-03-07T23:21:05.818Z] 23:21:05     INFO - TinderboxPrint: I/O read bytes / time<br/>606,863,360 / 145
[task 2024-03-07T23:21:05.818Z] 23:21:05     INFO - TinderboxPrint: I/O write bytes / time<br/>628,813,824 / 13
[task 2024-03-07T23:21:05.819Z] 23:21:05     INFO - TinderboxPrint: CPU idle<br/>3,474.4 (71.6%)
[task 2024-03-07T23:21:05.819Z] 23:21:05     INFO - TinderboxPrint: CPU system<br/>511.8 (10.5%)
[task 2024-03-07T23:21:05.819Z] 23:21:05     INFO - TinderboxPrint: CPU user<br/>842.2 (17.4%)
[task 2024-03-07T23:21:05.820Z] 23:21:05     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-03-07T23:21:05.822Z] 23:21:05     INFO - install - Wall time: 5s; CPU: 28%; Read bytes: 1205248; Write bytes: 322404352; Read time: 0; Write time: 4
[task 2024-03-07T23:21:05.824Z] 23:21:05     INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-03-07T23:21:05.863Z] 23:21:05     INFO - run-tests - Wall time: 599s; CPU: 28%; Read bytes: 605658112; Write bytes: 306278400; Read time: 145; Write time: 9
[task 2024-03-07T23:21:05.866Z] 23:21:05     INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-03-07T23:21:07.849Z] 23:21:07  WARNING - returning nonzero exit status 2
[taskcluster 2024-03-07T23:21:07.974Z]    Exit Code: 2
[taskcluster 2024-03-07T23:21:07.974Z]    User Time: 0s
[taskcluster 2024-03-07T23:21:07.974Z]  Kernel Time: 46.875ms
[taskcluster 2024-03-07T23:21:07.974Z]    Wall Time: 13m37.8486921s
[taskcluster 2024-03-07T23:21:07.974Z]       Result: FAILED
[taskcluster 2024-03-07T23:21:07.974Z] === Task Finished ===
[taskcluster 2024-03-07T23:21:07.974Z] Task Duration: 13m37.8761064s
Status: NEW → RESOLVED
Closed: 8 months ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.