1124732 - Telephony marionette test, test_outgoing_radio_off.js, fails on emulator-kk

Status: RESOLVED FIXED

(Firefox OS Graveyard :: RIL, defect)

Description

[Edgar Chen [:edgar] (OOO untils 09/01, slow response)]

Attachment: logcat.txt

========================
Console log:
========================

Traceback (most recent call last):
  File "/data/B2G/emulator-x86-kk/../../mercurial/mozilla-central/testing/marionette/client/marionette/marionette_test.py", line 283, in run
    testMethod()
  File "/data/B2G/emulator-x86-kk/../../mercurial/mozilla-central/testing/marionette/client/marionette/marionette_test.py", line 619, in runTest
    filename=os.path.basename(self.jsFile))
  File "/data/B2G/emulator-x86-kk/../../mercurial/mozilla-central/testing/marionette/client/marionette/marionette.py", line 1214, in execute_js_script
    line=None)
  File "/data/B2G/emulator-x86-kk/../../mercurial/mozilla-central/testing/marionette/client/marionette/decorators.py", line 36, in _
    return func(*args, **kwargs)
  File "/data/B2G/emulator-x86-kk/../../mercurial/mozilla-central/testing/marionette/client/marionette/marionette.py", line 621, in _send_message
    response = self._handle_emulator_cmd(response)
  File "/data/B2G/emulator-x86-kk/../../mercurial/mozilla-central/testing/marionette/client/marionette/marionette.py", line 642, in _handle_emulator_cmd
    result = self.emulator._run_telnet(cmd)
  File "/data/B2G/emulator-x86-kk/../../mercurial/mozilla-central/testing/mozbase/mozrunner/mozrunner/devices/emulator.py", line 237, in _run_telnet
    return self._get_telnet_response(command)
  File "/data/B2G/emulator-x86-kk/../../mercurial/mozilla-central/testing/mozbase/mozrunner/mozrunner/devices/emulator.py", line 231, in _get_telnet_response
    raise Exception('bad telnet response: %s' % line)
Exception: bad telnet response: KO: no current call to/from number '112'


========================
Logcat:
========================

