1530759 - windows/aarch64 - toolkit/components/telemetry/tests/unit/test_ModulesPing.js | xpcshell return code: 0

Status: RESOLVED FIXED

(Toolkit :: Telemetry, defect, P5)

Description

[Treeherder Bug Filer]

#[markdown(off)]
Filed by: egao [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=230585094&repo=try

https://queue.taskcluster.net/v1/task/IMvnjdRqR9-sTC7FQ1FP1Q/runs/0/artifacts/public/logs/live_backing.log

Issue observed during windows10-aarch64 test run on opt-xpcshell-7.

Bug 1525417 fixed most of the failures, however I suspect this test is still trying to load DLLs built for x86_64.

18:12:45 WARNING - TEST-UNEXPECTED-FAIL | toolkit/components/telemetry/tests/unit/test_ModulesPing.js | xpcshell return code: 0
18:12:45 INFO - TEST-INFO took 304ms
18:12:45 INFO - >>>>>>>
18:12:45 INFO - (xpcshell/head.js) | test MAIN run_test pending (1)
18:12:45 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2)
18:12:45 INFO - (xpcshell/head.js) | test MAIN run_test finished (2)
18:12:45 INFO - running event loop
18:12:45 INFO - toolkit/components/telemetry/tests/unit/test_ModulesPing.js | Starting setup
18:12:45 INFO - (xpcshell/head.js) | test setup pending (2)
18:12:45 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2)
18:12:45 INFO - Unexpected exception Error: couldn't open library C:\\tasks\\task_1551203970\\build\\tests\\xpcshell\\tests\\toolkit\\components\\telemetry\\tests\\unit\\testUnicodePDBAArch64.dll: error 1114 at C:/tasks/task_1551203970/build/tests/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_ModulesPing.js:131
18:12:45 INFO - setup@C:/tasks/task_1551203970/build/tests/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_ModulesPing.js:131:36
18:12:45 INFO - async*run_next_test/_run_next_test/<@C:\tasks\task_1551203970\build\tests\xpcshell\head.js:1434:22
18:12:45 INFO - async*_run_next_test@C:\tasks\task_1551203970\build\tests\xpcshell\head.js:1434:38
18:12:45 INFO - run@C:\tasks\task_1551203970\build\tests\xpcshell\head.js:685:9
18:12:45 INFO - _do_main@C:\tasks\task_1551203970\build\tests\xpcshell\head.js:224:6
18:12:45 INFO - _execute_test@C:\tasks\task_1551203970\build\tests\xpcshell\head.js:526:5
18:12:45 INFO - @-e:1:1
18:12:45 INFO - exiting test
18:12:45 INFO - TypeError: this._httpServer is null at C:/tasks/task_1551203970/build/tests/xpcshell/tests/toolkit/components/telemetry/tests/unit/head.js:89
18:12:45 INFO - stop/<@C:/tasks/task_1551203970/build/tests/xpcshell/tests/toolkit/components/telemetry/tests/unit/head.js:89:7
18:12:45 INFO - stop@C:/tasks/task_1551203970/build/tests/xpcshell/tests/toolkit/components/telemetry/tests/unit/head.js:88:12
18:12:45 INFO - @C:/tasks/task_1551203970/build/tests/xpcshell/tests/toolkit/components/telemetry/tests/unit/test_ModulesPing.js:165:26
18:12:45 INFO - _execute_test@C:\tasks\task_1551203970\build\tests\xpcshell\head.js:600:16
18:12:45 INFO - @-e:1:1
18:12:45 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "1551204765494 Toolkit.Telemetry ERROR TelemetrySend::shutdown - failed to remove observer for idle-daily: [Exception... "Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]" nsresult: "0x80004005 (NS_ERROR_FAILURE)" location: "JS frame :: resource://gre/modules/TelemetrySend.jsm :: shutdown :: line 744" data: no] Stack trace: shutdown()@resource://gre/modules/TelemetrySend.jsm:744" {file: "resource://gre/modules/Log.jsm" line: 679}]
18:12:45 INFO - append@resource://gre/modules/Log.jsm:679:12
18:12:45 INFO - log@resource://gre/modules/Log.jsm:360:16
18:12:45 INFO - getLoggerWithMessagePrefix/proxy.log@resource://gre/modules/Log.jsm:484:18
18:12:45 INFO - error@resource://gre/modules/Log.jsm:368:10
18:12:45 INFO - shutdown@resource://gre/modules/TelemetrySend.jsm:746:19
18:12:45 INFO - async*shutdown@resource://gre/modules/TelemetrySend.jsm:200:30
18:12:45 INFO - @C:/tasks/task_1551203970/build/tests/xpcshell/tests/toolkit/components/telemetry/tests/unit/head.js:438:47
18:12:45 INFO - _execute_test/<@C:\\tasks\\task_1551203970\\build\\tests\\xpcshell\\head.js:590:28
18:12:45 INFO - async*_execute_test@C:\\tasks\\task_1551203970\\build\\tests\\xpcshell\\head.js:599:5
18:12:45 INFO - @-e:1:1
18:12:45 INFO - "
18:12:45 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "1551204765494 Toolkit.Telemetry ERROR TelemetrySend::shutdown - failed to remove observer for quit-application-granted: [Exception... "Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIObserverService.removeObserver]" nsresult: "0x80070057 (NS_ERROR_ILLEGAL_VALUE)" location: "JS frame :: resource://gre/modules/TelemetrySend.jsm :: shutdown :: line 744" data: no] Stack trace: shutdown()@resource://gre/modules/TelemetrySend.jsm:744" {file: "resource://gre/modules/Log.jsm" line: 679}]
18:12:45 INFO - append@resource://gre/modules/Log.jsm:679:12
18:12:45 INFO - log@resource://gre/modules/Log.jsm:360:16
18:12:45 INFO - getLoggerWithMessagePrefix/proxy.log@resource://gre/modules/Log.jsm:484:18
18:12:45 INFO - error@resource://gre/modules/Log.jsm:368:10
18:12:45 INFO - shutdown@resource://gre/modules/TelemetrySend.jsm:746:19
18:12:45 INFO - async*shutdown@resource://gre/modules/TelemetrySend.jsm:200:30
18:12:45 INFO - @C:/tasks/task_1551203970/build/tests/xpcshell/tests/toolkit/components/telemetry/tests/unit/head.js:438:47
18:12:45 INFO - _execute_test/<@C:\\tasks\\task_1551203970\\build\\tests\\xpcshell\\head.js:590:28
18:12:45 INFO - async*_execute_test@C:\\tasks\\task_1551203970\\build\\tests\\xpcshell\\head.js:599:5
18:12:45 INFO - @-e:1:1
18:12:45 INFO - "
18:12:45 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "1551204765495 Toolkit.Telemetry ERROR TelemetrySend::shutdown - failed to remove observer for quit-application-forced: [Exception... "Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]" nsresult: "0x80004005 (NS_ERROR_FAILURE)" location: "JS frame :: resource://gre/modules/TelemetrySend.jsm :: shutdown :: line 744" data: no] Stack trace: shutdown()@resource://gre/modules/TelemetrySend.jsm:744" {file: "resource://gre/modules/Log.jsm" line: 679}]
18:12:45 INFO - append@resource://gre/modules/Log.jsm:679:12
18:12:45 INFO - log@resource://gre/modules/Log.jsm:360:16
18:12:45 INFO - getLoggerWithMessagePrefix/proxy.log@resource://gre/modules/Log.jsm:484:18
18:12:45 INFO - error@resource://gre/modules/Log.jsm:368:10
18:12:45 INFO - shutdown@resource://gre/modules/TelemetrySend.jsm:746:19
18:12:45 INFO - async*shutdown@resource://gre/modules/TelemetrySend.jsm:200:30
18:12:45 INFO - @C:/tasks/task_1551203970/build/tests/xpcshell/tests/toolkit/components/telemetry/tests/unit/head.js:438:47
18:12:45 INFO - _execute_test/<@C:\\tasks\\task_1551203970\\build\\tests\\xpcshell\\head.js:590:28
18:12:45 INFO - async*_execute_test@C:\\tasks\\task_1551203970\\build\\tests\\xpcshell\\head.js:599:5
18:12:45 INFO - @-e:1:1
18:12:45 INFO - "
18:12:45 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "1551204765495 Toolkit.Telemetry ERROR TelemetrySend::shutdown - failed to remove observer for profile-change-net-teardown: [Exception... "Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIObserverService.removeObserver]" nsresult: "0x80070057 (NS_ERROR_ILLEGAL_VALUE)" location: "JS frame :: resource://gre/modules/TelemetrySend.jsm :: shutdown :: line 744" data: no] Stack trace: shutdown()@resource://gre/modules/TelemetrySend.jsm:744" {file: "resource://gre/modules/Log.jsm" line: 679}]
18:12:45 INFO - append@resource://gre/modules/Log.jsm:679:12
18:12:45 INFO - log@resource://gre/modules/Log.jsm:360:16
18:12:45 INFO - getLoggerWithMessagePrefix/proxy.log@resource://gre/modules/Log.jsm:484:18
18:12:45 INFO - error@resource://gre/modules/Log.jsm:368:10
18:12:45 INFO - shutdown@resource://gre/modules/TelemetrySend.jsm:746:19
18:12:45 INFO - async*shutdown@resource://gre/modules/TelemetrySend.jsm:200:30
18:12:45 INFO - @C:/tasks/task_1551203970/build/tests/xpcshell/tests/toolkit/components/telemetry/tests/unit/head.js:438:47
18:12:45 INFO - _execute_test/<@C:\\tasks\\task_1551203970\\build\\tests\\xpcshell\\head.js:590:28
18:12:45 INFO - async*_execute_test@C:\\tasks\\task_1551203970\\build\\tests\\xpcshell\\head.js:599:5
18:12:45 INFO - @-e:1:1
18:12:45 INFO - "
18:12:45 INFO - "CONSOLE_MESSAGE: (info) 1551204765496 Toolkit.Telemetry TRACE TelemetrySend::Scheduler::shutdown"
18:12:45 INFO - "CONSOLE_MESSAGE: (info) 1551204765496 Toolkit.Telemetry TRACE TelemetrySend::promisePendingPingActivity - Waiting for ping task"
18:12:45 INFO - <<<<<<<

