Closed Bug 1532550 Opened 1 year ago Closed 1 year ago

Intermittent telemetry/marionette/tests/client/test_search_counts_across_sessions.py TestSearchCounts.test_search_counts | KeyError: 'SEARCH_COUNTS'

Categories

(Toolkit :: Telemetry, defect, P5)

defect

Tracking

()

RESOLVED FIXED
mozilla68
Tracking Status
firefox-esr60 --- unaffected
firefox67 --- wontfix
firefox68 --- fixed

People

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

References

Details

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

Attachments

(1 file)

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

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

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

[task 2019-03-04T17:59:43.468Z] 17:59:43 INFO - 1551722383455 Toolkit.Telemetry TRACE TelemetryStorage::_scanArchive - checking in subdir: /builds/worker/workspace/build/tmp6CbdA9.mozrunner/datareporting/archived/2019-03
[task 2019-03-04T17:59:43.485Z] 17:59:43 INFO - 1551722383478 Toolkit.Telemetry TRACE TelemetryStorage::_enforceArchiveQuota
[task 2019-03-04T17:59:43.541Z] 17:59:43 INFO - 1551722383540 Marionette TRACE All scripts recorded.
[task 2019-03-04T17:59:43.569Z] 17:59:43 INFO - 1551722383566 Marionette INFO Listening on port 2828
[task 2019-03-04T17:59:43.570Z] 17:59:43 INFO - 1551722383566 Marionette DEBUG Remote service is active
[task 2019-03-04T17:59:43.594Z] 17:59:43 INFO - 1551722383592 Marionette DEBUG Accepted connection 0 from 127.0.0.1:50776
[task 2019-03-04T17:59:43.603Z] 17:59:43 INFO - 1551722383599 Marionette DEBUG Closed connection 0
[task 2019-03-04T17:59:43.607Z] 17:59:43 INFO - 1551722383602 Marionette DEBUG Accepted connection 1 from 127.0.0.1:50778
[task 2019-03-04T17:59:43.607Z] 17:59:43 INFO - 1551722383605 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2019-03-04T17:59:43.615Z] 17:59:43 INFO - 1551722383612 Marionette TRACE [8] Frame script loaded
[task 2019-03-04T17:59:43.615Z] 17:59:43 INFO - 1551722383612 Marionette TRACE [8] Frame script registered
[task 2019-03-04T17:59:43.623Z] 17:59:43 INFO - 1551722383616 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"28a93719-6e46-4530-b737-50bc39005e82","capabilities":{"browserName":"firefox","browserVersion":"67.0" ... ld/tmp6CbdA9.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2019-03-04T17:59:43.623Z] 17:59:43 INFO - 1551722383618 Marionette DEBUG 1 -> [0,2,"Marionette:SetContext",{"value":"content"}]
[task 2019-03-04T17:59:43.623Z] 17:59:43 INFO - 1551722383619 Marionette DEBUG 1 <- [1,2,null,{"value":null}]
[task 2019-03-04T17:59:43.624Z] 17:59:43 INFO - 1551722383621 Marionette DEBUG 1 -> [0,3,"Marionette:GetContext",{}]
[task 2019-03-04T17:59:43.624Z] 17:59:43 INFO - 1551722383621 Marionette DEBUG 1 <- [1,3,null,{"value":"content"}]
[task 2019-03-04T17:59:43.628Z] 17:59:43 INFO - 1551722383622 Marionette DEBUG 1 -> [0,4,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-03-04T17:59:43.628Z] 17:59:43 INFO - 1551722383622 Marionette DEBUG 1 <- [1,4,null,{"value":null}]
[task 2019-03-04T17:59:43.630Z] 17:59:43 INFO - 1551722383627 Marionette DEBUG 1 -> [0,5,"WebDriver:TakeScreenshot",{"highlights":null,"full":true,"hash":false,"id":null,"scroll":true}]
[task 2019-03-04T17:59:43.686Z] 17:59:43 INFO - 1551722383678 Marionette DEBUG 1 <- [1,5,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAQQCAYAAAC9RfbYAAAgAElEQVR4nOzd768c130naL30KJd/gCnDwMAIMKJwLy+vflEUbVGkyOusB ... kAAQAAACAwASAAAAAABCYABAAAAIDABIAAAAAAEJgAEAAAAAACEwACAAAAQGACQAAAAAAITAAIAAAAAIEJAAEAAAAgsP8D+IR5JE2BsUsAAAAASUVORK5CYII="}]
[task 2019-03-04T17:59:43.695Z] 17:59:43 INFO - 1551722383690 Marionette DEBUG 1 -> [0,6,"Marionette:SetContext",{"value":"content"}]
[task 2019-03-04T17:59:43.696Z] 17:59:43 INFO - 1551722383691 Marionette DEBUG 1 <- [1,6,null,{"value":null}]
[task 2019-03-04T17:59:43.698Z] 17:59:43 INFO - 1551722383693 Marionette DEBUG 1 -> [0,7,"Marionette:GetContext",{}]
[task 2019-03-04T17:59:43.700Z] 17:59:43 INFO - 1551722383693 Marionette DEBUG 1 <- [1,7,null,{"value":"content"}]
[task 2019-03-04T17:59:43.701Z] 17:59:43 INFO - 1551722383694 Marionette DEBUG 1 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2019-03-04T17:59:43.702Z] 17:59:43 INFO - 1551722383695 Marionette DEBUG 1 <- [1,8,null,{"value":null}]
[task 2019-03-04T17:59:43.704Z] 17:59:43 INFO - 1551722383697 Marionette DEBUG 1 -> [0,9,"WebDriver:GetPageSource",{}]
[task 2019-03-04T17:59:43.704Z] 17:59:43 INFO - 1551722383701 Marionette DEBUG 1 <- [1,9,null,{"value":"<html xmlns=\"http://www.w3.org/1999/xhtml\" dir=\"ltr\" lang=\"en-US\">\n<head>\n <title data-l10n-id=\ ... "about:webrtc\">about:webrtc</a></li><li><a href=\"about:welcome\">about:welcome</a></li></ul>\n </div>\n</body>\n</html>"}]
[task 2019-03-04T17:59:43.742Z] 17:59:43 INFO - TEST-UNEXPECTED-ERROR | telemetry/marionette/tests/client/test_search_counts_across_sessions.py TestSearchCounts.test_search_counts | KeyError: 'SEARCH_COUNTS'
[task 2019-03-04T17:59:43.743Z] 17:59:43 INFO - Traceback (most recent call last):
[task 2019-03-04T17:59:43.743Z] 17:59:43 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 159, in run
[task 2019-03-04T17:59:43.743Z] 17:59:43 INFO - testMethod()
[task 2019-03-04T17:59:43.743Z] 17:59:43 INFO - File "/builds/worker/workspace/build/tests/telemetry/marionette/tests/client/test_search_counts_across_sessions.py", line 92, in test_search_counts
[task 2019-03-04T17:59:43.743Z] 17:59:43 INFO - search_counts1 = keyed_histograms1["SEARCH_COUNTS"][
[task 2019-03-04T17:59:43.743Z] 17:59:43 INFO - TEST-INFO took 33671ms
[task 2019-03-04T17:59:43.743Z] 17:59:43 INFO - 1551722383706 Marionette DEBUG 1 -> [0,10,"Marionette:SetContext",{"value":"content"}]
[task 2019-03-04T17:59:43.743Z] 17:59:43 INFO - 1551722383706 Marionette DEBUG 1 <- [1,10,null,{"value":null}]
[task 2019-03-04T17:59:43.743Z] 17:59:43 INFO - 1551722383710 Marionette DEBUG 1 -> [0,11,"WebDriver:GetChromeWindowHandles",{}]
[task 2019-03-04T17:59:43.743Z] 17:59:43 INFO - 1551722383711 Marionette DEBUG 1 <- [1,11,null,["3"]]
[task 2019-03-04T17:59:43.745Z] 17:59:43 INFO - 1551722383712 Marionette DEBUG 1 -> [0,12,"WebDriver:GetWindowHandles",{}]
[task 2019-03-04T17:59:43.745Z] 17:59:43 INFO - 1551722383713 Marionette DEBUG 1 <- [1,12,null,["8"]]
[task 2019-03-04T17:59:43.802Z] 17:59:43 INFO - 1551722383798 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - Topic blocklist-loaded
[task 2019-03-04T17:59:43.810Z] 17:59:43 INFO - 1551722383805 Toolkit.Telemetry TRACE TelemetryEnvironment::_updateAddons
[task 2019-03-04T17:59:43.813Z] 17:59:43 INFO - 1551722383810 Toolkit.Telemetry TRACE TelemetryEnvironment::_updateAddons: addons differ
[task 2019-03-04T17:59:43.813Z] 17:59:43 INFO - 1551722383811 Toolkit.Telemetry TRACE TelemetryEnvironment::_startWatchingPrefs - [object Map]
[task 2019-03-04T17:59:44.354Z] 17:59:44 INFO - TEST-START | telemetry/marionette/tests/unit/test_ping_server_received_ping.py TestPingServer.test_ping_server_received_ping

Summary: Intermittent telemetry/marionette/tests/client/test_search_counts_across_sessions.py TestSearchCounts.test_search_counts | KeyError: 'SEARCH_COUNTS' → Intermittent Tier 2 telemetry/marionette/tests/client/test_search_counts_across_sessions.py TestSearchCounts.test_search_counts | KeyError: 'SEARCH_COUNTS'
Assignee: nobody → rpierzina
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Summary: Intermittent Tier 2 telemetry/marionette/tests/client/test_search_counts_across_sessions.py TestSearchCounts.test_search_counts | KeyError: 'SEARCH_COUNTS' → Intermittent telemetry/marionette/tests/client/test_search_counts_across_sessions.py TestSearchCounts.test_search_counts | KeyError: 'SEARCH_COUNTS'

This bug has failed 41 times in the last 7 days. Occurs on Linux platforms on opt and debug build types.

Logs:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=245802868&repo=autoland&lineNumber=1805

gfritzsche: Could you please take a look at this bug?

Flags: needinfo?(gfritzsche)
Whiteboard: [stockwell needswork]

I'm investigating this intermittent now.

See Also: → 1551485
Pushed by rpierzina@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/18ce39639db8
Wait for 0.1 seconds in telemetry-tests-client tests after performing search; r=janerik
Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla68
Flags: needinfo?(gfritzsche)
You need to log in before you can comment on or make changes to this bug.