1791887 - Intermittent Assertion failure: currentTime - lastAllocRateUpdateTime >= collectorTimeSinceAllocRateUpdate.ref(), at /builds/worker/checkouts/gecko/js/src/gc/GC.cpp:2947

Status: RESOLVED FIXED

(Core :: JavaScript: GC, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-09-21T21:21:23.486Z] TEST-PASS | js/src/jit-test/tests/gc/bug1511412.js | Success (code 0, args "--blinterp-eager") [0.0 s]
[task 2022-09-21T21:21:23.496Z] Assertion failure: currentTime - lastAllocRateUpdateTime >= collectorTimeSinceAllocRateUpdate.ref(), at /builds/worker/checkouts/gecko/js/src/gc/GC.cpp:2947
[task 2022-09-21T21:21:23.496Z] #01: ???[/builds/worker/workspace/obj-spider/dist/bin/js +0xbe4a27]
[task 2022-09-21T21:21:23.496Z] #02: ???[/builds/worker/workspace/obj-spider/dist/bin/js +0xbeaf61]
[task 2022-09-21T21:21:23.496Z] #03: ???[/builds/worker/workspace/obj-spider/dist/bin/js +0xbb8768]
[task 2022-09-21T21:21:23.496Z] #04: JSRuntime::destroyRuntime()[/builds/worker/workspace/obj-spider/dist/bin/js +0x6b86b1]
[task 2022-09-21T21:21:23.496Z] #05: ???[/builds/worker/workspace/obj-spider/dist/bin/js +0x563f05]
[task 2022-09-21T21:21:23.496Z] #06: ???[/builds/worker/workspace/obj-spider/dist/bin/js +0x255329]
[task 2022-09-21T21:21:23.496Z] #07: __libc_start_main[/lib/x86_64-linux-gnu/libc.so.6 +0x26d0a]
[task 2022-09-21T21:21:23.496Z] #08: ???[/builds/worker/workspace/obj-spider/dist/bin/js +0x246ee9]
[task 2022-09-21T21:21:23.496Z] #09: ??? (???:???)
[task 2022-09-21T21:21:23.496Z] ExceptionHandler::GenerateDump cloned child 6842
[task 2022-09-21T21:21:23.496Z] ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2022-09-21T21:21:23.497Z] ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2022-09-21T21:21:23.497Z] Exit code: -11
[task 2022-09-21T21:21:23.497Z] FAIL - gc/bug1471949.js
[task 2022-09-21T21:21:23.497Z] TEST-UNEXPECTED-FAIL | js/src/jit-test/tests/gc/bug1471949.js | Assertion failure: currentTime - lastAllocRateUpdateTime >= collectorTimeSinceAllocRateUpdate.ref(), at /builds/worker/checkouts/gecko/js/src/gc/GC.cpp:2947 (code -11, args "--baseline-eager") [0.1 s]
[task 2022-09-21T21:21:23.498Z] INFO exit-status     : -11
[task 2022-09-21T21:21:23.499Z] INFO timed-out       : False
[task 2022-09-21T21:21:23.499Z] INFO stderr         2> Assertion failure: currentTime - lastAllocRateUpdateTime >= collectorTimeSinceAllocRateUpdate.ref(), at /builds/worker/checkouts/gecko/js/src/gc/GC.cpp:2947
[task 2022-09-21T21:21:23.499Z] INFO stderr         2> #01: ???[/builds/worker/workspace/obj-spider/dist/bin/js +0xbe4a27]
[task 2022-09-21T21:21:23.500Z] INFO stderr         2> #02: ???[/builds/worker/workspace/obj-spider/dist/bin/js +0xbeaf61]
[task 2022-09-21T21:21:23.500Z] INFO stderr         2> #03: ???[/builds/worker/workspace/obj-spider/dist/bin/js +0xbb8768]
[task 2022-09-21T21:21:23.500Z] INFO stderr         2> #04: JSRuntime::destroyRuntime()[/builds/worker/workspace/obj-spider/dist/bin/js +0x6b86b1]
[task 2022-09-21T21:21:23.501Z] INFO stderr         2> #05: ???[/builds/worker/workspace/obj-spider/dist/bin/js +0x563f05]
[task 2022-09-21T21:21:23.501Z] INFO stderr         2> #06: ???[/builds/worker/workspace/obj-spider/dist/bin/js +0x255329]
[task 2022-09-21T21:21:23.501Z] INFO stderr         2> #07: __libc_start_main[/lib/x86_64-linux-gnu/libc.so.6 +0x26d0a]
[task 2022-09-21T21:21:23.501Z] INFO stderr         2> #08: ???[/builds/worker/workspace/obj-spider/dist/bin/js +0x246ee9]
[task 2022-09-21T21:21:23.501Z] INFO stderr         2> #09: ??? (???:???)
[task 2022-09-21T21:21:23.501Z] INFO stderr         2> ExceptionHandler::GenerateDump cloned child 6842
[task 2022-09-21T21:21:23.501Z] INFO stderr         2> ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2022-09-21T21:21:23.502Z] INFO stderr         2> ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2022-09-21T21:21:23.507Z] TEST-PASS | js/src/jit-test/tests/gc/bug1600017.js | Success (code 0, args "") [0.0 s]

Comment 1

[Jon Coppeard (:jonco)]

Attachment: Bug 1791887 - Remove timing assertion that doesn't always hold r?sfink

There's a comment below that says that we occasionally observe collector time
being larger than total time, so it's not safe to assert this.

Comment 2

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/04b1663a5ed9
Remove timing assertion that doesn't always hold r=sfink

Comment 3

[Cristian Tuns]

https://hg.mozilla.org/mozilla-central/rev/04b1663a5ed9

Comment 4

[Intermittent Failures Robot]

12 failures in 2909 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 10
• mozilla-central: 2

Platform and build breakdown:

• linux64: 10
  • debug: 10
• linux32: 2
  • debug: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1791887&startday=2022-09-19&endday=2022-09-25&tree=all