1603040 - ubuntu1804 - remote/test/browser/input/browser_dispatchKeyEvent_race.js | Test timed out -

Reporter

Description

•

5 years ago

treeherder

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

suite: mochitest-remote
extra: fission

context:
[task 2019-12-11T08:04:40.375Z] 08:04:40 INFO - checking window state
[task 2019-12-11T08:04:40.375Z] 08:04:40 INFO - TEST-START | remote/test/browser/input/browser_dispatchKeyEvent_race.js
[task 2019-12-11T08:05:25.169Z] 08:05:25 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-12-11T08:05:25.171Z] 08:05:25 INFO - Buffered messages logged at 08:04:40
[task 2019-12-11T08:05:25.172Z] 08:05:25 INFO - Entering test bound
[task 2019-12-11T08:05:25.173Z] 08:05:25 INFO - CDP server started
[task 2019-12-11T08:05:25.173Z] 08:05:25 INFO - CDP client instantiated
[task 2019-12-11T08:05:25.174Z] 08:05:25 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.com/browser/remote/test/browser/input/doc_dispatchKeyEvent_race.html" line: 0}]
[task 2019-12-11T08:05:25.174Z] 08:05:25 INFO - Buffered messages logged at 08:04:41
[task 2019-12-11T08:05:25.175Z] 08:05:25 INFO - Enable the Runtime domain
[task 2019-12-11T08:05:25.175Z] 08:05:25 INFO - Buffered messages finished
[task 2019-12-11T08:05:25.176Z] 08:05:25 INFO - TEST-UNEXPECTED-FAIL | remote/test/browser/input/browser_dispatchKeyEvent_race.js | Test timed out -
[task 2019-12-11T08:05:25.176Z] 08:05:25 INFO - GECKO(2092) | MEMORY STAT | vsize 3029MB | residentFast 316MB | heapAllocated 102MB
[task 2019-12-11T08:05:25.178Z] 08:05:25 INFO - TEST-OK | remote/test/browser/input/browser_dispatchKeyEvent_race.js | took 45010ms
[task 2019-12-11T08:05:25.178Z] 08:05:25 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-12-11T08:05:25.179Z] 08:05:25 INFO - TEST-UNEXPECTED-FAIL | remote/test/browser/input/browser_dispatchKeyEvent_race.js | Found a tab after previous test timed out: http://example.com/browser/remote/test/browser/input/doc_dispatchKeyEvent_race.html -
[task 2019-12-11T08:05:25.267Z] 08:05:25 INFO - checking window state

Edwin Takahashi (:egao | infrequent contributor)

Comment 1

•

5 years ago

:jdescottes - much like bug 1603038, this is a permafail on ubuntu1804 only with fission with the test timing out.

It would be appreciated if you could take a look at this failure and make a call if it should be fixed or marked as expected fail for os == linux' && os_version == '18.04' && fission.

To push to try with ubuntu1804-test, use ./mach try fuzzy --ubuntu-bionic and select test-linux64 jobs as normal.

Flags: needinfo?(jdescottes)

See Also: → https://bugzilla.mozilla.org/show_bug.cgi?id=1603038

Henrik Skupin [:whimboo][⌚️UTC+2]

Comment 2

•

5 years ago

Maja worked on that part not that long ago. So lets move the needinfo to her, also Jdescottes isn't working on the remote agent anymore.

