1550141 - OSX Mojave (10.14) - toolkit/modules/tests/xpcshell/test_DeferredTask.js fails when run on osx 10.14

Status: RESOLVED FIXED

(Toolkit :: Async Tooling, defect)

Description

[Joel Maher ( :jmaher ) (UTC -8)]

I am working to get tests running in CI on OSX 10.14 instead of 10.10.

here is a try push with failures:
https://treeherder.mozilla.org/#/jobs?repo=try&searchStr=osx%2Cxpcshell&revision=c7df55609292b1af69338a9a1a3c49b346ce819a&selectedJob=244756160

and a log file:
https://taskcluster-artifacts.net/R_AztudsSCK5t--vjRoFlw/0/public/logs/live_backing.log

here is the data from the log:
21:41:32 INFO - TEST-PASS | toolkit/modules/tests/xpcshell/test_DeferredTask.js | test_arm_coalesced - [test_arm_coalesced : 85] true == true
21:41:32 INFO - (xpcshell/head.js) | test run_next_test 4 pending (2)
21:41:32 INFO - (xpcshell/head.js) | test test_arm_coalesced finished (2)
21:41:32 INFO - toolkit/modules/tests/xpcshell/test_DeferredTask.js | Starting test_arm_coalesced_nodelay
21:41:32 INFO - (xpcshell/head.js) | test test_arm_coalesced_nodelay pending (2)
21:41:32 INFO - (xpcshell/head.js) | test run_next_test 4 finished (2)
21:41:32 INFO - TEST-PASS | toolkit/modules/tests/xpcshell/test_DeferredTask.js | test_arm_coalesced_nodelay - [test_arm_coalesced_nodelay : 102] true == true
21:41:32 INFO - (xpcshell/head.js) | test run_next_test 5 pending (2)
21:41:32 INFO - (xpcshell/head.js) | test test_arm_coalesced_nodelay finished (2)
21:41:32 INFO - toolkit/modules/tests/xpcshell/test_DeferredTask.js | Starting test_arm_recursive
21:41:32 INFO - (xpcshell/head.js) | test test_arm_recursive pending (2)
21:41:32 INFO - (xpcshell/head.js) | test run_next_test 5 finished (2)
21:41:32 INFO - (xpcshell/head.js) | test run_next_test 6 pending (2)
21:41:32 INFO - (xpcshell/head.js) | test test_arm_recursive finished (2)
21:41:32 INFO - toolkit/modules/tests/xpcshell/test_DeferredTask.js | Starting test_arm_async
21:41:32 INFO - (xpcshell/head.js) | test test_arm_async pending (2)
21:41:32 INFO - (xpcshell/head.js) | test run_next_test 6 finished (2)
21:41:32 INFO - TEST-PASS | toolkit/modules/tests/xpcshell/test_DeferredTask.js | test_arm_async - [test_arm_async : 152] true == true
21:41:32 INFO - TEST-PASS | toolkit/modules/tests/xpcshell/test_DeferredTask.js | test_arm_async - [test_arm_async : 153] true == true
21:41:32 WARNING - TEST-UNEXPECTED-FAIL | toolkit/modules/tests/xpcshell/test_DeferredTask.js | test_arm_async - [test_arm_async : 160] false == true
21:41:32 INFO - /Users/cltbld/tasks/task_1557089195/build/tests/xpcshell/tests/toolkit/modules/tests/xpcshell/test_DeferredTask.js:test_arm_async/<:160
21:41:32 INFO - /Users/cltbld/tasks/task_1557089195/build/tests/xpcshell/head.js:notify:201
21:41:32 INFO - /Users/cltbld/tasks/task_1557089195/build/tests/xpcshell/head.js:_do_main:227
21:41:32 INFO - /Users/cltbld/tasks/task_1557089195/build/tests/xpcshell/head.js:_execute_test:529
21:41:32 INFO - -e:null:1
21:41:32 INFO - exiting test
21:41:32 INFO - exception thrown from do_timeout callback: [Exception... "Abort" nsresult: "0x80004004 (NS_ERROR_ABORT)" location: "JS frame :: /Users/cltbld/tasks/task_1557089195/build/tests/xpcshell/head.js :: _abort_failed_test :: line 742" data: no]
21:41:32 INFO - /Users/cltbld/tasks/task_1557089195/build/tests/xpcshell/head.js:notify:203
21:41:32 INFO - /Users/cltbld/tasks/task_1557089195/build/tests/xpcshell/head.js:_do_main:227
21:41:32 INFO - /Users/cltbld/tasks/task_1557089195/build/tests/xpcshell/head.js:_execute_test:529
21:41:32 INFO - -e:null:1
21:41:32 INFO - exiting test
21:41:32 INFO - PID 7099 | JavaScript error: /Users/cltbld/tasks/task_1557089195/build/tests/xpcshell/head.js, line 742: NS_ERROR_ABORT:
21:41:32 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "NS_ERROR_ABORT: " {file: "/Users/cltbld/tasks/task_1557089195/build/tests/xpcshell/head.js" line: 742}]"
21:41:32 INFO - PID 7099 | [7099, Main Thread] WARNING: OOPDeinit() without successful OOPInit(): file /builds/worker/workspace/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 3104
21:41:32 INFO - PID 7099 | [7099, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
21:41:32 INFO - PID 7099 | [7099, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
21:41:32 INFO - PID 7099 | nsStringStats
21:41:32 INFO - PID 7099 | => mAllocCount: 11058
21:41:32 INFO - PID 7099 | => mReallocCount: 0
21:41:32 INFO - PID 7099 | => mFreeCount: 11058
21:41:32 INFO - PID 7099 | => mShareCount: 10240
21:41:32 INFO - PID 7099 | => mAdoptCount: 177
21:41:32 INFO - PID 7099 | => mAdoptFreeCount: 177
21:41:32 INFO - PID 7099 | => Process ID: 7099, Thread ID: 4540548544
21:41:32 INFO - <<<<<<<

Comment 1

[Joel Maher ( :jmaher ) (UTC -8)]

:yoric - could you help me figure out what is going on with this test on 10.14 so we can keep it enabled and green?

Comment 2

[David Teller [:Yoric] - still alive but not very active]

There doesn't seem to be any system-bound code. Is this a deterministic failure?

Comment 3

[Joel Maher ( :jmaher ) (UTC -8)]

I did a series of runs, including runs where it was the only test run:
https://treeherder.mozilla.org/#/jobs?repo=try&author=jmaher%40mozilla.com&fromchange=8d297eb7a85ee9577dc0cbe5d5471d2257d8d5cf&tochange=be4831d5785cb5fdc07ca3fd193e33f68d8a19e3&searchStr=xpcshell

you can see this reproduces 100% of the time, usually line 73
https://searchfox.org/mozilla-central/source/toolkit/modules/tests/xpcshell/test_DeferredTask.js#73

sometimes line 172/173:
https://searchfox.org/mozilla-central/source/toolkit/modules/tests/xpcshell/test_DeferredTask.js#172

and a few random other failure locations as well.

There appears to be a mix of failures, but getting it to fail on line 73 seems reasonable. Possibly this is a timing thing?

Comment 4

[Joel Maher ( :jmaher ) (UTC -8)]

:yoric, is there work you can do on this in the near future, or should we plan on disabling this on 10.14- We will start moving test suites over this upcoming week, this could wait a week or two if we believe this will get fixed.

Comment 5

[David Teller [:Yoric] - still alive but not very active]

I'm afraid that I have 0 time to work on this. Also, I haven't worked on toolkit/ for ~3 years, so I might not even be the right person in the first place.

Comment 6

[Joel Maher ( :jmaher ) (UTC -8)]

:yoric, thanks for mentioning this- do you think there is a better candidate for triage of this bugzilla component?

Comment 7

[Joel Maher ( :jmaher ) (UTC -8)]

Attachment: Bug 1550141 - disable toolkit/modules/tests/xpcshell/test_DeferredTask.js on osx 10.14. r=Yoric

disable toolkit/modules/tests/xpcshell/test_DeferredTask.js on osx 10.14.

Comment 8

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/6e835c76add3
disable toolkit/modules/tests/xpcshell/test_DeferredTask.js on osx 10.14. r=Yoric

Comment 9

[BugBot [:suhaib / :marco/ :calixte]]

The priority flag is not set for this bug.
:Yoric, could you have a look please?

For more information, please visit auto_nag documentation.

Comment 10

[Cristian Brindusan [:cbrindusan]]

https://hg.mozilla.org/mozilla-central/rev/6e835c76add3