Closed Bug 1649034 Opened 4 years ago Closed 4 years ago

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

Categories

(Toolkit :: General, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE
Tracking Status
firefox-esr68 --- unaffected
firefox-esr78 --- unaffected
firefox78 --- unaffected
firefox79 --- wontfix
firefox80 --- wontfix

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

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

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?

Flags: needinfo?(dharvey)
Regressed by: 1627555
Has Regression Range: --- → yes
Keywords: regression

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.

Flags: needinfo?(dharvey)
Assignee: nobody → dharvey

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

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

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → WORKSFORME
Status: RESOLVED → REOPENED
Resolution: WORKSFORME → ---

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

Assignee: dharvey → nobody
Status: REOPENED → RESOLVED
Closed: 4 years ago4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.