Gstd version 0.12.0 Copyright (C) 2015-2020 RidgeRun (https://www.ridgerun.com) 0:00:24.592396711 36 0x55970c4f7190 LOG GST_DEBUG gstinfo.c:1605:gst_debug_reset_threshold: category streams matches pattern 0x7f31d404d8b0 - gets set to level 6 0:00:24.592423552 36 0x55970c4f7190 LOG GST_DEBUG gstinfo.c:1605:gst_debug_reset_threshold: category streamcollection matches pattern 0x7f31d404d8b0 - gets set to level 6 0:00:24.592432258 36 0x55970c4f7190 INFO gstdjsonbuilder gstd_json_builder.c:80:gstd_json_builder_init: Initializing Json builder 0:00:24.592447167 36 0x55970c4f7190 DEBUG default gstd_debug.c:179:gstd_debug_get_property: Returning debug level threshold **:6 0:00:24.592471863 36 0x55970c4f7190 DEBUG gstdjsonbuilder gstd_json_builder.c:248:gstd_json_builder_finalize: finalize 0:00:24.592479288 36 0x55970c4f7190 INFO gstdproperty gstd_property.c:117:gstd_property_dispose: Disposing threshold property 0:00:24.592485579 36 0x55970c4f7190 DEBUG gstdobject gstd_object.c:186:gstd_object_dispose: Deinitializing threshold object 0:00:24.592492733 36 0x55970c4f7190 DEBUG gstdobject gstd_object.c:136:gstd_object_finalize: finalize 0:00:24.598456359 36 0x55970c4f7190 DEBUG gstdsession gstd_session.c:167:gstd_session_get_property: Returning pipeline list 0x55970c47cd80 0:00:24.598469163 36 0x55970c4f7190 DEBUG gstdsession gstd_session.c:167:gstd_session_get_property: Returning pipeline list 0x55970c47cd80 0:00:24.598478381 36 0x55970c4f7190 LOG gstdlist gstd_list.c:193:gstd_list_find_node: Comparing test vs test 0:00:24.598487768 36 0x55970c4f7190 DEBUG gstdpipeline gstd_pipeline.c:337:gstd_pipeline_get_property: Returning element list 0x55970c47ce40 0:00:24.598495743 36 0x55970c4f7190 DEBUG gstdpipeline gstd_pipeline.c:337:gstd_pipeline_get_property: Returning element list 0x55970c47ce40 0:00:24.598503117 36 0x55970c4f7190 LOG gstdlist gstd_list.c:193:gstd_list_find_node: Comparing capsfilter1 vs balluff 0:00:24.598508678 36 0x55970c4f7190 LOG gstdlist gstd_list.c:193:gstd_list_find_node: Comparing capsfilter0 vs balluff 0:00:24.598513697 36 0x55970c4f7190 LOG gstdlist gstd_list.c:193:gstd_list_find_node: Comparing multifilesink0 vs balluff 0:00:24.598518637 36 0x55970c4f7190 LOG gstdlist gstd_list.c:193:gstd_list_find_node: Comparing jpegenc0 vs balluff 0:00:24.598523486 36 0x55970c4f7190 LOG gstdlist gstd_list.c:193:gstd_list_find_node: Comparing videoconvert0 vs balluff 0:00:24.598528325 36 0x55970c4f7190 LOG gstdlist gstd_list.c:193:gstd_list_find_node: Comparing bayer2rgb0 vs balluff 0:00:24.598533014 36 0x55970c4f7190 LOG gstdlist gstd_list.c:193:gstd_list_find_node: Comparing balluff vs balluff 0:00:24.598539086 36 0x55970c4f7190 DEBUG gstdelement gstd_element.c:233:gstd_element_get_property: Returning properties 0x7f31d403f640 0:00:24.598544987 36 0x55970c4f7190 DEBUG gstdelement gstd_element.c:233:gstd_element_get_property: Returning properties 0x7f31d403f640 0:00:24.598550467 36 0x55970c4f7190 LOG gstdlist gstd_list.c:193:gstd_list_find_node: Comparing name vs camera-name 0:00:24.598555396 36 0x55970c4f7190 LOG gstdlist gstd_list.c:193:gstd_list_find_node: Comparing parent vs camera-name 0:00:24.598560396 36 0x55970c4f7190 LOG gstdlist gstd_list.c:193:gstd_list_find_node: Comparing blocksize vs camera-name 0:00:24.598565135 36 0x55970c4f7190 LOG gstdlist gstd_list.c:193:gstd_list_find_node: Comparing num-buffers vs camera-name 0:00:24.598570415 36 0x55970c4f7190 LOG gstdlist gstd_list.c:193:gstd_list_find_node: Comparing typefind vs camera-name 0:00:24.598575044 36 0x55970c4f7190 LOG gstdlist gstd_list.c:193:gstd_list_find_node: Comparing do-timestamp vs camera-name 0:00:24.598579773 36 0x55970c4f7190 LOG gstdlist gstd_list.c:193:gstd_list_find_node: Comparing camera-name vs camera-name 0:00:24.598590593 36 0x55970c4f7190 DEBUG aravissrc gstaravis.c:687:gst_aravis_set_property: setting property camera-name 0:00:25.700304621 36 0x55970c4f7190 LOG aravissrc gstaravis.c:700:gst_aravis_set_property: Set camera name to BALLUFF GmbH-BVS CA-GX0-0032AC-GX301282 0:00:25.700334057 36 0x55970c4f7190 LOG GST_PROPERTIES gstobject.c:448:gst_object_dispatch_properties_changed: deep notification from balluff (camera-name) 0:00:25.700351810 36 0x55970c4f7190 INFO gstdjsonbuilder gstd_json_builder.c:80:gstd_json_builder_init: Initializing Json builder 0:00:25.700379894 36 0x55970c4f7190 DEBUG aravissrc gstaravis.c:783:gst_aravis_get_property: getting property camera-name 0:00:25.700416513 36 0x55970c4f7190 DEBUG gstdjsonbuilder gstd_json_builder.c:248:gstd_json_builder_finalize: finalize 0:00:30.709847501 36 0x55970c4f7190 DEBUG gstdsession gstd_session.c:167:gstd_session_get_property: Returning pipeline list 0x55970c47cd80 0:00:30.709867909 36 0x55970c4f7190 DEBUG gstdsession gstd_session.c:167:gstd_session_get_property: Returning pipeline list 0x55970c47cd80 0:00:30.709878660 36 0x55970c4f7190 LOG gstdlist gstd_list.c:193:gstd_list_find_node: Comparing test vs test 0:00:30.709888889 36 0x55970c4f7190 DEBUG gstdpipeline gstd_pipeline.c:345:gstd_pipeline_get_property: Returning pipeline state 0x55970c69d660 0:00:30.709895512 36 0x55970c4f7190 DEBUG gstdpipeline gstd_pipeline.c:345:gstd_pipeline_get_property: Returning pipeline state 0x55970c69d660 0:00:30.709923154 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to PLAYING 0:00:30.709930187 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to PLAYING 0:00:30.709937962 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current NULL, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.709944775 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: intermediate: setting state from NULL to READY 0:00:30.709951979 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:472:gst_bus_set_flushing: unset bus flushing 0:00:30.709959393 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:2854:gst_bin_change_state_func: changing state of children from NULL to READY 0:00:30.709966336 36 0x55970c4f7190 DEBUG bin gstbin.c:2891:gst_bin_change_state_func: clearing all cached messages 0:00:30.709972247 36 0x55970c4f7190 DEBUG bin gstbin.c:2699:gst_bin_src_pads_activate: deactivate pads 0:00:30.709980082 36 0x55970c4f7190 DEBUG bin gstbin.c:2707:gst_bin_src_pads_activate: pad deactivation successful 0:00:30.709995301 36 0x55970c4f7190 DEBUG bin gstbin.c:2385:gst_bin_sort_iterator_resync: resync 0:00:30.710003647 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'multifilesink0' to queue 0:00:30.710010970 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.710016441 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.710024486 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element bayer2rgb0, degree 0->1, linked to capsfilter1 0:00:30.710032471 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.710036589 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.710047610 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element balluff, degree 0->1, linked to capsfilter0 0:00:30.710056256 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.710063821 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.710070764 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element jpegenc0, degree 0->1, linked to multifilesink0 0:00:30.710100771 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.710114386 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.710122392 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element videoconvert0, degree 0->1, linked to jpegenc0 0:00:30.710128723 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.710135176 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.710142449 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element capsfilter1, degree 0->1, linked to videoconvert0 0:00:30.710148511 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.710154332 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.710160604 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element capsfilter0, degree 0->1, linked to bayer2rgb0 0:00:30.710166064 36 0x55970c4f7190 DEBUG bin gstbin.c:2305:update_degree: element balluff not linked on any sinkpads 0:00:30.710172436 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives multifilesink0 0:00:30.710179189 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.710184379 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.710191422 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element jpegenc0, degree 1->0, linked to multifilesink0 0:00:30.710195750 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'jpegenc0' to queue 0:00:30.710202794 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY 0:00:30.710207663 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.710211610 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.710218393 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element multifilesink0 to READY, base_time 0:00:00.000000000 0:00:30.710222832 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to READY 0:00:30.710227220 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to READY 0:00:30.710231047 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current NULL, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.710234694 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from NULL to READY 0:00:30.710240625 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from NULL to READY (000a) 0:00:30.710243461 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.710249522 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY 0:00:30.710256866 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:30.710266004 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source multifilesink0: creating new message 0x7f31d402b800 state-changed 0:00:30.710281483 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402b800] posting on bus state-changed message: 0x7f31d402b800, time 99:99:99.999999999, seq-num 21, element 'multifilesink0', GstMessageStateChanged, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.710293065 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31d402b800] handling child multifilesink0 message of type state-changed 0:00:30.710299757 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.710308434 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402b800] posting on bus state-changed message: 0x7f31d402b800, time 99:99:99.999999999, seq-num 21, element 'multifilesink0', GstMessageStateChanged, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.710313544 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d402b800] pushing on async queue 0:00:30.710317711 36 0x55970c4f7190 LOG GST_POLL gstpoll.c:290:raise_wakeup: 0x7f31d40105e0: raise 0:00:30.710333351 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d402b800] pushed on async queue 0:00:30.710337589 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31d402b800] dropped 0:00:30.710344081 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.710347768 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.710351856 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'multifilesink0' changed state to 2(READY) successfully 0:00:30.710356184 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives jpegenc0 0:00:30.710360953 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.710366474 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.710371483 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element videoconvert0, degree 1->0, linked to jpegenc0 0:00:30.710374810 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'videoconvert0' to queue 0:00:30.710378597 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY 0:00:30.710381532 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.710384047 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.710387804 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element jpegenc0 to READY, base_time 0:00:00.000000000 0:00:30.710390569 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to READY 0:00:30.710393275 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to READY 0:00:30.710396721 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current NULL, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.710399977 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from NULL to READY 0:00:30.710404095 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from NULL to READY (000a) 0:00:30.710407041 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.710409926 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY 0:00:30.710414946 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:30.710419985 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source jpegenc0: creating new message 0x7f31d4025700 state-changed 0:00:30.710429573 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d4025700] posting on bus state-changed message: 0x7f31d4025700, time 99:99:99.999999999, seq-num 22, element 'jpegenc0', GstMessageStateChanged, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.710434433 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31d4025700] handling child jpegenc0 message of type state-changed 0:00:30.710438420 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.710445854 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d4025700] posting on bus state-changed message: 0x7f31d4025700, time 99:99:99.999999999, seq-num 22, element 'jpegenc0', GstMessageStateChanged, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.710450172 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d4025700] pushing on async queue 0:00:30.710454751 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d4025700] pushed on async queue 0:00:30.710458037 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31d4025700] dropped 0:00:30.710461394 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.710465692 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.710469780 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'jpegenc0' changed state to 2(READY) successfully 0:00:30.710475991 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives videoconvert0 0:00:30.710479247 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.710482834 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.710486772 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element capsfilter1, degree 1->0, linked to videoconvert0 0:00:30.710489948 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'capsfilter1' to queue 0:00:30.710494005 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY 0:00:30.710497362 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.710500277 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.710504515 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element videoconvert0 to READY, base_time 0:00:00.000000000 0:00:30.710507621 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to READY 0:00:30.710511268 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to READY 0:00:30.710517289 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current NULL, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.710520826 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from NULL to READY 0:00:30.710525986 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from NULL to READY (000a) 0:00:30.710529863 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.710534432 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY 0:00:30.710539261 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:30.710544521 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source videoconvert0: creating new message 0x55970c6a18a0 state-changed 0:00:30.710559179 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x55970c6a18a0] posting on bus state-changed message: 0x55970c6a18a0, time 99:99:99.999999999, seq-num 23, element 'videoconvert0', GstMessageStateChanged, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.710565290 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x55970c6a18a0] handling child videoconvert0 message of type state-changed 0:00:30.710569548 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.710575129 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x55970c6a18a0] posting on bus state-changed message: 0x55970c6a18a0, time 99:99:99.999999999, seq-num 23, element 'videoconvert0', GstMessageStateChanged, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.710579197 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x55970c6a18a0] pushing on async queue 0:00:30.710582373 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x55970c6a18a0] pushed on async queue 0:00:30.710586110 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x55970c6a18a0] dropped 0:00:30.710589416 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.710594175 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.710599355 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'videoconvert0' changed state to 2(READY) successfully 0:00:30.710604004 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives capsfilter1 0:00:30.710608172 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.710611889 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.710621006 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element bayer2rgb0, degree 1->0, linked to capsfilter1 0:00:30.710628200 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'bayer2rgb0' to queue 0:00:30.710634361 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY 0:00:30.710651484 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.710657495 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.710664989 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element capsfilter1 to READY, base_time 0:00:00.000000000 0:00:30.710671011 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to READY 0:00:30.710675509 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to READY 0:00:30.710680949 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current NULL, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.710686199 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from NULL to READY 0:00:30.710691870 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from NULL to READY (000a) 0:00:30.710696068 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.710700206 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY 0:00:30.710705085 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:30.710711317 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source capsfilter1: creating new message 0x55970c6a1820 state-changed 0:00:30.710722408 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x55970c6a1820] posting on bus state-changed message: 0x55970c6a1820, time 99:99:99.999999999, seq-num 24, element 'capsfilter1', GstMessageStateChanged, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.710729101 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x55970c6a1820] handling child capsfilter1 message of type state-changed 0:00:30.710733138 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.710741334 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x55970c6a1820] posting on bus state-changed message: 0x55970c6a1820, time 99:99:99.999999999, seq-num 24, element 'capsfilter1', GstMessageStateChanged, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.710746914 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x55970c6a1820] pushing on async queue 0:00:30.710751012 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x55970c6a1820] pushed on async queue 0:00:30.710755200 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x55970c6a1820] dropped 0:00:30.710759729 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.710764117 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.710769237 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'capsfilter1' changed state to 2(READY) successfully 0:00:30.710774166 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives bayer2rgb0 0:00:30.710776981 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.710781209 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.710786840 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element capsfilter0, degree 1->0, linked to bayer2rgb0 0:00:30.710791168 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'capsfilter0' to queue 0:00:30.710796949 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY 0:00:30.710801818 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.710805846 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.710812719 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element bayer2rgb0 to READY, base_time 0:00:00.000000000 0:00:30.710817288 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to READY 0:00:30.710821977 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to READY 0:00:30.710827357 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current NULL, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.710833098 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from NULL to READY 0:00:30.710838578 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from NULL to READY (000a) 0:00:30.710843047 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.710847174 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY 0:00:30.710852464 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:30.710858686 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source bayer2rgb0: creating new message 0x55970c69ef40 state-changed 0:00:30.710869457 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x55970c69ef40] posting on bus state-changed message: 0x55970c69ef40, time 99:99:99.999999999, seq-num 25, element 'bayer2rgb0', GstMessageStateChanged, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.710876009 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x55970c69ef40] handling child bayer2rgb0 message of type state-changed 0:00:30.710880087 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.710888072 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x55970c69ef40] posting on bus state-changed message: 0x55970c69ef40, time 99:99:99.999999999, seq-num 25, element 'bayer2rgb0', GstMessageStateChanged, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.710893993 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x55970c69ef40] pushing on async queue 0:00:30.710898131 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x55970c69ef40] pushed on async queue 0:00:30.710902359 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x55970c69ef40] dropped 0:00:30.710906988 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.710911737 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.710916806 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'bayer2rgb0' changed state to 2(READY) successfully 0:00:30.710922627 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives capsfilter0 0:00:30.710927106 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.710931925 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.710937425 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element balluff, degree 1->0, linked to capsfilter0 0:00:30.710942054 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'balluff' to queue 0:00:30.710947665 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY 0:00:30.710952464 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.710956692 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.710963104 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element capsfilter0 to READY, base_time 0:00:00.000000000 0:00:30.710967582 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to READY 0:00:30.710997098 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to READY 0:00:30.711003551 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current NULL, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.711009652 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from NULL to READY 0:00:30.711016685 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from NULL to READY (000a) 0:00:30.711021625 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.711026544 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY 0:00:30.711032265 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:30.711039308 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source capsfilter0: creating new message 0x55970c69eec0 state-changed 0:00:30.711047945 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x55970c69eec0] posting on bus state-changed message: 0x55970c69eec0, time 99:99:99.999999999, seq-num 26, element 'capsfilter0', GstMessageStateChanged, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.711054026 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x55970c69eec0] handling child capsfilter0 message of type state-changed 0:00:30.711057453 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.711064726 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x55970c69eec0] posting on bus state-changed message: 0x55970c69eec0, time 99:99:99.999999999, seq-num 26, element 'capsfilter0', GstMessageStateChanged, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.711069916 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x55970c69eec0] pushing on async queue 0:00:30.711075467 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x55970c69eec0] pushed on async queue 0:00:30.711080126 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x55970c69eec0] dropped 0:00:30.711086989 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.711092429 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.711097879 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'capsfilter0' changed state to 2(READY) successfully 0:00:30.711104261 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives balluff 0:00:30.711109752 36 0x55970c4f7190 DEBUG bin gstbin.c:2305:update_degree: element balluff not linked on any sinkpads 0:00:30.711116084 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY 0:00:30.711121644 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.711126584 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.711132946 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element balluff to READY, base_time 0:00:00.000000000 0:00:30.711138897 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to READY 0:00:30.711143997 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to READY 0:00:30.711150148 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current NULL, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.711157332 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from NULL to READY 0:00:30.711164265 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from NULL to READY (000a) 0:00:30.711168723 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.711173903 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY 0:00:30.711179534 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:30.711186828 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source balluff: creating new message 0x55970c69ee40 state-changed 0:00:30.711198309 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x55970c69ee40] posting on bus state-changed message: 0x55970c69ee40, time 99:99:99.999999999, seq-num 27, element 'balluff', GstMessageStateChanged, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.711206385 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x55970c69ee40] handling child balluff message of type state-changed 0:00:30.711210543 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.711218498 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x55970c69ee40] posting on bus state-changed message: 0x55970c69ee40, time 99:99:99.999999999, seq-num 27, element 'balluff', GstMessageStateChanged, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.711223307 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x55970c69ee40] pushing on async queue 0:00:30.711227785 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x55970c69ee40] pushed on async queue 0:00:30.711232224 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x55970c69ee40] dropped 0:00:30.711236792 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.711241922 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.711247012 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'balluff' changed state to 2(READY) successfully 0:00:30.711251751 36 0x55970c4f7190 DEBUG bin gstbin.c:2361:gst_bin_sort_iterator_next: queue empty, elements exhausted 0:00:30.711255408 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:3013:gst_bin_change_state_func: iterator done 0:00:30.711260157 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from NULL to READY (000a) 0:00:30.711264345 36 0x55970c4f7190 DEBUG bin gstbin.c:2403:gst_bin_sort_iterator_free: free 0:00:30.711273963 36 0x55970c4f7190 DEBUG bin gstbin.c:3048:gst_bin_change_state_func: no async elements 0:00:30.711283411 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:3082:gst_bin_change_state_func: done changing bin's state from NULL to READY, now in NULL, ret SUCCESS 0:00:30.711289041 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.711294892 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2651:gst_element_continue_state: committing state from NULL to READY, pending PLAYING, next PAUSED 0:00:30.711300082 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (PLAYING pending) 0:00:30.711305733 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source test: creating new message 0x7f31d402bb80 state-changed 0:00:30.711317385 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402bb80] posting on bus state-changed message: 0x7f31d402bb80, time 99:99:99.999999999, seq-num 28, element 'test', GstMessageStateChanged, old-state=(GstState)GST_STATE_NULL, new-state=(GstState)GST_STATE_READY, pending-state=(GstState)GST_STATE_PLAYING; 0:00:30.711324048 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d402bb80] pushing on async queue 0:00:30.711328055 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d402bb80] pushed on async queue 0:00:30.711334317 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2658:gst_element_continue_state: continue state change READY to PAUSED, final PLAYING 0:00:30.711338866 36 0x55970c4f7190 DEBUG pipeline gstpipeline.c:306:reset_start_time: reset start_time to 0 0:00:30.711343304 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:2854:gst_bin_change_state_func: changing state of children from READY to PAUSED 0:00:30.711348103 36 0x55970c4f7190 DEBUG bin gstbin.c:2878:gst_bin_change_state_func: clearing EOS elements 0:00:30.711352852 36 0x55970c4f7190 DEBUG bin gstbin.c:2699:gst_bin_src_pads_activate: activate pads 0:00:30.711357952 36 0x55970c4f7190 DEBUG bin gstbin.c:2707:gst_bin_src_pads_activate: pad activation successful 0:00:30.711362360 36 0x55970c4f7190 DEBUG bin gstbin.c:2385:gst_bin_sort_iterator_resync: resync 0:00:30.711367279 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'multifilesink0' to queue 0:00:30.711372650 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.711377389 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.711383490 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element bayer2rgb0, degree 0->1, linked to capsfilter1 0:00:30.711388289 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.711392036 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.711397166 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element balluff, degree 0->1, linked to capsfilter0 0:00:30.711401144 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.711404720 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.711409950 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element jpegenc0, degree 0->1, linked to multifilesink0 0:00:30.711413838 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.711417795 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.711422905 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element videoconvert0, degree 0->1, linked to jpegenc0 0:00:30.711426953 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.711430890 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.711435859 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element capsfilter1, degree 0->1, linked to videoconvert0 0:00:30.711439727 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.711443524 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.711448213 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element capsfilter0, degree 0->1, linked to bayer2rgb0 0:00:30.711452942 36 0x55970c4f7190 DEBUG bin gstbin.c:2305:update_degree: element balluff not linked on any sinkpads 0:00:30.711457330 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives multifilesink0 0:00:30.711460466 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.711464273 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.711469734 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element jpegenc0, degree 1->0, linked to multifilesink0 0:00:30.711472900 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'jpegenc0' to queue 0:00:30.711478811 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED 0:00:30.711483770 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.711488038 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.711492607 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element multifilesink0 to PAUSED, base_time 0:00:00.000000000 0:00:30.711496685 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to PAUSED 0:00:30.711501163 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to PAUSED 0:00:30.711506223 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current READY, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.711511042 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from READY to PAUSED 0:00:30.711515961 36 0x55970c4f7190 DEBUG basesink gstbasesink.c:5065:gst_base_sink_change_state: READY to PAUSED 0:00:30.711521882 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from READY to PAUSED (0013) 0:00:30.711526120 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:3097:gst_element_pads_activate: activate pads 0:00:30.711531931 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1106:gst_pad_set_active: activating pad from none 0:00:30.711538263 36 0x55970c4f7190 DEBUG basesink gstbasesink.c:4207:gst_base_sink_pad_activate: Trying pull mode first 0:00:30.711546609 36 0x55970c4f7190 DEBUG basesink gstbasesink.c:4213:gst_base_sink_pad_activate: pull mode disabled 0:00:30.711552280 36 0x55970c4f7190 DEBUG basesink gstbasesink.c:4263:gst_base_sink_pad_activate: Falling back to push mode 0:00:30.711557780 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1012:pre_activate: setting pad into push mode, unset flushing 0:00:30.711564423 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1024:pre_activate: reschedule events on peer jpegenc0:src 0:00:30.711574552 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1235:activate_mode_internal: activated in push mode 0:00:30.711582828 36 0x55970c4f7190 DEBUG basesink gstbasesink.c:4265:gst_base_sink_pad_activate: Success activating push mode 0:00:30.711587917 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:3116:gst_element_pads_activate: pad activation successful 0:00:30.711593939 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.711599038 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED 0:00:30.711604859 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:30.711614989 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source multifilesink0: creating new message 0x7f31d402bc00 state-changed 0:00:30.711626861 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402bc00] posting on bus state-changed message: 0x7f31d402bc00, time 99:99:99.999999999, seq-num 29, element 'multifilesink0', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.711633935 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31d402bc00] handling child multifilesink0 message of type state-changed 0:00:30.711639084 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.711647751 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402bc00] posting on bus state-changed message: 0x7f31d402bc00, time 99:99:99.999999999, seq-num 29, element 'multifilesink0', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.711654303 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d402bc00] pushing on async queue 0:00:30.711659693 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d402bc00] pushed on async queue 0:00:30.711664983 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31d402bc00] dropped 0:00:30.711670494 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.711675804 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.711681104 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'multifilesink0' changed state to 3(PAUSED) successfully 0:00:30.711687857 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives jpegenc0 0:00:30.711695902 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.711701032 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.711706071 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element videoconvert0, degree 1->0, linked to jpegenc0 0:00:30.711709949 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'videoconvert0' to queue 0:00:30.711714808 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED 0:00:30.711718926 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.711722222 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.711727782 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element jpegenc0 to PAUSED, base_time 0:00:00.000000000 0:00:30.711732621 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to PAUSED 0:00:30.711737310 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to PAUSED 0:00:30.711743602 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current READY, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.711749854 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from READY to PAUSED 0:00:30.711758120 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from READY to PAUSED (0013) 0:00:30.711764131 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:3097:gst_element_pads_activate: activate pads 0:00:30.711770654 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1106:gst_pad_set_active: activating pad from none 0:00:30.711777016 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1012:pre_activate: setting pad into push mode, unset flushing 0:00:30.711784059 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1235:activate_mode_internal: activated in push mode 0:00:30.711790050 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1106:gst_pad_set_active: activating pad from none 0:00:30.711794729 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1012:pre_activate: setting pad into push mode, unset flushing 0:00:30.711799658 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1024:pre_activate: reschedule events on peer videoconvert0:src 0:00:30.711804778 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1235:activate_mode_internal: activated in push mode 0:00:30.711809728 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:3116:gst_element_pads_activate: pad activation successful 0:00:30.711816240 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.711821289 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED 0:00:30.711827782 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:30.711846016 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source jpegenc0: creating new message 0x7f31d402bc80 state-changed 0:00:30.711859211 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402bc80] posting on bus state-changed message: 0x7f31d402bc80, time 99:99:99.999999999, seq-num 30, element 'jpegenc0', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.711867016 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31d402bc80] handling child jpegenc0 message of type state-changed 0:00:30.711875192 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.711889589 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402bc80] posting on bus state-changed message: 0x7f31d402bc80, time 99:99:99.999999999, seq-num 30, element 'jpegenc0', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.711900610 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d402bc80] pushing on async queue 0:00:30.711905459 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d402bc80] pushed on async queue 0:00:30.711910488 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31d402bc80] dropped 0:00:30.711915688 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.711920207 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.711926579 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'jpegenc0' changed state to 3(PAUSED) successfully 0:00:30.711933762 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives videoconvert0 0:00:30.711938311 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.711942439 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.711948310 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element capsfilter1, degree 1->0, linked to videoconvert0 0:00:30.711952608 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'capsfilter1' to queue 0:00:30.711958610 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED 0:00:30.711963188 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.711967186 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.711973878 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element videoconvert0 to PAUSED, base_time 0:00:00.000000000 0:00:30.711979629 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to PAUSED 0:00:30.711985290 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to PAUSED 0:00:30.711992003 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current READY, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.711997493 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from READY to PAUSED 0:00:30.712003855 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from READY to PAUSED (0013) 0:00:30.712009997 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:3097:gst_element_pads_activate: activate pads 0:00:30.712015678 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1106:gst_pad_set_active: activating pad from none 0:00:30.712021739 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1012:pre_activate: setting pad into push mode, unset flushing 0:00:30.712028512 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1235:activate_mode_internal: activated in push mode 0:00:30.712035205 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1106:gst_pad_set_active: activating pad from none 0:00:30.712039162 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1012:pre_activate: setting pad into push mode, unset flushing 0:00:30.712043380 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1024:pre_activate: reschedule events on peer capsfilter1:src 0:00:30.712051576 36 0x55970c4f7190 DEBUG GST_CAPS gstpad.c:2733:gst_pad_get_current_caps: get current pad caps (NULL) 0:00:30.712059501 36 0x55970c4f7190 DEBUG GST_CAPS gstpad.c:2733:gst_pad_get_current_caps: get current pad caps (NULL) 0:00:30.712064300 36 0x55970c4f7190 DEBUG basetransform gstbasetransform.c:2401:gst_base_transform_activate: have_same_caps 0 0:00:30.712070652 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1235:activate_mode_internal: activated in push mode 0:00:30.712075962 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:3116:gst_element_pads_activate: pad activation successful 0:00:30.712083526 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.712088215 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED 0:00:30.712093255 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:30.712099977 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source videoconvert0: creating new message 0x7f31d402bd00 state-changed 0:00:30.712113012 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402bd00] posting on bus state-changed message: 0x7f31d402bd00, time 99:99:99.999999999, seq-num 31, element 'videoconvert0', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.712123562 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31d402bd00] handling child videoconvert0 message of type state-changed 0:00:30.712129774 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.712140574 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402bd00] posting on bus state-changed message: 0x7f31d402bd00, time 99:99:99.999999999, seq-num 31, element 'videoconvert0', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.712148629 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d402bd00] pushing on async queue 0:00:30.712154420 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d402bd00] pushed on async queue 0:00:30.712159039 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31d402bd00] dropped 0:00:30.712164570 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.712169960 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.712186942 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'videoconvert0' changed state to 3(PAUSED) successfully 0:00:30.712201810 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives capsfilter1 0:00:30.712217600 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.712225245 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.712232298 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element bayer2rgb0, degree 1->0, linked to capsfilter1 0:00:30.712242597 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'bayer2rgb0' to queue 0:00:30.712264218 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED 0:00:30.712273636 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.712277774 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.712283635 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element capsfilter1 to PAUSED, base_time 0:00:00.000000000 0:00:30.712287723 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to PAUSED 0:00:30.712291791 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to PAUSED 0:00:30.712297782 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current READY, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.712302741 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from READY to PAUSED 0:00:30.712307691 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from READY to PAUSED (0013) 0:00:30.712311728 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:3097:gst_element_pads_activate: activate pads 0:00:30.712317249 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1106:gst_pad_set_active: activating pad from none 0:00:30.712321417 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1012:pre_activate: setting pad into push mode, unset flushing 0:00:30.712326627 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1235:activate_mode_internal: activated in push mode 0:00:30.712331145 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1106:gst_pad_set_active: activating pad from none 0:00:30.712335153 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1012:pre_activate: setting pad into push mode, unset flushing 0:00:30.712339721 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1024:pre_activate: reschedule events on peer bayer2rgb0:src 0:00:30.712344651 36 0x55970c4f7190 DEBUG GST_CAPS gstpad.c:2733:gst_pad_get_current_caps: get current pad caps (NULL) 0:00:30.712348678 36 0x55970c4f7190 DEBUG GST_CAPS gstpad.c:2733:gst_pad_get_current_caps: get current pad caps (NULL) 0:00:30.712353217 36 0x55970c4f7190 DEBUG basetransform gstbasetransform.c:2401:gst_base_transform_activate: have_same_caps 0 0:00:30.712357585 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1235:activate_mode_internal: activated in push mode 0:00:30.712363346 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:3116:gst_element_pads_activate: pad activation successful 0:00:30.712367624 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.712371882 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED 0:00:30.712376681 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:30.712382713 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source capsfilter1: creating new message 0x7f31d402bd80 state-changed 0:00:30.712393213 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402bd80] posting on bus state-changed message: 0x7f31d402bd80, time 99:99:99.999999999, seq-num 32, element 'capsfilter1', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.712399274 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31d402bd80] handling child capsfilter1 message of type state-changed 0:00:30.712403322 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.712411157 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402bd80] posting on bus state-changed message: 0x7f31d402bd80, time 99:99:99.999999999, seq-num 32, element 'capsfilter1', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.712416517 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d402bd80] pushing on async queue 0:00:30.712420174 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d402bd80] pushed on async queue 0:00:30.712424252 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31d402bd80] dropped 0:00:30.712428620 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.712433219 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.712438028 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'capsfilter1' changed state to 3(PAUSED) successfully 0:00:30.712443799 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives bayer2rgb0 0:00:30.712448808 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.712453597 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.712459709 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element capsfilter0, degree 1->0, linked to bayer2rgb0 0:00:30.712464347 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'capsfilter0' to queue 0:00:30.712470118 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED 0:00:30.712473966 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.712477733 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.712482071 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element bayer2rgb0 to PAUSED, base_time 0:00:00.000000000 0:00:30.712485287 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to PAUSED 0:00:30.712488914 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to PAUSED 0:00:30.712494204 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current READY, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.712499414 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from READY to PAUSED 0:00:30.712504884 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from READY to PAUSED (0013) 0:00:30.712508762 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:3097:gst_element_pads_activate: activate pads 0:00:30.712513531 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1106:gst_pad_set_active: activating pad from none 0:00:30.712518230 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1012:pre_activate: setting pad into push mode, unset flushing 0:00:30.712522948 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1235:activate_mode_internal: activated in push mode 0:00:30.712527417 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1106:gst_pad_set_active: activating pad from none 0:00:30.712531304 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1012:pre_activate: setting pad into push mode, unset flushing 0:00:30.712536204 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1024:pre_activate: reschedule events on peer capsfilter0:src 0:00:30.712541444 36 0x55970c4f7190 DEBUG GST_CAPS gstpad.c:2733:gst_pad_get_current_caps: get current pad caps (NULL) 0:00:30.712545421 36 0x55970c4f7190 DEBUG GST_CAPS gstpad.c:2733:gst_pad_get_current_caps: get current pad caps (NULL) 0:00:30.712549489 36 0x55970c4f7190 DEBUG basetransform gstbasetransform.c:2401:gst_base_transform_activate: have_same_caps 0 0:00:30.712554388 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1235:activate_mode_internal: activated in push mode 0:00:30.712558997 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:3116:gst_element_pads_activate: pad activation successful 0:00:30.712562604 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.712567012 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED 0:00:30.712571510 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:30.712576931 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source bayer2rgb0: creating new message 0x7f31d402be00 state-changed 0:00:30.712586579 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402be00] posting on bus state-changed message: 0x7f31d402be00, time 99:99:99.999999999, seq-num 33, element 'bayer2rgb0', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.712599934 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31d402be00] handling child bayer2rgb0 message of type state-changed 0:00:30.712607358 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.712618680 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402be00] posting on bus state-changed message: 0x7f31d402be00, time 99:99:99.999999999, seq-num 33, element 'bayer2rgb0', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.712625372 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d402be00] pushing on async queue 0:00:30.712630943 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d402be00] pushed on async queue 0:00:30.712636544 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31d402be00] dropped 0:00:30.712642285 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.712648266 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.712653806 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'bayer2rgb0' changed state to 3(PAUSED) successfully 0:00:30.712660790 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives capsfilter0 0:00:30.712666160 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.712671470 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.712677692 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element balluff, degree 1->0, linked to capsfilter0 0:00:30.712682781 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'balluff' to queue 0:00:30.712689524 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED 0:00:30.712695626 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.712700565 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.712707167 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element capsfilter0 to PAUSED, base_time 0:00:00.000000000 0:00:30.712712327 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to PAUSED 0:00:30.712717427 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to PAUSED 0:00:30.712723368 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current READY, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.712728969 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from READY to PAUSED 0:00:30.712734880 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from READY to PAUSED (0013) 0:00:30.712741983 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:3097:gst_element_pads_activate: activate pads 0:00:30.712748085 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1106:gst_pad_set_active: activating pad from none 0:00:30.712752413 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1012:pre_activate: setting pad into push mode, unset flushing 0:00:30.712757272 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1235:activate_mode_internal: activated in push mode 0:00:30.712761781 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1106:gst_pad_set_active: activating pad from none 0:00:30.712766941 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1012:pre_activate: setting pad into push mode, unset flushing 0:00:30.712776899 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1024:pre_activate: reschedule events on peer balluff:src 0:00:30.712781899 36 0x55970c4f7190 DEBUG GST_CAPS gstpad.c:2733:gst_pad_get_current_caps: get current pad caps (NULL) 0:00:30.712785997 36 0x55970c4f7190 DEBUG GST_CAPS gstpad.c:2733:gst_pad_get_current_caps: get current pad caps (NULL) 0:00:30.712790846 36 0x55970c4f7190 DEBUG basetransform gstbasetransform.c:2401:gst_base_transform_activate: have_same_caps 0 0:00:30.712797038 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1235:activate_mode_internal: activated in push mode 0:00:30.712803219 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:3116:gst_element_pads_activate: pad activation successful 0:00:30.712806826 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.712811465 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED 0:00:30.712816234 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:30.712823668 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source capsfilter0: creating new message 0x7f31d402be80 state-changed 0:00:30.712834639 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402be80] posting on bus state-changed message: 0x7f31d402be80, time 99:99:99.999999999, seq-num 34, element 'capsfilter0', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.712841301 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31d402be80] handling child capsfilter0 message of type state-changed 0:00:30.712847503 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.712863273 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402be80] posting on bus state-changed message: 0x7f31d402be80, time 99:99:99.999999999, seq-num 34, element 'capsfilter0', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.712870948 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d402be80] pushing on async queue 0:00:30.712876318 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d402be80] pushed on async queue 0:00:30.712881007 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31d402be80] dropped 0:00:30.712887679 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.712893180 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.712899051 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'capsfilter0' changed state to 3(PAUSED) successfully 0:00:30.712905273 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives balluff 0:00:30.712910893 36 0x55970c4f7190 DEBUG bin gstbin.c:2305:update_degree: element balluff not linked on any sinkpads 0:00:30.712917846 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED 0:00:30.712924068 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.712929218 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.712935640 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element balluff to PAUSED, base_time 0:00:00.000000000 0:00:30.712940670 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to PAUSED 0:00:30.712946050 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to PAUSED 0:00:30.712952232 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current READY, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.712957682 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from READY to PAUSED 0:00:30.712963804 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from READY to PAUSED (0013) 0:00:30.712969023 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:3097:gst_element_pads_activate: activate pads 0:00:30.712975225 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1106:gst_pad_set_active: activating pad from none 0:00:30.712980305 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1012:pre_activate: setting pad into push mode, unset flushing 0:00:30.712986096 36 0x55970c4f7190 DEBUG basesrc gstbasesrc.c:3858:gst_base_src_activate_mode: activating in mode 1 0:00:30.712992087 36 0x55970c4f7190 DEBUG basesrc gstbasesrc.c:3784:gst_base_src_activate_push: Activating in push mode 0:00:30.712999211 36 0x55970c4f7190 LOG aravissrc gstaravis.c:436:gst_aravis_start: Open camera 'BALLUFF GmbH-BVS CA-GX0-0032AC-GX301282' 0:00:30.713005002 36 0x55970c4f7190 LOG aravissrc gstaravis.c:119:gst_aravis_get_all_camera_caps: Get all camera caps 0:00:30.860304875 36 0x55970c4f7190 DEBUG structure gststructure.c:1964:gst_structure_parse_field: trying field name 'format' 0:00:30.860322188 36 0x55970c4f7190 DEBUG default gstvalue.c:2548:_priv_gst_value_parse_value: trying type name 'string' 0:00:30.860338740 36 0x55970c4f7190 DEBUG basesrc gstbasesrc.c:3481:gst_base_src_start_complete: starting source 0:00:30.860347927 36 0x55970c4f7190 DEBUG basesrc gstbasesrc.c:3505:gst_base_src_start_complete: format: time, have size: 0, size: 18446744073709551615, duration: -1 0:00:30.860354179 36 0x55970c4f7190 DEBUG basesrc gstbasesrc.c:3508:gst_base_src_start_complete: is seekable: 0 0:00:30.860357976 36 0x55970c4f7190 DEBUG basesrc gstbasesrc.c:3513:gst_base_src_start_complete: is random_access: 0 0:00:30.860364769 36 0x55970c4f7190 DEBUG basesrc gstbasesrc.c:1615:gst_base_src_perform_seek: doing seek: (NULL) 0:00:30.860371151 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:6201:gst_pad_pause_task: pause task 0:00:30.860375810 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:6222:gst_pad_pause_task: pad has no task 0:00:30.860380098 36 0x55970c4f7190 DEBUG basesrc gstbasesrc.c:1671:gst_base_src_perform_seek: seek with seqnum 36 0:00:30.860384937 36 0x55970c4f7190 DEBUG basesrc gstbasesrc.c:1708:gst_base_src_perform_seek: segment configured from 0 to -1, position 0 0:00:30.860396038 36 0x55970c4f7190 INFO basesrc gstbasesrc.c:1358:gst_base_src_do_seek: seeking: time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999 0:00:30.860401448 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:6145:gst_pad_start_task: start task 0:00:30.860438859 36 0x55970c4f7190 DEBUG task gsttask.c:428:gst_task_new: Created task 0x7f31d492e050 0:00:30.860447936 36 0x55970c4f7190 INFO task gsttask.c:457:gst_task_set_lock: setting stream lock 0x7f31d4012190 on task 0x7f31d492e050 0:00:30.860456222 36 0x55970c4f7190 INFO GST_PADS gstpad.c:6154:gst_pad_start_task: created task 0x7f31d492e050 0:00:30.860462815 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:6071:do_stream_status: doing stream-status 0 0:00:30.860474497 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source src: creating new message 0x7f31d402bf00 stream-status 0:00:30.860485958 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:6100:do_stream_status: posting stream-status 0 0:00:30.860514402 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402bf00] posting on bus stream-status message: 0x7f31d402bf00, time 99:99:99.999999999, seq-num 37, element 'src', GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_CREATE, owner=(GstElement)"\(GstAravis\)\ balluff", object=(GstTask)"\(GstTask\)\ task0"; 0:00:30.860525343 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31d402bf00] handling child src message of type stream-status 0:00:30.860531815 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.860547946 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d402bf00] posting on bus stream-status message: 0x7f31d402bf00, time 99:99:99.999999999, seq-num 37, element 'src', GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_CREATE, owner=(GstElement)"\(GstAravis\)\ balluff", object=(GstTask)"\(GstTask\)\ task0"; 0:00:30.860554438 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d402bf00] pushing on async queue 0:00:30.860561251 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d402bf00] pushed on async queue 0:00:30.860568926 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31d402bf00] dropped 0:00:30.860576700 36 0x55970c4f7190 DEBUG task gsttask.c:688:gst_task_set_state: Changing task 0x7f31d492e050 to state 0 0:00:30.860651342 36 0x55970c4f7190 DEBUG basesrc gstbasesrc.c:3618:gst_base_src_start_wait: got ok 0:00:30.860659317 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:1235:activate_mode_internal: activated in push mode 0:00:30.860670628 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:3116:gst_element_pads_activate: pad activation successful 0:00:30.860678082 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2995:gst_element_change_state: element changed state NO_PREROLL 0:00:30.860687109 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED 0:00:30.860696587 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:30.860707668 36 0x7f31d47e9d90 DEBUG task gsttask.c:287:gst_task_func: Entering task 0x7f31d492e050, thread 0x7f31d47e9d90 0:00:30.860734078 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:6071:do_stream_status: doing stream-status 1 0:00:30.860711075 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source balluff: creating new message 0x7f31d492c020 state-changed 0:00:30.860760028 36 0x7f31d47e9d90 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source src: creating new message 0x7f31d492c320 stream-status 0:00:30.860801165 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d492c020] posting on bus state-changed message: 0x7f31d492c020, time 99:99:99.999999999, seq-num 38, element 'balluff', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.860817717 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31d492c020] handling child balluff message of type state-changed 0:00:30.860825341 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.860807157 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:6100:do_stream_status: posting stream-status 1 0:00:30.860839388 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d492c020] posting on bus state-changed message: 0x7f31d492c020, time 99:99:99.999999999, seq-num 38, element 'balluff', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.860857532 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d492c020] pushing on async queue 0:00:30.860865076 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d492c020] pushed on async queue 0:00:30.860873332 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31d492c020] dropped 0:00:30.860885205 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 3 0:00:30.860865908 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d492c320] posting on bus stream-status message: 0x7f31d492c320, time 99:99:99.999999999, seq-num 39, element 'src', GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_ENTER, owner=(GstElement)"\(GstAravis\)\ balluff", object=(GstTask)"\(GstTask\)\ balluff:src"; 0:00:30.860897167 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned NO PREROLL 0:00:30.860909751 36 0x7f31d47e9d90 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31d492c320] handling child src message of type stream-status 0:00:30.860919359 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2997:gst_bin_change_state_func: child 'balluff' changed state to 3(PAUSED) successfully without preroll 0:00:30.860925571 36 0x7f31d47e9d90 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.860937914 36 0x55970c4f7190 DEBUG bin gstbin.c:2361:gst_bin_sort_iterator_next: queue empty, elements exhausted 0:00:30.860952743 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:3013:gst_bin_change_state_func: iterator done 0:00:30.860966569 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from READY to PAUSED (0013) 0:00:30.860974864 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d492c320] posting on bus stream-status message: 0x7f31d492c320, time 99:99:99.999999999, seq-num 39, element 'src', GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_ENTER, owner=(GstElement)"\(GstAravis\)\ balluff", object=(GstTask)"\(GstTask\)\ balluff:src"; 0:00:30.860987058 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d492c320] pushing on async queue 0:00:30.860977660 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:3097:gst_element_pads_activate: activate pads 0:00:30.860994482 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d492c320] pushed on async queue 0:00:30.861009540 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:3116:gst_element_pads_activate: pad activation successful 0:00:30.861011854 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31d492c320] dropped 0:00:30.861021743 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:3026:gst_bin_change_state_func: we have NO_PREROLL elements SUCCESS -> NO_PREROLL 0:00:30.861034137 36 0x7f31d47e9d90 DEBUG task gsttask.c:249:gst_task_configure_name: Setting thread name to 'balluff:src' 0:00:30.861036020 36 0x55970c4f7190 DEBUG bin gstbin.c:2403:gst_bin_sort_iterator_free: free 0:00:30.861046400 36 0x7f31d47e9d90 INFO GST_ELEMENT_PADS gstelement.c:917:gst_element_get_static_pad: no such pad 'sink' in element "balluff" 0:00:30.861055577 36 0x55970c4f7190 DEBUG bin gstbin.c:3048:gst_bin_change_state_func: no async elements 0:00:30.861063633 36 0x7f31d47e9d90 DEBUG query gstquery.c:675:gst_query_new_custom: creating new query 0x7f31c8004800 uri 0:00:30.861067790 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:3082:gst_bin_change_state_func: done changing bin's state from READY to PAUSED, now in READY, ret NO PREROLL 0:00:30.861082308 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2995:gst_element_change_state: element changed state NO_PREROLL 0:00:30.861098238 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2651:gst_element_continue_state: committing state from READY to PAUSED, pending PLAYING, next PLAYING 0:00:30.861112736 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (PLAYING pending) 0:00:30.861126482 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source test: creating new message 0x7f31d492c0a0 state-changed 0:00:30.861068442 36 0x7f31d47e9d90 DEBUG GST_ELEMENT_PADS gstelement.c:1962:gst_element_query: send query on element balluff 0:00:30.861144426 36 0x7f31d47e9d90 DEBUG GST_ELEMENT_PADS gstelement.c:1746:gst_element_get_random_pad: getting a random pad 0:00:30.861146399 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d492c0a0] posting on bus state-changed message: 0x7f31d492c0a0, time 99:99:99.999999999, seq-num 40, element 'test', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_PLAYING; 0:00:30.861153653 36 0x7f31d47e9d90 DEBUG GST_ELEMENT_PADS gstelement.c:1765:gst_element_get_random_pad: checking pad balluff:src 0:00:30.861171447 36 0x7f31d47e9d90 DEBUG GST_ELEMENT_PADS gstelement.c:1777:gst_element_get_random_pad: found pad balluff:src 0:00:30.861179843 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:4049:gst_pad_query: doing query 0x7f31c8004800 (uri) 0:00:30.861167139 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d492c0a0] pushing on async queue 0:00:30.861189481 36 0x7f31d47e9d90 DEBUG basesrc gstbasesrc.c:1310:gst_base_src_default_query: query uri returns 0 0:00:30.861201373 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:4072:gst_pad_query: sent query 0x7f31c8004800 (uri), result 0 0:00:30.861208056 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:4117:gst_pad_query: query failed 0:00:30.861214538 36 0x7f31d47e9d90 FIXME default gstutils.c:3981:gst_pad_create_stream_id_internal: Creating random stream-id, consider implementing a deterministic way of creating a stream-id 0:00:30.861193539 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d492c0a0] pushed on async queue 0:00:30.861233414 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2658:gst_element_continue_state: continue state change PAUSED to PLAYING, final PLAYING 0:00:30.861240748 36 0x55970c4f7190 DEBUG pipeline gstpipeline.c:412:gst_pipeline_change_state: selecting clock and base_time 0:00:30.861248803 36 0x55970c4f7190 DEBUG pipeline gstpipeline.c:433:gst_pipeline_change_state: Need to update start_time 0:00:30.861256618 36 0x55970c4f7190 DEBUG pipeline gstpipeline.c:438:gst_pipeline_change_state: Need to update clock. 0:00:30.861260175 36 0x7f31d47e9d90 DEBUG basesrc gstbasesrc.c:897:gst_base_src_send_stream_start: Pushing STREAM_START 0:00:30.861268250 36 0x55970c4f7190 DEBUG bin gstbin.c:837:gst_bin_provide_clock_func: returning old clock (nil) 0:00:30.861281205 36 0x7f31d47e9d90 DEBUG GST_EVENT gstevent.c:306:gst_event_new_custom: creating new event 0x7f31d4037e00 stream-start 10254 0:00:30.861290202 36 0x55970c4f7190 DEBUG GST_CLOCK gstsystemclock.c:355:gst_system_clock_obtain: creating new static system clock 0:00:30.861290522 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5159:store_sticky_event: Removing pending EOS and StreamGroupDone events 0:00:30.861306232 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event stream-start 0:00:30.861311652 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3980:check_sticky: pushing all sticky events 0:00:30.861319818 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31d4037e00 (stream-start) to peerpad 0:00:30.861337511 36 0x7f31d47e9d90 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type stream-start event: 0x7f31d4037e00, time 99:99:99.999999999, seq-num 41, GstEventStreamStart, stream-id=(string)607596e5a80525c6ddfea5f15e5a223b, flags=(GstStreamFlags)GST_STREAM_FLAG_NONE, group-id=(uint)1; 0:00:30.861338022 36 0x55970c4f7190 DEBUG GST_POLL gstpoll.c:681:gst_poll_new: 0x7f31d47e9de0: new controllable : 1 0:00:30.861345977 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5701:gst_pad_send_event_unchecked: Removing pending EOS events 0:00:30.861364553 36 0x7f31d47e9d90 LOG capsfilter gstcapsfilter.c:520:gst_capsfilter_sink_event: Forwarding stream-start event 0:00:30.861370123 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5159:store_sticky_event: Removing pending EOS and StreamGroupDone events 0:00:30.861375313 36 0x55970c4f7190 DEBUG GST_POLL gstpoll.c:848:gst_poll_add_fd_unlocked: 0x7f31d47e9de0: fd (fd:12, idx:0) 0:00:30.861375764 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event stream-start 0:00:30.861385873 36 0x55970c4f7190 DEBUG GST_POLL gstpoll.c:1015:gst_poll_fd_ctl_read_unlocked: 0x7f31d47e9de0: fd (fd:12, idx:0), active : 1 0:00:30.861386574 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3980:check_sticky: pushing all sticky events 0:00:30.861406642 36 0x55970c4f7190 DEBUG GST_CLOCK gstpipeline.c:732:gst_pipeline_provide_clock_func: pipeline obtained system clock: 0x7f31d49300d0 (GstSystemClock) 0:00:30.861408877 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31d4037e00 (stream-start) to peerpad 0:00:30.861421380 36 0x55970c4f7190 DEBUG GST_CLOCK gstclock.c:1046:gst_clock_get_internal_time: internal time 382:56:07.591328816 0:00:30.861438212 36 0x55970c4f7190 DEBUG GST_CLOCK gstclock.c:1091:gst_clock_get_time: adjusted time 382:56:07.591328816 0:00:30.861446027 36 0x55970c4f7190 DEBUG GST_CLOCK gstelement.c:429:gst_element_set_clock: setting clock 0x7f31d49300d0 0:00:30.861439775 36 0x7f31d47e9d90 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type stream-start event: 0x7f31d4037e00, time 99:99:99.999999999, seq-num 41, GstEventStreamStart, stream-id=(string)607596e5a80525c6ddfea5f15e5a223b, flags=(GstStreamFlags)GST_STREAM_FLAG_NONE, group-id=(uint)1; 0:00:30.861459232 36 0x55970c4f7190 DEBUG GST_CLOCK gstelement.c:429:gst_element_set_clock: setting clock 0x7f31d49300d0 0:00:30.861469061 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5701:gst_pad_send_event_unchecked: Removing pending EOS events 0:00:30.861487355 36 0x55970c4f7190 DEBUG GST_CLOCK gstelement.c:429:gst_element_set_clock: setting clock 0x7f31d49300d0 0:00:30.861502814 36 0x55970c4f7190 DEBUG GST_CLOCK gstelement.c:429:gst_element_set_clock: setting clock 0x7f31d49300d0 0:00:30.861514236 36 0x55970c4f7190 DEBUG GST_CLOCK gstelement.c:429:gst_element_set_clock: setting clock 0x7f31d49300d0 0:00:30.861525648 36 0x55970c4f7190 DEBUG GST_CLOCK gstelement.c:429:gst_element_set_clock: setting clock 0x7f31d49300d0 0:00:30.861535536 36 0x55970c4f7190 DEBUG GST_CLOCK gstelement.c:429:gst_element_set_clock: setting clock 0x7f31d49300d0 0:00:30.861545325 36 0x55970c4f7190 DEBUG GST_CLOCK gstelement.c:429:gst_element_set_clock: setting clock 0x7f31d49300d0 0:00:30.861555875 36 0x55970c4f7190 DEBUG bin gstbin.c:726:gst_bin_set_clock_func: iterator done 0:00:30.861568108 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source test: creating new message 0x7f31d492c1a0 new-clock 0:00:30.861587234 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d492c1a0] posting on bus new-clock message: 0x7f31d492c1a0, time 99:99:99.999999999, seq-num 42, element 'test', GstMessageNewClock, clock=(GstClock)"\(GstSystemClock\)\ GstSystemClock"; 0:00:30.861600730 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d492c1a0] pushing on async queue 0:00:30.861610108 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d492c1a0] pushed on async queue 0:00:30.861623573 36 0x55970c4f7190 DEBUG pipeline gstpipeline.c:478:gst_pipeline_change_state: start_time=0:00:00.000000000, now=382:56:07.591328816, base_time 382:56:07.591328816 0:00:30.861635436 36 0x55970c4f7190 DEBUG GST_CLOCK gstelement.c:489:gst_element_set_base_time: set base_time=382:56:07.591328816, old 0:00:00.000000000 0:00:30.861647118 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:2854:gst_bin_change_state_func: changing state of children from PAUSED to PLAYING 0:00:30.861664421 36 0x55970c4f7190 DEBUG bin gstbin.c:2757:gst_bin_do_latency_func: querying latency 0:00:30.861675411 36 0x55970c4f7190 DEBUG query gstquery.c:675:gst_query_new_custom: creating new query 0x7f31d47e9e30 latency 0:00:30.861685851 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:1962:gst_element_query: send query on element test 0:00:30.861696551 36 0x55970c4f7190 DEBUG bin gstbin.c:4375:gst_bin_query: Sending query 0x7f31d47e9e30 (type latency) to sink children 0:00:30.861705248 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child capsfilter1 is not sink 0:00:30.861713874 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child capsfilter0 is not sink 0:00:30.861724144 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child multifilesink0 is sink 0:00:30.861734173 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:1962:gst_element_query: send query on element multifilesink0 0:00:30.861746636 36 0x55970c4f7190 DEBUG basesink gstbasesink.c:1168:gst_base_sink_query_latency: we are ready for LATENCY query 0:00:30.861760222 36 0x55970c4f7190 DEBUG query gstquery.c:675:gst_query_new_custom: creating new query 0x7f31d47e9e80 latency 0:00:30.861772185 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4176:gst_pad_peer_query: peer query 0x7f31d47e9e80 (latency) 0:00:30.861785279 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4049:gst_pad_query: doing query 0x7f31d47e9e80 (latency) 0:00:30.861797142 36 0x55970c4f7190 DEBUG videoencoder gstvideoencoder.c:1387:gst_video_encoder_src_query: received query 7683, latency 0:00:30.861813242 36 0x55970c4f7190 LOG videoencoder gstvideoencoder.c:1322:gst_video_encoder_src_query_default: handling query: latency query: 0x7f31d47e9e80, GstQueryLatency, live=(boolean)false, min-latency=(guint64)0, max-latency=(guint64)18446744073709551615; 0:00:30.861825576 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4176:gst_pad_peer_query: peer query 0x7f31d47e9e80 (latency) 0:00:30.861835745 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4049:gst_pad_query: doing query 0x7f31d47e9e80 (latency) 0:00:30.861844401 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4176:gst_pad_peer_query: peer query 0x7f31d47e9e80 (latency) 0:00:30.861852587 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4049:gst_pad_query: doing query 0x7f31d47e9e80 (latency) 0:00:30.861863237 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4176:gst_pad_peer_query: peer query 0x7f31d47e9e80 (latency) 0:00:30.861873687 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4049:gst_pad_query: doing query 0x7f31d47e9e80 (latency) 0:00:30.861887543 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4176:gst_pad_peer_query: peer query 0x7f31d47e9e80 (latency) 0:00:30.861905487 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5159:store_sticky_event: Removing pending EOS and StreamGroupDone events 0:00:30.861916037 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event stream-start 0:00:30.861914604 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4049:gst_pad_query: doing query 0x7f31d47e9e80 (latency) 0:00:30.861925044 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3980:check_sticky: pushing all sticky events 0:00:30.861946244 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4176:gst_pad_peer_query: peer query 0x7f31d47e9e80 (latency) 0:00:30.861956424 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31d4037e00 (stream-start) to peerpad 0:00:30.861963387 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4049:gst_pad_query: doing query 0x7f31d47e9e80 (latency) 0:00:30.861980028 36 0x7f31d47e9d90 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type stream-start event: 0x7f31d4037e00, time 99:99:99.999999999, seq-num 41, GstEventStreamStart, stream-id=(string)607596e5a80525c6ddfea5f15e5a223b, flags=(GstStreamFlags)GST_STREAM_FLAG_NONE, group-id=(uint)1; 0:00:30.861997461 36 0x55970c4f7190 LOG basesrc gstbasesrc.c:746:gst_base_src_query_latency: latency: live 1, min 0:00:00.000000000, max 0:00:00.000000000 0:00:30.861998583 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5701:gst_pad_send_event_unchecked: Removing pending EOS events 0:00:30.862016708 36 0x55970c4f7190 LOG basesrc gstbasesrc.c:1202:gst_base_src_default_query: report latency: live 1, min 0:00:00.000000000, max 0:00:00.000000000 0:00:30.862020114 36 0x7f31d47e9d90 LOG capsfilter gstcapsfilter.c:520:gst_capsfilter_sink_event: Forwarding stream-start event 0:00:30.862035002 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5159:store_sticky_event: Removing pending EOS and StreamGroupDone events 0:00:30.862031025 36 0x55970c4f7190 DEBUG basesrc gstbasesrc.c:1310:gst_base_src_default_query: query latency returns 1 0:00:30.862046164 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event stream-start 0:00:30.862057535 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4072:gst_pad_query: sent query 0x7f31d47e9e80 (latency), result 1 0:00:30.862064128 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3980:check_sticky: pushing all sticky events 0:00:30.862078004 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4072:gst_pad_query: sent query 0x7f31d47e9e80 (latency), result 1 0:00:30.862098423 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31d4037e00 (stream-start) to peerpad 0:00:30.862103693 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4072:gst_pad_query: sent query 0x7f31d47e9e80 (latency), result 1 0:00:30.862122047 36 0x7f31d47e9d90 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type stream-start event: 0x7f31d4037e00, time 99:99:99.999999999, seq-num 41, GstEventStreamStart, stream-id=(string)607596e5a80525c6ddfea5f15e5a223b, flags=(GstStreamFlags)GST_STREAM_FLAG_NONE, group-id=(uint)1; 0:00:30.862124632 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4072:gst_pad_query: sent query 0x7f31d47e9e80 (latency), result 1 0:00:30.862134080 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5701:gst_pad_send_event_unchecked: Removing pending EOS events 0:00:30.862152014 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5159:store_sticky_event: Removing pending EOS and StreamGroupDone events 0:00:30.862155621 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4072:gst_pad_query: sent query 0x7f31d47e9e80 (latency), result 1 0:00:30.862161692 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event stream-start 0:00:30.862179797 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3980:check_sticky: pushing all sticky events 0:00:30.862194104 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31d4037e00 (stream-start) to peerpad 0:00:30.862199544 36 0x55970c4f7190 DEBUG videoencoder gstvideoencoder.c:1351:gst_video_encoder_src_query_default: Peer latency: live 1, min 0:00:00.000000000 max 0:00:00.000000000 0:00:30.862216566 36 0x7f31d47e9d90 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type stream-start event: 0x7f31d4037e00, time 99:99:99.999999999, seq-num 41, GstEventStreamStart, stream-id=(string)607596e5a80525c6ddfea5f15e5a223b, flags=(GstStreamFlags)GST_STREAM_FLAG_NONE, group-id=(uint)1; 0:00:30.862217789 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:4072:gst_pad_query: sent query 0x7f31d47e9e80 (latency), result 1 0:00:30.862226325 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5701:gst_pad_send_event_unchecked: Removing pending EOS events 0:00:30.862244750 36 0x7f31d47e9d90 DEBUG videoencoder gstvideoencoder.c:1206:gst_video_encoder_sink_event: received event 10254, stream-start 0:00:30.862246082 36 0x55970c4f7190 DEBUG basesink gstbasesink.c:1210:gst_base_sink_query_latency: latency query: live: 0, have_latency 1, upstream: 1, min 0:00:00.000000000, max 0:00:00.000000000 0:00:30.862252775 36 0x7f31d47e9d90 DEBUG videoencoder gstvideoencoder.c:1108:gst_video_encoder_sink_event_default: STREAM_START, clearing upstream tags 0:00:30.862265850 36 0x55970c4f7190 DEBUG basesink gstbasesink.c:4935:default_element_query: query latency returns 1 0:00:30.862273835 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.862288623 36 0x55970c4f7190 DEBUG bin gstbin.c:4190:bin_query_latency_fold: got latency min 0:00:00.000000000, max 0:00:00.000000000, live 0 0:00:30.862289745 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5159:store_sticky_event: Removing pending EOS and StreamGroupDone events 0:00:30.862303621 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child jpegenc0 is not sink 0:00:30.862309683 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event stream-start 0:00:30.862321826 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child videoconvert0 is not sink 0:00:30.862331424 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31d4037e00 (stream-start) to peerpad , ret ok 0:00:30.862340471 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child bayer2rgb0 is not sink 0:00:30.862349438 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3932:push_sticky: event stream-start marked received 0:00:30.862359187 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child balluff is not sink 0:00:30.862365739 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.862382561 36 0x55970c4f7190 DEBUG bin gstbin.c:4221:bin_query_latency_done: latency min 0:00:00.000000000, max 99:99:99.999999999, live 0 0:00:30.862382431 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5159:store_sticky_event: Removing pending EOS and StreamGroupDone events 0:00:30.862396437 36 0x55970c4f7190 DEBUG bin gstbin.c:4397:gst_bin_query: query 0x7f31d47e9e30 result 1 0:00:30.862410444 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event stream-start 0:00:30.862419561 36 0x55970c4f7190 DEBUG bin gstbin.c:2768:gst_bin_do_latency_func: got min latency 0:00:00.000000000, max latency 99:99:99.999999999, live 0 0:00:30.862426624 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31d4037e00 (stream-start) to peerpad , ret ok 0:00:30.862430922 36 0x55970c4f7190 INFO GST_EVENT gstevent.c:1388:gst_event_new_latency: creating latency event 0:00:00.000000000 0:00:30.862436022 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3932:push_sticky: event stream-start marked received 0:00:30.862452263 36 0x55970c4f7190 DEBUG GST_EVENT gstevent.c:306:gst_event_new_custom: creating new event 0x7f31c8007100 latency 56321 0:00:30.862453996 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.862465518 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:1856:gst_element_send_event: send latency event on element test 0:00:30.862472792 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5159:store_sticky_event: Removing pending EOS and StreamGroupDone events 0:00:30.862484273 36 0x55970c4f7190 DEBUG bin gstbin.c:3139:gst_bin_send_event: Sending latency event to sink children 0:00:30.862491637 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event stream-start 0:00:30.862516835 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31d4037e00 (stream-start) to peerpad , ret ok 0:00:30.862506045 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child capsfilter1 is not sink 0:00:30.862529920 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3932:push_sticky: event stream-start marked received 0:00:30.862544788 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child capsfilter0 is not sink 0:00:30.862550589 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.862568132 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5159:store_sticky_event: Removing pending EOS and StreamGroupDone events 0:00:30.862580265 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event stream-start 0:00:30.862591036 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31d4037e00 (stream-start) to peerpad , ret ok 0:00:30.862559466 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child multifilesink0 is sink 0:00:30.862603099 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3932:push_sticky: event stream-start marked received 0:00:30.862616083 36 0x55970c4f7190 DEBUG GST_ELEMENT_PADS gstelement.c:1856:gst_element_send_event: send latency event on element multifilesink0 0:00:30.862618698 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.862630460 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5159:store_sticky_event: Removing pending EOS and StreamGroupDone events 0:00:30.862632414 36 0x55970c4f7190 DEBUG basesink gstbasesink.c:4470:gst_base_sink_send_event: handling event 0x7f31c8007100 latency event: 0x7f31c8007100, time 99:99:99.999999999, seq-num 43, GstEventLatency, latency=(guint64)0; 0:00:30.862639367 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event stream-start 0:00:30.862649737 36 0x55970c4f7190 DEBUG basesink gstbasesink.c:4487:gst_base_sink_send_event: latency set to 0:00:00.000000000 0:00:30.862655678 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31d4037e00 (stream-start) to peerpad , ret ok 0:00:30.862667901 36 0x55970c4f7190 DEBUG basesink gstbasesink.c:4509:gst_base_sink_send_event: sending event 0x7f31c8007100 latency event: 0x7f31c8007100, time 99:99:99.999999999, seq-num 43, GstEventLatency, latency=(guint64)0; 0:00:30.862679243 36 0x55970c4f7190 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31c8007100 (latency) to peerpad 0:00:30.862679543 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3932:push_sticky: event stream-start marked received 0:00:30.862690374 36 0x55970c4f7190 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type latency event: 0x7f31c8007100, time 99:99:99.999999999, seq-num 43, GstEventLatency, latency=(guint64)0; 0:00:30.862694311 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:1677:gst_pad_check_reconfigure: remove RECONFIGURE flag 0:00:30.862708759 36 0x7f31d47e9d90 DEBUG basesrc gstbasesrc.c:3367:gst_base_src_negotiate: starting negotiation 0:00:30.862705322 36 0x55970c4f7190 LOG videoencoder gstvideoencoder.c:1305:gst_video_encoder_src_event: handling event: latency event: 0x7f31c8007100, time 99:99:99.999999999, seq-num 43, GstEventLatency, latency=(guint64)0; 0:00:30.862719048 36 0x7f31d47e9d90 DEBUG GST_CAPS gstutils.c:3058:gst_pad_query_caps: get pad caps with filter (NULL) 0:00:30.862729227 36 0x55970c4f7190 LOG GST_PADS gstpad.c:3087:gst_pad_event_default: default event handler for event latency event: 0x7f31c8007100, time 99:99:99.999999999, seq-num 43, GstEventLatency, latency=(guint64)0; 0:00:30.862734598 36 0x7f31d47e9d90 DEBUG query gstquery.c:675:gst_query_new_custom: creating new query 0x7f31c8004850 caps 0:00:30.862740178 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:2897:gst_pad_iterate_internal_links_default: Making iterator 0:00:30.862748314 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:4049:gst_pad_query: doing query 0x7f31c8004850 (caps) 0:00:30.862756619 36 0x55970c4f7190 LOG GST_PADS gstpad.c:3007:gst_pad_forward: calling forward function on pad jpegenc0:sink 0:00:30.862768973 36 0x7f31d47e9d90 DEBUG GST_PERFORMANCE gstcaps.c:179:_gst_caps_copy:video/x-bayer, format=(string)grbg, width=(int)[ 256, 2064 ], height=(int)[ 16, 1544 ] doing copy 0x7f31d47e9d40 -> 0x7f31c80048a0 0:00:30.862773040 36 0x55970c4f7190 LOG GST_PADS gstpad.c:3052:event_forward_func: Reffing and pushing event 0x7f31c8007100 (latency) to jpegenc0:sink 0:00:30.862785354 36 0x7f31d47e9d90 LOG aravissrc gstaravis.c:181:gst_aravis_get_caps: Available caps = video/x-bayer, format=(string)grbg, width=(int)[ 256, 2064 ], height=(int)[ 16, 1544 ] 0:00:30.862789381 36 0x55970c4f7190 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31c8007100 (latency) to peerpad 0:00:30.862797617 36 0x7f31d47e9d90 DEBUG basesrc gstbasesrc.c:1310:gst_base_src_default_query: query caps returns 1 0:00:30.862806103 36 0x55970c4f7190 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type latency event: 0x7f31c8007100, time 99:99:99.999999999, seq-num 43, GstEventLatency, latency=(guint64)0; 0:00:30.862812495 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:4072:gst_pad_query: sent query 0x7f31c8004850 (caps), result 1 0:00:30.862821793 36 0x55970c4f7190 DEBUG basetransform gstbasetransform.c:1941:gst_base_transform_src_eventfunc: handling event 0x7f31c8007100 latency event: 0x7f31c8007100, time 99:99:99.999999999, seq-num 43, GstEventLatency, latency=(guint64)0; 0:00:30.862828606 36 0x7f31d47e9d90 DEBUG GST_CAPS gstutils.c:3065:gst_pad_query_caps: query returned video/x-bayer, format=(string)grbg, width=(int)[ 256, 2064 ], height=(int)[ 16, 1544 ] 0:00:30.862836270 36 0x55970c4f7190 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31c8007100 (latency) to peerpad 0:00:30.862850347 36 0x55970c4f7190 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type latency event: 0x7f31c8007100, time 99:99:99.999999999, seq-num 43, GstEventLatency, latency=(guint64)0; 0:00:30.862851649 36 0x7f31d47e9d90 DEBUG basesrc gstbasesrc.c:3297:gst_base_src_default_negotiate: caps of src: video/x-bayer, format=(string)grbg, width=(int)[ 256, 2064 ], height=(int)[ 16, 1544 ] 0:00:30.862863532 36 0x55970c4f7190 DEBUG basetransform gstbasetransform.c:1941:gst_base_transform_src_eventfunc: handling event 0x7f31c8007100 latency event: 0x7f31c8007100, time 99:99:99.999999999, seq-num 43, GstEventLatency, latency=(guint64)0; 0:00:30.862870174 36 0x7f31d47e9d90 DEBUG GST_CAPS gstutils.c:3104:gst_pad_peer_query_caps: get pad peer caps with filter video/x-bayer, format=(string)grbg, width=(int)[ 256, 2064 ], height=(int)[ 16, 1544 ] 0:00:30.862878250 36 0x55970c4f7190 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31c8007100 (latency) to peerpad 0:00:30.862885984 36 0x7f31d47e9d90 DEBUG query gstquery.c:675:gst_query_new_custom: creating new query 0x7f31c80048f0 caps 0:00:30.862893158 36 0x55970c4f7190 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type latency event: 0x7f31c8007100, time 99:99:99.999999999, seq-num 43, GstEventLatency, latency=(guint64)0; 0:00:30.862898739 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:4176:gst_pad_peer_query: peer query 0x7f31c80048f0 (caps) 0:00:30.862908647 36 0x55970c4f7190 DEBUG basetransform gstbasetransform.c:1941:gst_base_transform_src_eventfunc: handling event 0x7f31c8007100 latency event: 0x7f31c8007100, time 99:99:99.999999999, seq-num 43, GstEventLatency, latency=(guint64)0; 0:00:30.862913887 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:4049:gst_pad_query: doing query 0x7f31c80048f0 (caps) 0:00:30.862922173 36 0x55970c4f7190 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31c8007100 (latency) to peerpad 0:00:30.862932192 36 0x7f31d47e9d90 DEBUG basetransform gstbasetransform.c:633:gst_base_transform_query_caps: filter caps video/x-bayer, format=(string)grbg, width=(int)[ 256, 2064 ], height=(int)[ 16, 1544 ] 0:00:30.862939816 36 0x55970c4f7190 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type latency event: 0x7f31c8007100, time 99:99:99.999999999, seq-num 43, GstEventLatency, latency=(guint64)0; 0:00:30.862945217 36 0x7f31d47e9d90 DEBUG basetransform gstbasetransform.c:636:gst_base_transform_query_caps: our template ANY 0:00:30.862957891 36 0x55970c4f7190 DEBUG basetransform gstbasetransform.c:1941:gst_base_transform_src_eventfunc: handling event 0x7f31c8007100 latency event: 0x7f31c8007100, time 99:99:99.999999999, seq-num 43, GstEventLatency, latency=(guint64)0; 0:00:30.862971446 36 0x55970c4f7190 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31c8007100 (latency) to peerpad 0:00:30.862974903 36 0x7f31d47e9d90 DEBUG basetransform gstbasetransform.c:638:gst_base_transform_query_caps: intersected video/x-bayer, format=(string)grbg, width=(int)[ 256, 2064 ], height=(int)[ 16, 1544 ] 0:00:30.862981996 36 0x55970c4f7190 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type latency event: 0x7f31c8007100, time 99:99:99.999999999, seq-num 43, GstEventLatency, latency=(guint64)0; 0:00:30.862991053 36 0x7f31d47e9d90 DEBUG basetransform gstbasetransform.c:462:gst_base_transform_transform_caps: transform caps (direction = 2) 0:00:30.863003808 36 0x55970c4f7190 DEBUG basesrc gstbasesrc.c:2027:gst_base_src_default_event: handle event latency event: 0x7f31c8007100, time 99:99:99.999999999, seq-num 43, GstEventLatency, latency=(guint64)0; 0:00:30.863015259 36 0x7f31d47e9d90 LOG basetransform gstbasetransform.c:464:gst_base_transform_transform_caps: from: video/x-bayer, format=(string)grbg, width=(int)[ 256, 2064 ], height=(int)[ 16, 1544 ] 0:00:30.863029356 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.863033694 36 0x7f31d47e9d90 DEBUG capsfilter gstcapsfilter.c:296:gst_capsfilter_transform_caps: input: video/x-bayer, format=(string)grbg, width=(int)[ 256, 2064 ], height=(int)[ 16, 1544 ] 0:00:30.863043312 36 0x55970c4f7190 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31c8007100 (latency) to peerpad , ret ok 0:00:30.863050225 36 0x7f31d47e9d90 DEBUG capsfilter gstcapsfilter.c:297:gst_capsfilter_transform_caps: filter: (NULL) 0:00:30.863057529 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.863075263 36 0x55970c4f7190 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31c8007100 (latency) to peerpad , ret ok 0:00:30.863081415 36 0x7f31d47e9d90 DEBUG capsfilter gstcapsfilter.c:299:gst_capsfilter_transform_caps: caps filter: video/x-bayer, format=(string)rggb, width=(int)1920, height=(int)1080, framerate=(fraction)0/1, pixel-aspect-ratio=(fraction)1/1 0:00:30.863082607 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.863093928 36 0x7f31d47e9d90 DEBUG capsfilter gstcapsfilter.c:300:gst_capsfilter_transform_caps: intersect: EMPTY 0:00:30.863102304 36 0x55970c4f7190 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31c8007100 (latency) to peerpad , ret ok 0:00:30.863110289 36 0x7f31d47e9d90 LOG basetransform gstbasetransform.c:466:gst_base_transform_transform_caps: to: EMPTY 0:00:30.863119006 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.863127913 36 0x7f31d47e9d90 DEBUG basetransform gstbasetransform.c:489:gst_base_transform_transform_caps: to: EMPTY 0:00:30.863136258 36 0x55970c4f7190 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31c8007100 (latency) to peerpad , ret ok 0:00:30.863145115 36 0x7f31d47e9d90 DEBUG basetransform gstbasetransform.c:643:gst_base_transform_query_caps: transformed EMPTY 0:00:30.863151117 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.863162067 36 0x7f31d47e9d90 DEBUG basetransform gstbasetransform.c:659:gst_base_transform_query_caps: peer filter caps EMPTY 0:00:30.863169932 36 0x55970c4f7190 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31c8007100 (latency) to peerpad , ret ok 0:00:30.863176996 36 0x7f31d47e9d90 DEBUG basetransform gstbasetransform.c:662:gst_base_transform_query_caps: peer filter caps are empty 0:00:30.863186484 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.863193106 36 0x7f31d47e9d90 DEBUG basetransform gstbasetransform.c:722:gst_base_transform_query_caps: returning EMPTY 0:00:30.863206782 36 0x55970c4f7190 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31c8007100 (latency) to peerpad , ret ok 0:00:30.863214156 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:4072:gst_pad_query: sent query 0x7f31c80048f0 (caps), result 1 0:00:30.863222682 36 0x55970c4f7190 DEBUG basesink gstbasesink.c:4518:gst_base_sink_send_event: handled event: 1 0:00:30.863238472 36 0x55970c4f7190 LOG bin gstbin.c:3152:gst_bin_send_event: After handling latency event: 1 0:00:30.863251817 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child jpegenc0 is not sink 0:00:30.863259512 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child videoconvert0 is not sink 0:00:30.863268148 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child bayer2rgb0 is not sink 0:00:30.863278558 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child balluff is not sink 0:00:30.863230467 36 0x7f31d47e9d90 DEBUG GST_CAPS gstutils.c:3111:gst_pad_peer_query_caps: peer query returned EMPTY 0:00:30.863302143 36 0x55970c4f7190 DEBUG bin gstbin.c:3179:gst_bin_send_event: Sending latency event to src pads 0:00:30.863312653 36 0x7f31d47e9d90 DEBUG basesrc gstbasesrc.c:3307:gst_base_src_default_negotiate: caps of peer: EMPTY 0:00:30.863318584 36 0x55970c4f7190 LOG GST_EVENT gstevent.c:223:_gst_event_free: freeing event 0x7f31c8007100 type latency 0:00:30.863320097 36 0x7f31d47e9d90 DEBUG basesrc gstbasesrc.c:3337:gst_base_src_default_negotiate: no common caps 0:00:30.863331258 36 0x7f31d47e9d90 DEBUG basesrc gstbasesrc.c:2992:gst_base_src_loop: Not negotiated 0:00:30.863335315 36 0x7f31d47e9d90 DEBUG basesrc gstbasesrc.c:3008:gst_base_src_loop: pausing task, reason not-negotiated 0:00:30.863332119 36 0x55970c4f7190 INFO bin gstbin.c:2783:gst_bin_do_latency_func: configured latency of 0:00:00.000000000 0:00:30.863338191 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:6201:gst_pad_pause_task: pause task 0:00:30.863347939 36 0x55970c4f7190 DEBUG bin gstbin.c:328:_gst_boolean_accumulator: invocation 2, 1 0:00:30.863349182 36 0x7f31d47e9d90 DEBUG task gsttask.c:688:gst_task_set_state: Changing task 0x7f31d492e050 to state 2 0:00:30.863356786 36 0x55970c4f7190 DEBUG bin gstbin.c:2740:gst_bin_recalculate_latency: latency returned 1 0:00:30.863359251 36 0x7f31d47e9d90 DEBUG GST_EVENT gstevent.c:306:gst_event_new_custom: creating new event 0x7f31d4037e70 eos 28174 0:00:30.863365553 36 0x55970c4f7190 DEBUG bin gstbin.c:2385:gst_bin_sort_iterator_resync: resync 0:00:30.863368619 36 0x7f31d47e9d90 WARN basesrc gstbasesrc.c:3055:gst_base_src_loop: error: Internal data stream error. 0:00:30.863376263 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'multifilesink0' to queue 0:00:30.863377565 36 0x7f31d47e9d90 WARN basesrc gstbasesrc.c:3055:gst_base_src_loop: error: streaming stopped, reason not-negotiated (-4) 0:00:30.863388867 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.863411610 36 0x7f31d47e9d90 DEBUG GST_MESSAGE gstelement.c:2114:gst_element_message_full_with_details: start 0:00:30.863423663 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.863444222 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element bayer2rgb0, degree 0->1, linked to capsfilter1 0:00:30.863453870 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.863462256 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.863472646 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element balluff, degree 0->1, linked to capsfilter0 0:00:30.863481643 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.863491020 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.863501671 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element jpegenc0, degree 0->1, linked to multifilesink0 0:00:30.863509405 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.863517020 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.863527069 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element videoconvert0, degree 0->1, linked to jpegenc0 0:00:30.863534763 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.863542287 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.863552417 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element capsfilter1, degree 0->1, linked to videoconvert0 0:00:30.863560131 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.863567375 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.863577043 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element capsfilter0, degree 0->1, linked to bayer2rgb0 0:00:30.863583486 36 0x55970c4f7190 DEBUG bin gstbin.c:2305:update_degree: element balluff not linked on any sinkpads 0:00:30.863593304 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives multifilesink0 0:00:30.863601780 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.863609946 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.863620746 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element jpegenc0, degree 1->0, linked to multifilesink0 0:00:30.863629633 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'jpegenc0' to queue 0:00:30.863649200 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:30.863659399 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.863661103 36 0x7f31d47e9d90 INFO GST_ERROR_SYSTEM gstelement.c:2145:gst_element_message_full_with_details: posting message: Internal data stream error. 0:00:30.863670871 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.863691901 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element multifilesink0 to PLAYING, base_time 382:56:07.591328816 0:00:30.863696560 36 0x7f31d47e9d90 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source balluff: creating new message 0x7f31d492c3a0 error 0:00:30.863698423 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to PLAYING 0:00:30.863716838 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to PLAYING 0:00:30.863718040 36 0x7f31d47e9d90 WARN structure gststructure.c:1832:priv_gst_structure_append_to_gstring: No value transform to serialize field 'gerror' of type 'GError' 0:00:30.863728590 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current PAUSED, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.863746023 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from PAUSED to PLAYING 0:00:30.863749019 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d492c3a0] posting on bus error message: 0x7f31d492c3a0, time 99:99:99.999999999, seq-num 45, element 'balluff', GstMessageError, gerror=(GError)NULL, debug=(string)"gstbasesrc.c\(3055\):\ gst_base_src_loop\ \(\):\ /GstPipeline:test/GstAravis:balluff:\012streaming\ stopped\,\ reason\ not-negotiated\ \(-4\)", details=(structure)"details\,\ flow-return\=\(int\)-4\;"; 0:00:30.863757445 36 0x55970c4f7190 DEBUG basesink gstbasesink.c:3341:gst_base_sink_needs_preroll: have_preroll: 0, EOS: 0 => needs preroll: 1 0:00:30.863770660 36 0x7f31d47e9d90 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31d492c3a0] handling child balluff message of type error 0:00:30.863777553 36 0x55970c4f7190 DEBUG basesink gstbasesink.c:5118:gst_base_sink_change_state: PAUSED to PLAYING, we are not prerolled 0:00:30.863784426 36 0x7f31d47e9d90 DEBUG bin gstbin.c:3724:gst_bin_handle_message_func: got ERROR message, unlocking state change 0:00:30.863797551 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from PAUSED to PLAYING (001c) 0:00:30.863808802 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.863809794 36 0x7f31d47e9d90 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.863818410 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING 0:00:30.863827337 36 0x7f31d47e9d90 WARN structure gststructure.c:1832:priv_gst_structure_append_to_gstring: No value transform to serialize field 'gerror' of type 'GError' 0:00:30.863835613 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:30.863851363 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source multifilesink0: creating new message 0x7f31d492c220 state-changed 0:00:30.863857915 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d492c3a0] posting on bus error message: 0x7f31d492c3a0, time 99:99:99.999999999, seq-num 45, element 'balluff', GstMessageError, gerror=(GError)NULL, debug=(string)"gstbasesrc.c\(3055\):\ gst_base_src_loop\ \(\):\ /GstPipeline:test/GstAravis:balluff:\012streaming\ stopped\,\ reason\ not-negotiated\ \(-4\)", details=(structure)"details\,\ flow-return\=\(int\)-4\;"; 0:00:30.863869177 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d492c220] posting on bus state-changed message: 0x7f31d492c220, time 99:99:99.999999999, seq-num 46, element 'multifilesink0', GstMessageStateChanged, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.863869036 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d492c3a0] pushing on async queue 0:00:30.863880107 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31d492c220] handling child multifilesink0 message of type state-changed 0:00:30.863894725 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.863889255 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d492c3a0] pushed on async queue 0:00:30.863908541 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d492c220] posting on bus state-changed message: 0x7f31d492c220, time 99:99:99.999999999, seq-num 46, element 'multifilesink0', GstMessageStateChanged, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.863912098 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31d492c3a0] dropped 0:00:30.863918109 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d492c220] pushing on async queue 0:00:30.863929150 36 0x7f31d47e9d90 INFO GST_ERROR_SYSTEM gstelement.c:2172:gst_element_message_full_with_details: posted error message: Internal data stream error. 0:00:30.863935683 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d492c220] pushed on async queue 0:00:30.863942676 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event eos 0:00:30.863947886 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31d492c220] dropped 0:00:30.863952815 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3980:check_sticky: pushing all sticky events 0:00:30.863959418 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.863973124 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.863967653 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3910:push_sticky: event stream-start was already received 0:00:30.863980988 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'multifilesink0' changed state to 4(PLAYING) successfully 0:00:30.863989575 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31d4037e70 (eos) to peerpad 0:00:30.863993252 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives jpegenc0 0:00:30.864004643 36 0x7f31d47e9d90 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type eos event: 0x7f31d4037e70, time 99:99:99.999999999, seq-num 36, (NULL) 0:00:30.864014011 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.864026765 36 0x7f31d47e9d90 LOG capsfilter gstcapsfilter.c:520:gst_capsfilter_sink_event: Forwarding eos event 0:00:30.864038968 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.864047314 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event eos 0:00:30.864059587 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element videoconvert0, degree 1->0, linked to jpegenc0 0:00:30.864071029 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'videoconvert0' to queue 0:00:30.864072372 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3980:check_sticky: pushing all sticky events 0:00:30.864084955 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:30.864090626 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3910:push_sticky: event stream-start was already received 0:00:30.864101948 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.864113660 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.864117056 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31d4037e70 (eos) to peerpad 0:00:30.864135441 36 0x7f31d47e9d90 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type eos event: 0x7f31d4037e70, time 99:99:99.999999999, seq-num 36, (NULL) 0:00:30.864146272 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event eos 0:00:30.864126113 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element jpegenc0 to PLAYING, base_time 382:56:07.591328816 0:00:30.864177701 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to PLAYING 0:00:30.864154808 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3980:check_sticky: pushing all sticky events 0:00:30.864200975 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3910:push_sticky: event stream-start was already received 0:00:30.864222686 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31d4037e70 (eos) to peerpad 0:00:30.864228688 36 0x7f31d47e9d90 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type eos event: 0x7f31d4037e70, time 99:99:99.999999999, seq-num 36, (NULL) 0:00:30.864191367 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to PLAYING 0:00:30.864235110 36 0x7f31d47e9d90 LOG capsfilter gstcapsfilter.c:520:gst_capsfilter_sink_event: Forwarding eos event 0:00:30.864250309 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current PAUSED, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.864274074 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from PAUSED to PLAYING 0:00:30.864272250 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event eos 0:00:30.864299792 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3980:check_sticky: pushing all sticky events 0:00:30.864313849 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3910:push_sticky: event stream-start was already received 0:00:30.864287920 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from PAUSED to PLAYING (001c) 0:00:30.864336492 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.864349637 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING 0:00:30.864360487 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:30.864373352 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source jpegenc0: creating new message 0x7f31d492c2a0 state-changed 0:00:30.864392929 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d492c2a0] posting on bus state-changed message: 0x7f31d492c2a0, time 99:99:99.999999999, seq-num 47, element 'jpegenc0', GstMessageStateChanged, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.864405823 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31d492c2a0] handling child jpegenc0 message of type state-changed 0:00:30.864413057 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.864428847 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d492c2a0] posting on bus state-changed message: 0x7f31d492c2a0, time 99:99:99.999999999, seq-num 47, element 'jpegenc0', GstMessageStateChanged, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.864439637 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d492c2a0] pushing on async queue 0:00:30.864448233 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d492c2a0] pushed on async queue 0:00:30.864456800 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31d492c2a0] dropped 0:00:30.864464775 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.864471297 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.864478531 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'jpegenc0' changed state to 4(PLAYING) successfully 0:00:30.864486296 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives videoconvert0 0:00:30.864492998 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.864501434 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.864509850 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element capsfilter1, degree 1->0, linked to videoconvert0 0:00:30.864520120 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'capsfilter1' to queue 0:00:30.864532203 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:30.864542793 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.864551529 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.864564043 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element videoconvert0 to PLAYING, base_time 382:56:07.591328816 0:00:30.864573260 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to PLAYING 0:00:30.864581486 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to PLAYING 0:00:30.864594310 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current PAUSED, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.864607315 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from PAUSED to PLAYING 0:00:30.864618636 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from PAUSED to PLAYING (001c) 0:00:30.864625299 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.864633013 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING 0:00:30.864643583 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:30.864656528 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source videoconvert0: creating new message 0x7f31c80029b0 state-changed 0:00:30.864676656 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31c80029b0] posting on bus state-changed message: 0x7f31c80029b0, time 99:99:99.999999999, seq-num 48, element 'videoconvert0', GstMessageStateChanged, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.864687857 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31c80029b0] handling child videoconvert0 message of type state-changed 0:00:30.864696524 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.864714007 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31c80029b0] posting on bus state-changed message: 0x7f31c80029b0, time 99:99:99.999999999, seq-num 48, element 'videoconvert0', GstMessageStateChanged, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.864726661 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31c80029b0] pushing on async queue 0:00:30.864734426 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31c80029b0] pushed on async queue 0:00:30.864741178 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31c80029b0] dropped 0:00:30.864748272 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.864756938 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.864767017 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'videoconvert0' changed state to 4(PLAYING) successfully 0:00:30.864778038 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives capsfilter1 0:00:30.864786234 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.864792976 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.864805410 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element bayer2rgb0, degree 1->0, linked to capsfilter1 0:00:30.864813736 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'bayer2rgb0' to queue 0:00:30.864825087 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:30.864835016 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.864843141 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.864858180 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element capsfilter1 to PLAYING, base_time 382:56:07.591328816 0:00:30.864866886 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to PLAYING 0:00:30.864875964 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to PLAYING 0:00:30.864886894 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current PAUSED, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.864897975 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from PAUSED to PLAYING 0:00:30.864909677 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from PAUSED to PLAYING (001c) 0:00:30.864918795 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.864929435 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING 0:00:30.864941388 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:30.864953260 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source capsfilter1: creating new message 0x7f31c8002a30 state-changed 0:00:30.864971014 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31c8002a30] posting on bus state-changed message: 0x7f31c8002a30, time 99:99:99.999999999, seq-num 49, element 'capsfilter1', GstMessageStateChanged, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.864982435 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31c8002a30] handling child capsfilter1 message of type state-changed 0:00:30.864992064 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.865008445 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31c8002a30] posting on bus state-changed message: 0x7f31c8002a30, time 99:99:99.999999999, seq-num 49, element 'capsfilter1', GstMessageStateChanged, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.865017061 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31c8002a30] pushing on async queue 0:00:30.865025827 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31c8002a30] pushed on async queue 0:00:30.865034975 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31c8002a30] dropped 0:00:30.865044473 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.865053510 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.865063379 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'capsfilter1' changed state to 4(PLAYING) successfully 0:00:30.865074590 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives bayer2rgb0 0:00:30.865082395 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.865093065 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.865101411 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element capsfilter0, degree 1->0, linked to bayer2rgb0 0:00:30.865110217 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'capsfilter0' to queue 0:00:30.865120707 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:30.865131568 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.865137840 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.865148219 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element bayer2rgb0 to PLAYING, base_time 382:56:07.591328816 0:00:30.865155152 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to PLAYING 0:00:30.865163669 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to PLAYING 0:00:30.865172876 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current PAUSED, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.865182755 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from PAUSED to PLAYING 0:00:30.865195669 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from PAUSED to PLAYING (001c) 0:00:30.865204636 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.865212501 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING 0:00:30.865220035 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:30.865231367 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source bayer2rgb0: creating new message 0x7f31c8002ab0 state-changed 0:00:30.865247447 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31c8002ab0] posting on bus state-changed message: 0x7f31c8002ab0, time 99:99:99.999999999, seq-num 50, element 'bayer2rgb0', GstMessageStateChanged, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.865256695 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31c8002ab0] handling child bayer2rgb0 message of type state-changed 0:00:30.865263227 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.865279839 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31c8002ab0] posting on bus state-changed message: 0x7f31c8002ab0, time 99:99:99.999999999, seq-num 50, element 'bayer2rgb0', GstMessageStateChanged, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.865288896 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31c8002ab0] pushing on async queue 0:00:30.865297833 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31c8002ab0] pushed on async queue 0:00:30.865305998 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31c8002ab0] dropped 0:00:30.865315125 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.865323131 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.865335224 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'bayer2rgb0' changed state to 4(PLAYING) successfully 0:00:30.865346705 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives capsfilter0 0:00:30.865354791 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000 0:00:30.865361764 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: structure-change 0:00:30.865371031 36 0x55970c4f7190 DEBUG bin gstbin.c:2282:update_degree: change element balluff, degree 1->0, linked to capsfilter0 0:00:30.865378886 36 0x55970c4f7190 DEBUG bin gstbin.c:2165:add_to_queue: adding 'balluff' to queue 0:00:30.865388935 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:30.865398283 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00100000 0:00:30.865404685 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: async-start 0:00:30.865414724 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element capsfilter0 to PLAYING, base_time 382:56:07.591328816 0:00:30.865422569 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to PLAYING 0:00:30.865429282 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to PLAYING 0:00:30.865436856 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current PAUSED, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 0:00:30.865446374 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from PAUSED to PLAYING 0:00:30.865455311 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from PAUSED to PLAYING (001c) 0:00:30.865461673 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.865471512 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING 0:00:30.865480469 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:30.865489376 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source capsfilter0: creating new message 0x7f31c8002b30 state-changed 0:00:30.865504624 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31c8002b30] posting on bus state-changed message: 0x7f31c8002b30, time 99:99:99.999999999, seq-num 51, element 'capsfilter0', GstMessageStateChanged, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.865517118 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31c8002b30] handling child capsfilter0 message of type state-changed 0:00:30.865523099 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.865538208 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31c8002b30] posting on bus state-changed message: 0x7f31c8002b30, time 99:99:99.999999999, seq-num 51, element 'capsfilter0', GstMessageStateChanged, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.865547686 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31c8002b30] pushing on async queue 0:00:30.865555711 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31c8002b30] pushed on async queue 0:00:30.865564858 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31c8002b30] dropped 0:00:30.865574687 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.865584155 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.865594976 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'capsfilter0' changed state to 4(PLAYING) successfully 0:00:30.865604423 36 0x55970c4f7190 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives balluff 0:00:30.865613350 36 0x55970c4f7190 DEBUG bin gstbin.c:2305:update_degree: element balluff not linked on any sinkpads 0:00:30.865621756 36 0x55970c4f7190 DEBUG bin gstbin.c:2498:gst_bin_element_set_state: element is NO_PREROLL, ignore async elements 0:00:30.865633649 36 0x55970c4f7190 DEBUG bin gstbin.c:2601:gst_bin_element_set_state: setting element balluff to PLAYING, base_time 382:56:07.591328816 0:00:30.865643457 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2827:gst_element_set_state_func: set_state to PLAYING 0:00:30.865652294 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2852:gst_element_set_state_func: setting target state to PLAYING 0:00:30.865664938 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2865:gst_element_set_state_func: current PAUSED, old_pending VOID_PENDING, next VOID_PENDING, old return NO PREROLL 0:00:30.865675749 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2899:gst_element_set_state_func: final: setting state from PAUSED to PLAYING 0:00:30.865684716 36 0x55970c4f7190 DEBUG basesrc gstbasesrc.c:3893:gst_base_src_change_state: PAUSED->PLAYING 0:00:30.865693011 36 0x55970c4f7190 DEBUG basesrc gstbasesrc.c:3742:gst_base_src_set_playing: unschedule clock 0:00:30.865701848 36 0x55970c4f7190 DEBUG basesrc gstbasesrc.c:3749:gst_base_src_set_playing: live running 1 0:00:30.865709833 36 0x55970c4f7190 DEBUG GST_PADS gstpad.c:6145:gst_pad_start_task: start task 0:00:30.865720333 36 0x55970c4f7190 DEBUG task gsttask.c:688:gst_task_set_state: Changing task 0x7f31d492e050 to state 0 0:00:30.865731474 36 0x55970c4f7190 DEBUG basesrc gstbasesrc.c:3767:gst_base_src_set_playing: signal 0:00:30.865742305 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from PAUSED to PLAYING (001c) 0:00:30.865752664 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.865761832 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING 0:00:30.865772151 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:30.865783673 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source balluff: creating new message 0x7f31c8002bb0 state-changed 0:00:30.865803831 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31c8002bb0] posting on bus state-changed message: 0x7f31c8002bb0, time 99:99:99.999999999, seq-num 52, element 'balluff', GstMessageStateChanged, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.865813890 36 0x55970c4f7190 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31c8002bb0] handling child balluff message of type state-changed 0:00:30.865822296 36 0x55970c4f7190 DEBUG bin gstbin.c:4054:gst_bin_handle_message_func: posting message upward 0:00:30.865838116 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31c8002bb0] posting on bus state-changed message: 0x7f31c8002bb0, time 99:99:99.999999999, seq-num 52, element 'balluff', GstMessageStateChanged, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.865846833 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31c8002bb0] pushing on async queue 0:00:30.865855800 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31c8002bb0] pushed on async queue 0:00:30.865864616 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31c8002bb0] dropped 0:00:30.865873643 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.865882500 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.865892850 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child 'balluff' changed state to 4(PLAYING) successfully 0:00:30.865902909 36 0x55970c4f7190 DEBUG bin gstbin.c:2361:gst_bin_sort_iterator_next: queue empty, elements exhausted 0:00:30.865910112 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:3013:gst_bin_change_state_func: iterator done 0:00:30.865921244 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3154:gst_element_change_state_func: default handler tries setting state from PAUSED to PLAYING (001c) 0:00:30.865930070 36 0x55970c4f7190 DEBUG bin gstbin.c:2403:gst_bin_sort_iterator_free: free 0:00:30.865938476 36 0x55970c4f7190 DEBUG bin gstbin.c:3048:gst_bin_change_state_func: no async elements 0:00:30.865948165 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:3082:gst_bin_change_state_func: done changing bin's state from PAUSED to PLAYING, now in PAUSED, ret SUCCESS 0:00:30.865957703 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2988:gst_element_change_state: element changed state SUCCESS 0:00:30.865967150 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING 0:00:30.865978362 36 0x55970c4f7190 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:30.865990545 36 0x55970c4f7190 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source test: creating new message 0x7f31c8002c30 state-changed 0:00:30.866007407 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31c8002c30] posting on bus state-changed message: 0x7f31c8002c30, time 99:99:99.999999999, seq-num 53, element 'test', GstMessageStateChanged, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING; 0:00:30.866016193 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31c8002c30] pushing on async queue 0:00:30.866024950 36 0x55970c4f7190 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31c8002c30] pushed on async queue 0:00:30.866037223 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child capsfilter1 is not sink 0:00:30.866046110 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child capsfilter0 is not sink 0:00:30.866055558 36 0x55970c4f7190 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child multifilesink0 is sink 0:00:30.866064455 36 0x55970c4f7190 DEBUG bin gstbin.c:885:find_message: no message found matching types 00000001 0:00:30.866074334 36 0x55970c4f7190 DEBUG bin gstbin.c:892:find_message: eos 0:00:30.866081437 36 0x55970c4f7190 DEBUG bin gstbin.c:998:is_eos: sink 'multifilesink0' did not post EOS yet 0:00:30.866099120 36 0x55970c4f7190 LOG bin gstbin.c:3553:bin_do_eos: Not forwarding EOS due to in progress state change, or already posted, or waiting for more EOS 0:00:30.866108819 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.864330591 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31d4037e70 (eos) to peerpad 0:00:30.866120621 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.866141100 36 0x55970c4f7190 LOG GST_STATES gstelement.c:3004:gst_element_change_state: exit state change 1 0:00:30.866144176 36 0x7f31d47e9d90 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type eos event: 0x7f31d4037e70, time 99:99:99.999999999, seq-num 36, (NULL) 0:00:30.866147723 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS 0:00:30.866160557 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event eos 0:00:30.866171087 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3980:check_sticky: pushing all sticky events 0:00:30.866166628 36 0x55970c4f7190 INFO gstdjsonbuilder gstd_json_builder.c:80:gstd_json_builder_init: Initializing Json builder 0:00:30.866182969 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3910:push_sticky: event stream-start was already received 0:00:30.866204009 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31d4037e70 (eos) to peerpad 0:00:30.866205352 36 0x55970c4f7190 INFO GST_STATES gstbin.c:2089:gst_bin_get_state_func: getting state 0:00:30.866217925 36 0x7f31d47e9d90 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type eos event: 0x7f31d4037e70, time 99:99:99.999999999, seq-num 36, (NULL) 0:00:30.866225961 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2364:gst_element_get_state_func: getting state, timeout 0:00:00.000000000 0:00:30.866240057 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2369:gst_element_get_state_func: RETURN is SUCCESS 0:00:30.866236090 36 0x7f31d47e9d90 DEBUG videoencoder gstvideoencoder.c:1206:gst_video_encoder_sink_event: received event 28174, eos 0:00:30.866249295 36 0x55970c4f7190 DEBUG GST_STATES gstelement.c:2439:gst_element_get_state_func: state current: PLAYING, pending: VOID_PENDING, result: SUCCESS 0:00:30.866255847 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5159:store_sticky_event: Removing pending EOS and StreamGroupDone events 0:00:30.866269363 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event stream-start 0:00:30.866276226 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3980:check_sticky: pushing all sticky events 0:00:30.866285253 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31d4037e00 (stream-start) to peerpad 0:00:30.866292988 36 0x55970c4f7190 DEBUG gstdjsonbuilder gstd_json_builder.c:248:gstd_json_builder_finalize: finalize 0:00:30.866307515 36 0x7f31d47e9d90 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type stream-start event: 0x7f31d4037e00, time 99:99:99.999999999, seq-num 41, GstEventStreamStart, stream-id=(string)607596e5a80525c6ddfea5f15e5a223b, flags=(GstStreamFlags)GST_STREAM_FLAG_NONE, group-id=(uint)1; 0:00:30.866318847 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5701:gst_pad_send_event_unchecked: Removing pending EOS events 0:00:30.866336620 36 0x7f31d47e9d90 DEBUG basesink gstbasesink.c:3252:gst_base_sink_event: received event 0x7f31d4037e00 stream-start event: 0x7f31d4037e00, time 99:99:99.999999999, seq-num 41, GstEventStreamStart, stream-id=(string)607596e5a80525c6ddfea5f15e5a223b, flags=(GstStreamFlags)GST_STREAM_FLAG_NONE, group-id=(uint)1; 0:00:30.866349655 36 0x7f31d47e9d90 DEBUG basesink gstbasesink.c:3138:gst_base_sink_default_event: Now posting STREAM_START (seqnum:41) 0:00:30.866361588 36 0x7f31d47e9d90 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source multifilesink0: creating new message 0x7f31d492c420 stream-start 0:00:30.866380574 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d492c420] posting on bus stream-start message: 0x7f31d492c420, time 99:99:99.999999999, seq-num 41, element 'multifilesink0', GstMessageStreamStart, group-id=(uint)1; 0:00:30.866393739 36 0x7f31d47e9d90 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31d492c420] handling child multifilesink0 message of type stream-start 0:00:30.866403457 36 0x7f31d47e9d90 DEBUG bin gstbin.c:885:find_message: no message found matching types 10000000 0:00:30.866412083 36 0x7f31d47e9d90 DEBUG bin gstbin.c:892:find_message: stream-start 0:00:30.866422914 36 0x7f31d47e9d90 DEBUG bin gstbin.c:932:bin_replace_message: got new message 0x7f31d492c420, stream-start from multifilesink0 0:00:30.866433374 36 0x7f31d47e9d90 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child capsfilter1 is not sink 0:00:30.866444685 36 0x7f31d47e9d90 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child capsfilter0 is not sink 0:00:30.866453802 36 0x7f31d47e9d90 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child multifilesink0 is sink 0:00:30.866464022 36 0x7f31d47e9d90 LOG bin gstbin.c:863:message_check: looking at message 0x7f31d492c420: 1 0:00:30.866474471 36 0x7f31d47e9d90 DEBUG bin gstbin.c:883:find_message: we found a message 0x7f31d492c420 from multifilesink0 matching types 10000000 0:00:30.866483218 36 0x7f31d47e9d90 DEBUG bin gstbin.c:1039:is_stream_start: sink 'multifilesink0' posted STREAM_START 0:00:30.866495040 36 0x7f31d47e9d90 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child jpegenc0 is not sink 0:00:30.866503927 36 0x7f31d47e9d90 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child videoconvert0 is not sink 0:00:30.866512904 36 0x7f31d47e9d90 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child bayer2rgb0 is not sink 0:00:30.866524596 36 0x7f31d47e9d90 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child balluff is not sink 0:00:30.866534305 36 0x7f31d47e9d90 LOG bin gstbin.c:863:message_check: looking at message 0x7f31d492c420: 1 0:00:30.866545716 36 0x7f31d47e9d90 DEBUG bin gstbin.c:961:bin_remove_messages: deleting message 0x7f31d492c420 of type stream-start (types 0x10000000) 0:00:30.866555425 36 0x7f31d47e9d90 LOG GST_MESSAGE gstmessage.c:208:_gst_message_free: finalize message 0x7f31d492c420, stream-start from multifilesink0 0:00:30.866568700 36 0x7f31d47e9d90 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source test: creating new message 0x7f31d492c4a0 stream-start 0:00:30.866578879 36 0x7f31d47e9d90 DEBUG bin gstbin.c:3586:bin_do_stream_start: all sinks posted STREAM_START, posting seqnum #41 0:00:30.866594118 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d492c4a0] posting on bus stream-start message: 0x7f31d492c4a0, time 99:99:99.999999999, seq-num 41, element 'test', GstMessageStreamStart, group-id=(uint)1; 0:00:30.866604107 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d492c4a0] pushing on async queue 0:00:30.866611932 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d492c4a0] pushed on async queue 0:00:30.866621640 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31d492c420] dropped 0:00:30.866630978 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.866640125 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5159:store_sticky_event: Removing pending EOS and StreamGroupDone events 0:00:30.866650355 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event stream-start 0:00:30.866662067 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31d4037e00 (stream-start) to peerpad , ret ok 0:00:30.866671565 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3932:push_sticky: event stream-start marked received 0:00:30.866680552 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event eos 0:00:30.866687595 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3980:check_sticky: pushing all sticky events 0:00:30.866696762 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3910:push_sticky: event stream-start was already received 0:00:30.866707613 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5404:gst_pad_push_event_unchecked: sending event 0x7f31d4037e70 (eos) to peerpad 0:00:30.866719415 36 0x7f31d47e9d90 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type eos event: 0x7f31d4037e70, time 99:99:99.999999999, seq-num 36, (NULL) 0:00:30.866730877 36 0x7f31d47e9d90 DEBUG basesink gstbasesink.c:3252:gst_base_sink_event: received event 0x7f31d4037e70 eos event: 0x7f31d4037e70, time 99:99:99.999999999, seq-num 36, (NULL) 0:00:30.866745996 36 0x7f31d47e9d90 DEBUG basesink gstbasesink.c:1958:gst_base_sink_get_sync_times: sync times for EOS 99:99:99.999999999 0:00:30.866755904 36 0x7f31d47e9d90 DEBUG basesink gstbasesink.c:2312:gst_base_sink_do_preroll: prerolling object 0x7f31d4037e70 0:00:30.866766434 36 0x7f31d47e9d90 DEBUG basesink gstbasesink.c:1628:gst_base_sink_commit_state: nothing to commit, now in PLAYING 0:00:30.866775311 36 0x7f31d47e9d90 DEBUG basesink gstbasesink.c:3120:gst_base_sink_default_event: Now posting EOS 0:00:30.866784338 36 0x7f31d47e9d90 DEBUG basesink gstbasesink.c:3123:gst_base_sink_default_event: Got seqnum #36 0:00:30.866794357 36 0x7f31d47e9d90 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source multifilesink0: creating new message 0x7f31d492c520 eos 0:00:30.866809997 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d492c520] posting on bus eos message: 0x7f31d492c520, time 99:99:99.999999999, seq-num 36, element 'multifilesink0', (NULL) 0:00:30.866822050 36 0x7f31d47e9d90 DEBUG bin gstbin.c:3717:gst_bin_handle_message_func: [msg 0x7f31d492c520] handling child multifilesink0 message of type eos 0:00:30.866832339 36 0x7f31d47e9d90 DEBUG bin gstbin.c:885:find_message: no message found matching types 00000001 0:00:30.866840665 36 0x7f31d47e9d90 DEBUG bin gstbin.c:892:find_message: eos 0:00:30.866850874 36 0x7f31d47e9d90 DEBUG bin gstbin.c:932:bin_replace_message: got new message 0x7f31d492c520, eos from multifilesink0 0:00:30.866860533 36 0x7f31d47e9d90 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child capsfilter1 is not sink 0:00:30.866870411 36 0x7f31d47e9d90 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child capsfilter0 is not sink 0:00:30.866879779 36 0x7f31d47e9d90 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child multifilesink0 is sink 0:00:30.866890730 36 0x7f31d47e9d90 LOG bin gstbin.c:863:message_check: looking at message 0x7f31d492c520: 1 0:00:30.866920957 36 0x7f31d47e9d90 DEBUG bin gstbin.c:883:find_message: we found a message 0x7f31d492c520 from multifilesink0 matching types 00000001 0:00:30.866929433 36 0x7f31d47e9d90 DEBUG bin gstbin.c:993:is_eos: sink 'multifilesink0' posted EOS 0:00:30.866938130 36 0x7f31d47e9d90 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child jpegenc0 is not sink 0:00:30.866947628 36 0x7f31d47e9d90 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child videoconvert0 is not sink 0:00:30.866956514 36 0x7f31d47e9d90 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child bayer2rgb0 is not sink 0:00:30.866965652 36 0x7f31d47e9d90 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child balluff is not sink 0:00:30.866974048 36 0x7f31d47e9d90 LOG bin gstbin.c:863:message_check: looking at message 0x7f31d492c520: 1 0:00:30.866984758 36 0x7f31d47e9d90 DEBUG bin gstbin.c:961:bin_remove_messages: deleting message 0x7f31d492c520 of type eos (types 0x00000001) 0:00:30.866995979 36 0x7f31d47e9d90 LOG GST_MESSAGE gstmessage.c:208:_gst_message_free: finalize message 0x7f31d492c520, eos from multifilesink0 0:00:30.867007902 36 0x7f31d47e9d90 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source test: creating new message 0x7f31d492c5a0 eos 0:00:30.867018502 36 0x7f31d47e9d90 DEBUG bin gstbin.c:3549:bin_do_eos: all sinks posted EOS, posting seqnum #36 0:00:30.867030845 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:318:gst_bus_post: [msg 0x7f31d492c5a0] posting on bus eos message: 0x7f31d492c5a0, time 99:99:99.999999999, seq-num 36, element 'test', (NULL) 0:00:30.867039943 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x7f31d492c5a0] pushing on async queue 0:00:30.867048849 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x7f31d492c5a0] pushed on async queue 0:00:30.867058347 36 0x7f31d47e9d90 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x7f31d492c520] dropped 0:00:30.867067725 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.867077373 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event eos 0:00:30.867094516 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31d4037e70 (eos) to peerpad , ret ok 0:00:30.867105737 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3932:push_sticky: event eos marked received 0:00:30.867116227 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.867126446 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event eos 0:00:30.867139641 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31d4037e70 (eos) to peerpad , ret ok 0:00:30.867149069 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3932:push_sticky: event eos marked received 0:00:30.867158417 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.867167424 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event eos 0:00:30.867206508 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31d4037e70 (eos) to peerpad , ret ok 0:00:30.867216868 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3932:push_sticky: event eos marked received 0:00:30.867226927 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.867238358 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event eos 0:00:30.867249730 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31d4037e70 (eos) to peerpad , ret ok 0:00:30.867256413 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3932:push_sticky: event eos marked received 0:00:30.867263736 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.867272513 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event eos 0:00:30.867281420 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31d4037e70 (eos) to peerpad , ret ok 0:00:30.867288173 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3932:push_sticky: event eos marked received 0:00:30.867294665 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok 0:00:30.867300596 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5219:store_sticky_event: stored sticky event eos 0:00:30.867313741 36 0x7f31d47e9d90 LOG GST_PADS gstpad.c:5412:gst_pad_push_event_unchecked: sent event 0x7f31d4037e70 (eos) to peerpad , ret ok 0:00:30.867324111 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:3932:push_sticky: event eos marked received 0:00:30.867334050 36 0x7f31d47e9d90 DEBUG basesrc gstbasesrc.c:2777:gst_base_src_loop: Pad is marked as EOS, pause the task 0:00:30.867342395 36 0x7f31d47e9d90 DEBUG GST_PADS gstpad.c:6201:gst_pad_pause_task: pause task 0:00:30.867355921 36 0x7f31d47e9d90 DEBUG task gsttask.c:688:gst_task_set_state: Changing task 0x7f31d492e050 to state 2 0:00:30.867366711 36 0x7f31d47e9d90 INFO task gsttask.c:316:gst_task_func: Task going to paused