2> LC_ALL=C PYTHONPATH=../tests/autopilot autopilot3 run unity8.shell.tests.test_rotation.RotationBase.test_rotation
dpkg-query: no packages found matching python3-autopilot
dpkg-query: no packages found matching python3-autopilot
Loading tests from: /home/phablet/src/unity8-shellRotation/tests/autopilot

Tests running...
[1427727252.515001] Loader: Loading modules from: /usr/lib/arm-linux-gnueabihf/mir/client-platform/
[1427727252.515611] Loader: Loading module: /usr/lib/arm-linux-gnueabihf/mir/client-platform/android.so.2
[1427727252.517381] Loader: Loading module: /usr/lib/arm-linux-gnueabihf/mir/client-platform/dummy.so
[1427727252.519609] Loader: Loading module: /usr/lib/arm-linux-gnueabihf/mir/client-platform/mesa.so.2
[1427727252.580986] <ERROR> mircommon: Caught exception at Mir/EGL driver boundary (in setSwapInterval): /build/buildd/mir-0.12.1+15.04.20150324/src/client/buffer_stream.cpp(283): Throw in function virtual void mir::client::BufferStream::request_and_wait_for_configure(MirSurfaceAttrib, int)
Dynamic exception type: N5boost16exception_detail10clone_implINS0_19error_info_injectorISt11logic_errorEEEE
std::exception::what: Attempt to set swap interval on screencast is invalid

======================================================================
ERROR: unity8.shell.tests.test_rotation.RotationBase.test_rotation(Native Device)
----------------------------------------------------------------------
Binary content:
  FailedTestScreenshot (image/png)

/var/log/syslog: {{{
Mar 30 16:54:04 ubuntu-phablet kernel: [20886.109260] input: autopilot-finger as /devices/virtual/input/input8
Mar 30 16:54:05 ubuntu-phablet kernel: [20886.471567] input: autopilot-finger as /devices/virtual/input/input9
Mar 30 16:54:06 ubuntu-phablet powerd[837]: handle_requestWakeup from :1.129 - ubuntu push client, 1427727546
Mar 30 16:54:06 ubuntu-phablet powerd[837]: void {anonymous}::reset_alarm_clock() found 2 remaining wakeup requests
Mar 30 16:54:06 ubuntu-phablet powerd[837]: setting hardware wakeup time to 2015-03-30 16:56:40 for ubuntu push client
Mar 30 16:54:09 ubuntu-phablet kernel: [20890.566852] pm8xxx-led pm8xxx-led: resume red set 0
Mar 30 16:54:09 ubuntu-phablet kernel: [20890.566913] pm8xxx-led pm8xxx-led: resume green set 0
Mar 30 16:54:09 ubuntu-phablet kernel: [20890.566974] pm8xxx-led pm8xxx-led: resume blue set 0
Mar 30 16:54:09 ubuntu-phablet signond[20186]: ../../../../src/signond/signondaemon.cpp 390 init Failed to SUID root. Secure storage will not be available.
Mar 30 16:54:12 ubuntu-phablet powerd[837]: handle_requestWakeup from :1.138 - ubuntu push client, 1427727552
Mar 30 16:54:12 ubuntu-phablet powerd[837]: void {anonymous}::reset_alarm_clock() found 3 remaining wakeup requests
Mar 30 16:54:12 ubuntu-phablet powerd[837]: setting hardware wakeup time to 2015-03-30 16:56:40 for ubuntu push client
Mar 30 16:54:15 ubuntu-phablet kernel: [20896.416875] pm8xxx-led pm8xxx-led: resume red set 0
Mar 30 16:54:15 ubuntu-phablet kernel: [20896.416936] pm8xxx-led pm8xxx-led: resume green set 0
Mar 30 16:54:15 ubuntu-phablet kernel: [20896.416966] pm8xxx-led pm8xxx-led: resume blue set 0
Mar 30 16:54:41 ubuntu-phablet signond[20186]: QObject::disconnect: Unexpected null parameter
Mar 30 16:55:00 ubuntu-phablet powerd[837]: light_dev: setting brightness to 1
Mar 30 16:55:15 ubuntu-phablet powerd[837]: light_dev: setting brightness to 0
Mar 30 16:55:15 ubuntu-phablet kernel: [20956.254506] lm3530_backlight_off, on: 1
Mar 30 16:55:15 ubuntu-phablet powerd[837]: we get signal from :1.11: DisplayPowerStateChange
Mar 30 16:55:15 ubuntu-phablet powerd[837]: Received DisplayPowerStateChange: state=0 flags=1
Mar 30 16:55:15 ubuntu-phablet kernel: [20956.273093] lm3530_backlight_off, on: 0
Mar 30 16:55:15 ubuntu-phablet kernel: [20956.302027] mipi_lgit_lcd_off started
Mar 30 16:55:15 ubuntu-phablet kernel: [20956.432013] mipi_lgit_lcd_off finished
Mar 30 16:55:15 ubuntu-phablet kernel: [20956.432043] mipi_dsi_panel_power: mipi lcd function started status = 0 
Mar 30 16:55:15 ubuntu-phablet kernel: [20956.437018] lm3530_backlight_off, on: 0
Mar 30 16:55:15 ubuntu-phablet powerd[837]: handle_clearSysState from :1.11, cookie: 89a0a059-fe54-4355-804f-30c7cd3e78a7
Mar 30 16:55:15 ubuntu-phablet powerd[837]: clear_sys_request: com.canonical.Unity.Screen - :1.11
Mar 30 16:55:15 ubuntu-phablet powerd[837]: name_watch_remove: looking for :1.11
Mar 30 16:55:15 ubuntu-phablet powerd[837]: name_watch: ref_count for :1.11 is now 0
Mar 30 16:55:15 ubuntu-phablet powerd[837]: no longer watching :1.11, there are no more requests
Mar 30 16:55:15 ubuntu-phablet powerd[837]: libsuspend: acquire_wake_lock: powerd_power_request
Mar 30 16:55:15 ubuntu-phablet powerd[837]: Enqueue state change to SUSPEND
Mar 30 16:55:15 ubuntu-phablet powerd[837]: libsuspend: prepare_suspend.
Mar 30 16:55:15 ubuntu-phablet powerd[837]: Emitting signal for transition to state SUSPEND (0)
Mar 30 16:55:15 ubuntu-phablet powerd[837]: entering suspend
Mar 30 16:55:15 ubuntu-phablet powerd[837]: libsuspend: calling enter_suspend
Mar 30 16:55:15 ubuntu-phablet kernel: [20956.466287] request_suspend_state: sleep (0->3) at 20956374278094 (2015-03-30 14:55:15.300137894 UTC)
Mar 30 16:55:15 ubuntu-phablet kernel: [20956.466379] [Touch D]touch disable
Mar 30 16:55:15 ubuntu-phablet powerd[837]: libsuspend: enter_suspend succeeded
Mar 30 16:55:15 ubuntu-phablet powerd[837]: Transition to SUSPEND complete
Mar 30 16:55:15 ubuntu-phablet powerd[837]: libsuspend: release_wake_lock: powerd_power_request
Mar 30 16:55:15 ubuntu-phablet kernel: [20956.494976] active wake lock sns_async_ev_wakelock
Mar 30 16:55:15 ubuntu-phablet kernel: [20956.495007] active wake lock qcril
Mar 30 16:55:15 ubuntu-phablet kernel: [20956.495037] active wake lock msm_hsic_host
Mar 30 16:55:15 ubuntu-phablet kernel: [20956.495037] active wake lock pm8921_eoc
Mar 30 16:55:15 ubuntu-phablet kernel: [20956.495068] active wake lock msm_otg
Mar 30 16:55:15 ubuntu-phablet kernel: [20956.495098] PM: Syncing filesystems...
Mar 30 16:55:15 ubuntu-phablet kernel: [20956.544603] sync done.
Mar 30 16:55:44 ubuntu-phablet powerd[837]: handle_requestSysState from :1.11 (com.canonical.Unity.Screen) - ACTIVE (1)
Mar 30 16:55:44 ubuntu-phablet powerd[837]: name_watch_add: looking for :1.11
Mar 30 16:55:44 ubuntu-phablet powerd[837]: watching :1.11 to see when it disappears on dbus
Mar 30 16:55:44 ubuntu-phablet powerd[837]: libsuspend: acquire_wake_lock: powerd_power_request
Mar 30 16:55:44 ubuntu-phablet powerd[837]: handle_requestSysState - SUCCESS
Mar 30 16:55:44 ubuntu-phablet powerd[837]: Enqueue state change to ACTIVE
Mar 30 16:55:44 ubuntu-phablet powerd[837]: exiting suspend
Mar 30 16:55:44 ubuntu-phablet powerd[837]: libsuspend: calling exit_suspend
Mar 30 16:55:44 ubuntu-phablet kernel: [20985.787728] request_suspend_state: wakeup (3->0) at 20985695718653 (2015-03-30 14:55:44.621578453 UTC)
Mar 30 16:55:44 ubuntu-phablet kernel: [20985.787880] [Touch D]touch enable
Mar 30 16:55:44 ubuntu-phablet powerd[837]: libsuspend: exit_suspend succeeded
Mar 30 16:55:44 ubuntu-phablet powerd[837]: Emitting signal for transition to state ACTIVE (1)
Mar 30 16:55:44 ubuntu-phablet powerd[837]: Transition to ACTIVE complete
Mar 30 16:55:44 ubuntu-phablet powerd[837]: libsuspend: release_wake_lock: powerd_power_request
Mar 30 16:55:44 ubuntu-phablet kernel: [20985.800302] mipi_dsi_panel_power: mipi lcd function started status = 1 
Mar 30 16:55:44 ubuntu-phablet kernel: [20985.802042]  mipi_dsi_panel_power : reset start.
Mar 30 16:55:44 ubuntu-phablet kernel: [20985.826336] mipi_lgit_lcd_on started
Mar 30 16:55:44 ubuntu-phablet kernel: [20985.882219] mipi_lgit_lcd_on finished
Mar 30 16:55:44 ubuntu-phablet kernel: [20985.890856] lm3530_backlight_on, ++ lm3530_backlight_on  
Mar 30 16:55:44 ubuntu-phablet powerd[837]: light_dev: setting brightness to 87
Mar 30 16:55:44 ubuntu-phablet powerd[837]: we get signal from :1.11: DisplayPowerStateChange
Mar 30 16:55:44 ubuntu-phablet powerd[837]: Received DisplayPowerStateChange: state=1 flags=2
Mar 30 16:55:44 ubuntu-phablet kernel: [20985.939200] mdp4_overlay_mdp_perf_upd mdp clk is changed [1] from 0 to 96000000
}}}

