Closed Bug 1041823 Opened 10 years ago Closed 9 years ago

Intermittent test_nfc_error_messages.js | TimeoutException: TimeoutException: Connection timed out

Categories

(Firefox OS Graveyard :: NFC, defect)

x86_64
Gonk (Firefox OS)
defect
Not set
normal

Tracking

(Not tracked)

RESOLVED WORKSFORME

People

(Reporter: KWierso, Unassigned)

References

Details

(Keywords: intermittent-failure)

https://tbpl.mozilla.org/php/getParsedLog.php?id=44284004&tree=Mozilla-Inbound
b2g_emulator_vm mozilla-inbound opt test marionette-webapi on 2014-07-21 09:01:32 PDT for push 8e5e7c9c0d66

slave: tst-linux64-spot-921



09:53:04     INFO -  07-21 12:47:01.600 E/GeckoConsole(   44): [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: "data:text/html,<html>test%20page</html>" line: 0}]
09:53:04     INFO -  07-21 12:47:01.880 I/Gecko   (   44): 1405961221883	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {a3fe0a67-4393-4ad7-bd1c-cd9a4068c75b}, {a3fe0a67-4393-4ad7-bd1c-cd9a4068c75b}
09:53:04     INFO -  07-21 12:47:01.920 I/Gecko   (   44): 1405961221924	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {a043cd2b-6894-4561-b196-d32f94fe277d}, {a043cd2b-6894-4561-b196-d32f94fe277d}
09:53:04     INFO -  07-21 12:47:02.139 I/Gecko   (   44): MARIONETTE LOG: INFO: Skipping test on system without NFC
09:53:04     INFO -  07-21 12:47:02.139 I/Gecko   (   44): MARIONETTE TEST RESULT:TEST-PASS | test_nfc_read_tag.js | Skipping test on system without NFC - true was true, expected true
09:53:04     INFO -  07-21 12:47:02.159 I/Gecko   (   44): 1405961222162	Marionette	INFO	sendToClient: {"from":"0","value":{"passed":1,"failed":0,"failures":[]}}, {07b82694-b973-44d4-b54c-3f95cc0c6a3d}, {07b82694-b973-44d4-b54c-3f95cc0c6a3d}
09:53:04     INFO -  07-21 12:47:02.179 I/Gecko   (   44): 1405961222186	Marionette	DEBUG	Got request: execute, data: {"to":"0","sessionId":{"rotatable":true,"browserVersion":"33.0a1","takesScreenshot":true,"appBuildId":"20140721080821","XULappId":"{3c2e2abc-06d4-11e1-ac3b-374f68613e61}","secureSsl":false,"platform":"ANDROID","browserName":"B2G","version":"33.0a1","device":"qemu","b2g":true,"nativeEvents":false,"platformVersion":"33.0a1","takesElementScreenshot":true,"platformName":"ANDROID","handlesAlerts":false},"name":"executeScript","parameters":{"scriptTimeout":null,"specialPowers":false,"script":"log('TEST-END: /builds/slave/test/build/tests/marionette/tests/dom/nfc/tests/marionette/test_nfc_read_tag.js');","newSandbox":true,"args":[],"filename":"marionette_test.py","line":537}}, id: {def73de4-5366-4547-80fa-83303867eb64}
09:53:04     INFO -  07-21 12:47:02.209 I/Gecko   (   44): MARIONETTE LOG: INFO: TEST-END: /builds/slave/test/build/tests/marionette/tests/dom/nfc/tests/marionette/test_nfc_read_tag.js
09:53:04     INFO -  07-21 12:47:02.229 I/Gecko   (   44): 1405961222231	Marionette	INFO	sendToClient: {"from":"0","value":null}, {def73de4-5366-4547-80fa-83303867eb64}, {def73de4-5366-4547-80fa-83303867eb64}
09:53:04     INFO -  07-21 12:47:02.249 I/Gecko   (   44): 1405961222253	Marionette	DEBUG	Got request: setTestName, data: {"to":"0","sessionId":{"rotatable":true,"browserVersion":"33.0a1","takesScreenshot":true,"appBuildId":"20140721080821","XULappId":"{3c2e2abc-06d4-11e1-ac3b-374f68613e61}","secureSsl":false,"platform":"ANDROID","browserName":"B2G","version":"33.0a1","device":"qemu","b2g":true,"nativeEvents":false,"platformVersion":"33.0a1","takesElementScreenshot":true,"platformName":"ANDROID","handlesAlerts":false},"name":"setTestName","parameters":{"value":null}}, id: {0c55e8ed-0562-430f-8c12-7b5a07fe2f11}
09:53:04     INFO -  07-21 12:47:02.249 I/Gecko   (   44): 1405961222259	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {0c55e8ed-0562-430f-8c12-7b5a07fe2f11}, {0c55e8ed-0562-430f-8c12-7b5a07fe2f11}
09:53:04     INFO -  07-21 12:47:02.289 I/Gecko   (   44): 1405961222297	Marionette	INFO	sendToClient: {"from":"0","value":[["INFO","TEST-START: /builds/slave/test/build/tests/marionette/tests/dom/nfc/tests/marionette/test_nfc_read_tag.js","Mon Jul 21 2014 12:47:01 GMT-0400 (EDT)"],["INFO","Skipping test on system without NFC","Mon Jul 21 2014 12:47:02 GMT-0400 (EDT)"],["INFO","TEST-END: /builds/slave/test/build/tests/marionette/tests/dom/nfc/tests/marionette/test_nfc_read_tag.js","Mon Jul 21 2014 12:47:02 GMT-0400 (EDT)"]]}, {ae0dd510-5504-4739-9287-74388bca756d}, {ae0dd510-5504-4739-9287-74388bca756d}
09:53:04     INFO -  07-21 12:47:02.359 I/Gecko   (   44): 1405961222363	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {1e90c03a-6d9d-4f1e-a0bd-7ff64996c82f}, {1e90c03a-6d9d-4f1e-a0bd-7ff64996c82f}
09:53:04     INFO -  07-21 12:47:03.870 I/Gecko   (   44): 1405961223869	Marionette	DEBUG	accepted connection on 127.0.0.1:36999
09:53:04     INFO -  07-21 12:47:04.030 I/Gecko   (   44): 1405961224036	Marionette	INFO	could not load listener into content for page: chrome://b2g/content/shell.html
09:53:04     INFO -  07-21 12:47:04.089 I/Gecko   (   44): 1405961224092	Marionette	INFO	sendToClient: {"from":"0","value":{"browserName":"B2G","browserVersion":"33.0a1","platformName":"ANDROID","platformVersion":"33.0a1","handlesAlerts":false,"nativeEvents":false,"rotatable":true,"secureSsl":false,"takesElementScreenshot":true,"takesScreenshot":true,"platform":"ANDROID","XULappId":"{3c2e2abc-06d4-11e1-ac3b-374f68613e61}","appBuildId":"20140721080821","device":"qemu","version":"33.0a1","b2g":true}}, {b7cc9326-081e-4a63-9670-379d784a6485}, {b7cc9326-081e-4a63-9670-379d784a6485}
09:53:04     INFO -  07-21 12:47:04.149 I/Gecko   (   44): 1405961224158	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {953ebbd9-84a3-4506-a3ba-4d9ca8807d44}, {953ebbd9-84a3-4506-a3ba-4d9ca8807d44}
09:53:04     INFO -  07-21 12:47:04.229 I/Gecko   (   44): 1405961224236	Marionette	DEBUG	Got request: setTestName, data: {"to":"0","sessionId":{"rotatable":true,"browserVersion":"33.0a1","takesScreenshot":true,"appBuildId":"20140721080821","XULappId":"{3c2e2abc-06d4-11e1-ac3b-374f68613e61}","secureSsl":false,"platform":"ANDROID","browserName":"B2G","version":"33.0a1","device":"qemu","b2g":true,"nativeEvents":false,"platformVersion":"33.0a1","takesElementScreenshot":true,"platformName":"ANDROID","handlesAlerts":false},"name":"setTestName","parameters":{"value":"test_nfc_checkP2PRegistration.js"}}, id: {679dead2-2571-4922-be9b-92c5f4059721}
09:53:04     INFO -  07-21 12:47:04.249 I/Gecko   (   44): 1405961224254	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {679dead2-2571-4922-be9b-92c5f4059721}, {679dead2-2571-4922-be9b-92c5f4059721}
09:53:04     INFO -  07-21 12:47:04.279 I/Gecko   (   44): 1405961224282	Marionette	DEBUG	Got request: execute, data: {"to":"0","sessionId":{"rotatable":true,"browserVersion":"33.0a1","takesScreenshot":true,"appBuildId":"20140721080821","XULappId":"{3c2e2abc-06d4-11e1-ac3b-374f68613e61}","secureSsl":false,"platform":"ANDROID","browserName":"B2G","version":"33.0a1","device":"qemu","b2g":true,"nativeEvents":false,"platformVersion":"33.0a1","takesElementScreenshot":true,"platformName":"ANDROID","handlesAlerts":false},"name":"executeScript","parameters":{"scriptTimeout":null,"specialPowers":false,"script":"log('TEST-START: /builds/slave/test/build/tests/marionette/tests/dom/nfc/tests/marionette/test_nfc_checkP2PRegistration.js');","newSandbox":true,"args":[],"filename":"marionette_test.py","line":409}}, id: {ce27dd54-213f-482f-a246-956caed9f889}
09:53:04     INFO -  07-21 12:47:04.319 I/Gecko   (   44): MARIONETTE LOG: INFO: TEST-START: /builds/slave/test/build/tests/marionette/tests/dom/nfc/tests/marionette/test_nfc_checkP2PRegistration.js
09:53:04     INFO -  07-21 12:47:04.369 I/Gecko   (   44): 1405961224376	Marionette	INFO	sendToClient: {"from":"0","value":null}, {ce27dd54-213f-482f-a246-956caed9f889}, {ce27dd54-213f-482f-a246-956caed9f889}
09:53:04     INFO -  07-21 12:47:04.620 E/GeckoConsole(   44): [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: "data:text/html,<html>test%20page</html>" line: 0}]
09:53:04     INFO -  07-21 12:47:04.910 I/Gecko   (   44): 1405961224912	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {f0fd50f2-eb43-43ba-9f35-d33bf90e6f1d}, {f0fd50f2-eb43-43ba-9f35-d33bf90e6f1d}
09:53:04     INFO -  07-21 12:47:04.940 I/Gecko   (   44): 1405961224946	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {564a499d-6e7b-4774-b328-843e516882ab}, {564a499d-6e7b-4774-b328-843e516882ab}
09:53:04     INFO -  07-21 12:47:05.339 I/Gecko   (   44): MARIONETTE LOG: INFO: Skipping test on system without NFC
09:53:04     INFO -  07-21 12:47:05.359 I/Gecko   (   44): MARIONETTE TEST RESULT:TEST-PASS | test_nfc_checkP2PRegistration.js | Skipping test on system without NFC - true was true, expected true
09:53:04     INFO -  07-21 12:47:05.429 I/Gecko   (   44): 1405961225432	Marionette	INFO	sendToClient: {"from":"0","value":{"passed":1,"failed":0,"failures":[]}}, {b11b29cc-3a9b-43ee-a77a-02a7813f5f9b}, {b11b29cc-3a9b-43ee-a77a-02a7813f5f9b}
09:53:04     INFO -  07-21 12:47:05.460 I/Gecko   (   44): 1405961225464	Marionette	DEBUG	Got request: execute, data: {"to":"0","sessionId":{"rotatable":true,"browserVersion":"33.0a1","takesScreenshot":true,"appBuildId":"20140721080821","XULappId":"{3c2e2abc-06d4-11e1-ac3b-374f68613e61}","secureSsl":false,"platform":"ANDROID","browserName":"B2G","version":"33.0a1","device":"qemu","b2g":true,"nativeEvents":false,"platformVersion":"33.0a1","takesElementScreenshot":true,"platformName":"ANDROID","handlesAlerts":false},"name":"executeScript","parameters":{"scriptTimeout":null,"specialPowers":false,"script":"log('TEST-END: /builds/slave/test/build/tests/marionette/tests/dom/nfc/tests/marionette/test_nfc_checkP2PRegistration.js');","newSandbox":true,"args":[],"filename":"marionette_test.py","line":537}}, id: {92c803ae-f389-45f3-9c09-64776fafe2a6}
09:53:04     INFO -  07-21 12:47:05.490 I/Gecko   (   44): MARIONETTE LOG: INFO: TEST-END: /builds/slave/test/build/tests/marionette/tests/dom/nfc/tests/marionette/test_nfc_checkP2PRegistration.js
09:53:04     INFO -  07-21 12:47:05.510 I/Gecko   (   44): 1405961225510	Marionette	INFO	sendToClient: {"from":"0","value":null}, {92c803ae-f389-45f3-9c09-64776fafe2a6}, {92c803ae-f389-45f3-9c09-64776fafe2a6}
09:53:04     INFO -  07-21 12:47:05.540 I/Gecko   (   44): 1405961225543	Marionette	DEBUG	Got request: setTestName, data: {"to":"0","sessionId":{"rotatable":true,"browserVersion":"33.0a1","takesScreenshot":true,"appBuildId":"20140721080821","XULappId":"{3c2e2abc-06d4-11e1-ac3b-374f68613e61}","secureSsl":false,"platform":"ANDROID","browserName":"B2G","version":"33.0a1","device":"qemu","b2g":true,"nativeEvents":false,"platformVersion":"33.0a1","takesElementScreenshot":true,"platformName":"ANDROID","handlesAlerts":false},"name":"setTestName","parameters":{"value":null}}, id: {844e61c4-97c5-4af0-9c7d-0b9d2ae57db5}
09:53:04     INFO -  07-21 12:47:05.540 I/Gecko   (   44): 1405961225549	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {844e61c4-97c5-4af0-9c7d-0b9d2ae57db5}, {844e61c4-97c5-4af0-9c7d-0b9d2ae57db5}
09:53:04     INFO -  07-21 12:47:05.580 I/Gecko   (   44): 1405961225586	Marionette	INFO	sendToClient: {"from":"0","value":[["INFO","TEST-START: /builds/slave/test/build/tests/marionette/tests/dom/nfc/tests/marionette/test_nfc_checkP2PRegistration.js","Mon Jul 21 2014 12:47:04 GMT-0400 (EDT)"],["INFO","Skipping test on system without NFC","Mon Jul 21 2014 12:47:05 GMT-0400 (EDT)"],["INFO","TEST-END: /builds/slave/test/build/tests/marionette/tests/dom/nfc/tests/marionette/test_nfc_checkP2PRegistration.js","Mon Jul 21 2014 12:47:05 GMT-0400 (EDT)"]]}, {2884f2ce-f6ef-4662-b2d7-cee056922ec5}, {2884f2ce-f6ef-4662-b2d7-cee056922ec5}
09:53:04     INFO -  07-21 12:47:05.649 I/Gecko   (   44): 1405961225650	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {de675298-0edc-4ab5-923a-5dacbf144717}, {de675298-0edc-4ab5-923a-5dacbf144717}
09:53:04     INFO -  07-21 12:47:07.060 I/Gecko   (   44): 1405961227062	Marionette	DEBUG	accepted connection on 127.0.0.1:37000
09:53:04     INFO -  07-21 12:47:07.199 I/Gecko   (   44): 1405961227204	Marionette	INFO	could not load listener into content for page: chrome://b2g/content/shell.html
09:53:04     INFO -  07-21 12:47:07.240 I/Gecko   (   44): 1405961227245	Marionette	INFO	sendToClient: {"from":"0","value":{"browserName":"B2G","browserVersion":"33.0a1","platformName":"ANDROID","platformVersion":"33.0a1","handlesAlerts":false,"nativeEvents":false,"rotatable":true,"secureSsl":false,"takesElementScreenshot":true,"takesScreenshot":true,"platform":"ANDROID","XULappId":"{3c2e2abc-06d4-11e1-ac3b-374f68613e61}","appBuildId":"20140721080821","device":"qemu","version":"33.0a1","b2g":true}}, {5d61ccd6-6707-4609-8318-b8f6a4b74b97}, {5d61ccd6-6707-4609-8318-b8f6a4b74b97}
09:53:04     INFO -  07-21 12:47:07.339 I/Gecko   (   44): 1405961227330	Marionette	INFO	sendToClient: {"from":"0","ok":true}, {4c55a224-37d6-4e75-aecd-ca1ba53ce594}, {4c55a224-37d6-4e75-aecd-ca1ba53ce594}
09:53:04     INFO -  B2G is still running but Marionette can't respond due to either a Gecko, Gaia or Marionette error. Above, the 5 most recent errors are listed. Check logcat for all errors if these errors are not the cause of the failure.
09:53:04    ERROR -  TEST-UNEXPECTED-ERROR | test_nfc_error_messages.js | TimeoutException: TimeoutException: Connection timed out
09:53:04     INFO -  Traceback (most recent call last):
09:53:04     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette_test.py", line 171, in run
09:53:04     INFO -      testMethod()
09:53:04     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette_test.py", line 408, in runTest
09:53:04     INFO -      self.marionette.test_name = os.path.basename(self.jsFile)
09:53:04     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 753, in test_name
09:53:04     INFO -      if self._send_message('setTestName', 'ok', value=test_name):
09:53:04     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/decorators.py", line 35, in _
09:53:04     INFO -      return func(*args, **kwargs)
09:53:04     INFO -    File "/builds/slave/test/build/tests/marionette/marionette/marionette.py", line 611, in _send_message
09:53:04     INFO -      "Connection timed out", status=errors.ErrorCodes.TIMEOUT)
09:53:04     INFO -  TEST-INFO expected PASS | took 362152ms
09:53:04     INFO -  DeviceManager _runCmd - command: /builds/slave/test/build/emulator/b2g-distro/out/host/linux-x86/bin/adb -s emulator-5554 pull /data/local/tests/profile/minidumps /tmp/tmpAU3ZQo
09:53:04     INFO -  DeviceManager remote object '/data/local/tests/profile/minidumps' does not exist
09:53:04     INFO -  DeviceManager _runCmd - command: /builds/slave/test/build/emulator/b2g-distro/out/host/linux-x86/bin/adb -s emulator-5554 shell ls -a /data/local/tests/profile/minidumps/
09:53:04     INFO -  DeviceManager /data/local/tests/profile/minidumps/: No such file or directory
09:53:04     INFO -  DeviceManager _runCmd - command: /builds/slave/test/build/emulator/b2g-distro/out/host/linux-x86/bin/adb -s emulator-5554 shell ls -a /data/local/tests/profile/minidumps
09:53:04     INFO -  DeviceManager /data/local/tests/profile/minidumps: No such file or directory
09:53:04     INFO -  DeviceManager _runCmd - command: /builds/slave/test/build/emulator/b2g-distro/out/host/linux-x86/bin/adb -s emulator-5554 pull /data/local/tests/profile/minidumps /tmp/tmpjBwNL6
09:53:04     INFO -  DeviceManager remote object '/data/local/tests/profile/minidumps' does not exist
09:53:04     INFO -  DeviceManager _runCmd - command: /builds/slave/test/build/emulator/b2g-distro/out/host/linux-x86/bin/adb -s emulator-5554 shell ls -a /data/local/tests/profile/minidumps/
09:53:04     INFO -  DeviceManager /data/local/tests/profile/minidumps/: No such file or directory
09:53:04     INFO -  DeviceManager _runCmd - command: /builds/slave/test/build/emulator/b2g-distro/out/host/linux-x86/bin/adb -s emulator-5554 shell ls -a /data/local/tests/profile/minidumps
09:53:05     INFO -  DeviceManager /data/local/tests/profile/minidumps: No such file or directory
09:53:05     INFO -  DeviceManager shell - command: /builds/slave/test/build/emulator/b2g-distro/out/host/linux-x86/bin/adb -s emulator-5554 shell b2g-ps; echo $?
09:53:05     INFO -  DeviceManager APPLICATION    SEC USER     PID   PPID  VSIZE  RSS     WCHAN    PC         NAME
09:53:05     INFO -  DeviceManager b2g              0 root      44    1     176500 72408 ffffffff 400693c0 S /system/b2g/b2g
09:53:05     INFO -  DeviceManager (Nuwa)           0 root      145   44    54572  20932 ffffffff 400693c0 S /system/b2g/plugin-container
09:53:05     INFO -  DeviceManager (Preallocated a  2 app_6066  6066  145   60456  20500 ffffffff 400693c0 S /system/b2g/plugin-container
09:53:05     INFO -  DeviceManager 0
09:53:05     INFO -  TEST-START | test_sensor_orientation.js
Inactive; closing (see bug 1180138).
Status: NEW → RESOLVED
Closed: 9 years ago
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.