Comment 1

[Edwin Takahashi (:egao | infrequent contributor)]

:froydnj - this test is failing to load testUnicodePDBAArch64.dll resulting in an unexpected failure. My initial suspicion was that this test attempts to load the wrong DLL (just like with Bug 1525417), but that does not appear to be the case, so I'm wondering if this requires the test developer to fix.

Comment 2

[Nathan Froyd [:froydnj]]

(In reply to Edwin Gao (:egao) from comment #1)

:froydnj - this test is failing to load testUnicodePDBAArch64.dll resulting in an unexpected failure. My initial suspicion was that this test attempts to load the wrong DLL (just like with Bug 1525417), but that does not appear to be the case, so I'm wondering if this requires the test developer to fix.

Hm, I guess this would be a problem with how the library is compiled. The error is:

Unexpected exception Error: couldn't open library C:\tasks\task_1551203970\build\tests\xpcshell\tests\toolkit\components\telemetry\tests\unit\testUnicodePDBAArch64.dll: error 1114

which according to MSDN is ERROR_DLL_INIT_FAILED. We compiled the DLL with /nodefaultlib /entry:nothing, so I guess the appropriate initialization routines are not there? Do we need to provide our own DllMain or somesuch?

Comment 3

[(Away)]

Oh, I didn't know you'd actually want to load it in a process. :)

In that case, you do need an entry point that has the DllMain signature (regardless of what it's named), and returns TRUE.

Comment 4

[Intermittent Failures Robot]

2 failures in 2981 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 2

Platform breakdown:

• windows10-aarch64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1530759&startday=2019-02-25&endday=2019-03-03&tree=all

Comment 5

[Intermittent Failures Robot]

2 failures in 2818 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 2

Platform breakdown:

• windows10-aarch64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1530759&startday=2019-03-04&endday=2019-03-10&tree=all

Comment 6

[Edwin Takahashi (:egao | infrequent contributor)]

Attachment: Bug 1530759 - disable test_ModulesPing.js

Comment 7

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/9fa71578c5e7
disable test_ModulesPing.js r=jmaher

Comment 8

[Oana Pop-Rus]

https://hg.mozilla.org/mozilla-central/rev/9fa71578c5e7