1649546 - Intermittent TEST-UNEXPECTED-TIMEOUT | toolkit/components/search/tests/xpcshell/test_geodefaults.js | Test timed out

Status: RESOLVED INCOMPLETE

(Firefox :: Search, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-06-30T18:20:09.552Z] 18:20:09 INFO - TEST-START | xpcshell.ini:toolkit/components/search/tests/xpcshell/test_geodefaults.js
[task 2020-06-30T18:25:09.679Z] 18:25:09 WARNING - TEST-UNEXPECTED-TIMEOUT | xpcshell.ini:toolkit/components/search/tests/xpcshell/test_geodefaults.js | Test timed out
[task 2020-06-30T18:25:09.679Z] 18:25:09 INFO - TEST-INFO took 300137ms
[task 2020-06-30T18:25:09.679Z] 18:25:09 INFO - >>>>>>>
[task 2020-06-30T18:25:09.679Z] 18:25:09 INFO - PID 4320 | [4320, 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 2926
[task 2020-06-30T18:25:09.680Z] 18:25:09 INFO - (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2020-06-30T18:25:09.680Z] 18:25:09 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2020-06-30T18:25:09.680Z] 18:25:09 INFO - (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2020-06-30T18:25:09.680Z] 18:25:09 INFO - running event loop
[task 2020-06-30T18:25:09.680Z] 18:25:09 INFO - xpcshell.ini:toolkit/components/search/tests/xpcshell/test_geodefaults.js | Starting no_request_if_prefed_off
[task 2020-06-30T18:25:09.680Z] 18:25:09 INFO - (xpcshell/head.js) | test no_request_if_prefed_off pending (2)
[task 2020-06-30T18:25:09.680Z] 18:25:09 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2020-06-30T18:25:09.680Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "init"
[task 2020-06-30T18:25:09.680Z] 18:25:09 INFO - PID 4320 | console.info: Region.jsm: "_getRegion called"
[task 2020-06-30T18:25:09.680Z] 18:25:09 INFO - PID 4320 | console.info: Region.jsm: "_getRegion url is: " ""
[task 2020-06-30T18:25:09.681Z] 18:25:09 INFO - PID 4320 | console.error: SearchCache: "_readCacheFile: Error reading cache file:" (new Error("", "(unknown module)"))
[task 2020-06-30T18:25:09.681Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "_loadEnginesLegacy: start"
[task 2020-06-30T18:25:09.681Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "_findEnginesLegacy: looking for engines in list.json"
[task 2020-06-30T18:25:09.681Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "_loadEnginesLegacy: Absent or outdated cache. Loading engines from disk."
[task 2020-06-30T18:25:09.681Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: " "[e-mail]"
[task 2020-06-30T18:25:09.681Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: Installing " "[e-mail]"
[task 2020-06-30T18:25:09.681Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "_handleIgnoreListUpdated"
[task 2020-06-30T18:25:09.681Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: [e-mail]"
[task 2020-06-30T18:25:09.681Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: Ignoring builtIn engine."
[task 2020-06-30T18:25:09.681Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "installExtensionEngine:" "[e-mail]"
[task 2020-06-30T18:25:09.682Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: installing:" "[e-mail]" ":" "b-d"
[task 2020-06-30T18:25:09.682Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEngineWithDetails: Adding" "Google"
[task 2020-06-30T18:25:09.682Z] 18:25:09 INFO - PID 4320 | console.debug: SearchEngine: "_setIcon: Setting icon url for" "Google" "to" "moz-extension://776e2bd1-cc29-ec44-b5e2-47365431ab36/favicon.ico"
[task 2020-06-30T18:25:09.682Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "_addEngineToStore: Adding engine:" "Google"
[task 2020-06-30T18:25:09.682Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: " "[e-mail]" " installed."
[task 2020-06-30T18:25:09.682Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: " "[e-mail]"
[task 2020-06-30T18:25:09.682Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: Installing " "[e-mail]"
[task 2020-06-30T18:25:09.682Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: [e-mail]"
[task 2020-06-30T18:25:09.682Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: Ignoring builtIn engine."
[task 2020-06-30T18:25:09.682Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "installExtensionEngine:" "[e-mail]"
[task 2020-06-30T18:25:09.683Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: installing:" "[e-mail]" ":" "default"
[task 2020-06-30T18:25:09.683Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEngineWithDetails: Adding" "Amazon.com"
[task 2020-06-30T18:25:09.683Z] 18:25:09 INFO - PID 4320 | console.debug: SearchEngine: "_setIcon: Setting icon url for" "Amazon.com" "to" "moz-extension://b9237cf6-0e76-7a41-93ec-640356ebd469/favicon.ico"
[task 2020-06-30T18:25:09.683Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "_addEngineToStore: Adding engine:" "Amazon.com"
[task 2020-06-30T18:25:09.683Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: " "[e-mail]" " installed."
[task 2020-06-30T18:25:09.683Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: " "[e-mail]"
[task 2020-06-30T18:25:09.683Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: Installing " "[e-mail]"
[task 2020-06-30T18:25:09.683Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: [e-mail]"
[task 2020-06-30T18:25:09.683Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: Ignoring builtIn engine."
[task 2020-06-30T18:25:09.684Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "installExtensionEngine:" "[e-mail]"
[task 2020-06-30T18:25:09.684Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: installing:" "[e-mail]" ":" "default"
[task 2020-06-30T18:25:09.684Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEngineWithDetails: Adding" "Bing"
[task 2020-06-30T18:25:09.684Z] 18:25:09 INFO - PID 4320 | console.debug: SearchEngine: "_setIcon: Setting icon url for" "Bing" "to" "moz-extension://9cad9a82-592d-5e4b-bb57-c89eb4bd12f6/favicon.ico"
[task 2020-06-30T18:25:09.684Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "_addEngineToStore: Adding engine:" "Bing"
[task 2020-06-30T18:25:09.684Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: " "[e-mail]" " installed."
[task 2020-06-30T18:25:09.684Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: " "[e-mail]"
[task 2020-06-30T18:25:09.684Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: Installing " "[e-mail]"
[task 2020-06-30T18:25:09.684Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: [e-mail]"
[task 2020-06-30T18:25:09.684Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: Ignoring builtIn engine."
[task 2020-06-30T18:25:09.685Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "installExtensionEngine:" "[e-mail]"
[task 2020-06-30T18:25:09.689Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: installing:" "[e-mail]" ":" "default"
[task 2020-06-30T18:25:09.689Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEngineWithDetails: Adding" "DuckDuckGo"
[task 2020-06-30T18:25:09.689Z] 18:25:09 INFO - PID 4320 | console.debug: SearchEngine: "_setIcon: Setting icon url for" "DuckDuckGo" "to" "moz-extension://af75074a-786e-3844-b447-c3e65b3a600f/favicon.ico"
[task 2020-06-30T18:25:09.690Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "_addEngineToStore: Adding engine:" "DuckDuckGo"
[task 2020-06-30T18:25:09.690Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: " "[e-mail]" " installed."
[task 2020-06-30T18:25:09.690Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: " "[e-mail]"
[task 2020-06-30T18:25:09.690Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: Installing " "[e-mail]"
[task 2020-06-30T18:25:09.690Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: [e-mail]"
[task 2020-06-30T18:25:09.690Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: Ignoring builtIn engine."
[task 2020-06-30T18:25:09.690Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "installExtensionEngine:" "[e-mail]"
[task 2020-06-30T18:25:09.690Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: installing:" "[e-mail]" ":" "default"
[task 2020-06-30T18:25:09.690Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEngineWithDetails: Adding" "eBay"
[task 2020-06-30T18:25:09.690Z] 18:25:09 INFO - PID 4320 | console.debug: SearchEngine: "_setIcon: Setting icon url for" "eBay" "to" "moz-extension://7c7eed9f-164e-804b-ae4c-10cc2e333e9f/favicon.ico"
[task 2020-06-30T18:25:09.691Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "_addEngineToStore: Adding engine:" "eBay"
[task 2020-06-30T18:25:09.691Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: " "[e-mail]" " installed."
[task 2020-06-30T18:25:09.691Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: " "[e-mail]"
[task 2020-06-30T18:25:09.691Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: Installing " "[e-mail]"
[task 2020-06-30T18:25:09.691Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: [e-mail]"
[task 2020-06-30T18:25:09.691Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: Ignoring builtIn engine."
[task 2020-06-30T18:25:09.691Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "installExtensionEngine:" "[e-mail]"
[task 2020-06-30T18:25:09.691Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEnginesFromExtension: installing:" "[e-mail]" ":" "default"
[task 2020-06-30T18:25:09.691Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEngineWithDetails: Adding" "Wikipedia (en)"
[task 2020-06-30T18:25:09.691Z] 18:25:09 INFO - PID 4320 | console.debug: SearchEngine: "_setIcon: Setting icon url for" "Wikipedia (en)" "to" "moz-extension://1921dd2c-a142-e245-8aff-7b11671cfd44/favicon.ico"
[task 2020-06-30T18:25:09.692Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "_addEngineToStore: Adding engine:" "Wikipedia (en)"
[task 2020-06-30T18:25:09.692Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "ensureBuiltinExtension: " "[e-mail]" " installed."
[task 2020-06-30T18:25:09.692Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "_loadEnginesLegacy: loading" 0 "engines reported by AddonManager startup"
[task 2020-06-30T18:25:09.692Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "_loadEnginesLegacy: loading user-installed engines from the obsolete cache"
[task 2020-06-30T18:25:09.692Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "_loadEnginesLegacy: done using rebuilt cache"
[task 2020-06-30T18:25:09.692Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "_init: engines loaded, writing cache"
[task 2020-06-30T18:25:09.692Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "Completed _init"
[task 2020-06-30T18:25:09.692Z] 18:25:09 INFO - PID 4320 | console.debug: SearchCache: "batchTask: Invalidating engine cache"
[task 2020-06-30T18:25:09.692Z] 18:25:09 INFO - PID 4320 | console.debug: SearchCache: "_buildCache: Writing to cache file."
[task 2020-06-30T18:25:09.692Z] 18:25:09 INFO - PID 4320 | console.debug: SearchCache: "_buildCache: cache file written to disk."
[task 2020-06-30T18:25:09.693Z] 18:25:09 INFO - "Adding engine: Test search engine"
[task 2020-06-30T18:25:09.693Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "addEngine: Adding" "http://localhost:55567/data/engine.xml"
[task 2020-06-30T18:25:09.693Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "init"
[task 2020-06-30T18:25:09.693Z] 18:25:09 INFO - PID 4320 | console.debug: SearchEngine: "_initFromURIAndLoad: Downloading engine from:" "http://localhost:55567/data/engine.xml"
[task 2020-06-30T18:25:09.693Z] 18:25:09 INFO - PID 4320 | console.debug: SearchEngine: "loadListener: Starting request:" "http://localhost:55567/data/engine.xml"
[task 2020-06-30T18:25:09.693Z] 18:25:09 INFO - PID 4320 | console.debug: SearchEngine: "loadListener: Stopping request:" "http://localhost:55567/data/engine.xml"
[task 2020-06-30T18:25:09.693Z] 18:25:09 INFO - PID 4320 | console.debug: SearchEngine: "Initing search plugin from" "http://localhost:55567/data/engine.xml"
[task 2020-06-30T18:25:09.693Z] 18:25:09 INFO - PID 4320 | console.debug: SearchEngine: "_setIcon: Setting icon url for" "Test search engine" "to" "data:image/png;base64,AAABAAEAEBAAAAEAGABoAwAAFgAAACgAAAAQAAAAIAAAAAEAGAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAADs9Pt8xetPtu9FsfFNtu%2BTzvb2%2B%..."
[task 2020-06-30T18:25:09.693Z] 18:25:09 INFO - PID 4320 | console.debug: SearchUtils: "NOTIFY: Engine:" "Test search engine" "Verb:" "engine-loaded"
[task 2020-06-30T18:25:09.693Z] 18:25:09 INFO - "Observed engine-loaded for Test search engine"
[task 2020-06-30T18:25:09.694Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "observe: Done installation of " "Test search engine"
[task 2020-06-30T18:25:09.694Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "_addEngineToStore: Adding engine:" "Test search engine"
[task 2020-06-30T18:25:09.694Z] 18:25:09 INFO - PID 4320 | console.debug: SearchUtils: "NOTIFY: Engine:" "Test search engine" "Verb:" "engine-added"
[task 2020-06-30T18:25:09.694Z] 18:25:09 INFO - "Observed engine-added for Test search engine"
[task 2020-06-30T18:25:09.694Z] 18:25:09 INFO - PID 4320 | console.debug: SearchCache: "batchTask: Invalidating engine cache"
[task 2020-06-30T18:25:09.694Z] 18:25:09 INFO - PID 4320 | console.debug: SearchCache: "_buildCache: Writing to cache file."
[task 2020-06-30T18:25:09.694Z] 18:25:09 INFO - PID 4320 | console.debug: SearchCache: "_buildCache: cache file written to disk."
[task 2020-06-30T18:25:09.694Z] 18:25:09 INFO - PID 4320 | console.debug: SearchService: "init"
[task 2020-06-30T18:25:09.694Z] 18:25:09 INFO - TEST-PASS | xpcshell.ini:toolkit/components/search/tests/xpcshell/test_geodefaults.js

Comment 1

[Intermittent Failures Robot]

7 failures in 4516 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 6
• mozilla-central: 1

Platform breakdown:

• macosx1014-64: 4
• macosx1014-64-shippable: 3

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

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 16
• comm-central: 1
• mozilla-central: 1

Platform breakdown:

• macosx1014-64: 12
• linux1804-64: 1
• macosx1014-64-shippable: 4
• linux1804-64-tsan: 1

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

Comment 3

[Intermittent Failures Robot]

8 failures in 4014 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• autoland: 7

Platform breakdown:

• linux1804-64-ccov: 1
• macosx1014-64-shippable: 3
• macosx1014-64: 3
• windows10-64: 1

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

Comment 4

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

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