test-log: {{{
16:54:05.060 WARNING testcase:175 - Process manager backend unavailable, application snapshot support disabled.
16:54:05.281 WARNING utilities:263 - WARNING: in file "/home/phablet/src/unity8-shellRotation/tests/autopilot/unity8/shell/tests/__init__.py", line 163 in setUp
This function is deprecated. Please use 'the Touch class to instantiate a device object' instead.

16:54:05.411 INFO __init__:264 - Lib path is '/home/phablet/src/unity8-shellRotation/builddir/install/bin', binary path is '/home/phablet/src/unity8-shellRotation/builddir/install/bin/unity8'
16:54:05.412 INFO __init__:340 - Setting up LightDM mock lib
16:54:05.413 INFO __init__:349 - New library path: /home/phablet/src/unity8-shellRotation/builddir/install/lib/arm-linux-gnueabihf/unity8/qml/mocks/libusermetrics:/home/phablet/src/unity8-shellRotation/builddir/install/lib/arm-linux-gnueabihf/unity8/qml/mocks/LightDM/liblightdm:/vendor/lib:/system/lib
16:54:05.414 INFO __init__:312 - Starting unity
16:54:05.511 INFO process_helpers:151 - Starting job unity8 with arguments ('BINARY=/home/phablet/src/unity8-shellRotation/builddir/install/bin/unity8', 'ARGS=', 'LD_LIBRARY_PATH=/home/phablet/src/unity8-shellRotation/builddir/install/lib/arm-linux-gnueabihf/unity8/qml/mocks/libusermetrics:/home/phablet/src/unity8-shellRotation/builddir/install/lib/arm-linux-gnueabihf/unity8/qml/mocks/LightDM/liblightdm:/vendor/lib:/system/lib', 'XDG_DATA_DIRS=/home/phablet/src/unity8-shellRotation/tests/mocks/data:/usr/share/ubuntu-touch:/usr/local/share:/usr/share', 'QT_LOAD_TESTABILITY=1').
16:54:06.827 INFO process_helpers:159 - unity8 start/running, process 20112

16:54:10.546 ERROR proxies:410 - Introspect error on :1.147:/com/canonical/Autopilot/Introspection: dbus.exceptions.DBusException: org.freedesktop.DBus.Error.NoReply: Message did not receive a reply (timeout by message bus)
16:54:10.547 DEBUG proxies:413 - Executing introspect queue due to error
16:54:13.014 INFO __init__:325 - Stopping unity
16:56:13.916 INFO __init__:242 - Resetting upstart env QT_LOAD_TESTABILITY to None
}}}

