https://mir-jenkins.ubuntu.com/job/build-2-binpkg-mir/arch=i386,compiler=gcc,platform=mesa,release=xenial+overlay/3421/consoleFull NestedInputWithMouse.mouse_pointer_coordinates_in_nested_server_are_accumulated 14:37:49 11: [2017-01-10 14:37:49.081169] mirserver: Starting 14:37:49 11: [2017-01-10 14:37:49.086512] mirserver: Selected driver: mir:stub-graphics (version 0.26.0) 14:37:49 11: [2017-01-10 14:37:49.374743] mirserver: Using software cursor 14:37:49 11: [2017-01-10 14:37:49.472982] mirserver: Selected input driver: mir:stub-input (version: 0.26.0) 14:37:49 11: [2017-01-10 14:37:49.478280] mirserver: Mir version 0.26.0 14:37:49 11: [2017-01-10 14:37:49.658588] mirserver: Initial display configuration: 14:37:49 11: [2017-01-10 14:37:49.659096] mirserver: Output 1: VGA connected, used 14:37:49 11: [2017-01-10 14:37:49.659354] mirserver: Physical size 0.0" 0x0mm 14:37:49 11: [2017-01-10 14:37:49.659564] mirserver: Power is on 14:37:49 11: [2017-01-10 14:37:49.659838] mirserver: Current mode 1920x1080 60.00Hz 14:37:49 11: [2017-01-10 14:37:49.660054] mirserver: Preferred mode 1920x1080 60.00Hz 14:37:49 11: [2017-01-10 14:37:49.660203] mirserver: Orientation normal 14:37:49 11: [2017-01-10 14:37:49.660359] mirserver: Logical size 1920x1080 14:37:49 11: [2017-01-10 14:37:49.660507] mirserver: Logical position +0+0 14:37:49 11: [2017-01-10 14:37:49.700426] mirserver: Starting 14:37:50 11: [2017-01-10 14:37:50.256729] mirserver: Using nested cursor 14:37:50 11: [2017-01-10 14:37:50.433649] mirserver: Mir version 0.26.0 14:37:50 11: [2017-01-10 14:37:50.605717] mirserver: Initial display configuration: 14:37:50 11: [2017-01-10 14:37:50.606549] mirserver: Output 1: VGA connected, used 14:37:50 11: [2017-01-10 14:37:50.606758] mirserver: Physical size 0.0" 0x0mm 14:37:50 11: [2017-01-10 14:37:50.606920] mirserver: Power is on 14:37:50 11: [2017-01-10 14:37:50.607230] mirserver: Current mode 1920x1080 60.00Hz 14:37:50 11: [2017-01-10 14:37:50.607430] mirserver: Preferred mode 1920x1080 60.00Hz 14:37:50 11: [2017-01-10 14:37:50.607595] mirserver: Orientation normal 14:37:50 11: [2017-01-10 14:37:50.607772] mirserver: Logical size 1920x1080 14:37:50 11: [2017-01-10 14:37:50.607935] mirserver: Logical position +0+0 14:37:53 11: unknown file: Failure 14:37:53 11: 14:37:53 11: Unexpected mock function call - returning default value. 14:37:53 11: Function call: handle(pointer_event(when=5469973890470079 (97.283700ms ago), from=2, motion, button_state=0, x=5, y=0, dx=5, dy=-2, vscroll=0, hscroll=0, modifiers=none)) 14:37:53 11: Returns: false 14:37:53 11: Google Mock tried the following 3 expectations, but none matched: 14:37:53 11: 14:37:53 11: /<>/mir-0.26.0+xenial3451bzr3917/tests/acceptance-tests/test_nested_input.cpp:321: tried expectation #0: EXPECT_CALL(nested_event_filter, handle(mt::InputDeviceStateEvent()))... 14:37:53 11: Expected arg #0: input device state event 14:37:53 11: Actual: pointer_event(when=5469973890470079 (43.210829ms ago), from=2, motion, button_state=0, x=5, y=0, dx=5, dy=-2, vscroll=0, hscroll=0, modifiers=none) 14:37:53 11: Expected: to be called once 14:37:53 11: Actual: called once - saturated and active 14:37:53 11: /<>/mir-0.26.0+xenial3451bzr3917/tests/acceptance-tests/test_nested_input.cpp:326: tried expectation #1: EXPECT_CALL(nested_event_filter, handle(AllOf(mt::PointerEventWithPosition(initial_movement_x, initial_movement_y), mt::PointerEventWithDiff(initial_movement_x, initial_movement_y))))... 14:37:53 11: Expected arg #0: (pointer event with position (30, 30)) and (pointer event with diff (30, 30)) 14:37:53 11: Actual: pointer_event(when=5469973890470079 (91.935433ms ago), from=2, motion, button_state=0, x=5, y=0, dx=5, dy=-2, vscroll=0, hscroll=0, modifiers=none) 14:37:53 11: Expected: to be called once 14:37:53 11: Actual: never called - unsatisfied and active 14:37:53 11: /<>/mir-0.26.0+xenial3451bzr3917/tests/acceptance-tests/test_nested_input.cpp:342: tried expectation #2: EXPECT_CALL(nested_event_filter, handle(AllOf(mt::PointerEventWithPosition(initial_movement_x + second_movement_x, initial_movement_y + second_movement_y), mt::PointerEventWithDiff(second_movement_x, second_movement_y))))... 14:37:53 11: Expected arg #0: (pointer event with position (35, 28)) and (pointer event with diff (5, -2)) 14:37:53 11: Actual: pointer_event(when=5469973890470079 (95.044705ms ago), from=2, motion, button_state=0, x=5, y=0, dx=5, dy=-2, vscroll=0, hscroll=0, modifiers=none) 14:37:53 11: Expected: to be called once 14:37:53 11: Actual: never called - unsatisfied and active 14:37:55 11: [2017-01-10 14:37:55.076597] mirserver: Stopping 14:37:55 11: [2017-01-10 14:37:55.197744] mirserver: Stopping 14:37:55 11: /<>/mir-0.26.0+xenial3451bzr3917/tests/acceptance-tests/test_nested_input.cpp:326: Failure 14:37:55 11: Actual function call count doesn't match EXPECT_CALL(nested_event_filter, handle(AllOf(mt::PointerEventWithPosition(initial_movement_x, initial_movement_y), mt::PointerEventWithDiff(initial_movement_x, initial_movement_y))))... 14:37:55 11: Expected: to be called once 14:37:55 11: Actual: never called - unsatisfied and active 14:37:55 11: /<>/mir-0.26.0+xenial3451bzr3917/tests/acceptance-tests/test_nested_input.cpp:342: Failure 14:37:55 11: Actual function call count doesn't match EXPECT_CALL(nested_event_filter, handle(AllOf(mt::PointerEventWithPosition(initial_movement_x + second_movement_x, initial_movement_y + second_movement_y), mt::PointerEventWithDiff(second_movement_x, second_movement_y))))... 14:37:55 11: Expected: to be called once 14:37:55 11: Actual: never called - unsatisfied and active 14:37:55 11: [ FAILED ] NestedInputWithMouse.mouse_pointer_coordinates_in_nested_server_are_accumulated (6277 ms) ... 14:44:23 [ RUN ] NestedInputWithMouse.mouse_pointer_coordinates_in_nested_server_are_accumulated 14:44:23 [2017-01-10 14:37:49.081169] mirserver: Starting 14:44:23 [2017-01-10 14:37:49.086512] mirserver: Selected driver: mir:stub-graphics (version 0.26.0) 14:44:23 [2017-01-10 14:37:49.374743] mirserver: Using software cursor 14:44:23 [2017-01-10 14:37:49.472982] mirserver: Selected input driver: mir:stub-input (version: 0.26.0) 14:44:23 [2017-01-10 14:37:49.478280] mirserver: Mir version 0.26.0 14:44:23 [2017-01-10 14:37:49.658588] mirserver: Initial display configuration: 14:44:23 [2017-01-10 14:37:49.659096] mirserver: Output 1: VGA connected, used 14:44:23 [2017-01-10 14:37:49.659354] mirserver: Physical size 0.0" 0x0mm 14:44:23 [2017-01-10 14:37:49.659564] mirserver: Power is on 14:44:23 [2017-01-10 14:37:49.659838] mirserver: Current mode 1920x1080 60.00Hz 14:44:23 [2017-01-10 14:37:49.660054] mirserver: Preferred mode 1920x1080 60.00Hz 14:44:23 [2017-01-10 14:37:49.660203] mirserver: Orientation normal 14:44:23 [2017-01-10 14:37:49.660359] mirserver: Logical size 1920x1080 14:44:23 [2017-01-10 14:37:49.660507] mirserver: Logical position +0+0 14:44:23 [2017-01-10 14:37:49.700426] mirserver: Starting 14:44:23 [2017-01-10 14:37:50.256729] mirserver: Using nested cursor 14:44:23 [2017-01-10 14:37:50.433649] mirserver: Mir version 0.26.0 14:44:23 [2017-01-10 14:37:50.605717] mirserver: Initial display configuration: 14:44:23 [2017-01-10 14:37:50.606549] mirserver: Output 1: VGA connected, used 14:44:23 [2017-01-10 14:37:50.606758] mirserver: Physical size 0.0" 0x0mm 14:44:23 [2017-01-10 14:37:50.606920] mirserver: Power is on 14:44:23 [2017-01-10 14:37:50.607230] mirserver: Current mode 1920x1080 60.00Hz 14:44:23 [2017-01-10 14:37:50.607430] mirserver: Preferred mode 1920x1080 60.00Hz 14:44:23 [2017-01-10 14:37:50.607595] mirserver: Orientation normal 14:44:23 [2017-01-10 14:37:50.607772] mirserver: Logical size 1920x1080 14:44:23 [2017-01-10 14:37:50.607935] mirserver: Logical position +0+0 14:44:23 unknown file: Failure 14:44:23 14:44:23 Unexpected mock function call - returning default value. 14:44:23 Function call: handle(pointer_event(when=5469973890470079 (97.283700ms ago), from=2, motion, button_state=0, x=5, y=0, dx=5, dy=-2, vscroll=0, hscroll=0, modifiers=none)) 14:44:23 Returns: false 14:44:23 Google Mock tried the following 3 expectations, but none matched: 14:44:23 14:44:23 /<>/mir-0.26.0+xenial3451bzr3917/tests/acceptance-tests/test_nested_input.cpp:321: tried expectation #0: EXPECT_CALL(nested_event_filter, handle(mt::InputDeviceStateEvent()))... 14:44:23 Expected arg #0: input device state event 14:44:23 Actual: pointer_event(when=5469973890470079 (43.210829ms ago), from=2, motion, button_state=0, x=5, y=0, dx=5, dy=-2, vscroll=0, hscroll=0, modifiers=none) 14:44:23 Expected: to be called once 14:44:23 Actual: called once - saturated and active 14:44:23 /<>/mir-0.26.0+xenial3451bzr3917/tests/acceptance-tests/test_nested_input.cpp:326: tried expectation #1: EXPECT_CALL(nested_event_filter, handle(AllOf(mt::PointerEventWithPosition(initial_movement_x, initial_movement_y), mt::PointerEventWithDiff(initial_movement_x, initial_movement_y))))... 14:44:23 Expected arg #0: (pointer event with position (30, 30)) and (pointer event with diff (30, 30)) 14:44:23 Actual: pointer_event(when=5469973890470079 (91.935433ms ago), from=2, motion, button_state=0, x=5, y=0, dx=5, dy=-2, vscroll=0, hscroll=0, modifiers=none) 14:44:23 Expected: to be called once 14:44:23 Actual: never called - unsatisfied and active 14:44:23 /<>/mir-0.26.0+xenial3451bzr3917/tests/acceptance-tests/test_nested_input.cpp:342: tried expectation #2: EXPECT_CALL(nested_event_filter, handle(AllOf(mt::PointerEventWithPosition(initial_movement_x + second_movement_x, initial_movement_y + second_movement_y), mt::PointerEventWithDiff(second_movement_x, second_movement_y))))... 14:44:23 Expected arg #0: (pointer event with position (35, 28)) and (pointer event with diff (5, -2)) 14:44:23 Actual: pointer_event(when=5469973890470079 (95.044705ms ago), from=2, motion, button_state=0, x=5, y=0, dx=5, dy=-2, vscroll=0, hscroll=0, modifiers=none) 14:44:23 Expected: to be called once 14:44:23 Actual: never called - unsatisfied and active 14:44:23 [2017-01-10 14:37:55.076597] mirserver: Stopping 14:44:23 [2017-01-10 14:37:55.197744] mirserver: Stopping 14:44:23 /<>/mir-0.26.0+xenial3451bzr3917/tests/acceptance-tests/test_nested_input.cpp:326: Failure 14:44:23 Actual function call count doesn't match EXPECT_CALL(nested_event_filter, handle(AllOf(mt::PointerEventWithPosition(initial_movement_x, initial_movement_y), mt::PointerEventWithDiff(initial_movement_x, initial_movement_y))))... 14:44:23 Expected: to be called once 14:44:23 Actual: never called - unsatisfied and active 14:44:23 /<>/mir-0.26.0+xenial3451bzr3917/tests/acceptance-tests/test_nested_input.cpp:342: Failure 14:44:23 Actual function call count doesn't match EXPECT_CALL(nested_event_filter, handle(AllOf(mt::PointerEventWithPosition(initial_movement_x + second_movement_x, initial_movement_y + second_movement_y), mt::PointerEventWithDiff(second_movement_x, second_movement_y))))... 14:44:23 Expected: to be called once 14:44:23 Actual: never called - unsatisfied and active 14:44:23 [ FAILED ] NestedInputWithMouse.mouse_pointer_coordinates_in_nested_server_are_accumulated (6277 ms)