upgrading to 1.12.4
David Wells
dwells at blackcreekisc.com
Wed Jan 24 14:15:45 UTC 2018
Here is the last several lines with GST_DEBUG=7. If there is further data needed please let me know.
0:00:01.136720014 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<http-src> 06927318 ref 5->6
0:00:01.142703187 13340 0763F060 DEBUG basesrc gstbasesrc.c:3374:gst_base_src_start_complete:<http-src> starting source
0:00:01.142731339 13340 0763F060 DEBUG souphttpsrc gstsouphttpsrc.c:1803:gst_soup_http_src_get_size:<http-src> get_size() = FALSE
0:00:01.142746588 13340 0763F060 DEBUG basesrc gstbasesrc.c:3387:gst_base_src_start_complete:<http-src> setting size 18446744073709551615
0:00:01.142763303 13340 0763F060 DEBUG basesrc gstbasesrc.c:3398:gst_base_src_start_complete:<http-src> format: bytes, have size: 0, size: 18446744073709551615, duration: -1
0:00:01.142781191 13340 0763F060 DEBUG basesrc gstbasesrc.c:3401:gst_base_src_start_complete:<http-src> is seekable: 0
0:00:01.142795854 13340 0763F060 DEBUG basesrc gstbasesrc.c:3406:gst_base_src_start_complete:<http-src> is random_access: 0
0:00:01.142810223 13340 0763F060 DEBUG basesrc gstbasesrc.c:3569:gst_base_src_set_flushing:<http-src> flushing 0, live_play 0
0:00:01.142825765 13340 0763F060 DEBUG basesrc gstbasesrc.c:1568:gst_base_src_perform_seek:<http-src> doing seek: (NULL)
0:00:01.142841600 13340 0763F060 DEBUG GST_PADS gstpad.c:6115:gst_pad_pause_task:<http-src:src> pause task
0:00:01.142855970 13340 0763F060 DEBUG GST_PADS gstpad.c:6133:gst_pad_pause_task:<http-src:src> pad has no task
0:00:01.142870046 13340 0763F060 DEBUG basesrc gstbasesrc.c:1624:gst_base_src_perform_seek:<http-src> seek with seqnum 15
0:00:01.142884415 13340 0763F060 DEBUG basesrc gstbasesrc.c:1661:gst_base_src_perform_seek:<http-src> segment configured from 0 to -1, position 0
0:00:01.142899664 13340 0763F060 INFO basesrc gstbasesrc.c:1331:gst_base_src_do_seek:<http-src> seeking: bytes segment start=0, offset=0, stop=-1, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0, base=0, position 0, duration -1
0:00:01.142925177 13340 0763F060 DEBUG souphttpsrc gstsouphttpsrc.c:1849:gst_soup_http_src_do_seek:<http-src> do_seek(0-18446744073709551615)
0:00:01.142941012 13340 0763F060 DEBUG souphttpsrc gstsouphttpsrc.c:1854:gst_soup_http_src_do_seek:<http-src> Seek to current read/end position and no seek pending
0:00:01.142955968 13340 0763F060 DEBUG GST_PADS gstpad.c:6059:gst_pad_start_task:<http-src:src> start task
0:00:01.142972683 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:207:gst_object_init:<GstObject at 076580D8> 076580D8 new
0:00:01.142987932 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<taskpool0> 06852000 ref 1->2
0:00:01.143004061 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:290:gst_object_ref_sink:<GstTask at 076580D8> 076580D8 ref_sink 1->2
0:00:01.143023122 13340 0763F060 DEBUG task gsttask.c:431:gst_task_new: Created task 076580D8
0:00:01.143037784 13340 0763F060 INFO task gsttask.c:457:gst_task_set_lock: setting stream lock 07655134 on task 076580D8
0:00:01.143053620 13340 0763F060 INFO GST_PADS gstpad.c:6068:gst_pad_start_task:<http-src:src> created task 076580D8
0:00:01.143067989 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<task0> 076580D8 ref 1->2
0:00:01.143083238 13340 0763F060 DEBUG GST_PADS gstpad.c:5985:do_stream_status:<http-src:src> doing stream-status 0
0:00:01.143101713 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<http-src> 06927318 ref 6->7
0:00:01.143116962 13340 0763F060 TRACE structure gststructure.c:154:gst_structure_new_id_empty_with_size: created structure 076406C0
0:00:01.143133091 13340 0763F060 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source src: creating new message 068529A0 stream-status
0:00:01.143149513 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<http-src:src> 076550E8 ref 2->3
0:00:01.143165641 13340 0763F060 DEBUG GST_PADS gstpad.c:6014:do_stream_status:<http-src:src> posting stream-status 0
0:00:01.143180890 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<bus0> 0763CED8 ref 5->6
0:00:01.143195846 13340 0763F060 DEBUG GST_BUS gstbus.c:318:gst_bus_post:<bus0> [msg 068529A0] posting on bus stream-status message: 068529A0, time 99:99:99.999999999, seq-num 16, element 'src', GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_CREATE, owner=(GstElement)"\(GstSoupHTTPSrc\)\ http-src", object=(GstTask)"\(GstTask\)\ task0";
0:00:01.143233089 13340 0763F060 DEBUG bin gstbin.c:3708:gst_bin_handle_message_func:<soup-pipeline> [msg 068529A0] handling child src message of type stream-status
0:00:01.143249511 13340 0763F060 DEBUG bin gstbin.c:4045:gst_bin_handle_message_func:<soup-pipeline> posting message upward
0:00:01.143263587 13340 0763F060 TRACE GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 068529A0 ref 1->2
0:00:01.143282941 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<bus1> 0763CF68 ref 1->2
0:00:01.143297897 13340 0763F060 DEBUG GST_BUS gstbus.c:318:gst_bus_post:<bus1> [msg 068529A0] posting on bus stream-status message: 068529A0, time 99:99:99.999999999, seq-num 16, element 'src', GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_CREATE, owner=(GstElement)"\(GstSoupHTTPSrc\)\ http-src", object=(GstTask)"\(GstTask\)\ task0";
0:00:01.143326635 13340 0763F060 DEBUG GST_BUS gstbus.c:357:gst_bus_post:<bus1> [msg 068529A0] pushing on async queue
0:00:01.143341884 13340 0763F060 DEBUG GST_BUS gstbus.c:360:gst_bus_post:<bus1> [msg 068529A0] pushed on async queue
0:00:01.143356254 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<bus1> 0763CF68 unref 2->1
0:00:01.143370916 13340 0763F060 TRACE GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 068529A0 unref 2->1
0:00:01.143385872 13340 0763F060 DEBUG GST_BUS gstbus.c:353:gst_bus_post:<bus0> [msg 068529A0] dropped
0:00:01.143399948 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<bus0> 0763CED8 unref 6->5
0:00:01.143414903 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<http-src> 06927318 unref 8->7
0:00:01.143429566 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<task0> 076580D8 unref 3->2
0:00:01.143444522 13340 0763F060 DEBUG task gsttask.c:688:gst_task_set_state:<task0> Changing task 076580D8 to state 0
0:00:01.143459477 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<task0> 076580D8 ref 2->3
0:00:01.143474140 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<taskpool0> 06852000 ref 2->3
0:00:01.143542467 13340 0763F060 DEBUG basesrc gstbasesrc.c:3515:gst_base_src_start_wait:<http-src> got ok
0:00:01.143562408 13340 0763F060 DEBUG GST_PADS gstpad.c:1227:activate_mode_internal:<http-src:src> activated in push mode
0:00:01.143578244 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<http-src> 06927318 unref 7->6
0:00:01.143595838 13340 0763F060 DEBUG GST_ELEMENT_PADS gstelement.c:2907:gst_element_pads_activate:<http-src> pad activation successful
0:00:01.143614020 13340 0763F060 DEBUG GST_STATES gstelement.c:2786:gst_element_change_state:<http-src> element changed state NO_PREROLL
0:00:01.143628976 13340 0763F060 INFO GST_STATES gstelement.c:2467:gst_element_continue_state:<http-src> completed state change to PAUSED
0:00:01.143643931 13340 0763F060 INFO GST_STATES gstelement.c:2372:_priv_gst_element_state_changed:<http-src> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:01.143660353 13340 0763F060 TRACE structure gststructure.c:154:gst_structure_new_id_empty_with_size: created structure 07640700
0:00:01.143675602 13340 0763F060 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source http-src: creating new message 068529F8 state-changed
0:00:01.143694077 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<http-src> 06927318 ref 5->6
0:00:01.143710792 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<bus0> 0763CED8 ref 5->6
0:00:01.143745103 13340 0763F060 DEBUG GST_BUS gstbus.c:318:gst_bus_post:<bus0> [msg 068529F8] posting on bus state-changed message: 068529F8, time 99:99:99.999999999, seq-num 17, element 'http-src', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING;
0:00:01.143786158 13340 0763F060 DEBUG bin gstbin.c:3708:gst_bin_handle_message_func:<soup-pipeline> [msg 068529F8] handling child http-src message of type state-changed
0:00:01.143814016 13340 0763F060 DEBUG bin gstbin.c:4045:gst_bin_handle_message_func:<soup-pipeline> posting message upward
0:00:01.143828972 13340 0763F060 TRACE GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 068529F8 ref 1->2
0:00:01.143845394 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<bus1> 0763CF68 ref 1->2
0:00:01.143879704 13340 0763F060 DEBUG GST_BUS gstbus.c:318:gst_bus_post:<bus1> [msg 068529F8] posting on bus state-changed message: 068529F8, time 99:99:99.999999999, seq-num 17, element 'http-src', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING;
0:00:01.143915774 13340 0763F060 DEBUG GST_BUS gstbus.c:357:gst_bus_post:<bus1> [msg 068529F8] pushing on async queue
0:00:01.143941873 13340 0763F060 DEBUG GST_BUS gstbus.c:360:gst_bus_post:<bus1> [msg 068529F8] pushed on async queue
0:00:01.143957709 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<bus1> 0763CF68 unref 2->1
0:00:01.143991432 13340 0763F060 TRACE GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 068529F8 unref 2->1
0:00:01.144007268 13340 0763F060 DEBUG GST_BUS gstbus.c:353:gst_bus_post:<bus0> [msg 068529F8] dropped
0:00:01.144023103 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<bus0> 0763CED8 unref 6->5
0:00:01.144039525 13340 0763F060 LOG GST_STATES gstelement.c:2795:gst_element_change_state:<http-src> exit state change 3
0:00:01.144055361 13340 0763F060 DEBUG GST_STATES gstelement.c:2702:gst_element_set_state_func:<http-src> returned NO PREROLL
0:00:01.144071783 13340 0763F060 INFO GST_STATES gstbin.c:2988:gst_bin_change_state_func:<soup-pipeline> child 'http-src' changed state to 3(PAUSED) successfully without preroll
0:00:01.144089964 13340 0763F060 DEBUG bin gstbin.c:2352:gst_bin_sort_iterator_next:<soup-pipeline> queue empty, elements exhausted
0:00:01.144106093 13340 0763F060 DEBUG GST_STATES gstbin.c:3004:gst_bin_change_state_func:<soup-pipeline> iterator done
0:00:01.144121635 13340 0763F060 LOG GST_STATES gstelement.c:2945:gst_element_change_state_func:<soup-pipeline> default handler tries setting state from READY to PAUSED (0013)
0:00:01.144140990 13340 0763F060 DEBUG GST_ELEMENT_PADS gstelement.c:2888:gst_element_pads_activate:<soup-pipeline> activate pads
0:00:01.144176180 13340 0763F060 DEBUG GST_ELEMENT_PADS gstelement.c:2907:gst_element_pads_activate:<soup-pipeline> pad activation successful
0:00:01.144192308 13340 0763F060 DEBUG GST_STATES gstbin.c:3017:gst_bin_change_state_func:<soup-pipeline> we have NO_PREROLL elements SUCCESS -> NO_PREROLL0:00:01.144226618 13340 0763F060 DEBUG bin gstbin.c:2394:gst_bin_sort_iterator_free:<soup-pipeline> free
0:00:01.144242454 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<soup-pipeline> 07652080 unref 3->2
0:00:01.144259169 13340 0763F060 DEBUG bin gstbin.c:3039:gst_bin_change_state_func:<soup-pipeline> no async elements
0:00:01.144274418 13340 0763F060 DEBUG GST_STATES gstbin.c:3073:gst_bin_change_state_func:<soup-pipeline> done changing bin's state from READY to PAUSED, now in READY, ret NO PREROLL
0:00:01.144292013 13340 0763F060 DEBUG GST_STATES gstelement.c:2786:gst_element_change_state:<soup-pipeline> element changed state NO_PREROLL
0:00:01.144307555 13340 0763F060 INFO GST_STATES gstelement.c:2467:gst_element_continue_state:<soup-pipeline> completed state change to PAUSED
0:00:01.144323684 13340 0763F060 INFO GST_STATES gstelement.c:2372:_priv_gst_element_state_changed:<soup-pipeline> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:01.144341279 13340 0763F060 TRACE structure gststructure.c:154:gst_structure_new_id_empty_with_size: created structure 07640730
0:00:01.144357408 13340 0763F060 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source soup-pipeline: creating new message 06852A50 state-changed
0:00:01.144374710 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<soup-pipeline> 07652080 ref 2->3
0:00:01.144408433 13340 0763F060 TRACE GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 06852A50 ref 1->2
0:00:01.144424562 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<bus1> 0763CF68 ref 1->2
0:00:01.144440691 13340 0763F060 DEBUG GST_BUS gstbus.c:318:gst_bus_post:<bus1> [msg 06852A50] posting on bus state-changed message: 06852A50, time 99:99:99.999999999, seq-num 18, element 'soup-pipeline', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING;
0:00:01.144477933 13340 0763F060 DEBUG GST_BUS gstbus.c:357:gst_bus_post:<bus1> [msg 06852A50] pushing on async queue
0:00:01.144504033 13340 0763F060 DEBUG GST_BUS gstbus.c:360:gst_bus_post:<bus1> [msg 06852A50] pushed on async queue
0:00:01.144519575 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<bus1> 0763CF68 unref 2->1
0:00:01.144553592 13340 0763F060 TRACE GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 06852A50 unref 2->1
0:00:01.144569721 13340 0763F060 LOG GST_STATES gstelement.c:2795:gst_element_change_state:<soup-pipeline> exit state change 3
0:00:01.144585849 13340 0763F060 LOG GST_STATES gstelement.c:2795:gst_element_change_state:<soup-pipeline> exit state change 3
0:00:01.144619573 13340 0763F060 DEBUG GST_STATES gstelement.c:2702:gst_element_set_state_func:<soup-pipeline> returned NO PREROLL
0:00:01.143684400 13340 0763DB18 DEBUG task gsttask.c:290:gst_task_func: Entering task 076580D8, thread 0763DB18
0:00:01.144711653 13340 0763DB18 DEBUG GST_PADS gstpad.c:5985:do_stream_status:<http-src:src> doing stream-status 1
0:00:01.144739805 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<http-src> 06927318 ref 5->6
0:00:01.144761213 13340 0763DB18 TRACE structure gststructure.c:154:gst_structure_new_id_empty_with_size: created structure 076406F0
0:00:01.144791417 13340 0763DB18 LOG GST_MESSAGE gstmessage.c:301:gst_message_new_custom: source src: creating new message 06852C60 stream-status
0:00:01.144819569 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<http-src:src> 076550E8 ref 2->3
0:00:01.144846548 13340 0763DB18 DEBUG GST_PADS gstpad.c:6014:do_stream_status:<http-src:src> posting stream-status 1
0:00:01.144676463 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<bus1> 0763CF68 ref 1->2
0:00:01.144871768 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<bus0> 0763CED8 ref 5->6
0:00:01.144932177 13340 0763DB18 DEBUG GST_BUS gstbus.c:318:gst_bus_post:<bus0> [msg 06852C60] posting on bus stream-status message: 06852C60, time 99:99:99.999999999, seq-num 19, element 'src', GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_ENTER, owner=(GstElement)"\(GstSoupHTTPSrc\)\ http-src", object=(GstTask)"\(GstTask\)\ http-src:src";
0:00:01.144989947 13340 0763DB18 DEBUG bin gstbin.c:3708:gst_bin_handle_message_func:<soup-pipeline> [msg 06852C60] handling child src message of type stream-status
0:00:01.145017806 13340 0763DB18 DEBUG bin gstbin.c:4045:gst_bin_handle_message_func:<soup-pipeline> posting message upward
0:00:01.145033348 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 06852C60 ref 1->2
0:00:01.145049770 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<bus1> 0763CF68 ref 2->3
0:00:01.145065899 13340 0763DB18 DEBUG GST_BUS gstbus.c:318:gst_bus_post:<bus1> [msg 06852C60] posting on bus stream-status message: 06852C60, time 99:99:99.999999999, seq-num 19, element 'src', GstMessageStreamStatus, type=(GstStreamStatusType)GST_STREAM_STATUS_TYPE_ENTER, owner=(GstElement)"\(GstSoupHTTPSrc\)\ http-src", object=(GstTask)"\(GstTask\)\ http-src:src";
0:00:01.145106074 13340 0763DB18 DEBUG GST_BUS gstbus.c:357:gst_bus_post:<bus1> [msg 06852C60] pushing on async queue
0:00:01.145132173 13340 0763DB18 DEBUG GST_BUS gstbus.c:360:gst_bus_post:<bus1> [msg 06852C60] pushed on async queue
0:00:01.145147716 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<bus1> 0763CF68 unref 3->2
0:00:01.145181732 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 06852C60 unref 2->1
0:00:01.145197568 13340 0763DB18 DEBUG GST_BUS gstbus.c:353:gst_bus_post:<bus0> [msg 06852C60] dropped
0:00:01.145231292 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<bus0> 0763CED8 unref 6->5
0:00:01.145247127 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<http-src> 06927318 unref 7->6
0:00:01.145282610 13340 0763DB18 INFO GST_ELEMENT_PADS gstelement.c:915:gst_element_get_static_pad: no such pad 'sink' in element "http-src"
0:00:01.145310762 13340 0763DB18 TRACE structure gststructure.c:154:gst_structure_new_id_empty_with_size: created structure 07640760
0:00:01.144904318 13340 0763F060 DEBUG GST_BUS gstelement.c:3192:gst_element_get_bus:<soup-pipeline> got bus <bus1>0:00:01.145378503 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<bus1> 0763CF68 ref 2->3
0:00:01.145337155 13340 0763DB18 DEBUG query gstquery.c:675:gst_query_new_custom: creating new query 07656350 uri
0:00:01.145439206 13340 0763DB18 DEBUG GST_ELEMENT_PADS gstelement.c:1755:gst_element_query: send query on element http-src
0:00:01.145464718 13340 0763DB18 DEBUG GST_ELEMENT_PADS gstelement.c:1539:gst_element_get_random_pad: getting a random pad
0:00:01.145479967 13340 0763DB18 DEBUG GST_ELEMENT_PADS gstelement.c:1558:gst_element_get_random_pad: checking pad http-src:src
0:00:01.145497562 13340 0763DB18 DEBUG GST_ELEMENT_PADS gstelement.c:1570:gst_element_get_random_pad: found pad http-src:src
0:00:01.145514571 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<http-src:src> 076550E8 ref 3->4
0:00:01.145531579 13340 0763DB18 DEBUG GST_PADS gstpad.c:3984:gst_pad_query:<http-src:src> doing query 07656350 (uri)
0:00:01.145548881 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<http-src> 06927318 ref 6->7
0:00:01.145566769 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<http-src> 06927318 unref 7->6
0:00:01.145583484 13340 0763DB18 DEBUG GST_PADS gstpad.c:4007:gst_pad_query:<http-src:src> sent query 07656350 (uri), result 1
0:00:01.145600493 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<http-src:src> 076550E8 unref 4->3
0:00:01.145408414 13340 0763F060 DEBUG GST_BUS gstbus.c:921:gst_bus_add_watch_full_unlocked:<bus1> New source 07668748 with id 1
0:00:01.145645067 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<bus1> 0763CF68 unref 3->2
0:00:01.145624539 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 07656350 unref 1->0
0:00:01.145703424 13340 0763DB18 TRACE structure gststructure.c:392:gst_structure_free: free structure 07640760
0:00:01.145720432 13340 0763DB18 DEBUG basesrc gstbasesrc.c:870:gst_base_src_send_stream_start:<http-src> Pushing STREAM_START
0:00:01.145736268 13340 0763DB18 TRACE structure gststructure.c:154:gst_structure_new_id_empty_with_size: created structure 07640770
0:00:01.145694626 13340 0763F060 DEBUG GST_STATES gstelement.c:2618:gst_element_set_state_func:<soup-pipeline> set_state to PLAYING
0:00:01.145774683 13340 0763F060 DEBUG GST_STATES gstelement.c:2643:gst_element_set_state_func:<soup-pipeline> setting target state to PLAYING
0:00:01.145791105 13340 0763F060 DEBUG GST_STATES gstelement.c:2656:gst_element_set_state_func:<soup-pipeline> current PAUSED, old_pending VOID_PENDING, next VOID_PENDING, old return NO PREROLL
0:00:01.145809580 13340 0763F060 DEBUG GST_STATES gstelement.c:2690:gst_element_set_state_func:<soup-pipeline> final: setting state from PAUSED to PLAYING
0:00:01.145827175 13340 0763F060 DEBUG pipeline gstpipeline.c:404:gst_pipeline_change_state:<soup-pipeline> selecting clock and base_time
0:00:01.145843597 13340 0763F060 DEBUG pipeline gstpipeline.c:425:gst_pipeline_change_state:<soup-pipeline> Need to update start_time
0:00:01.145859139 13340 0763F060 DEBUG pipeline gstpipeline.c:430:gst_pipeline_change_state:<soup-pipeline> Need to update clock.
0:00:01.145875268 13340 0763F060 DEBUG bin gstbin.c:836:gst_bin_provide_clock_func:<soup-pipeline> returning old clock 00000000
0:00:01.145891103 13340 0763F060 DEBUG GST_CLOCK gstsystemclock.c:353:gst_system_clock_obtain: creating new static system clock
0:00:01.145753863 13340 0763DB18 DEBUG GST_EVENT gstevent.c:306:gst_event_new_custom: creating new event 0684E948 stream-start 10254
0:00:01.145917203 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:207:gst_object_init:<GstObject at 0765A138> 0765A138 new
0:00:01.145930106 13340 0763DB18 LOG GST_PADS gstpad.c:5094:store_sticky_event:<http-src:src> Removing pending EOS and StreamGroupDone events
0:00:01.145968521 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 0684E948 ref 1->2
0:00:01.145985823 13340 0763DB18 LOG GST_PADS gstpad.c:5154:store_sticky_event:<http-src:src> stored sticky event stream-start
0:00:01.146003125 13340 0763DB18 DEBUG GST_PADS gstpad.c:3915:check_sticky:<http-src:src> pushing all sticky events
0:00:01.146019253 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 0684E948 ref 2->3
0:00:01.146035089 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 0684E948 ref 3->4
0:00:01.146052097 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<multipartdemux:sink> 076553A8 ref 1->2
0:00:01.146068813 13340 0763DB18 LOG GST_PADS gstpad.c:5329:gst_pad_push_event_unchecked:<http-src:src> sending event 0684E948 (stream-start) to peerpad <multipartdemux:sink>
0:00:01.146088460 13340 0763DB18 DEBUG GST_EVENT gstpad.c:5616:gst_pad_send_event_unchecked:<multipartdemux:sink> have event type stream-start event: 0684E948, time 99:99:99.999999999, seq-num 20, GstEventStreamStart, stream-id=(string)a25bbc9283ed1d85edc950b54632e2adc65f6c441f27c6ca75a924ce340e5a38, flags=(GstStreamFlags)GST_STREAM_FLAG_NONE, group-id=(uint)0;
0:00:01.146122477 13340 0763DB18 LOG GST_PADS gstpad.c:5624:gst_pad_send_event_unchecked:<multipartdemux:sink> Removing pending EOS events
0:00:01.146138020 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<multipartdemux> 07657048 ref 3->4
0:00:01.146153855 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 0684E948 ref 4->5
0:00:01.146170570 13340 0763DB18 LOG GST_PADS gstpad.c:3065:gst_pad_event_default:<multipartdemux:sink> default event handler for event stream-start event: 0684E948, time 99:99:99.999999999, seq-num 20, GstEventStreamStart, stream-id=(string)a25bbc9283ed1d85edc950b54632e2adc65f6c441f27c6ca75a924ce340e5a38, flags=(GstStreamFlags)GST_STREAM_FLAG_NONE, group-id=(uint)0;0:00:01.146196376 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<multipartdemux> 07657048 ref 4->5
0:00:01.145951513 13340 0763F060 TRACE GST_REFCOUNTING gstobject.c:290:gst_object_ref_sink:<GstClock at 0765A138> 0765A138 ref_sink 1->2
0:00:01.146231273 13340 0763F060 DEBUG GST_POLL gstpoll.c:681:gst_poll_new: 07654050: new controllable : 1
0:00:01.146212212 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<multipartdemux> 07657048 ref 5->6
0:00:01.146269395 13340 0763DB18 DEBUG GST_PADS gstpad.c:2875:gst_pad_iterate_internal_links_default:<multipartdemux:sink> Making iterator
0:00:01.146286697 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<multipartdemux> 07657048 unref 7->6
0:00:01.146303706 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<multipartdemux> 07657048 unref 6->5
0:00:01.146320421 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0684E948 unref 5->4
0:00:01.146336843 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<multipartdemux> 07657048 unref 4->3
0:00:01.146353558 13340 0763DB18 DEBUG GST_PADS gstpad.c:5690:gst_pad_send_event_unchecked:<multipartdemux:sink> sent event, ret ok
0:00:01.146369980 13340 0763DB18 LOG GST_PADS gstpad.c:5094:store_sticky_event:<multipartdemux:sink> Removing pending EOS and StreamGroupDone events
0:00:01.146385229 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 0684E948 ref 4->5
0:00:01.146401651 13340 0763DB18 LOG GST_PADS gstpad.c:5154:store_sticky_event:<multipartdemux:sink> stored sticky event stream-start
0:00:01.146417193 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0684E948 unref 5->4
0:00:01.146433908 13340 0763DB18 LOG GST_PADS gstpad.c:5337:gst_pad_push_event_unchecked:<http-src:src> sent event 0684E948 (stream-start) to peerpad <multipartdemux:sink>, ret ok
0:00:01.146453263 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<multipartdemux:sink> 076553A8 unref 2->1
0:00:01.146473204 13340 0763DB18 DEBUG GST_PADS gstpad.c:3867:push_sticky:<http-src:src> event stream-start marked received
0:00:01.146489333 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0684E948 unref 4->3
0:00:01.146505168 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0684E948 unref 3->2
0:00:01.146521590 13340 0763DB18 DEBUG GST_PADS gstpad.c:1655:gst_pad_check_reconfigure:<http-src:src> remove RECONFIGURE flag
0:00:01.146536546 13340 0763DB18 DEBUG basesrc gstbasesrc.c:3260:gst_base_src_negotiate:<http-src> starting negotiation
0:00:01.146551502 13340 0763DB18 DEBUG GST_CAPS gstutils.c:2976:gst_pad_query_caps:<http-src:src> get pad caps with filter (NULL)
0:00:01.146568803 13340 0763DB18 TRACE structure gststructure.c:154:gst_structure_new_id_empty_with_size: created structure 076407A0
0:00:01.146584052 13340 0763DB18 DEBUG query gstquery.c:675:gst_query_new_custom: creating new query 07656380 caps
0:00:01.146599301 13340 0763DB18 DEBUG GST_PADS gstpad.c:3984:gst_pad_query:<http-src:src> doing query 07656380 (caps)
0:00:01.146614843 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<http-src> 06927318 ref 6->7
0:00:01.146632145 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 0763D8E8 ref 2->3
0:00:01.146647101 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 0763D8E8 ref 3->4
0:00:01.146661763 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0763D8E8 unref 4->3
0:00:01.146676719 13340 0763DB18 DEBUG basesrc gstbasesrc.c:1283:gst_base_src_default_query:<http-src> query caps returns 1
0:00:01.146692261 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<http-src> 06927318 unref 7->6
0:00:01.146707510 13340 0763DB18 DEBUG GST_PADS gstpad.c:4007:gst_pad_query:<http-src:src> sent query 07656380 (caps), result 1
0:00:01.146723053 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 0763D8E8 ref 3->4
0:00:01.146738008 13340 0763DB18 DEBUG GST_CAPS gstutils.c:2983:gst_pad_query_caps:<http-src:src> query returned ANY
0:00:01.146752671 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 07656380 unref 1->0
0:00:01.146767040 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0763D8E8 unref 4->3
0:00:01.146781702 13340 0763DB18 TRACE structure gststructure.c:392:gst_structure_free: free structure 076407A0
0:00:01.146796365 13340 0763DB18 DEBUG basesrc gstbasesrc.c:3190:gst_base_src_default_negotiate:<http-src> caps of src: ANY
0:00:01.146813667 13340 0763DB18 DEBUG basesrc gstbasesrc.c:3236:gst_base_src_default_negotiate:<http-src> no negotiation needed
0:00:01.146828036 13340 0763DB18 TRACE GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0763D8E8 unref 3->2
0:00:01.146843578 13340 0763DB18 DEBUG GST_CAPS gstpad.c:2711:gst_pad_get_current_caps:<http-src:src> get current pad caps (NULL)
0:00:01.146858534 13340 0763DB18 TRACE structure gststructure.c:154:gst_structure_new_id_empty_with_size: created structure 076407B0
0:00:01.146874369 13340 0763DB18 DEBUG query gstquery.c:675:gst_query_new_custom: creating new query 076563B0 allocation
0:00:01.146891378 13340 0763DB18 DEBUG GST_PADS gstpad.c:4111:gst_pad_peer_query:<http-src:src> peer query 076563B0 (allocation)
0:00:01.146908386 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<multipartdemux:sink> 076553A8 ref 1->2
0:00:01.146924515 13340 0763DB18 DEBUG GST_PADS gstpad.c:3984:gst_pad_query:<multipartdemux:sink> doing query 076563B0 (allocation)
0:00:01.146941817 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:237:gst_object_ref:<multipartdemux> 07657048 ref 3->4
0:00:01.146957359 13340 0763DB18 DEBUG GST_PADS gstpad.c:3416:gst_pad_query_default:<multipartdemux:sink> not forwarding 076563B0 (allocation) query
0:00:01.146973488 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<multipartdemux> 07657048 unref 4->3
0:00:01.146988737 13340 0763DB18 DEBUG GST_PADS gstpad.c:4007:gst_pad_query:<multipartdemux:sink> sent query 076563B0 (allocation), result 0
0:00:01.147005745 13340 0763DB18 DEBUG GST_PADS gstpad.c:4052:gst_pad_query:<multipartdemux:sink> query failed
0:00:01.147021581 13340 0763DB18 TRACE GST_REFCOUNTING gstobject.c:264:gst_object_unref:<multipartdemux:sink> 076553A8 unref 2->1
0:00:01.147038003 13340 0763DB18 DEBUG GST_PADS gstpad.c:4173:gst_pad_peer_query:<http-src:src> query failed
0:00:01.147053545 13340 0763DB18 DEBUG basesrc gstbasesrc.c:3130:gst_base_src_prepare_allocation:<http-src> peer ALLOCATION query failed
0:00:01.147076418 13340 0763DB18 DEBUG basesrc gstbasesrc.c:3137:gst_base_src_prepare_allocation:<http-src>
-----Original Message-----
From: gstreamer-devel [mailto:gstreamer-devel-bounces at lists.freedesktop.org] On Behalf Of David Wells
Sent: Wednesday, January 24, 2018 7:42 AM
To: Discussion of the development of and with GStreamer <gstreamer-devel at lists.freedesktop.org>
Subject: RE: upgrading to 1.12.4
[This sender failed our fraud detection checks and may not be who they appear to be. Learn about spoofing at http://aka.ms/LearnAboutSpoofing]
Hello Sebastian,
I definitely want to provide any data to support fix any gstreamer bugs. Forgive my inexperience in this manor, but when you are asking for the stack trace are you referring to setting GST_DEBUG=7? And that I give you the generated log file? Or, is there a way to get a copy of the trace stack using a windows tool or the like?
Thank you
-----Original Message-----
From: gstreamer-devel [mailto:gstreamer-devel-bounces at lists.freedesktop.org] On Behalf Of Sebastian Dröge
Sent: Wednesday, January 24, 2018 2:01 AM
To: Discussion of the development of and with GStreamer <gstreamer-devel at lists.freedesktop.org>
Subject: Re: upgrading to 1.12.4
On Tue, 2018-01-23 at 23:02 +0000, David Wells wrote:
> Hi Tim.
>
> We were setting up logging to do the trace and inadvertently turned
> off logging and the chain work.
>
> From that view point we found that if GST_DEBUG=4 or less it works as
> expected. If it is set to 5 or greater the results are what I
> reported.
> It appears the logging is causing the issue.
While that's possible, most likely this just means that your problem is a race condition somewhere and enabling enough debug output changes timing enough so that it does not happen anymore.
What would be useful to have would be a stack trace of all threads at the time when it locks up.
--
Sebastian Dröge, Centricular Ltd · https://www.centricular.com
_______________________________________________
gstreamer-devel mailing list
gstreamer-devel at lists.freedesktop.org
https://lists.freedesktop.org/mailman/listinfo/gstreamer-devel
More information about the gstreamer-devel
mailing list