Traceback (most recent call last):
  File "/home/phablet/src/unity8-shellRotation/tests/autopilot/unity8/shell/tests/test_rotation.py", line 56, in setUp
    self.shell_proxy = self.launch_unity()
  File "/home/phablet/src/unity8-shellRotation/tests/autopilot/unity8/shell/tests/__init__.py", line 294, in launch_unity
    self.unity_geometry_args,
  File "/home/phablet/src/unity8-shellRotation/tests/autopilot/unity8/shell/tests/__init__.py", line 322, in _launch_unity_with_upstart
    return process_helpers.restart_unity_with_testability(*all_args)
  File "/home/phablet/src/unity8-shellRotation/tests/autopilot/unity8/process_helpers.py", line 122, in restart_unity_with_testability
    return restart_unity(*args)
  File "/home/phablet/src/unity8-shellRotation/tests/autopilot/unity8/process_helpers.py", line 139, in restart_unity
    return _get_unity_proxy_object(pid)
  File "/home/phablet/src/unity8-shellRotation/tests/autopilot/unity8/process_helpers.py", line 248, in _get_unity_proxy_object
    emulator_base=emulators.UnityEmulatorBase,
  File "/usr/lib/python3/dist-packages/autopilot/introspection/_search.py", line 171, in get_proxy_object_for_existing_process
    process
  File "/usr/lib/python3/dist-packages/autopilot/introspection/_search.py", line 324, in _find_matching_connections
    in connections
  File "/usr/lib/python3/dist-packages/autopilot/introspection/_search.py", line 325, in <listcomp>
    if connection_matcher((bus, c))
  File "/usr/lib/python3/dist-packages/autopilot/introspection/_search.py", line 292, in _filter_runner
    for f in filter_list
  File "/usr/lib/python3/dist-packages/autopilot/introspection/_search.py", line 292, in <genexpr>
    for f in filter_list
  File "/usr/lib/python3/dist-packages/autopilot/introspection/_search.py", line 775, in matches
    eligible_pids = [pid] + _get_child_pids(pid)
  File "/usr/lib/python3/dist-packages/autopilot/introspection/_search.py", line 677, in __call__
    p.pid for p in psutil.Process(pid).get_children(recursive=True)
  File "/usr/lib/python3/dist-packages/psutil/__init__.py", line 296, in __init__
    self._init(pid)
  File "/usr/lib/python3/dist-packages/psutil/__init__.py", line 331, in _init
    raise NoSuchProcess(pid, None, msg)
psutil.NoSuchProcess: no process found with pid 20112

Ran 1 test in 129.264s
FAILED (failures=1)