Closed Bug 1347704 Opened 7 years ago Closed 7 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | test_ext_i18n.js | Test timed out

Categories

(WebExtensions :: General, defect)

defect
Not set
normal

Tracking

(firefox56 fixed)

RESOLVED FIXED
mozilla56
Tracking Status
firefox56 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: bsilverberg)

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell fixed])

Attachments

(1 file)

Summary: Intermittent TEST-UNEXPECTED-TIMEOUT | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | Test timed out → Intermittent TEST-UNEXPECTED-TIMEOUT | test_ext_i18n.js | Test timed out
This failure has been low frequency for a long time, but started happening frequently around July 10. I wonder if the change in frequency is a consequence of bug 1357490.

:kmag - Could you have a look?
Flags: needinfo?(kmaglione+bmo)
Whiteboard: [stockwell needswork]
This log seems typical:

https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=113807337&lineNumber=7949

15:29:35     INFO -  TEST-START | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js
15:34:35  WARNING -  TEST-UNEXPECTED-TIMEOUT | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | Test timed out
15:34:35     INFO -  TEST-INFO took 300001ms
15:34:35     INFO -  >>>>>>>
15:34:35     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
15:34:35     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
15:34:35     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
15:34:35     INFO -  running event loop
15:34:35     INFO -  xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | Starting test_i18n
15:34:35     INFO -  (xpcshell/head.js) | test test_i18n pending (2)
15:34:35     INFO -  "Extension attached"
15:34:35     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] @@extension_id builtin message - Expected: moz-extension://1d7a1924-ec4e-c74e-8f61-80fb16614d0e/, Actual: moz-extension://1d7a1924-ec4e-c74e-8f61-80fb16614d0e/ - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Simple message in selected locale. - Expected: Foo., Actual: Foo. - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Simple message fallback in default locale. - Expected: (bar), Actual: (bar) - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Unknown locale string. - Expected: , Actual:  - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Unknown built-in string. - Expected: , Actual:  - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Unknown built-in bidi string. - Expected: , Actual:  - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Multi-byte message in selected locale. - Expected: Føo., Actual: Føo. - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Basic numeric substitutions - Expected: '$0' '14' '' '5' '$$$$' '$'., Actual: '$0' '14' '' '5' '$$$$' '$'. - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Basic numeric substitutions, with non-array value - Expected: '$0' '' 'just a string' '' '$$$$' '$'., Actual: '$0' '' 'just a string' '' '$$$$' '$'. - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Named and numeric substitution - Expected: _foo_ (subst $1 $2) _bar_, Actual: _foo_ (subst $1 $2) _bar_ - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Numeric substitution amid named placeholders - Expected: (2 $1 $2), Actual: (2 $1 $2) - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Named placeholder with invalid key - Expected: $bad name$, Actual: $bad name$ - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Named placeholder with an invalid value - Expected: , Actual:  - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Named placeholder with a strange content value - Expected: Accepted, but shouldn't break., Actual: Accepted, but shouldn't break. - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Non-placeholder token that should be ignored - Expected: $foo, Actual: $foo - true == true
15:34:35     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "Unknown localization message some-unknown-locale-string" {file: "moz-extension://1d7a1924-ec4e-c74e-8f61-80fb16614d0e/%7B3d7ebf2b-ddeb-d44e-909f-bdc779d5b40f%7D.js" line: 21}]"
15:34:35     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "Unknown localization message @@unknown_builtin_string" {file: "moz-extension://1d7a1924-ec4e-c74e-8f61-80fb16614d0e/%7B3d7ebf2b-ddeb-d44e-909f-bdc779d5b40f%7D.js" line: 23}]"
15:34:35     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "Unknown localization message @@bidi_unknown_builtin_string" {file: "moz-extension://1d7a1924-ec4e-c74e-8f61-80fb16614d0e/%7B3d7ebf2b-ddeb-d44e-909f-bdc779d5b40f%7D.js" line: 24}]"
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] @@extension_id builtin message - Expected: moz-extension://1d7a1924-ec4e-c74e-8f61-80fb16614d0e/, Actual: moz-extension://1d7a1924-ec4e-c74e-8f61-80fb16614d0e/ - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Simple message in selected locale. - Expected: Foo., Actual: Foo. - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Simple message fallback in default locale. - Expected: (bar), Actual: (bar) - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Unknown locale string. - Expected: , Actual:  - true == true
15:34:35     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "Unknown localization message some-unknown-locale-string" {file: "moz-extension://1d7a1924-ec4e-c74e-8f61-80fb16614d0e/content.js" line: 17}]"
15:34:35     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "Unknown localization message @@unknown_builtin_string" {file: "moz-extension://1d7a1924-ec4e-c74e-8f61-80fb16614d0e/content.js" line: 19}]"
15:34:35     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "Unknown localization message @@bidi_unknown_builtin_string" {file: "moz-extension://1d7a1924-ec4e-c74e-8f61-80fb16614d0e/content.js" line: 20}]"
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Unknown built-in string. - Expected: , Actual:  - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Unknown built-in bidi string. - Expected: , Actual:  - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Multi-byte message in selected locale. - Expected: Føo., Actual: Føo. - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Basic numeric substitutions - Expected: '$0' '14' '' '5' '$$$$' '$'., Actual: '$0' '14' '' '5' '$$$$' '$'. - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Basic numeric substitutions, with non-array value - Expected: '$0' '' 'just a string' '' '$$$$' '$'., Actual: '$0' '' 'just a string' '' '$$$$' '$'. - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Named and numeric substitution - Expected: _foo_ (subst $1 $2) _bar_, Actual: _foo_ (subst $1 $2) _bar_ - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Numeric substitution amid named placeholders - Expected: (2 $1 $2), Actual: (2 $1 $2) - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Named placeholder with invalid key - Expected: $bad name$, Actual: $bad name$ - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Named placeholder with an invalid value - Expected: , Actual:  - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Named placeholder with a strange content value - Expected: Accepted, but shouldn't break., Actual: Accepted, but shouldn't break. - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_i18n - [test_i18n : 243] Non-placeholder token that should be ignored - Expected: $foo, Actual: $foo - true == true
15:34:35     INFO -  PID 13178 | Promise rejected after context unloaded: Message manager disconnected
15:34:35     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
15:34:35     INFO -  (xpcshell/head.js) | test test_i18n finished (2)
15:34:35     INFO -  xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | Starting test_get_accept_languages
15:34:35     INFO -  (xpcshell/head.js) | test test_get_accept_languages pending (2)
15:34:35     INFO -  "Extension attached"
15:34:35     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_accept_languages - [test_get_accept_languages : 243] got expected number of languages in contentScript - Expected: 2, Actual: 2 - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_accept_languages - [test_get_accept_languages : 243] got expected number of languages in background - Expected: 2, Actual: 2 - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_accept_languages - [test_get_accept_languages : 243] got expected language in background - Expected: en-US, Actual: en-US - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_accept_languages - [test_get_accept_languages : 243] got expected language in background - Expected: en, Actual: en - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_accept_languages - [test_get_accept_languages : 243] got expected language in contentScript - Expected: en-US, Actual: en-US - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_accept_languages - [test_get_accept_languages : 243] got expected language in contentScript - Expected: en, Actual: en - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_accept_languages - [test_get_accept_languages : 243] got expected number of languages in contentScript - Expected: 4, Actual: 4 - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_accept_languages - [test_get_accept_languages : 243] got expected number of languages in background - Expected: 4, Actual: 4 - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_accept_languages - [test_get_accept_languages : 243] got expected language in background - Expected: en-US, Actual: en-US - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_accept_languages - [test_get_accept_languages : 243] got expected language in background - Expected: en, Actual: en - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_accept_languages - [test_get_accept_languages : 243] got expected language in background - Expected: fr-CA, Actual: fr-CA - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_accept_languages - [test_get_accept_languages : 243] got expected language in background - Expected: fr, Actual: fr - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_accept_languages - [test_get_accept_languages : 243] got expected language in contentScript - Expected: en-US, Actual: en-US - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_accept_languages - [test_get_accept_languages : 243] got expected language in contentScript - Expected: en, Actual: en - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_accept_languages - [test_get_accept_languages : 243] got expected language in contentScript - Expected: fr-CA, Actual: fr-CA - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_accept_languages - [test_get_accept_languages : 243] got expected language in contentScript - Expected: fr, Actual: fr - true == true
15:34:35     INFO -  (xpcshell/head.js) | test run_next_test 2 pending (2)
15:34:35     INFO -  (xpcshell/head.js) | test test_get_accept_languages finished (2)
15:34:35     INFO -  xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | Starting test_get_ui_language
15:34:35     INFO -  (xpcshell/head.js) | test test_get_ui_language pending (2)
15:34:35     INFO -  "Extension attached"
15:34:35     INFO -  (xpcshell/head.js) | test run_next_test 2 finished (2)
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_ui_language - [test_get_ui_language : 243] Got expected getUILanguage result in background - Expected: en_US, Actual: en_US - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_ui_language - [test_get_ui_language : 243] Got expected getMessage result in background - Expected: en_US, Actual: en_US - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_ui_language - [test_get_ui_language : 243] Got expected getUILanguage result in contentScript - Expected: en_US, Actual: en_US - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_ui_language - [test_get_ui_language : 243] Got expected getMessage result in contentScript - Expected: en_US, Actual: en_US - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_ui_language - [test_get_ui_language : 243] Got expected getUILanguage result in background - Expected: he, Actual: he - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_ui_language - [test_get_ui_language : 243] Got expected getMessage result in background - Expected: he, Actual: he - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_ui_language - [test_get_ui_language : 243] Got expected getUILanguage result in contentScript - Expected: he, Actual: he - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_get_ui_language - [test_get_ui_language : 243] Got expected getMessage result in contentScript - Expected: he, Actual: he - true == true
15:34:35     INFO -  (xpcshell/head.js) | test run_next_test 3 pending (2)
15:34:35     INFO -  (xpcshell/head.js) | test test_get_ui_language finished (2)
15:34:35     INFO -  xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | Starting test_detect_language
15:34:35     INFO -  (xpcshell/head.js) | test test_detect_language pending (2)
15:34:35     INFO -  "Extension attached"
15:34:35     INFO -  (xpcshell/head.js) | test run_next_test 3 finished (2)
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_detect_language - [test_detect_language : 243] result.confident is true - Expected: true, Actual: true - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_detect_language - [test_detect_language : 243] result.languages contains the expected number of languages in background - Expected: 2, Actual: 2 - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_detect_language - [test_detect_language : 243] element 0 of result.languages array has the expected percentage in background - Expected: 67, Actual: 67 - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_detect_language - [test_detect_language : 243] element 0 of result.languages array has the expected language in background - Expected: fr, Actual: fr - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_detect_language - [test_detect_language : 243] element 1 of result.languages array has the expected percentage in background - Expected: 32, Actual: 32 - true == true
15:34:35     INFO -  TEST-PASS | xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | test_detect_language - [test_detect_language : 243] element 1 of result.languages array has the expected language in background - Expected: en, Actual: en - true == true
15:34:35     INFO -  <<<<<<<
15:34:35     INFO -  xpcshell return code: None
15:34:35     INFO -  xpcshell-remote.ini:toolkit/components/extensions/test/xpcshell/test_ext_i18n.js | Process still running after test!
Not sure what caused the spike in failures, but it looks like there's a race, and we expect the content script to be loaded before it's guaranteed to be loaded.
Assignee: nobody → kmaglione+bmo
Flags: needinfo?(kmaglione+bmo)
I'll look at fixing this along with the work I'm doing on bug 1374552.
Assignee: kmaglione+bmo → bob.silverberg
Comment on attachment 8886573 [details]
Bug 1347704 - Fix Intermittent test_ext_i18n.js,

https://reviewboard.mozilla.org/r/157400/#review163030
Attachment #8886573 - Flags: review?(kmaglione+bmo) → review+
Pushed by bsilverberg@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/cf70e6ecdc8c
Fix Intermittent test_ext_i18n.js, r=kmag
https://hg.mozilla.org/mozilla-central/rev/cf70e6ecdc8c
Status: NEW → RESOLVED
Closed: 7 years ago
Flags: in-testsuite+
Resolution: --- → FIXED
Whiteboard: [stockwell needswork] → [stockwell fixed]
Target Milestone: --- → mozilla56
Product: Toolkit → WebExtensions
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: