phablet-test-run -r 0000 ubuntu_system_settings.tests.test_cellular.DualSimCellularTestCase.test_change_sim1_name
Loading tests from: /usr/lib/python3/dist-packages

Tests running...
======================================================================
ERROR: ubuntu_system_settings.tests.test_cellular.DualSimCellularTestCase.test_change_sim1_name
----------------------------------------------------------------------
Binary content:
FailedTestScreenshot (image/png)
Empty attachments:
process-stdout (/usr/bin/system-settings)

/var/log/syslog: {{{
Jul 14 10:19:29 ubuntu-phablet kernel: [ 253.647001] input: py-evdev-uinput as /devices/virtual/input/input8
Jul 14 10:19:29 ubuntu-phablet kernel: [ 253.648008] keychord: using input dev py-evdev-uinput for fevent
Jul 14 10:19:30 ubuntu-phablet kernel: [ 254.106455] input: autopilot-finger as /devices/virtual/input/input9
Jul 14 10:19:30 ubuntu-phablet powerd[895]: handle_requestSysState from :1.61 (active) - ACTIVE (1)
Jul 14 10:19:30 ubuntu-phablet powerd[895]: name_watch_add: looking for :1.61
Jul 14 10:19:30 ubuntu-phablet powerd[895]: watching :1.61 to see when it disappears on dbus
Jul 14 10:19:30 ubuntu-phablet powerd[895]: libsuspend: acquire_wake_lock: powerd_power_request
Jul 14 10:19:30 ubuntu-phablet powerd[895]: handle_requestSysState - SUCCESS
Jul 14 10:19:30 ubuntu-phablet powerd[895]: Enqueue state change to ACTIVE
Jul 14 10:19:30 ubuntu-phablet powerd[895]: queue empty && state == current, discarding
Jul 14 10:19:30 ubuntu-phablet powerd[895]: libsuspend: release_wake_lock: powerd_power_request
Jul 14 10:19:31 ubuntu-phablet polkitd[6317]: started daemon version 0.105 using authority implementation `local' version `0.105'
Jul 14 10:19:31 ubuntu-phablet accounts-daemon[6308]: started daemon version 0.6.40
Jul 14 10:19:38 ubuntu-phablet powerd[895]: handle_requestSysState from :1.23 (usensord) - ACTIVE (1)
Jul 14 10:19:38 ubuntu-phablet powerd[895]: name_watch_add: looking for :1.23
Jul 14 10:19:38 ubuntu-phablet powerd[895]: watching :1.23 to see when it disappears on dbus
Jul 14 10:19:38 ubuntu-phablet powerd[895]: libsuspend: acquire_wake_lock: powerd_power_request
Jul 14 10:19:38 ubuntu-phablet powerd[895]: handle_requestSysState - SUCCESS
Jul 14 10:19:38 ubuntu-phablet powerd[895]: Enqueue state change to ACTIVE
Jul 14 10:19:38 ubuntu-phablet powerd[895]: queue empty && state == current, discarding
Jul 14 10:19:38 ubuntu-phablet powerd[895]: libsuspend: release_wake_lock: powerd_power_request
Jul 14 10:19:40 ubuntu-phablet powerd[895]: handle_clearSysState from :1.23, cookie: b229d930-2dbd-411a-b6af-c43540293c7b
Jul 14 10:19:40 ubuntu-phablet powerd[895]: clear_sys_request: usensord - :1.23
Jul 14 10:19:40 ubuntu-phablet powerd[895]: name_watch_remove: looking for :1.23
Jul 14 10:19:40 ubuntu-phablet powerd[895]: name_watch: ref_count for :1.23 is now 0
Jul 14 10:19:40 ubuntu-phablet powerd[895]: no longer watching :1.23, there are no more requests
Jul 14 10:19:40 ubuntu-phablet powerd[895]: libsuspend: acquire_wake_lock: powerd_power_request
Jul 14 10:19:40 ubuntu-phablet powerd[895]: Enqueue state change to ACTIVE
Jul 14 10:19:40 ubuntu-phablet powerd[895]: queue empty && state == current, discarding
Jul 14 10:19:40 ubuntu-phablet powerd[895]: libsuspend: release_wake_lock: powerd_power_request
Jul 14 10:19:42 ubuntu-phablet powerd[895]: handle_requestSysState from :1.23 (usensord) - ACTIVE (1)
Jul 14 10:19:42 ubuntu-phablet powerd[895]: name_watch_add: looking for :1.23
Jul 14 10:19:42 ubuntu-phablet powerd[895]: watching :1.23 to see when it disappears on dbus
Jul 14 10:19:42 ubuntu-phablet powerd[895]: libsuspend: acquire_wake_lock: powerd_power_request
Jul 14 10:19:42 ubuntu-phablet powerd[895]: handle_requestSysState - SUCCESS
Jul 14 10:19:42 ubuntu-phablet powerd[895]: Enqueue state change to ACTIVE
Jul 14 10:19:42 ubuntu-phablet powerd[895]: queue empty && state == current, discarding
Jul 14 10:19:42 ubuntu-phablet powerd[895]: libsuspend: release_wake_lock: powerd_power_request
Jul 14 10:19:44 ubuntu-phablet powerd[895]: handle_clearSysState from :1.23, cookie: 85623a65-e9b4-4242-8a70-064f3dc677d3
Jul 14 10:19:44 ubuntu-phablet powerd[895]: clear_sys_request: usensord - :1.23
Jul 14 10:19:44 ubuntu-phablet powerd[895]: name_watch_remove: looking for :1.23
Jul 14 10:19:44 ubuntu-phablet powerd[895]: name_watch: ref_count for :1.23 is now 0
Jul 14 10:19:44 ubuntu-phablet powerd[895]: no longer watching :1.23, there are no more requests
Jul 14 10:19:44 ubuntu-phablet powerd[895]: libsuspend: acquire_wake_lock: powerd_power_request
Jul 14 10:19:44 ubuntu-phablet powerd[895]: Enqueue state change to ACTIVE
Jul 14 10:19:44 ubuntu-phablet powerd[895]: queue empty && state == current, discarding
Jul 14 10:19:44 ubuntu-phablet powerd[895]: libsuspend: release_wake_lock: powerd_power_request
Jul 14 10:19:47 ubuntu-phablet powerd[895]: handle_requestSysState from :1.23 (usensord) - ACTIVE (1)
Jul 14 10:19:47 ubuntu-phablet powerd[895]: name_watch_add: looking for :1.23
Jul 14 10:19:47 ubuntu-phablet powerd[895]: watching :1.23 to see when it disappears on dbus
Jul 14 10:19:47 ubuntu-phablet powerd[895]: libsuspend: acquire_wake_lock: powerd_power_request
Jul 14 10:19:47 ubuntu-phablet powerd[895]: handle_requestSysState - SUCCESS
Jul 14 10:19:47 ubuntu-phablet powerd[895]: Enqueue state change to ACTIVE
Jul 14 10:19:47 ubuntu-phablet powerd[895]: queue empty && state == current, discarding
Jul 14 10:19:47 ubuntu-phablet powerd[895]: libsuspend: release_wake_lock: powerd_power_request
Jul 14 10:19:49 ubuntu-phablet powerd[895]: handle_clearSysState from :1.23, cookie: 8dad9f6f-ab55-4351-a4aa-04220d830ee9
Jul 14 10:19:49 ubuntu-phablet powerd[895]: clear_sys_request: usensord - :1.23
Jul 14 10:19:49 ubuntu-phablet powerd[895]: name_watch_remove: looking for :1.23
Jul 14 10:19:49 ubuntu-phablet powerd[895]: name_watch: ref_count for :1.23 is now 0
Jul 14 10:19:49 ubuntu-phablet powerd[895]: no longer watching :1.23, there are no more requests
Jul 14 10:19:49 ubuntu-phablet powerd[895]: libsuspend: acquire_wake_lock: powerd_power_request
Jul 14 10:19:49 ubuntu-phablet powerd[895]: Enqueue state change to ACTIVE
Jul 14 10:19:49 ubuntu-phablet powerd[895]: queue empty && state == current, discarding
Jul 14 10:19:49 ubuntu-phablet powerd[895]: libsuspend: release_wake_lock: powerd_power_request
Jul 14 10:19:50 ubuntu-phablet powerd[895]: handle_clearSysState from :1.61, cookie: 6e7967e9-50fe-4c18-94f4-18db4d06c2ea
Jul 14 10:19:50 ubuntu-phablet powerd[895]: clear_sys_request: active - :1.61
Jul 14 10:19:50 ubuntu-phablet powerd[895]: name_watch_remove: looking for :1.61
Jul 14 10:19:50 ubuntu-phablet powerd[895]: name_watch: ref_count for :1.61 is now 0
Jul 14 10:19:50 ubuntu-phablet powerd[895]: no longer watching :1.61, there are no more requests
Jul 14 10:19:50 ubuntu-phablet powerd[895]: libsuspend: acquire_wake_lock: powerd_power_request
Jul 14 10:19:50 ubuntu-phablet powerd[895]: Enqueue state change to ACTIVE
Jul 14 10:19:50 ubuntu-phablet powerd[895]: queue empty && state == current, discarding
Jul 14 10:19:50 ubuntu-phablet powerd[895]: libsuspend: release_wake_lock: powerd_power_request
}}}

process-return-code (/usr/bin/system-settings): {{{-15}}}
process-stderr (/usr/bin/system-settings): {{{
Loading module: 'libubuntu_application_api_touch_mirclient.so.3.0.0'
/usr/bin/system-settings: invalid option -- 't'
/usr/bin/system-settings: invalid option -- 'e'
/usr/bin/system-settings: invalid option -- 's'
/usr/bin/system-settings: invalid option -- 't'
/usr/bin/system-settings: invalid option -- 'a'
/usr/bin/system-settings: invalid option -- 'b'
/usr/bin/system-settings: invalid option -- 'i'
/usr/bin/system-settings: invalid option -- 'l'
/usr/bin/system-settings: invalid option -- 'i'
/usr/bin/system-settings: invalid option -- 't'
/usr/bin/system-settings: invalid option -- 'y'
Testability driver loaded. Wire protocol version is "1.4".
UCUriHandler: Empty "APP_ID" environment variable, ignoring.
Failed to get flight-mode status: The name org.freedesktop.URfkill was not provided by any .service files
2015-07-14 10:19:35,891 - WARNING - UbuntuClipboard - Got invalid serialized mime data. Ignoring it.
2015-07-14 10:19:36,857 - WARNING - UbuntuClipboard - Got invalid serialized mime data. Ignoring it.
2015-07-14 10:19:38,758 - WARNING - file:///usr/share/ubuntu/settings/system/qml-plugins/cellular/PageComponent.qml:31:1: QML PageComponent: Binding loop detected for property "flickable"
2015-07-14 10:19:39,497 - WARNING - file:///usr/share/ubuntu/settings/system/qml-plugins/cellular/Components/NoSim.qml:26: TypeError: Cannot read property of null
2015-07-14 10:19:39,497 - WARNING - file:///usr/share/ubuntu/settings/system/qml-plugins/cellular/Components/NoSim.qml:27: TypeError: Cannot read property of null
2015-07-14 10:19:39,497 - WARNING - file:///usr/share/ubuntu/settings/system/qml-plugins/cellular/Components/NoSim.qml:28: TypeError: Cannot read property of null
2015-07-14 10:19:42,475 - WARNING - UbuntuClipboard - Got invalid serialized mime data. Ignoring it.
}}}

test-log: {{{
10:19:30.374 WARNING testcase:175 - Process manager backend unavailable, application snapshot support disabled.
10:19:30.376 INFO _launcher:373 - Attempting to launch application '/usr/bin/system-settings' with arguments '--desktop_file_hint=/usr/share/applications/ubuntu-system-settings.desktop' as a normal process
10:19:30.389 INFO _launcher:431 - Launching process: ['/usr/bin/system-settings', '-testability', '--desktop_file_hint=/usr/share/applications/ubuntu-system-settings.desktop']
10:19:36.935 DEBUG logging:45 - SystemSettingsMainWindow: go_to_cellular_page. Arguments (). Keyword arguments: {}.
10:19:36.936 DEBUG logging:45 - SystemSettingsMainWindow: Click a system settings item. Arguments ('entryComponent-cellular',). Keyword arguments: {}.
10:19:37.418 INFO logging:45 - EntryComponent: Make the object visible. Arguments (). Keyword arguments: {}.
10:19:37.685 INFO logging:45 - QQuickFlickable: Make the child visible. Arguments (<autopilot.introspection._object_registry.EntryComponent object at 0xb45d3370>,). Keyword arguments: {}.
10:19:38.507 DEBUG _flickable:122 - The element is already visible.
10:19:38.526 DEBUG _common:45 - Moving to object's globalRect coordinates.
10:19:38.526 DEBUG _uinput:485 - Tapping at: 384,735
10:19:41.158 DEBUG logging:45 - CellularPage: set_name. Arguments ('/ril_0', 'FOO BAR'). Keyword arguments: {}.
10:19:42.509 DEBUG _common:45 - Moving to object's globalRect coordinates.
10:19:42.510 DEBUG _uinput:485 - Tapping at: 384,1020
10:19:47.291 INFO logging:45 - TextField: Write into the text field. Arguments ('FOO BAR',). Keyword arguments: {}.
10:19:47.292 DEBUG logging:45 - TextField: _ensure_focused. Arguments (). Keyword arguments: {}.
10:19:47.330 INFO logging:45 - TextField: Clear the text field. Arguments (). Keyword arguments: {}.
10:19:47.331 DEBUG logging:45 - TextField: _ensure_focused. Arguments (). Keyword arguments: {}.
10:19:47.462 DEBUG logging:45 - TextField: _click_clear_button. Arguments (). Keyword arguments: {}.
10:19:47.606 DEBUG _common:45 - Moving to object's globalRect coordinates.
10:19:47.606 DEBUG _uinput:485 - Tapping at: 691,476
10:19:47.791 DEBUG _osk:104 - Typing text: FOO BAR
10:19:50.104 INFO _launcher:544 - waiting for process to exit.
10:19:50.105 INFO _launcher:567 - Killing process 6275
}}}

Traceback (most recent call last):
File "/usr/lib/python3/dist-packages/ubuntu_system_settings/tests/test_cellular.py", line 170, in test_change_sim1_name
self.cellular_page.set_name(sim, new_name)
File "/usr/lib/python3/dist-packages/autopilot/logging.py", line 46, in inner
return f(instance, *args, **kwargs)
File "/usr/lib/python3/dist-packages/ubuntu_system_settings/__init__.py", line 289, in set_name
self._set_name(sim, name)
File "/usr/lib/python3/dist-packages/ubuntu_system_settings/__init__.py", line 306, in _set_name
field.write(name)
File "/usr/lib/python3/dist-packages/autopilot/logging.py", line 46, in inner
return f(instance, *args, **kwargs)
File "/usr/lib/python3/dist-packages/ubuntuuitoolkit/_custom_proxy_objects/_textfield.py", line 49, in write
self.keyboard.type(text)
File "/usr/lib/python3/dist-packages/autopilot/input/_osk.py", line 105, in type
self._keyboard.type(string, delay)
File "/usr/lib/python3/dist-packages/ubuntu_keyboard/emulators/keyboard.py", line 206, in type
self.press_key(char)
File "/usr/lib/python3/dist-packages/ubuntu_keyboard/emulators/keyboard.py", line 165, in press_key
raise RuntimeError("Keyboard is not on screen")
RuntimeError: Keyboard is not on screen