Closed Bug 1254202 Opened 8 years ago Closed 7 years ago

Intermittent browser_UITour_sync.js | Test timed out

Categories

(Firefox :: Tours, defect, P3)

x86_64
macOS
defect

Tracking

()

RESOLVED INCOMPLETE
Tracking Status
firefox47 --- affected

People

(Reporter: aryx, Unassigned)

References

Details

(Keywords: intermittent-failure)

+++ This bug was initially created as a clone of Bug #984659 +++

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

09:29:46     INFO -  199 INFO TEST-START | browser/components/uitour/test/browser_UITour_loop_panel.js
09:29:46     INFO -  console.error: Loop:
09:29:46     INFO -    TelemetryAddValue failed for histogram 'LOOP_MAU'
09:29:46     INFO -    Message: [Exception... "Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsITelemetry.getHistogramById]"  nsresult: "0x80070057 (NS_ERROR_ILLEGAL_VALUE)"  location: "JS frame :: chrome://loop/content/modules/MozLoopAPI.jsm :: kMessageHandlers.TelemetryAddValue :: line 1068"  data: no]
09:29:46     INFO -    Stack:
09:29:46     INFO -      kMessageHandlers.TelemetryAddValue@chrome://loop/content/modules/MozLoopAPI.jsm:1068:11
09:29:46     INFO -  this.LoopAPI<.sendMessageToHandler@chrome://loop/content/modules/MozLoopAPI.jsm:1388:7
09:29:46     INFO -  WindowListener.setupBrowserUI/LoopUI.openPanel/</callback@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/loop@mozilla.org.xpi!/bootstrap.js:190:13
09:29:46     INFO -  PanelFrame.showPopup@resource://app/modules/PanelFrame.jsm:193:7
09:29:46     INFO -  WindowListener.setupBrowserUI/LoopUI.openPanel/</<@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/loop@mozilla.org.xpi!/bootstrap.js:212:13
09:29:46     INFO -  Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23
09:29:46     INFO -  this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
09:29:46     INFO -  Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
09:29:46     INFO -  this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
09:29:46     INFO -  Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:454:5
09:29:46     INFO -  WindowListener.setupBrowserUI/LoopUI.openPanel/<@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/loop@mozilla.org.xpi!/bootstrap.js:199:11
09:29:46     INFO -  WindowListener.setupBrowserUI/LoopUI.openPanel@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/loop@mozilla.org.xpi!/bootstrap.js:178:16
09:29:46     INFO -  this.UITour.showMenu@resource://app/modules/UITour.jsm:1756:7
09:29:46     INFO -  this.UITour.onPageEvent@resource://app/modules/UITour.jsm:568:9
09:29:46     INFO -  @resource://app/components/nsBrowserGlue.js:3220:3
09:29:47     INFO -  MEMORY STAT | vsize 3866MB | residentFast 429MB | heapAllocated 120MB
09:29:47     INFO -  200 INFO TEST-OK | browser/components/uitour/test/browser_UITour_loop_panel.js | took 914ms
09:29:47     INFO -  201 INFO TEST-START | browser/components/uitour/test/browser_UITour_modalDialog.js
09:29:49     INFO -  MEMORY STAT | vsize 3874MB | residentFast 436MB | heapAllocated 123MB
09:29:49     INFO -  202 INFO TEST-OK | browser/components/uitour/test/browser_UITour_modalDialog.js | took 1946ms
09:29:49     INFO -  203 INFO TEST-START | browser/components/uitour/test/browser_UITour_observe.js
09:29:50     INFO -  MEMORY STAT | vsize 3886MB | residentFast 448MB | heapAllocated 128MB
09:29:50     INFO -  204 INFO TEST-OK | browser/components/uitour/test/browser_UITour_observe.js | took 1653ms
09:29:50     INFO -  205 INFO TEST-START | browser/components/uitour/test/browser_UITour_pocket.js
09:29:50     INFO -  MEMORY STAT | vsize 3886MB | residentFast 448MB | heapAllocated 128MB
09:29:50     INFO -  206 INFO TEST-OK | browser/components/uitour/test/browser_UITour_pocket.js | took 34ms
09:29:51     INFO -  207 INFO TEST-START | browser/components/uitour/test/browser_UITour_registerPageID.js
09:29:52     INFO -  MEMORY STAT | vsize 3887MB | residentFast 450MB | heapAllocated 133MB
09:29:52     INFO -  208 INFO TEST-OK | browser/components/uitour/test/browser_UITour_registerPageID.js | took 944ms
09:29:52     INFO -  209 INFO TEST-START | browser/components/uitour/test/browser_UITour_resetProfile.js
09:29:52     INFO -  MEMORY STAT | vsize 3887MB | residentFast 450MB | heapAllocated 135MB
09:29:52     INFO -  210 INFO TEST-OK | browser/components/uitour/test/browser_UITour_resetProfile.js | took 804ms
09:29:52     INFO -  211 INFO TEST-START | browser/components/uitour/test/browser_UITour_sync.js
09:32:30     INFO -  TEST-INFO | started process screencapture
09:32:31     INFO -  TEST-INFO | screencapture: exit 0
09:32:31     INFO -  <snipped 4 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
09:32:31     INFO -  212 INFO Entering test bound test_checkSyncSetup_disabled
09:32:31     INFO -  213 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Sync shouldn't be setup by default -
09:32:31     INFO -  214 INFO == Done test, doing shared checks before teardown ==
09:32:31     INFO -  215 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Element should not be null, when checking visibility -
09:32:31     INFO -  216 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Highlight should be closed/hidden after UITour tab is closed -
09:32:31     INFO -  217 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Element should not be null, when checking visibility -
09:32:31     INFO -  218 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Tooltip should be closed/hidden after UITour tab is closed -
09:32:31     INFO -  219 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | @noautohide on the menu panel should have been cleaned up -
09:32:31     INFO -  220 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | The panel shouldn't have @panelopen -
09:32:31     INFO -  221 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | The panel shouldn't be open -
09:32:31     INFO -  222 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Menu button should know that the menu is closed -
09:32:31     INFO -  223 INFO Done shared checks
09:32:31     INFO -  224 INFO Leaving test bound test_checkSyncSetup_disabled
09:32:31     INFO -  225 INFO Entering test bound test_checkSyncSetup_enabled
09:32:31     INFO -  226 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Sync should be setup -
09:32:31     INFO -  227 INFO == Done test, doing shared checks before teardown ==
09:32:31     INFO -  228 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Element should not be null, when checking visibility -
09:32:31     INFO -  229 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Highlight should be closed/hidden after UITour tab is closed -
09:32:31     INFO -  230 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Element should not be null, when checking visibility -
09:32:31     INFO -  231 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Tooltip should be closed/hidden after UITour tab is closed -
09:32:31     INFO -  232 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | @noautohide on the menu panel should have been cleaned up -
09:32:31     INFO -  233 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | The panel shouldn't have @panelopen -
09:32:31     INFO -  234 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | The panel shouldn't be open -
09:32:31     INFO -  235 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Menu button should know that the menu is closed -
09:32:31     INFO -  236 INFO Done shared checks
09:32:31     INFO -  237 INFO Leaving test bound test_checkSyncSetup_enabled
09:32:31     INFO -  238 INFO Entering test bound test_firefoxAccountsNoParams
09:32:31     INFO -  239 INFO Console message: [JavaScript Warning: "Error in parsing value for '-webkit-text-size-adjust'.  Declaration dropped." {file: "chrome://browser/content/aboutaccounts/normalize.css" line: 69 column: 30 source: "    -webkit-text-size-adjust: 100%; /* 2 */"}]
09:32:31     INFO -  240 INFO Console message: [JavaScript Warning: "Unknown pseudo-class or pseudo-element '-webkit-search-cancel-button'.  Ruleset ignored due to bad selector." {file: "chrome://browser/content/aboutaccounts/normalize.css" line: 366 column: 22 source: "input[type="search"]::-webkit-search-cancel-button,"}]
09:32:31     INFO -  241 INFO Console message: [JavaScript Warning: "Error in parsing value for 'min-height'.  Declaration dropped." {file: "chrome://browser/content/aboutaccounts/main.css" line: 141 column: 16 source: "    min-height: none;"}]
09:32:31     INFO -  242 INFO Console message: [JavaScript Warning: "Expected media feature name but found 'min-device-pixel-ratio'." {file: "chrome://browser/content/aboutaccounts/main.css" line: 160 column: 17 source: "only screen and (min-device-pixel-ratio: 2),"}]
09:32:31     INFO -  243 INFO == Done test, doing shared checks before teardown ==
09:32:31     INFO -  244 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Element should not be null, when checking visibility -
09:32:31     INFO -  245 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Highlight should be closed/hidden after UITour tab is closed -
09:32:31     INFO -  246 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Element should not be null, when checking visibility -
09:32:31     INFO -  247 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Tooltip should be closed/hidden after UITour tab is closed -
09:32:31     INFO -  248 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | @noautohide on the menu panel should have been cleaned up -
09:32:31     INFO -  249 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | The panel shouldn't have @panelopen -
09:32:31     INFO -  250 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | The panel shouldn't be open -
09:32:31     INFO -  251 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Menu button should know that the menu is closed -
09:32:31     INFO -  252 INFO Done shared checks
09:32:31     INFO -  253 INFO Leaving test bound test_firefoxAccountsNoParams
09:32:31     INFO -  254 INFO Entering test bound test_firefoxAccountsValidParams
09:32:31     INFO -  255 INFO == Done test, doing shared checks before teardown ==
09:32:31     INFO -  256 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Element should not be null, when checking visibility -
09:32:31     INFO -  257 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Highlight should be closed/hidden after UITour tab is closed -
09:32:31     INFO -  258 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Element should not be null, when checking visibility -
09:32:31     INFO -  259 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Tooltip should be closed/hidden after UITour tab is closed -
09:32:31     INFO -  260 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | @noautohide on the menu panel should have been cleaned up -
09:32:31     INFO -  261 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | The panel shouldn't have @panelopen -
09:32:31     INFO -  262 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | The panel shouldn't be open -
09:32:31     INFO -  263 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Menu button should know that the menu is closed -
09:32:31     INFO -  264 INFO Done shared checks
09:32:31     INFO -  265 INFO Leaving test bound test_firefoxAccountsValidParams
09:32:31     INFO -  266 INFO Entering test bound test_firefoxAccountsNonAlphaValue
09:32:31     INFO -  267 INFO == Done test, doing shared checks before teardown ==
09:32:31     INFO -  268 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Element should not be null, when checking visibility -
09:32:31     INFO -  269 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Highlight should be closed/hidden after UITour tab is closed -
09:32:31     INFO -  270 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Element should not be null, when checking visibility -
09:32:31     INFO -  271 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Tooltip should be closed/hidden after UITour tab is closed -
09:32:31     INFO -  272 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | @noautohide on the menu panel should have been cleaned up -
09:32:31     INFO -  273 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | The panel shouldn't have @panelopen -
09:32:31     INFO -  274 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | The panel shouldn't be open -
09:32:31     INFO -  275 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Menu button should know that the menu is closed -
09:32:31     INFO -  276 INFO Done shared checks
09:32:31     INFO -  277 INFO Leaving test bound test_firefoxAccountsNonAlphaValue
09:32:31     INFO -  278 INFO Entering test bound test_firefoxAccountsNonObject
09:32:31     INFO -  279 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | No about:accounts tab opened -
09:32:31     INFO -  280 INFO == Done test, doing shared checks before teardown ==
09:32:31     INFO -  281 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Element should not be null, when checking visibility -
09:32:31     INFO -  282 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Highlight should be closed/hidden after UITour tab is closed -
09:32:31     INFO -  283 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Element should not be null, when checking visibility -
09:32:31     INFO -  284 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Tooltip should be closed/hidden after UITour tab is closed -
09:32:31     INFO -  285 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | @noautohide on the menu panel should have been cleaned up -
09:32:31     INFO -  286 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | The panel shouldn't have @panelopen -
09:32:31     INFO -  287 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | The panel shouldn't be open -
09:32:31     INFO -  288 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Menu button should know that the menu is closed -
09:32:31     INFO -  289 INFO Done shared checks
09:32:31     INFO -  290 INFO Leaving test bound test_firefoxAccountsNonObject
09:32:31     INFO -  291 INFO Entering test bound test_firefoxAccountsNonUtmPrefix
09:32:31     INFO -  292 INFO Console message: [JavaScript Error: "TelemetryStopwatch: key "FX_TAB_SWITCH_TOTAL_MS" was already initialized" {file: "resource://gre/modules/TelemetryStopwatch.jsm" line: 282}]
09:32:31     INFO -  this.TelemetryStopwatchImpl.start@resource://gre/modules/TelemetryStopwatch.jsm:282:7
09:32:31     INFO -  this.TelemetryStopwatch.start@resource://gre/modules/TelemetryStopwatch.jsm:136:12
09:32:31     INFO -  updateCurrentBrowser@chrome://browser/content/tabbrowser.xml:1034:17
09:32:31     INFO -  onselect@chrome://browser/content/browser.xul:1:44
09:32:31     INFO -  set_selectedIndex@chrome://global/content/bindings/tabbox.xml:656:13
09:32:31     INFO -  set_selectedPanel@chrome://global/content/bindings/tabbox.xml:675:13
09:32:31     INFO -  set_selectedIndex@chrome://global/content/bindings/tabbox.xml:403:15
09:32:31     INFO -  set_selectedItem@chrome://global/content/bindings/tabbox.xml:435:34
09:32:31     INFO -  set_selectedTab@chrome://global/content/bindings/tabbox.xml:114:15
09:32:31     INFO -  set_selectedTab@chrome://browser/content/tabbrowser.xml:2826:11
09:32:31     INFO -  loadUITourTestPage@chrome://mochitests/content/browser/browser/components/uitour/test/head.js:247:3
09:32:31     INFO -  add_UITour_task/genFun/</<@chrome://mochitests/content/browser/browser/components/uitour/test/head.js:385:9
09:32:31     INFO -  SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:741:59
09:32:31     INFO -  293 INFO Console message: [JavaScript Error: "TelemetryStopwatch: requesting elapsed time for nonexisting stopwatch. Histogram: "FX_TAB_SWITCH_TOTAL_MS", key: "null"" {file: "resource://gre/modules/TelemetryStopwatch.jsm" line: 297}]
09:32:31     INFO -  this.TelemetryStopwatchImpl.timeElapsed@resource://gre/modules/TelemetryStopwatch.jsm:297:7
09:32:31     INFO -  this.TelemetryStopwatchImpl.finish@resource://gre/modules/TelemetryStopwatch.jsm:315:17
09:32:31     INFO -  this.TelemetryStopwatch.finish@resource://gre/modules/TelemetryStopwatch.jsm:192:12
09:32:31     INFO -  onMozAfterPaint@chrome://browser/content/tabbrowser.xml:1036:19
09:32:31     INFO -  EventListener.handleEvent*updateCurrentBrowser@chrome://browser/content/tabbrowser.xml:1035:17
09:32:31     INFO -  onselect@chrome://browser/content/browser.xul:1:44
09:32:31     INFO -  set_selectedIndex@chrome://global/content/bindings/tabbox.xml:656:13
09:32:31     INFO -  set_selectedPanel@chrome://global/content/bindings/tabbox.xml:675:13
09:32:31     INFO -  set_selectedIndex@chrome://global/content/bindings/tabbox.xml:403:15
09:32:31     INFO -  set_selectedItem@chrome://global/content/bindings/tabbox.xml:435:34
09:32:31     INFO -  set_selectedTab@chrome://global/content/bindings/tabbox.xml:114:15
09:32:31     INFO -  set_selectedTab@chrome://browser/content/tabbrowser.xml:2826:11
09:32:31     INFO -  _blurTab@chrome://browser/content/tabbrowser.xml:2477:13
09:32:31     INFO -  _endRemoveTab@chrome://browser/content/tabbrowser.xml:2351:13
09:32:31     INFO -  removeTab@chrome://browser/content/tabbrowser.xml:2193:15
09:32:31     INFO -  done/</<@chrome://mochitests/content/browser/browser/components/uitour/test/head.js:338:9
09:32:31     INFO -  testScope/test_executeSoon/<.run@chrome://mochikit/content/browser-test.js:992:9
09:32:31     INFO -  294 INFO Console message: [JavaScript Error: "TelemetryStopwatch: requesting elapsed time for nonexisting stopwatch. Histogram: "FX_TAB_SWITCH_TOTAL_MS", key: "null"" {file: "resource://gre/modules/TelemetryStopwatch.jsm" line: 297}]
09:32:31     INFO -  this.TelemetryStopwatchImpl.timeElapsed@resource://gre/modules/TelemetryStopwatch.jsm:297:7
09:32:31     INFO -  this.TelemetryStopwatchImpl.finish@resource://gre/modules/TelemetryStopwatch.jsm:315:17
09:32:31     INFO -  this.TelemetryStopwatch.finish@resource://gre/modules/TelemetryStopwatch.jsm:192:12
09:32:31     INFO -  onMozAfterPaint@chrome://browser/content/tabbrowser.xml:1036:19
09:32:31     INFO -  EventListener.handleEvent*updateCurrentBrowser@chrome://browser/content/tabbrowser.xml:1035:17
09:32:31     INFO -  onselect@chrome://browser/content/browser.xul:1:44
09:32:31     INFO -  set_selectedIndex@chrome://global/content/bindings/tabbox.xml:656:13
09:32:31     INFO -  set_selectedPanel@chrome://global/content/bindings/tabbox.xml:675:13
09:32:31     INFO -  set_selectedIndex@chrome://global/content/bindings/tabbox.xml:403:15
09:32:31     INFO -  set_selectedItem@chrome://global/content/bindings/tabbox.xml:435:34
09:32:31     INFO -  set_selectedTab@chrome://global/content/bindings/tabbox.xml:114:15
09:32:31     INFO -  set_selectedTab@chrome://browser/content/tabbrowser.xml:2826:11
09:32:31     INFO -  loadUITourTestPage@chrome://mochitests/content/browser/browser/components/uitour/test/head.js:247:3
09:32:31     INFO -  add_UITour_task/genFun/</<@chrome://mochitests/content/browser/browser/components/uitour/test/head.js:385:9
09:32:31     INFO -  SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:741:59
09:32:31     INFO -  295 INFO Console message: [JavaScript Error: "1457371800997	Toolkit.GMP	ERROR	GMPInstallManager.simpleCheckAndInstall Could not check for addons: Error: got node name: html, expected: updates (resource://gre/modules/addons/ProductAddonChecker.jsm:147:11) JS Stack trace: parseXML@ProductAddonChecker.jsm:147:11 < promise callback*ProductAddonChecker.getProductAddonList@ProductAddonChecker.jsm:301:12 < GMPInstallManager.prototype.checkForAddons@GMPInstallManager.jsm:107:5 < GMPInstallManager.prototype.simpleCheckAndInstall<@GMPInstallManager.jsm:204:29 < gBrowserInit._delayedStartup/<@browser.js:1319:7 < setTimeout handler*gBrowserInit._delayedStartup@browser.js:1315:5 < EventListener.handleEvent*gBrowserInit.onLoad@browser.js:1030:5 < onload@browser.xul:1:1" {file: "resource://gre/modules/Log.jsm" line: 751}]
09:32:31     INFO -  App_append@resource://gre/modules/Log.jsm:751:9
09:32:31     INFO -  Logger.prototype.log@resource://gre/modules/Log.jsm:388:7
09:32:31     INFO -  LoggerRepository.prototype.getLoggerWithMessagePrefix/proxy.log@resource://gre/modules/Log.jsm:503:44
09:32:31     INFO -  Logger.prototype.error@resource://gre/modules/Log.jsm:396:5
09:32:31     INFO -  GMPInstallManager.prototype.simpleCheckAndInstall<@resource://gre/modules/GMPInstallManager.jsm:285:7
09:32:31     INFO -  TaskImpl_run@resource://gre/modules/Task.jsm:320:40
09:32:31     INFO -  Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:940:21
09:32:31     INFO -  this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
09:32:31     INFO -  Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
09:32:31     INFO -  this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
09:32:31     INFO -  this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
09:32:31     INFO -  GMPInstallManager.prototype.checkForAddons/<@resource://gre/modules/GMPInstallManager.jsm:116:7
09:32:31     INFO -  promise callback*GMPInstallManager.prototype.checkForAddons@resource://gre/modules/GMPInstallManager.jsm:107:5
09:32:31     INFO -  GMPInstallManager.prototype.simpleCheckAndInstall<@resource://gre/modules/GMPInstallManager.jsm:204:29
09:32:31     INFO -  TaskImpl_run@resource://gre/modules/Task.jsm:319:40
09:32:31     INFO -  TaskImpl@resource://gre/modules/Task.jsm:280:3
09:32:31     INFO -  createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:254:14
09:32:31     INFO -  gBrowserInit._delayedStartup/<@chrome://browser/content/browser.js:1319:7
09:32:31     INFO -  setTimeout handler*gBrowserInit._delayedStartup@chrome://browser/content/browser.js:1315:5
09:32:31     INFO -  EventListener.handleEvent*gBrowserInit.onLoad@chrome://browser/content/browser.js:1030:5
09:32:31     INFO -  onload@chrome://browser/content/browser.xul:1:1
09:32:31     INFO -  296 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | No about:accounts tab opened -
09:32:31     INFO -  297 INFO == Done test, doing shared checks before teardown ==
09:32:31     INFO -  298 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Element should not be null, when checking visibility -
09:32:31     INFO -  299 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Highlight should be closed/hidden after UITour tab is closed -
09:32:31     INFO -  300 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Element should not be null, when checking visibility -
09:32:31     INFO -  301 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Tooltip should be closed/hidden after UITour tab is closed -
09:32:31     INFO -  302 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | @noautohide on the menu panel should have been cleaned up -
09:32:31     INFO -  303 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | The panel shouldn't have @panelopen -
09:32:31     INFO -  304 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | The panel shouldn't be open -
09:32:31     INFO -  305 INFO TEST-PASS | browser/components/uitour/test/browser_UITour_sync.js | Menu button should know that the menu is closed -
09:32:31     INFO -  306 INFO Done shared checks
09:32:31     INFO -  307 INFO Leaving test bound test_firefoxAccountsNonUtmPrefix
09:32:31     INFO -  308 INFO Entering test bound test_firefoxAccountsNonAlphaName
09:32:31     INFO -  309 INFO Console message: [JavaScript Error: "TelemetryStopwatch: key "FX_TAB_SWITCH_TOTAL_MS" was already initialized" {file: "resource://gre/modules/TelemetryStopwatch.jsm" line: 282}]
09:32:31     INFO -  this.TelemetryStopwatchImpl.start@resource://gre/modules/TelemetryStopwatch.jsm:282:7
09:32:31     INFO -  this.TelemetryStopwatch.start@resource://gre/modules/TelemetryStopwatch.jsm:136:12
09:32:31     INFO -  updateCurrentBrowser@chrome://browser/content/tabbrowser.xml:1034:17
09:32:31     INFO -  onselect@chrome://browser/content/browser.xul:1:44
09:32:31     INFO -  set_selectedIndex@chrome://global/content/bindings/tabbox.xml:656:13
09:32:31     INFO -  set_selectedPanel@chrome://global/content/bindings/tabbox.xml:675:13
09:32:31     INFO -  set_selectedIndex@chrome://global/content/bindings/tabbox.xml:403:15
09:32:31     INFO -  set_selectedItem@chrome://global/content/bindings/tabbox.xml:435:34
09:32:31     INFO -  set_selectedTab@chrome://global/content/bindings/tabbox.xml:114:15
09:32:31     INFO -  set_selectedTab@chrome://browser/content/tabbrowser.xml:2826:11
09:32:31     INFO -  loadUITourTestPage@chrome://mochitests/content/browser/browser/components/uitour/test/head.js:247:3
09:32:31     INFO -  add_UITour_task/genFun/</<@chrome://mochitests/content/browser/browser/components/uitour/test/head.js:385:9
09:32:31     INFO -  SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:741:59
09:32:31     INFO -  310 INFO Console message: [JavaScript Error: "TelemetryStopwatch: requesting elapsed time for nonexisting stopwatch. Histogram: "FX_TAB_SWITCH_TOTAL_MS", key: "null"" {file: "resource://gre/modules/TelemetryStopwatch.jsm" line: 297}]
09:32:31     INFO -  this.TelemetryStopwatchImpl.timeElapsed@resource://gre/modules/TelemetryStopwatch.jsm:297:7
09:32:31     INFO -  this.TelemetryStopwatchImpl.finish@resource://gre/modules/TelemetryStopwatch.jsm:315:17
09:32:31     INFO -  this.TelemetryStopwatch.finish@resource://gre/modules/TelemetryStopwatch.jsm:192:12
09:32:31     INFO -  onMozAfterPaint@chrome://browser/content/tabbrowser.xml:1036:19
09:32:31     INFO -  EventListener.handleEvent*updateCurrentBrowser@chrome://browser/content/tabbrowser.xml:1035:17
09:32:31     INFO -  onselect@chrome://browser/content/browser.xul:1:44
09:32:31     INFO -  set_selectedIndex@chrome://global/content/bindings/tabbox.xml:656:13
09:32:31     INFO -  set_selectedPanel@chrome://global/content/bindings/tabbox.xml:675:13
09:32:31     INFO -  set_selectedIndex@chrome://global/content/bindings/tabbox.xml:403:15
09:32:31     INFO -  set_selectedItem@chrome://global/content/bindings/tabbox.xml:435:34
09:32:31     INFO -  set_selectedTab@chrome://global/content/bindings/tabbox.xml:114:15
09:32:31     INFO -  set_selectedTab@chrome://browser/content/tabbrowser.xml:2826:11
09:32:31     INFO -  _blurTab@chrome://browser/content/tabbrowser.xml:2477:13
09:32:31     INFO -  _endRemoveTab@chrome://browser/content/tabbrowser.xml:2351:13
09:32:31     INFO -  removeTab@chrome://browser/content/tabbrowser.xml:2193:15
09:32:31     INFO -  done/</<@chrome://mochitests/content/browser/browser/components/uitour/test/head.js:338:9
09:32:31     INFO -  testScope/test_executeSoon/<.run@chrome://mochikit/content/browser-test.js:992:9
09:32:31     INFO -  311 INFO Console message: [JavaScript Error: "TelemetryStopwatch: requesting elapsed time for nonexisting stopwatch. Histogram: "FX_TAB_SWITCH_TOTAL_MS", key: "null"" {file: "resource://gre/modules/TelemetryStopwatch.jsm" line: 297}]
09:32:31     INFO -  this.TelemetryStopwatchImpl.timeElapsed@resource://gre/modules/TelemetryStopwatch.jsm:297:7
09:32:31     INFO -  this.TelemetryStopwatchImpl.finish@resource://gre/modules/TelemetryStopwatch.jsm:315:17
09:32:31     INFO -  this.TelemetryStopwatch.finish@resource://gre/modules/TelemetryStopwatch.jsm:192:12
09:32:31     INFO -  onMozAfterPaint@chrome://browser/content/tabbrowser.xml:1036:19
09:32:31     INFO -  EventListener.handleEvent*updateCurrentBrowser@chrome://browser/content/tabbrowser.xml:1035:17
09:32:31     INFO -  onselect@chrome://browser/content/browser.xul:1:44
09:32:31     INFO -  set_selectedIndex@chrome://global/content/bindings/tabbox.xml:656:13
09:32:31     INFO -  set_selectedPanel@chrome://global/content/bindings/tabbox.xml:675:13
09:32:31     INFO -  set_selectedIndex@chrome://global/content/bindings/tabbox.xml:403:15
09:32:31     INFO -  set_selectedItem@chrome://global/content/bindings/tabbox.xml:435:34
09:32:31     INFO -  set_selectedTab@chrome://global/content/bindings/tabbox.xml:114:15
09:32:31     INFO -  set_selectedTab@chrome://browser/content/tabbrowser.xml:2826:11
09:32:31     INFO -  loadUITourTestPage@chrome://mochitests/content/browser/browser/components/uitour/test/head.js:247:3
09:32:31     INFO -  add_UITour_task/genFun/</<@chrome://mochitests/content/browser/browser/components/uitour/test/head.js:385:9
09:32:31     INFO -  SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:741:59
09:32:31     INFO -  312 INFO TEST-UNEXPECTED-FAIL | browser/components/uitour/test/browser_UITour_sync.js | Test timed out -
How sure are we this is precisely the first instance of this bug?
Flags: needinfo?(aryx.bugmail)
(basically, wondering if I caused this with https://hg.mozilla.org/mozilla-central/rev/c1b8631b15a8 )
Didn't find any similar failures in the last 4 work days and also not brasstacks.
Flags: needinfo?(aryx.bugmail)
(In reply to :Gijs Kruitbosch from comment #2)
> (basically, wondering if I caused this with
> https://hg.mozilla.org/mozilla-central/rev/c1b8631b15a8 )

Hmm, although actually, the failure in comment #0 is non-e10s, so this can't be it. Then I don't know what's up here - might just be a very very rare failure.
Bulk assigning P3 to all open intermittent bugs without a priority set in Firefox components per bug 1298978.
Priority: -- → P3
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.