search

ubports / clock-app

Moved to GitLab
https://gitlab.com/ubports/apps/clock-app
GNU General Public License v3.0
5 stars 8 forks source link

[autopilot] stopwatch test are failing #29

Closed dark-eye closed 7 years ago

dark-eye commented 7 years ago

It seems that the stopwatch test are failing when running autopilot tests : 

$ autopilot3 run -vv ubuntu_clock_app.tests.test_stopwatch.TestStopwatch.test_pressing_gui_button_starts_stops_clears_stopwatch
10:28:11.853 INFO run:240 - Autopilot Source Version: 1.6.0
Autopilot Package Version: 1.6.0+17.04.20170313-0ubuntu1
Loading tests from: /home/eran/private/clock-app/tests/autopilot

Tests running...
10:28:11.890 DEBUG __init__:147 - Backed up database
10:28:11.890 INFO _logging:41 - ************************************************************
10:28:11.890 INFO _logging:42 - Starting test ubuntu_clock_app.tests.test_stopwatch.TestStopwatch.test_pressing_gui_button_starts_stops_clears_stopwatch
10:28:11.891 DEBUG utilities:66 - Possible backends: X11,OSK,UInput
10:28:11.930 DEBUG utilities:66 - Possible backends: UInput
10:28:11.931 DEBUG utilities:66 - Possible backends: X11,UPA
10:28:12.079 DEBUG utilities:66 - Possible backends: BAMF
10:28:12.091 INFO logging:45 - TestStopwatch: launch_test_local. Arguments (). Keyword arguments: {}.
10:28:12.091 WARNING base:52 - This function is deprecated. Use get_toolkit_launcher_command() instead.
10:28:12.131 INFO _launcher:398 - Attempting to launch application '/usr/lib/x86_64-linux-gnu/qt5/bin/qmlscene' with arguments '/home/eran/private/clock-app/app/ubuntu-clock-app.qml -I /home/eran/private/clock-app/builddir/backend' as a normal process
10:28:12.136 INFO _launcher:456 - Launching process: ['/usr/lib/x86_64-linux-gnu/qt5/bin/qmlscene', '-testability', '/home/eran/private/clock-app/app/ubuntu-clock-app.qml', '-I', '/home/eran/private/clock-app/builddir/backend']
10:28:13.703 INFO _search:248 - Found the parent pid, ignoring any others.
10:28:13.711 WARNING _object_registry:256 - Generating introspection instance for type 'b'comubuntuclock'' based on generic class.
10:28:13.712 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:13.712 DEBUG dbus:256 - Selecting object(s) of any type with attributes: {'objectName': 'clockMainView'}
10:28:13.738 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock//*[objectName="clockMainView"]')' took 0.025s
10:28:13.739 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:13.742 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView[id=2]')' took 0.002s
10:28:13.746 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView[id=2]')' took 0.002s
10:28:13.747 INFO logging:45 - MainView: Open the Stopwatch Page. Arguments (). Keyword arguments: {}.
10:28:13.747 DEBUG dbus:256 - Selecting object(s) of type MainPage with attributes: {'objectName': 'mainPage'}
10:28:13.772 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView[id=2]//MainPage[objectName="mainPage"]')' took 0.025s
10:28:13.774 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:13.776 DEBUG utilities:184 - 'GetState Query(b'/')' took 0.002s
10:28:13.776 WARNING _object_registry:256 - Generating introspection instance for type 'b'comubuntuclock'' based on generic class.
10:28:13.777 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:13.777 DEBUG dbus:256 - Selecting object(s) of any type with attributes: {'objectName': 'clockMainView'}
10:28:13.804 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock//*[objectName="clockMainView"]')' took 0.026s
10:28:13.805 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:13.805 DEBUG dbus:256 - Selecting object(s) of type ActionIcon with attributes: {'objectName': 'stopwatchNavigationButton'}
10:28:13.824 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage[id=3]//ActionIcon[objectName="stopwatchNavigationButton"]')' took 0.018s
10:28:13.824 WARNING _object_registry:256 - Generating introspection instance for type 'b'ActionIcon'' based on generic class.
10:28:13.825 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:13.827 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickItem/QQuickRectangle/QQuickRow/ActionIcon[id=4]')' took 0.002s
10:28:13.829 DEBUG _common:49 - Moving to object's globalRect coordinates.
10:28:13.829 DEBUG _X11:351 - Moving mouse to position 238,565 with animation.
10:28:13.829 DEBUG utilities:66 - Possible backends: X11,UPA
10:28:13.829 DEBUG utilities:66 - Possible backends: X11,UPA
10:28:14.357 DEBUG _X11:394 - The mouse is now at position 238,565.
10:28:14.357 DEBUG _X11:305 - Pressing mouse button 1
10:28:14.459 DEBUG _X11:312 - Releasing mouse button 1
10:28:14.470 DEBUG dbus:256 - Selecting object(s) of type QQuickListView with attributes: {'objectName': 'pageListView'}
10:28:14.624 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage[id=3]//QQuickListView[objectName="pageListView"]')' took 0.153s
10:28:14.628 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:14.632 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView[id=6]')' took 0.004s
10:28:14.636 DEBUG dbus:256 - Selecting object(s) of type StopwatchPage with attributes: {}
10:28:14.643 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView[id=2]//StopwatchPage')' took 0.007s
10:28:14.645 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:14.648 DEBUG utilities:184 - 'GetState Query(b'/')' took 0.003s
10:28:14.649 WARNING _object_registry:256 - Generating introspection instance for type 'b'comubuntuclock'' based on generic class.
10:28:14.650 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:14.651 DEBUG dbus:256 - Selecting object(s) of any type with attributes: {'objectName': 'clockMainView'}
10:28:14.720 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock//*[objectName="clockMainView"]')' took 0.069s
10:28:14.722 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:14.723 INFO logging:45 - StopwatchPage: start_stopwatch. Arguments (). Keyword arguments: {}.
10:28:14.723 DEBUG dbus:256 - Selecting object(s) of type Button with attributes: {'objectName': 'startAndStopButton'}
10:28:14.730 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage[id=13]//Button[objectName="startAndStopButton"]')' took 0.007s
10:28:14.731 WARNING _object_registry:256 - Generating introspection instance for type 'b'Button'' based on generic class.
10:28:14.732 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:14.734 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.002s
10:28:14.735 DEBUG _common:49 - Moving to object's globalRect coordinates.
10:28:14.735 DEBUG _X11:351 - Moving mouse to position 514,285 with animation.
10:28:14.735 DEBUG utilities:66 - Possible backends: X11,UPA
10:28:14.736 DEBUG utilities:66 - Possible backends: X11,UPA
10:28:15.468 DEBUG _X11:394 - The mouse is now at position 514,285.
10:28:15.468 DEBUG _X11:305 - Pressing mouse button 1
10:28:15.574 DEBUG _X11:312 - Releasing mouse button 1
10:28:15.577 DEBUG dbus:256 - Selecting object(s) of type Button with attributes: {'objectName': 'startAndStopButton'}
10:28:15.598 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage[id=13]//Button[objectName="startAndStopButton"]')' took 0.020s
10:28:15.599 WARNING _object_registry:256 - Generating introspection instance for type 'b'Button'' based on generic class.
10:28:15.602 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:15.604 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.003s
10:28:15.608 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.002s
10:28:16.619 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.009s
10:28:17.632 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.010s
10:28:18.646 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.011s
10:28:19.660 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.011s
10:28:20.673 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.011s
10:28:21.686 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.010s
10:28:22.700 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.012s
10:28:23.713 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.010s
10:28:24.725 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.010s
10:28:25.739 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.011s
10:28:25.903 INFO _launcher:569 - waiting for process to exit.
10:28:25.903 INFO _launcher:592 - Killing process 21354
10:28:25.907 DEBUG utilities:66 - Possible backends: BAMF
10:28:25.942 DEBUG utilities:66 - Possible backends: X11,UPA
10:28:25.942 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:25.942 DEBUG _X11:351 - Moving mouse to position 960,540 without animation.
10:28:25.953 ERROR testresult:44 - ERROR: ubuntu_clock_app.tests.test_stopwatch.TestStopwatch.test_pressing_gui_button_starts_stops_clears_stopwatch
10:28:25.953 ERROR testresult:44 - Binary attachment: "FailedTestScreenshot" (image/png)
10:28:25.953 ERROR testresult:44 - /var/log/syslog: {{{
Jul 10 10:28:11 eran-Latitude-E7270 kernel: [100168.753776] input: autopilot-finger as /devices/virtual/input/input67
}}}
10:28:25.953 ERROR testresult:44 - process-stdout (/usr/lib/x86_64-linux-gnu/qt5/bin/qmlscene): {{{
}}}
10:28:25.953 ERROR testresult:44 - process-return-code (/usr/lib/x86_64-linux-gnu/qt5/bin/qmlscene): {{{
-15}}}
10:28:25.953 ERROR testresult:44 - process-stderr (/usr/lib/x86_64-linux-gnu/qt5/bin/qmlscene): {{{
Testability driver loaded. Wire protocol version is "1.4".
qml: [LOG]: Clock Page loaded
file:///home/eran/private/clock-app/app/components/AlarmBottomEdge.qml:23:1: QML AlarmBottomEdge: Binding loop detected for property "alarmModel"
[LOG] Loading laps from  "/home/eran/.config/com.ubuntu.clock/com.ubuntu.clock.conf"
qml: [LOG]: Stopwatch Page Loaded
[PERFORMANCE]: Last frame took 32 ms to render.
WARNING: alarm manager "eds" not installed, using "memory"
qml: [LOG]: Alarm Database loaded
qml: [LOG]: Alarm Page loaded
qml: [LOG]: Stopping geolocation update service
QMetaProperty::read: Unable to handle unregistered datatype 'QScreen*' for property 'QGuiApplication::primaryScreen'
QMetaProperty::read: Unable to handle unregistered datatype 'QScreen*' for property 'QGuiApplication::primaryScreen'
QMetaProperty::read: Unable to handle unregistered datatype 'QScreen*' for property 'QGuiApplication::primaryScreen'
}}}
10:28:25.953 ERROR testresult:44 - traceback: {{{
Traceback (most recent call last):
  File "/home/eran/private/clock-app/tests/autopilot/ubuntu_clock_app/__init__.py", line 170, in start_stopwatch
    self._get_start_stop_button().text.wait_for("Stop")
  File "/usr/lib/python3/dist-packages/autopilot/introspection/types.py", line 181, in wait_for
    failure_msg))
AssertionError: After 10.0 seconds test on Button.text failed: 'Stop' != dbus.String('Start', variant_level=1)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/eran/private/clock-app/tests/autopilot/ubuntu_clock_app/tests/test_stopwatch.py", line 48, in test_pressing_gui_button_starts_stops_clears_stopwatch
    self.page.start_stopwatch()
  File "/usr/lib/python3/dist-packages/autopilot/logging.py", line 46, in inner
    return f(instance, *args, **kwargs)
  File "/home/eran/private/clock-app/tests/autopilot/ubuntu_clock_app/__init__.py", line 178, in start_stopwatch
    'Incorrect stopwatch run state')
ubuntu_clock_app.ClockEmulatorException: Incorrect stopwatch run state
}}}
======================================================================
ERROR: ubuntu_clock_app.tests.test_stopwatch.TestStopwatch.test_pressing_gui_button_starts_stops_clears_stopwatch
----------------------------------------------------------------------
Binary content:
  FailedTestScreenshot (image/png)
Empty attachments:
  process-stdout (/usr/lib/x86_64-linux-gnu/qt5/bin/qmlscene)

/var/log/syslog: {{{Jul 10 10:28:11 eran-Latitude-E7270 kernel: [100168.753776] input: autopilot-finger as /devices/virtual/input/input67}}}
process-return-code (/usr/lib/x86_64-linux-gnu/qt5/bin/qmlscene): {{{-15}}}
process-stderr (/usr/lib/x86_64-linux-gnu/qt5/bin/qmlscene): {{{
Testability driver loaded. Wire protocol version is "1.4".
qml: [LOG]: Clock Page loaded
file:///home/eran/private/clock-app/app/components/AlarmBottomEdge.qml:23:1: QML AlarmBottomEdge: Binding loop detected for property "alarmModel"
[LOG] Loading laps from  "/home/eran/.config/com.ubuntu.clock/com.ubuntu.clock.conf"
qml: [LOG]: Stopwatch Page Loaded
[PERFORMANCE]: Last frame took 32 ms to render.
WARNING: alarm manager "eds" not installed, using "memory"
qml: [LOG]: Alarm Database loaded
qml: [LOG]: Alarm Page loaded
qml: [LOG]: Stopping geolocation update service
QMetaProperty::read: Unable to handle unregistered datatype 'QScreen*' for property 'QGuiApplication::primaryScreen'
QMetaProperty::read: Unable to handle unregistered datatype 'QScreen*' for property 'QGuiApplication::primaryScreen'
QMetaProperty::read: Unable to handle unregistered datatype 'QScreen*' for property 'QGuiApplication::primaryScreen'
}}}

test-log: {{{
10:28:11.891 DEBUG utilities:66 - Possible backends: X11,OSK,UInput
10:28:11.930 DEBUG utilities:66 - Possible backends: UInput
10:28:11.931 DEBUG utilities:66 - Possible backends: X11,UPA
10:28:12.079 DEBUG utilities:66 - Possible backends: BAMF
10:28:12.091 INFO logging:45 - TestStopwatch: launch_test_local. Arguments (). Keyword arguments: {}.
10:28:12.091 WARNING base:52 - This function is deprecated. Use get_toolkit_launcher_command() instead.
10:28:12.131 INFO _launcher:398 - Attempting to launch application '/usr/lib/x86_64-linux-gnu/qt5/bin/qmlscene' with arguments '/home/eran/private/clock-app/app/ubuntu-clock-app.qml -I /home/eran/private/clock-app/builddir/backend' as a normal process
10:28:12.136 INFO _launcher:456 - Launching process: ['/usr/lib/x86_64-linux-gnu/qt5/bin/qmlscene', '-testability', '/home/eran/private/clock-app/app/ubuntu-clock-app.qml', '-I', '/home/eran/private/clock-app/builddir/backend']
10:28:13.703 INFO _search:248 - Found the parent pid, ignoring any others.
10:28:13.711 WARNING _object_registry:256 - Generating introspection instance for type 'b'comubuntuclock'' based on generic class.
10:28:13.712 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:13.712 DEBUG dbus:256 - Selecting object(s) of any type with attributes: {'objectName': 'clockMainView'}
10:28:13.738 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock//*[objectName="clockMainView"]')' took 0.025s
10:28:13.739 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:13.742 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView[id=2]')' took 0.002s
10:28:13.746 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView[id=2]')' took 0.002s
10:28:13.747 INFO logging:45 - MainView: Open the Stopwatch Page. Arguments (). Keyword arguments: {}.
10:28:13.747 DEBUG dbus:256 - Selecting object(s) of type MainPage with attributes: {'objectName': 'mainPage'}
10:28:13.772 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView[id=2]//MainPage[objectName="mainPage"]')' took 0.025s
10:28:13.774 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:13.776 DEBUG utilities:184 - 'GetState Query(b'/')' took 0.002s
10:28:13.776 WARNING _object_registry:256 - Generating introspection instance for type 'b'comubuntuclock'' based on generic class.
10:28:13.777 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:13.777 DEBUG dbus:256 - Selecting object(s) of any type with attributes: {'objectName': 'clockMainView'}
10:28:13.804 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock//*[objectName="clockMainView"]')' took 0.026s
10:28:13.805 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:13.805 DEBUG dbus:256 - Selecting object(s) of type ActionIcon with attributes: {'objectName': 'stopwatchNavigationButton'}
10:28:13.824 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage[id=3]//ActionIcon[objectName="stopwatchNavigationButton"]')' took 0.018s
10:28:13.824 WARNING _object_registry:256 - Generating introspection instance for type 'b'ActionIcon'' based on generic class.
10:28:13.825 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:13.827 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickItem/QQuickRectangle/QQuickRow/ActionIcon[id=4]')' took 0.002s
10:28:13.829 DEBUG _common:49 - Moving to object's globalRect coordinates.
10:28:13.829 DEBUG _X11:351 - Moving mouse to position 238,565 with animation.
10:28:13.829 DEBUG utilities:66 - Possible backends: X11,UPA
10:28:13.829 DEBUG utilities:66 - Possible backends: X11,UPA
10:28:14.357 DEBUG _X11:394 - The mouse is now at position 238,565.
10:28:14.357 DEBUG _X11:305 - Pressing mouse button 1
10:28:14.459 DEBUG _X11:312 - Releasing mouse button 1
10:28:14.470 DEBUG dbus:256 - Selecting object(s) of type QQuickListView with attributes: {'objectName': 'pageListView'}
10:28:14.624 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage[id=3]//QQuickListView[objectName="pageListView"]')' took 0.153s
10:28:14.628 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:14.632 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView[id=6]')' took 0.004s
10:28:14.636 DEBUG dbus:256 - Selecting object(s) of type StopwatchPage with attributes: {}
10:28:14.643 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView[id=2]//StopwatchPage')' took 0.007s
10:28:14.645 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:14.648 DEBUG utilities:184 - 'GetState Query(b'/')' took 0.003s
10:28:14.649 WARNING _object_registry:256 - Generating introspection instance for type 'b'comubuntuclock'' based on generic class.
10:28:14.650 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:14.651 DEBUG dbus:256 - Selecting object(s) of any type with attributes: {'objectName': 'clockMainView'}
10:28:14.720 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock//*[objectName="clockMainView"]')' took 0.069s
10:28:14.722 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:14.723 INFO logging:45 - StopwatchPage: start_stopwatch. Arguments (). Keyword arguments: {}.
10:28:14.723 DEBUG dbus:256 - Selecting object(s) of type Button with attributes: {'objectName': 'startAndStopButton'}
10:28:14.730 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage[id=13]//Button[objectName="startAndStopButton"]')' took 0.007s
10:28:14.731 WARNING _object_registry:256 - Generating introspection instance for type 'b'Button'' based on generic class.
10:28:14.732 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:14.734 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.002s
10:28:14.735 DEBUG _common:49 - Moving to object's globalRect coordinates.
10:28:14.735 DEBUG _X11:351 - Moving mouse to position 514,285 with animation.
10:28:14.735 DEBUG utilities:66 - Possible backends: X11,UPA
10:28:14.736 DEBUG utilities:66 - Possible backends: X11,UPA
10:28:15.468 DEBUG _X11:394 - The mouse is now at position 514,285.
10:28:15.468 DEBUG _X11:305 - Pressing mouse button 1
10:28:15.574 DEBUG _X11:312 - Releasing mouse button 1
10:28:15.577 DEBUG dbus:256 - Selecting object(s) of type Button with attributes: {'objectName': 'startAndStopButton'}
10:28:15.598 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage[id=13]//Button[objectName="startAndStopButton"]')' took 0.020s
10:28:15.599 WARNING _object_registry:256 - Generating introspection instance for type 'b'Button'' based on generic class.
10:28:15.602 DEBUG utilities:66 - Possible backends: X11,UInput
10:28:15.604 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.003s
10:28:15.608 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.002s
10:28:16.619 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.009s
10:28:17.632 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.010s
10:28:18.646 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.011s
10:28:19.660 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.011s
10:28:20.673 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.011s
10:28:21.686 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.010s
10:28:22.700 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.012s
10:28:23.713 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.010s
10:28:24.725 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.010s
10:28:25.739 DEBUG utilities:184 - 'GetState Query(b'/comubuntuclock/QQuickWindowQmlImpl/MainView/QQuickItem/QQuickItem/PageStack/UCPageWrapper/MainPage/QQuickListView/QQuickItem/QQuickLoader/StopwatchPage/QQuickItem/Button[id=14]')' took 0.011s
10:28:25.903 INFO _launcher:569 - waiting for process to exit.
10:28:25.903 INFO _launcher:592 - Killing process 21354
10:28:25.907 DEBUG utilities:66 - Possible backends: BAMF
}}}

Traceback (most recent call last):
  File "/home/eran/private/clock-app/tests/autopilot/ubuntu_clock_app/__init__.py", line 170, in start_stopwatch
    self._get_start_stop_button().text.wait_for("Stop")
  File "/usr/lib/python3/dist-packages/autopilot/introspection/types.py", line 181, in wait_for
    failure_msg))
AssertionError: After 10.0 seconds test on Button.text failed: 'Stop' != dbus.String('Start', variant_level=1)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/eran/private/clock-app/tests/autopilot/ubuntu_clock_app/tests/test_stopwatch.py", line 48, in test_pressing_gui_button_starts_stops_clears_stopwatch
    self.page.start_stopwatch()
  File "/usr/lib/python3/dist-packages/autopilot/logging.py", line 46, in inner
    return f(instance, *args, **kwargs)
  File "/home/eran/private/clock-app/tests/autopilot/ubuntu_clock_app/__init__.py", line 178, in start_stopwatch
    'Incorrect stopwatch run state')
ubuntu_clock_app.ClockEmulatorException: Incorrect stopwatch run state

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