Edwin, do you see this locally too? Could you run the test with `--setpref="remote.log.level=Trace"? It would be good to know which command that actually is.

Flags: needinfo?(mjzffr)

Henrik Skupin [:whimboo][⌚️UTC+2]

Updated

•

5 years ago

Flags: needinfo?(jdescottes)

Edwin Takahashi (:egao | infrequent contributor)

Comment 3

•

5 years ago

I ran the command /mach mochitest remote/test/browser/input/browser_dispatchKeyEvent_race.js:

worker@worker-VirtualBox:~/mozilla-central$ ./mach mochitest remote/test/browser/input/browser_dispatchKeyEvent_race.js --setpref="remote.log.level=Trace"
 0:16.02 INFO Checking for ssltunnel processes...
 0:16.13 INFO Checking for xpcshell processes...
 0:16.16 SUITE_START: mochitest-browser - running 1 tests
 0:16.16 INFO Running manifest: remote/test/browser/input/browser.ini
 0:16.16 INFO The following extra prefs will be set:
  remote.enabled=true
pk12util: PKCS12 IMPORT SUCCESSFUL
 0:16.73 INFO MochitestServer : launching [u'/home/worker/mozilla-central/obj-x86_64-pc-linux-gnu/dist/bin/xpcshell', '-g', u'/home/worker/mozilla-central/obj-x86_64-pc-linux-gnu/dist/bin', '-f', u'/home/worker/mozilla-central/obj-x86_64-pc-linux-gnu/dist/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmpWdSSi2.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/home/worker/mozilla-central/obj-x86_64-pc-linux-gnu/_tests/testing/mochitest/server.js']
 0:16.73 INFO runtests.py | Server pid: 1955
 0:16.76 INFO runtests.py | Websocket server pid: 1958
 0:16.95 INFO runtests.py | SSL tunnel pid: 1961
 0:17.61 INFO runtests.py | Running with scheme: http
 0:17.61 INFO runtests.py | Running with e10s: True
 0:17.61 INFO runtests.py | Running with fission: False
 0:17.61 INFO runtests.py | Running with serviceworker_e10s: True
 0:17.61 INFO runtests.py | Running with socketprocess_e10s: False
 0:17.61 INFO runtests.py | Running tests: start.

 0:17.67 INFO Application command: /home/worker/mozilla-central/obj-x86_64-pc-linux-gnu/dist/bin/firefox -marionette -foreground -profile /tmp/tmpWdSSi2.mozrunner
 0:17.69 INFO runtests.py | Application pid: 1978
 0:17.69 Started process `GECKO(1978)`
 0:18.68 GECKO(1978) 1576112951191	addons.webextension.doh-rollout@mozilla.org	WARN	Loading extension 'doh-rollout@mozilla.org': Reading manifest: Invalid extension permission: networkStatus
 0:19.68 GECKO(1978) 1576112952196	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
 0:19.68 GECKO(1978) 1576112952197	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: telemetry
 0:19.68 GECKO(1978) 1576112952198	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
 0:19.68 GECKO(1978) 1576112952198	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
 0:20.51 GECKO(1978) 1576112953025	Marionette	TRACE	Received observer notification profile-after-change
 0:20.75 GECKO(1978) 1576112953269	Marionette	TRACE	Received observer notification command-line-startup
 0:20.75 GECKO(1978) 1576112953269	Marionette	DEBUG	Init aborted (running=false, enabled=true, finalUIStartup=false)
 0:21.19 GECKO(1978) 1576112953707	Marionette	TRACE	Received observer notification toplevel-window-ready
 0:28.22 GECKO(1978) 1576112960734	Marionette	TRACE	Received observer notification marionette-startup-requested
 0:28.22 GECKO(1978) 1576112960734	Marionette	TRACE	Waiting until startup recorder finished recording startup scripts...
 0:28.34 GECKO(1978) 1576112960852	Marionette	TRACE	All scripts recorded.
 0:28.48 GECKO(1978) 1576112960992	Marionette	INFO	Listening on port 2828
 0:28.48 GECKO(1978) 1576112960992	Marionette	DEBUG	Marionette is listening
 0:28.66 GECKO(1978) 1576112961154	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:53780
 0:28.68 GECKO(1978) 1576112961177	Marionette	DEBUG	Closed connection 0
 0:28.77 GECKO(1978) 1576112961178	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:53782
 0:28.81 GECKO(1978) 1576112961322	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
 0:29.02 GECKO(1978) 1576112961532	Marionette	TRACE	[4] Frame script loaded
 0:29.02 GECKO(1978) 1576112961538	Marionette	TRACE	[4] Frame script registered
 0:29.08 GECKO(1978) 1576112961591	Marionette	TRACE	[24] Frame script loaded
 0:29.10 GECKO(1978) 1576112961599	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"fbc7f509-b0ee-40ee-a5a5-febb19e3506b","capabilities":{"browserName":"firefox","browserVersion":"73.0a ... mp/tmpWdSSi2.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
 0:29.14 GECKO(1978) 1576112961651	Marionette	TRACE	[24] Frame script registered
 0:29.39 GECKO(1978) 1576112961902	Marionette	DEBUG	1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpzYzlUc.zip","temporary":false}]
 0:29.81 GECKO(1978) 1576112962331	Marionette	DEBUG	1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
 0:29.95 GECKO(1978) 1576112962463	Marionette	DEBUG	1 -> [0,3,"Addon:Install",{"path":"/tmp/tmpwxdxvY.zip","temporary":false}]
 0:30.11 GECKO(1978) 1576112962631	Marionette	DEBUG	1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
 0:30.12 GECKO(1978) 1576112962635	Marionette	DEBUG	1 -> [0,4,"Marionette:GetContext",{}]
 0:30.12 GECKO(1978) 1576112962638	Marionette	DEBUG	1 <- [1,4,null,{"value":"content"}]
 0:30.14 GECKO(1978) 1576112962654	Marionette	DEBUG	1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
 0:30.16 GECKO(1978) 1576112962673	Marionette	DEBUG	1 <- [1,5,null,{"value":null}]
 0:30.20 GECKO(1978) 1576112962713	Marionette	DEBUG	1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... "about:blank","flavor":"browser-chrome"}],"filename":"_tests/testing/mochitest/runtests.py","sandbox":"default","line":1770}]
 0:30.25 GECKO(1978) 1576112962761	Marionette	DEBUG	1 <- [1,6,null,{"value":null}]
 0:30.30 GECKO(1978) 1576112962816	Marionette	TRACE	Received observer notification toplevel-window-ready
 0:30.50 GECKO(1978) 1576112963021	Marionette	DEBUG	1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
 0:30.51 GECKO(1978) 1576112963022	Marionette	DEBUG	1 <- [1,7,null,{"value":null}]
 0:30.52 GECKO(1978) 1576112963033	Marionette	DEBUG	1 -> [0,8,"WebDriver:DeleteSession",{}]
 0:30.54 GECKO(1978) 1576112963054	Marionette	DEBUG	1 <- [1,8,null,{"value":null}]
 0:30.57 INFO runtests.py | Waiting for browser...
 0:30.58 GECKO(1978) 1576112963095	Marionette	DEBUG	Closed connection 1
 0:30.89 INFO *** Start BrowserChrome Test Results ***
 0:30.99 INFO checking window state
 0:31.24 TEST_START: remote/test/browser/input/browser_dispatchKeyEvent_race.js
 0:31.26 INFO Entering test bound 
 0:31.37 INFO CDP server started
 0:32.56 INFO CDP client instantiated
 0:33.01 INFO Enable the Runtime domain
 0:33.10 INFO Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.com/browser/remote/test/browser/input/doc_dispatchKeyEvent_race.html" line: 0}]
 0:33.11 GECKO(1978) 1576112965628	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":1,"method":"Runtime.enable","params":{}}
 0:33.19 GECKO(1978) 1576112965699	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":1}
 0:33.19 GECKO(1978) 1576112965700	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"method":"Runtime.executionContextCreated","params":{"context":{"id":8589934594,"auxData":{"isDefault":true,"frameId":33}}}}
 0:33.20 INFO Focus the input on the page
 0:33.41 PASS Input should be focused - {} == {} - 
 0:33.42 INFO Retrieve the value of `window.testValue` in the test page
 0:33.47 GECKO(1978) 1576112965982	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":2,"method":"Runtime.evaluate","params":{"contextId":8589934594,"expression":"window.testValue"}}
 0:33.52 GECKO(1978) 1576112966036	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":2,"result":{"result":{"type":"string","value":"initial-value"}}}
 0:33.53 PASS Content window test value is correct - 
 0:33.53 INFO Write 'hhhhhh' ('h' times 6)
 0:33.53 INFO Send keyDown for key h
 0:33.55 GECKO(1978) 1576112966061	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":3,"method":"Input.dispatchKeyEvent","params":{"type":"keyDown","modifiers":0,"windowsVirtualKeyCode":72,"key":"h"}}
 0:33.59 INFO Console message: [JavaScript Warning: "Key event not available on some keyboard layouts: key=“r” modifiers=“accel,alt” id=“key_toggleReaderMode”" {file: "chrome://remote/content/external/EventUtils.js" line: 870}]
 0:33.59 INFO Console message: [JavaScript Warning: "Key event not available on some keyboard layouts: key=“r” modifiers=“accel,alt” id=“key_quickRestart”" {file: "chrome://remote/content/external/EventUtils.js" line: 870}]
 0:33.59 INFO Console message: [JavaScript Warning: "Key event not available on some keyboard layouts: key=“i” modifiers=“accel,alt,shift” id=“key_browserToolbox”" {file: "chrome://remote/content/external/EventUtils.js" line: 870}]
 0:34.59 GECKO(1978) 1576112967110	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":3}
 0:34.59 INFO Send keyUp for key h
 0:34.59 GECKO(1978) 1576112967112	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":4,"method":"Input.dispatchKeyEvent","params":{"type":"keyUp","modifiers":0,"windowsVirtualKeyCode":72,"key":"h"}}
 0:34.65 GECKO(1978) 1576112967164	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":4}
 0:34.65 INFO Send keyDown for key h
 0:34.65 GECKO(1978) 1576112967167	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":5,"method":"Input.dispatchKeyEvent","params":{"type":"keyDown","modifiers":0,"windowsVirtualKeyCode":72,"key":"h"}}
 0:35.68 GECKO(1978) 1576112968192	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":5}
 0:35.67 INFO Send keyUp for key h
 0:35.68 GECKO(1978) 1576112968195	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":6,"method":"Input.dispatchKeyEvent","params":{"type":"keyUp","modifiers":0,"windowsVirtualKeyCode":72,"key":"h"}}
 0:35.70 GECKO(1978) 1576112968221	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":6}
 0:35.71 INFO Send keyDown for key h
 0:35.72 GECKO(1978) 1576112968230	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":7,"method":"Input.dispatchKeyEvent","params":{"type":"keyDown","modifiers":0,"windowsVirtualKeyCode":72,"key":"h"}}
 0:35.75 GECKO(1978) 1576112968251	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":7}
 0:35.74 INFO Send keyUp for key h
 0:35.75 GECKO(1978) 1576112968256	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":8,"method":"Input.dispatchKeyEvent","params":{"type":"keyUp","modifiers":0,"windowsVirtualKeyCode":72,"key":"h"}}
 0:36.75 GECKO(1978) 1576112969271	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":8}
 0:36.75 INFO Send keyDown for key h
 0:36.76 GECKO(1978) 1576112969274	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":9,"method":"Input.dispatchKeyEvent","params":{"type":"keyDown","modifiers":0,"windowsVirtualKeyCode":72,"key":"h"}}
 0:37.77 GECKO(1978) 1576112970281	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":9}
 0:37.76 INFO Send keyUp for key h
 0:37.77 GECKO(1978) 1576112970284	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":10,"method":"Input.dispatchKeyEvent","params":{"type":"keyUp","modifiers":0,"windowsVirtualKeyCode":72,"key":"h"}}
 0:37.78 GECKO(1978) 1576112970295	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":10}
 0:37.78 INFO Send keyDown for key h
 0:37.79 GECKO(1978) 1576112970305	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":11,"method":"Input.dispatchKeyEvent","params":{"type":"keyDown","modifiers":0,"windowsVirtualKeyCode":72,"key":"h"}}
 0:38.90 GECKO(1978) 1576112971412	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":11}
 0:38.90 INFO Send keyUp for key h
 0:38.90 GECKO(1978) 1576112971413	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":12,"method":"Input.dispatchKeyEvent","params":{"type":"keyUp","modifiers":0,"windowsVirtualKeyCode":72,"key":"h"}}
 0:39.06 GECKO(1978) 1576112971571	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":12}
 0:39.05 INFO Send keyDown for key h
 0:39.06 GECKO(1978) 1576112971573	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":13,"method":"Input.dispatchKeyEvent","params":{"type":"keyDown","modifiers":0,"windowsVirtualKeyCode":72,"key":"h"}}
 0:40.07 GECKO(1978) 1576112972587	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":13}
 0:40.07 INFO Send keyUp for key h
 0:40.07 GECKO(1978) 1576112972590	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":14,"method":"Input.dispatchKeyEvent","params":{"type":"keyUp","modifiers":0,"windowsVirtualKeyCode":72,"key":"h"}}
 0:40.13 GECKO(1978) 1576112972648	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":14}
 0:40.13 INFO Retrieve the value of `window.testValue` in the test page
 0:40.13 GECKO(1978) 1576112972653	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":15,"method":"Runtime.evaluate","params":{"contextId":8589934594,"expression":"window.testValue"}}
 0:40.14 GECKO(1978) 1576112972654	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":15,"result":{"result":{"type":"string","value":"hhhhhh"}}}
 0:40.14 PASS Content window test value is correct - 
 0:40.14 INFO Write 'aaaaaa' with 6 consecutive keydown and one keyup
 0:40.14 INFO Send keyDown for key a
 0:40.14 INFO Send keyDown for key a
 0:40.14 INFO Send keyDown for key a
 0:40.14 INFO Send keyDown for key a
 0:40.14 INFO Send keyDown for key a
 0:40.14 INFO Send keyDown for key a
 0:40.14 GECKO(1978) 1576112972660	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":16,"method":"Input.dispatchKeyEvent","params":{"type":"keyDown","modifiers":0,"windowsVirtualKeyCode":65,"key":"a"}}
 0:40.15 GECKO(1978) 1576112972668	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":17,"method":"Input.dispatchKeyEvent","params":{"type":"keyDown","modifiers":0,"windowsVirtualKeyCode":65,"key":"a"}}
 0:40.15 GECKO(1978) 1576112972669	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":18,"method":"Input.dispatchKeyEvent","params":{"type":"keyDown","modifiers":0,"windowsVirtualKeyCode":65,"key":"a"}}
 0:40.15 GECKO(1978) 1576112972670	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":19,"method":"Input.dispatchKeyEvent","params":{"type":"keyDown","modifiers":0,"windowsVirtualKeyCode":65,"key":"a"}}
 0:40.16 GECKO(1978) 1576112972675	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":20,"method":"Input.dispatchKeyEvent","params":{"type":"keyDown","modifiers":0,"windowsVirtualKeyCode":65,"key":"a"}}
 0:40.17 GECKO(1978) 1576112972676	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":21,"method":"Input.dispatchKeyEvent","params":{"type":"keyDown","modifiers":0,"windowsVirtualKeyCode":65,"key":"a"}}
 0:41.38 GECKO(1978) 1576112973891	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":16}
 0:43.40 GECKO(1978) 1576112975910	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":17}
 0:43.40 GECKO(1978) 1576112975918	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":18}
 0:43.46 GECKO(1978) 1576112975977	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":19}
 0:43.46 GECKO(1978) 1576112975978	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":20}
 0:43.46 GECKO(1978) 1576112975981	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":21}
 0:43.47 INFO Send keyUp for key a
 0:43.47 GECKO(1978) 1576112975984	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":22,"method":"Input.dispatchKeyEvent","params":{"type":"keyUp","modifiers":0,"windowsVirtualKeyCode":65,"key":"a"}}
 0:46.54 GECKO(1978) 1576112979060	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":22}
 0:46.54 INFO Retrieve the value of `window.testValue` in the test page
 0:46.55 GECKO(1978) 1576112979063	RemoteAgent	TRACE	(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b})-> {"id":23,"method":"Runtime.evaluate","params":{"contextId":8589934594,"expression":"window.testValue"}}
 0:46.60 GECKO(1978) 1576112979099	RemoteAgent	TRACE	<-(connection {68a628c4-f4b6-475e-a124-2bfa8a6b613b}) {"id":23,"result":{"result":{"type":"string","value":"hhhhhhaaaaaa"}}}
 0:46.59 PASS Content window test value is correct - 
 0:46.80 INFO CDP client closed
 0:46.81 INFO CDP server stopped
 0:46.81 INFO Leaving test bound 
 0:46.85 GECKO(1978) MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
 0:46.85 GECKO(1978) MEMORY STAT | vsize 2753MB | residentFast 273MB | heapAllocated 108MB
 0:46.85 TEST_END: Test OK. Subtests passed 4/4. Unexpected 0
 0:46.94 INFO checking window state
 0:48.58 GECKO(1978) Completed ShutdownLeaks collections in process 2322
 0:48.63 GECKO(1978) Completed ShutdownLeaks collections in process 2108
 0:48.63 GECKO(1978) Completed ShutdownLeaks collections in process 2141
 0:48.69 GECKO(1978) Completed ShutdownLeaks collections in process 2031
 0:48.79 GECKO(1978) Completed ShutdownLeaks collections in process 2068
 0:49.15 GECKO(1978) Completed ShutdownLeaks collections in process 1978
 0:49.15 INFO TEST-START | Shutdown
 0:49.15 INFO Browser Chrome Test Summary
 0:49.15 INFO Passed:  4
 0:49.15 INFO Failed:  0
 0:49.15 INFO Todo:    0
 0:49.15 INFO Mode:    e10s
 0:49.15 INFO *** End BrowserChrome Test Results ***
 0:50.24 GECKO(1978) 1576112982757	Marionette	TRACE	Received observer notification xpcom-will-shutdown
 0:50.24 GECKO(1978) 1576112982757	Marionette	INFO	Stopped listening on port 2828
 0:50.24 GECKO(1978) 1576112982757	Marionette	DEBUG	Marionette stopped listening
 0:50.84 Main app process: exit 0
 0:50.84 INFO runtests.py | Application ran for: 0:00:33.168753
 0:50.84 INFO zombiecheck | Reading PID log: /tmp/tmpqB1_gppidlog
 0:50.84 INFO ==> process 1978 launched child process 1995
 0:50.84 INFO ==> process 1978 launched child process 1999
 0:50.84 INFO ==> process 1978 launched child process 2031
 0:50.84 INFO ==> process 1978 launched child process 2068
 0:50.84 INFO ==> process 1978 launched child process 2108
 0:50.84 INFO ==> process 1978 launched child process 2141
 0:50.84 INFO ==> process 1978 launched child process 2322
 0:50.84 INFO ==> process 1978 launched child process 2349
 0:50.84 INFO zombiecheck | Checking for orphan process with PID: 1995
 0:50.84 INFO zombiecheck | Checking for orphan process with PID: 2349
 0:50.84 INFO zombiecheck | Checking for orphan process with PID: 2031
 0:50.84 INFO zombiecheck | Checking for orphan process with PID: 2322
 0:50.84 INFO zombiecheck | Checking for orphan process with PID: 2068
 0:50.84 INFO zombiecheck | Checking for orphan process with PID: 1999
 0:50.84 INFO zombiecheck | Checking for orphan process with PID: 2108
 0:50.84 INFO zombiecheck | Checking for orphan process with PID: 2141
 0:50.84 INFO Stopping web server
 0:50.86 INFO Stopping web socket server
 0:50.87 INFO Stopping ssltunnel
 0:50.91 WARNING leakcheck | refcount logging is off, so leaks can't be detected!
 0:50.91 INFO runtests.py | Running tests: end.
 0:50.92 INFO Buffered messages finished
TEST-INFO | checking window state
Browser Chrome Test Summary
	Passed: 4
	Failed: 0
	Todo: 0
	Mode: e10s
*** End BrowserChrome Test Results ***
 0:50.92 INFO Buffered messages finished
 0:50.92 SUITE_END
 0:50.92 
Overall Summary
===============

mochitest-browser
~~~~~~~~~~~~~~~~~
Ran 5 checks (4 subtests, 1 tests)
Expected results: 5
Unexpected results: 0
OK

Henrik Skupin [:whimboo][⌚️UTC+2]

Comment 4

•

5 years ago

Given that it passes locally for you there might be some setting in CI that is causing this problem? Lets wait for Maja's feedback, also because she can reproduce the other failure.

Edwin Takahashi (:egao | infrequent contributor)

Comment 5

•

5 years ago

Agreed - it may even be the same root cause.

Comment hidden (Intermittent Failures Robot)

Edwin Takahashi (:egao | infrequent contributor)

Comment 7

•

5 years ago

Same with bug 1603038 - this test has been running fine on ubuntu1804 on autoland for the last day or so, since I landed the change.

Closing as fixed.

Status: NEW → RESOLVED

Closed: 5 years ago

Resolution: --- → FIXED

Henrik Skupin [:whimboo][⌚️UTC+2]

Comment 8

•

5 years ago

No patch =-> WFM.

Flags: needinfo?(mjzffr)

Resolution: FIXED → WORKSFORME

Bugzilla

ubuntu1804 - remote/test/browser/input/browser_dispatchKeyEvent_race.js | Test timed out -

Categories

(Remote Protocol :: Agent, defect, P5)

Tracking

(Not tracked)

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

Crash Data

Security

(public)

User Story

Description

Comment 1

Comment 2

Updated

Comment 3

Comment 4

Comment 5

Comment 6

Comment 7

Comment 8