logcat.txt:5565: 01-22 16:44:59.120   988   988 I Gecko   : MARIONETTE LOG: INFO: == Test SetUp ==
logcat.txt:5567: 01-22 16:44:59.120   988   988 I Gecko   : MARIONETTE LOG: INFO: Set debugging pref: false => true
logcat.txt:5569: 01-22 16:44:59.130   988   988 I Gecko   : MARIONETTE TEST RESULT:TEST-PASS | test_outgoing_radio_off.js | undefined - [object Telephony] was true, expected true
logcat.txt:5570: 01-22 16:44:59.130   988   988 I Gecko   : MARIONETTE TEST RESULT:TEST-PASS | test_outgoing_radio_off.js | undefined - [object TelephonyCallGroup] was true, expected true
logcat.txt:5571: 01-22 16:44:59.130   988   988 I Gecko   : MARIONETTE LOG: INFO: Clear existing calls.
logcat.txt:5572: 01-22 16:44:59.130   988   988 I Gecko   : 1421945099144	Marionette	INFO	sendToClient: {"emulator_cmd":"gsm clear","id":0}, -1, {9b95a027-d7a7-416f-9ebc-d21ce50e661c}
logcat.txt:5573: 01-22 16:44:59.150   988   988 I Gecko   : 1421945099167	Marionette	DEBUG	Got request: emulatorCmdResult, data: {"result":["OK"],"to":"0","name":"emulatorCmdResult","id":0}, id: {9b95a027-d7a7-416f-9ebc-d21ce50e661c}
logcat.txt:5574: 01-22 16:44:59.160   988   988 I Gecko   : MARIONETTE LOG: INFO: Verify initial state.
logcat.txt:5575: 01-22 16:44:59.160   988   988 I Gecko   : MARIONETTE TEST RESULT:TEST-PASS | test_outgoing_radio_off.js | telephony.call -  was true, expected true
logcat.txt:5576: 01-22 16:44:59.160   988   988 I Gecko   : MARIONETTE TEST RESULT:TEST-PASS | test_outgoing_radio_off.js | conference.calls -  was true, expected true
logcat.txt:5577: 01-22 16:44:59.160   988   988 I Gecko   : MARIONETTE TEST RESULT:TEST-PASS | test_outgoing_radio_off.js | telephony.active - null should equal null
logcat.txt:5578: 01-22 16:44:59.160   988   988 I Gecko   : MARIONETTE TEST RESULT:TEST-PASS | test_outgoing_radio_off.js | conference.state -  should equal
logcat.txt:5579: 01-22 16:44:59.160   988   988 I Gecko   : MARIONETTE LOG: INFO: == Test Start ==
logcat.txt:5582: 01-22 16:44:59.160   988   988 I Gecko   : MARIONETTE TEST RESULT:TEST-PASS | test_outgoing_radio_off.js | connection is instanceof function MozMobileConnection() {
logcat.txt:5585: 01-22 16:44:59.160   988   988 I Gecko   : MARIONETTE LOG: INFO: Set radio: disabled
logcat.txt:5611: 01-22 16:44:59.170   988   988 I Gecko   : MARIONETTE LOG: INFO: current radioState: disabling
logcat.txt:5671: 01-22 16:44:59.190   988   988 I Gecko   : MARIONETTE LOG: INFO: current radioState: disabled
logcat.txt:5672: 01-22 16:44:59.190   988   988 I Gecko   : MARIONETTE LOG: INFO: X received event 'radiostatechange'
logcat.txt:5765: 01-22 16:44:59.190   988   988 I Gecko   : MARIONETTE LOG: INFO: Make an outgoing call: 0912345678, serviceId: 0
logcat.txt:5779: 01-22 16:44:59.210   988   988 I Gecko   : MARIONETTE TEST RESULT:TEST-PASS | test_outgoing_radio_off.js | undefined - RadioNotAvailable should equal RadioNotAvailable
logcat.txt:5780: 01-22 16:44:59.210   988   988 I Gecko   : MARIONETTE TEST RESULT:TEST-PASS | test_outgoing_radio_off.js | telephony.active - null should equal null
logcat.txt:5781: 01-22 16:44:59.210   988   988 I Gecko   : MARIONETTE TEST RESULT:TEST-PASS | test_outgoing_radio_off.js | conference.state -  should equal
logcat.txt:5845: 01-22 16:44:59.210   988   988 I Gecko   : 1421945099222	Marionette	INFO	sendToClient: {"emulator_cmd":"gsm list","id":1}, -1, {9b95a027-d7a7-416f-9ebc-d21ce50e661c}
logcat.txt:5848: 01-22 16:44:59.240   988   988 I Gecko   : 1421945099255	Marionette	DEBUG	Got request: emulatorCmdResult, data: {"result":["OK"],"to":"0","name":"emulatorCmdResult","id":1}, id: {9b95a027-d7a7-416f-9ebc-d21ce50e661c}
logcat.txt:5849: 01-22 16:44:59.240   988   988 I Gecko   : MARIONETTE LOG: INFO: Call list is now: OK
logcat.txt:5850: 01-22 16:44:59.240   988   988 I Gecko   : MARIONETTE LOG: INFO: Set radio: disabled
logcat.txt:5851: 01-22 16:44:59.240   988   988 I Gecko   : MARIONETTE LOG: INFO: Make an outgoing call: 112, serviceId: 0
logcat.txt:5887: 01-22 16:44:59.250   988   988 I Gecko   : MARIONETTE TEST RESULT:TEST-PASS | test_outgoing_radio_off.js | check instance - true was true, expected true
logcat.txt:5888: 01-22 16:44:59.250   988   988 I Gecko   : MARIONETTE TEST RESULT:TEST-PASS | test_outgoing_radio_off.js | undefined - 112 should equal 112
logcat.txt:5889: 01-22 16:44:59.250   988   988 I Gecko   : MARIONETTE TEST RESULT:TEST-PASS | test_outgoing_radio_off.js | undefined - dialing should equal dialing
logcat.txt:5890: 01-22 16:44:59.250   988   988 I Gecko   : MARIONETTE TEST RESULT:TEST-UNEXPECTED-FAIL | test_outgoing_radio_off.js | undefined - got 1, expected 0
logcat.txt:5949: 01-22 16:45:00.260   988   988 I Gecko   : MARIONETTE LOG: INFO: Call (112) received event 'alerting'
logcat.txt:5950: 01-22 16:45:00.260   988   988 I Gecko   : MARIONETTE TEST RESULT:TEST-PASS | test_outgoing_radio_off.js | event.call - [object TelephonyCall] should equal [object TelephonyCall]
logcat.txt:5951: 01-22 16:45:00.260   988   988 I Gecko   : MARIONETTE TEST RESULT:TEST-PASS | test_outgoing_radio_off.js | check state - alerting should equal alerting
logcat.txt:5952: 01-22 16:45:00.260   988   988 I Gecko   : MARIONETTE LOG: INFO: Remote answering the call: 112

Comment 1

[Edgar Chen [:edgar] (OOO untils 09/01, slow response)]

Attachment: Patch, v1

Comment 2

[Edgar Chen [:edgar] (OOO untils 09/01, slow response)]

Attachment: Patch, v2

Comment 3

[Edgar Chen [:edgar] (OOO untils 09/01, slow response)]

Attachment: Patch, v3

Comment 4

[Szu-Yu Chen [:aknow] (inactive)]

Comment on attachment 8553525 [details] [diff] [review]
Patch, v3

Review of attachment 8553525 [details] [diff] [review]:
-----------------------------------------------------------------

Thaknk you.

Comment 5

[Edgar Chen [:edgar] (OOO untils 09/01, slow response)]

Try result: https://treeherder.mozilla.org/#/jobs?repo=try&revision=8a1141ba7ae5

Comment 6

[Edgar Chen [:edgar] (OOO untils 09/01, slow response)]

https://hg.mozilla.org/integration/b2g-inbound/rev/84620d3f3f07

Comment 7

[Wes Kocher (:KWierso) (Not reading bugmail; email directly if needed)]

https://hg.mozilla.org/mozilla-central/rev/84620d3f3f07