1649034 - Intermittent toolkit/modules/tests/xpcshell/test_Region.js | test_update - [test_update : 123] Should have correct region - "AU" == "FR" | xpcshell return code: 0

Status: RESOLVED INCOMPLETE

(Toolkit :: General, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: dluca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=307807211&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/WYQRCkKCQOqLWWgnIFsOOA/runs/0/artifacts/public/logs/live_backing.log

---

INFO -  TEST-START | toolkit/modules/tests/xpcshell/test_Region.js
[task 2020-06-28T22:49:54.643Z] 22:49:54  WARNING -  TEST-UNEXPECTED-FAIL | toolkit/modules/tests/xpcshell/test_Region.js | xpcshell return code: 0
[task 2020-06-28T22:49:54.643Z] 22:49:54     INFO -  TEST-INFO took 727ms
[task 2020-06-28T22:49:54.643Z] 22:49:54     INFO -  >>>>>>>
[task 2020-06-28T22:49:54.643Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | xpcw: cd /data/local/tmp/tests/xpc/toolkit/modules/tests/xpcshell
[task 2020-06-28T22:49:54.645Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | xpcw: xpcshell -r /data/local/tmp/tests/xpc/c/httpd.manifest --greomni /data/local/xpcb/geckoview-androidTest.apk -m -e const _HEAD_JS_PATH = "/data/local/tmp/tests/xpc/head.js"; -e const _MOZINFO_JS_PATH = "/data/local/tmp/tests/xpc/p/mozinfo.json"; -e const _PREFS_FILE = "/data/local/tmp/tests/xpc/user.js"; -e const _TESTING_MODULES_DIR = "/data/local/tmp/tests/xpc/m"; -f /data/local/tmp/tests/xpc/head.js -e const _HEAD_FILES = ["/data/local/tmp/tests/xpc/toolkit/modules/tests/xpcshell/head.js"]; -e const _JSDEBUGGER_PORT = 0; -e const _TEST_FILE = ["test_Region.js"]; -e const _TEST_NAME = "toolkit/modules/tests/xpcshell/test_Region.js"; -e _execute_test(); quit(0);
[task 2020-06-28T22:49:54.645Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Unnamed thread 7b4a5c522010] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-06-28T22:49:54.646Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Unnamed thread 7b4a5c522010] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-06-28T22:49:54.646Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Unnamed thread 7b4a5c522010] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-06-28T22:49:54.647Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Unnamed thread 7b4a5c522010] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-06-28T22:49:54.647Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Main Thread] WARNING: No Android crash handler set: file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp, line 1986
[task 2020-06-28T22:49:54.648Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Main Thread] WARNING: No default pref files found.: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4281
[task 2020-06-28T22:49:54.648Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp, line 2924
[task 2020-06-28T22:49:54.649Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111 (NS_ERROR_NOT_AVAILABLE): file /builds/worker/checkouts/gecko/startupcache/StartupCacheUtils.cpp, line 154
[task 2020-06-28T22:49:54.649Z] 22:49:54     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2020-06-28T22:49:54.649Z] 22:49:54     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2020-06-28T22:49:54.649Z] 22:49:54     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2020-06-28T22:49:54.649Z] 22:49:54     INFO -  running event loop
[task 2020-06-28T22:49:54.649Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | Starting test_basic
[task 2020-06-28T22:49:54.650Z] 22:49:54     INFO -  (xpcshell/head.js) | test test_basic pending (2)
[task 2020-06-28T22:49:54.650Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, kKnownEsrVersion) failed with result 0x80004002 (NS_NOINTERFACE): file /builds/worker/checkouts/gecko/toolkit/components/resistfingerprinting/nsRFPService.cpp, line 673
[task 2020-06-28T22:49:54.650Z] 22:49:54     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2020-06-28T22:49:54.651Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Main Thread] WARNING: GetDnsSuffixList is not supported without a bridge connection: file /builds/worker/checkouts/gecko/netwerk/system/android/nsAndroidNetworkLinkService.cpp, line 139
[task 2020-06-28T22:49:54.651Z] 22:49:54     INFO -  TEST-PASS | toolkit/modules/tests/xpcshell/test_Region.js | test_basic - [test_basic : 31] Region fetch should succeed - true == true
[task 2020-06-28T22:49:54.652Z] 22:49:54     INFO -  TEST-PASS | toolkit/modules/tests/xpcshell/test_Region.js | test_basic - [test_basic : 32] Region fetch should return correct result - "UK" == "UK"
[task 2020-06-28T22:49:54.652Z] 22:49:54     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2020-06-28T22:49:54.652Z] 22:49:54     INFO -  (xpcshell/head.js) | test test_basic finished (2)
[task 2020-06-28T22:49:54.652Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | Starting test_invalid_url
[task 2020-06-28T22:49:54.652Z] 22:49:54     INFO -  (xpcshell/head.js) | test test_invalid_url pending (2)
[task 2020-06-28T22:49:54.653Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Main Thread] WARNING: port blocked: file /builds/worker/checkouts/gecko/netwerk/base/nsNetUtil.cpp, line 1067
[task 2020-06-28T22:49:54.653Z] 22:49:54     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2020-06-28T22:49:54.653Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | console.error: Region.jsm: "Error fetching region" (new TypeError("NetworkError when attempting to fetch resource.", "resource://gre/modules/Region.jsm", 619))
[task 2020-06-28T22:49:54.654Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | console.error: Region.jsm: "Failed to fetch region" (new Error("NO_RESULT", "resource://gre/modules/Region.jsm", 343))
[task 2020-06-28T22:49:54.654Z] 22:49:54     INFO -  TEST-PASS | toolkit/modules/tests/xpcshell/test_Region.js | test_invalid_url - [test_invalid_url : 41] Should return no result - true == true
[task 2020-06-28T22:49:54.654Z] 22:49:54     INFO -  (xpcshell/head.js) | test run_next_test 2 pending (2)
[task 2020-06-28T22:49:54.654Z] 22:49:54     INFO -  (xpcshell/head.js) | test test_invalid_url finished (2)
[task 2020-06-28T22:49:54.654Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | Starting test_invalid_json
[task 2020-06-28T22:49:54.654Z] 22:49:54     INFO -  (xpcshell/head.js) | test test_invalid_json pending (2)
[task 2020-06-28T22:49:54.654Z] 22:49:54     INFO -  (xpcshell/head.js) | test run_next_test 2 finished (2)
[task 2020-06-28T22:49:54.655Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | console.error: Region.jsm: "Error fetching region" (new SyntaxError("JSON.parse: end of data after property name when ':' was expected at line 1 column 16 of the JSON data", "/data/local/tmp/tests/xpc/head.js", 248))
[task 2020-06-28T22:49:54.655Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | console.error: Region.jsm: "Failed to fetch region" (new Error("NO_RESULT", "resource://gre/modules/Region.jsm", 343))
[task 2020-06-28T22:49:54.655Z] 22:49:54     INFO -  TEST-PASS | toolkit/modules/tests/xpcshell/test_Region.js | test_invalid_json - [test_invalid_json : 52] Should return no result - true == true
[task 2020-06-28T22:49:54.655Z] 22:49:54     INFO -  (xpcshell/head.js) | test run_next_test 3 pending (2)
[task 2020-06-28T22:49:54.655Z] 22:49:54     INFO -  (xpcshell/head.js) | test test_invalid_json finished (2)
[task 2020-06-28T22:49:54.655Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | Starting test_timeout
[task 2020-06-28T22:49:54.656Z] 22:49:54     INFO -  (xpcshell/head.js) | test test_timeout pending (2)
[task 2020-06-28T22:49:54.656Z] 22:49:54     INFO -  (xpcshell/head.js) | test run_next_test 3 finished (2)
[task 2020-06-28T22:49:54.656Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | console.error: Region.jsm: "Error fetching region" (new SyntaxError("JSON.parse: unexpected character at line 1 column 1 of the JSON data", "/data/local/tmp/tests/xpc/head.js", 248))
[task 2020-06-28T22:49:54.656Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | console.error: Region.jsm: "Failed to fetch region" (new Error("NO_RESULT", "resource://gre/modules/Region.jsm", 343))
[task 2020-06-28T22:49:54.656Z] 22:49:54     INFO -  TEST-PASS | toolkit/modules/tests/xpcshell/test_Region.js | test_timeout - [test_timeout : 70] Region fetch should return null - null == null
[task 2020-06-28T22:49:54.656Z] 22:49:54     INFO -  (xpcshell/head.js) | test run_next_test 4 pending (2)
[task 2020-06-28T22:49:54.657Z] 22:49:54     INFO -  (xpcshell/head.js) | test test_timeout finished (2)
[task 2020-06-28T22:49:54.657Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | Starting test_mismatched_probe
[task 2020-06-28T22:49:54.657Z] 22:49:54     INFO -  (xpcshell/head.js) | test test_mismatched_probe pending (2)
[task 2020-06-28T22:49:54.657Z] 22:49:54     INFO -  (xpcshell/head.js) | test run_next_test 4 finished (2)
[task 2020-06-28T22:49:54.657Z] 22:49:54     INFO -  TEST-PASS | toolkit/modules/tests/xpcshell/test_Region.js | test_mismatched_probe - [test_mismatched_probe : 91] Should have correct region - "AU" == "AU"
[task 2020-06-28T22:49:54.657Z] 22:49:54     INFO -  (xpcshell/head.js) | test run_next_test 5 pending (2)
[task 2020-06-28T22:49:54.657Z] 22:49:54     INFO -  (xpcshell/head.js) | test test_mismatched_probe finished (2)
[task 2020-06-28T22:49:54.658Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | Starting test_location
[task 2020-06-28T22:49:54.658Z] 22:49:54     INFO -  (xpcshell/head.js) | test test_location pending (2)
[task 2020-06-28T22:49:54.658Z] 22:49:54     INFO -  (xpcshell/head.js) | test run_next_test 5 finished (2)
[task 2020-06-28T22:49:54.658Z] 22:49:54     INFO -  TEST-PASS | toolkit/modules/tests/xpcshell/test_Region.js | test_location - [test_location : 113] Region fetch should succeed - true == true
[task 2020-06-28T22:49:54.658Z] 22:49:54     INFO -  TEST-PASS | toolkit/modules/tests/xpcshell/test_Region.js | test_location - [test_location : 114] Location is returned - {"location":{"lat":-1,"lng":1},"accuracy":100} deepEqual {"location":{"lat":-1,"lng":1},"accuracy":100}
[task 2020-06-28T22:49:54.658Z] 22:49:54     INFO -  (xpcshell/head.js) | test run_next_test 6 pending (2)
[task 2020-06-28T22:49:54.658Z] 22:49:54     INFO -  (xpcshell/head.js) | test test_location finished (2)
[task 2020-06-28T22:49:54.659Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | Starting test_update
[task 2020-06-28T22:49:54.659Z] 22:49:54     INFO -  (xpcshell/head.js) | test test_update pending (2)
[task 2020-06-28T22:49:54.659Z] 22:49:54     INFO -  (xpcshell/head.js) | test run_next_test 6 finished (2)
[task 2020-06-28T22:49:54.659Z] 22:49:54  WARNING -  TEST-UNEXPECTED-FAIL | toolkit/modules/tests/xpcshell/test_Region.js | test_update - [test_update : 123] Should have correct region - "AU" == "FR"
[task 2020-06-28T22:49:54.659Z] 22:49:54     INFO -  test_Region.js:test_update:123
[task 2020-06-28T22:49:54.659Z] 22:49:54     INFO -  /data/local/tmp/tests/xpc/head.js:_do_main:248
[task 2020-06-28T22:49:54.659Z] 22:49:54     INFO -  /data/local/tmp/tests/xpc/head.js:_execute_test:577
[task 2020-06-28T22:49:54.659Z] 22:49:54     INFO -  -e:null:1
[task 2020-06-28T22:49:54.659Z] 22:49:54     INFO -  exiting test
[task 2020-06-28T22:49:54.659Z] 22:49:54     INFO -  Unexpected exception NS_ERROR_ABORT:
[task 2020-06-28T22:49:54.660Z] 22:49:54     INFO -  _abort_failed_test@/data/local/tmp/tests/xpc/head.js:833:20
[task 2020-06-28T22:49:54.660Z] 22:49:54     INFO -  do_report_result@/data/local/tmp/tests/xpc/head.js:934:5
[task 2020-06-28T22:49:54.660Z] 22:49:54     INFO -  Assert<@/data/local/tmp/tests/xpc/head.js:73:21
[task 2020-06-28T22:49:54.660Z] 22:49:54     INFO -  proto.report@resource://testing-common/Assert.jsm:233:10
[task 2020-06-28T22:49:54.660Z] 22:49:54     INFO -  equal@resource://testing-common/Assert.jsm:275:8
[task 2020-06-28T22:49:54.660Z] 22:49:54     INFO -  test_update@test_Region.js:123:10
[task 2020-06-28T22:49:54.660Z] 22:49:54     INFO -  _do_main@/data/local/tmp/tests/xpc/head.js:248:6
[task 2020-06-28T22:49:54.660Z] 22:49:54     INFO -  _execute_test@/data/local/tmp/tests/xpc/head.js:577:5
[task 2020-06-28T22:49:54.660Z] 22:49:54     INFO -  @-e:1:1
[task 2020-06-28T22:49:54.660Z] 22:49:54     INFO -  exiting test
[task 2020-06-28T22:49:54.661Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-06-28T22:49:54.661Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Main Thread] WARNING: OOPDeinit() without successful OOPInit(): file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp, line 3370
[task 2020-06-28T22:49:54.661Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-06-28T22:49:54.661Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-06-28T22:49:54.662Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-06-28T22:49:54.662Z] 22:49:54     INFO -  toolkit/modules/tests/xpcshell/test_Region.js | [12166, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp, line 202
[task 2020-06-28T22:49:54.662Z] 22:49:54     INFO -  <<<<<<<
[task 2020-06-28T22:49:58.331Z] 22:49:58     INFO -  TEST-START | toolkit/modules/tests/xpcshell/test_Region_geocoding.js

Comment 1

[Dorel Luca [:dluca]]

This is likely a regression from Bug 1627555

Something to do with this changes:
https://hg.mozilla.org/mozilla-central/rev/edbcc90b27426c0e6eaae10a75178c8c42a23393#l15.39

Dale: Can you please take a look at this bug?

Comment 2

[Intermittent Failures Robot]

1 failures in 5252 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• android-em-7-0-x86_64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1649034&startday=2020-06-22&endday=2020-06-28&tree=all

Comment 3

[Dale Harvey (:daleharvey)]

Am I right that this has failed once ever on Android?

It looks very unlikely to have been caused by my patch, I cant reproduce locally nor on treeherder, having a hard time following the log output on treeherder but am seeing a lot of crash data being reported. Right now I "think" this just happened to crash coincedently during the test that had recently changed.

Comment 4

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

Set release status flags based on info from the regressing bug 1627555

Comment 5

[Dale Harvey (:daleharvey)]

ok still cant get this to reproduce and only ever seen it once on treeherder so closing WFM

Comment 6

[Bogdan Tara[:bogdan_tara | bogdant]]

This popped up again: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=308645656&repo=autoland&lineNumber=2645

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• android-em-7-0-x86_64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1649034&startday=2020-07-06&endday=2020-07-12&tree=all

Comment 8

[Dale Harvey (:daleharvey)]

Having a hard time reproducing / getting to the bottom of this, the only thing that really makes sense is that the datauri in the pref is returning a stale value, but that also doesnt really make sense. Not currently working on this so deassigning

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• android-em-7-0-x86_64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1649034&startday=2020-07-27&endday=2020-08-02&tree=all

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• autoland: 2

Platform breakdown:

• android-em-7-0-x86_64: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1649034&startday=2020-08-03&endday=2020-08-09&tree=all

Comment 11

[Intermittent Failures Robot]

1 failures in 4100 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• android-em-7-0-x86_64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1649034&startday=2020-08-17&endday=2020-08-23&tree=all

Comment 12

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

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.