Last active
December 14, 2016 03:36
-
-
Save coolacid/3370222fbd87451fcf1ea9d66e7dab69 to your computer and use it in GitHub Desktop.
This file contains hidden or bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
| Moved to https://github.com/coolacid/NAADAlerts/blob/master/gstream.py so I can commit updates instead of using this gist. | |
Debug Trace:
0:00:00.000033275 2661 0x561d17aba8c0 INFO GST_INIT gst.c:511:init_pre: Initializing GStreamer Core Library version 1.8.3
0:00:00.000058478 2661 0x561d17aba8c0 INFO GST_INIT gst.c:512:init_pre: Using library installed in /usr/lib/x86_64-linux-gnu
0:00:00.000065784 2661 0x561d17aba8c0 INFO GST_INIT gst.c:523:init_pre: Linux homer 4.8.0-26-generic #28-Ubuntu SMP Tue Oct 18 14:39:52 UTC 2016 x86_64
0:00:00.000186058 2661 0x561d17aba8c0 INFO GST_INIT gstmessage.c:119:_priv_gst_message_initialize: init messages
0:00:00.000396162 2661 0x561d17aba8c0 INFO GST_INIT gstcontext.c:83:_priv_gst_context_initialize: init contexts
0:00:00.000502311 2661 0x561d17aba8c0 INFO GST_PLUGIN_LOADING gstplugin.c:316:_priv_gst_plugin_initialize: registering 0 static plugins
0:00:00.000556002 2661 0x561d17aba8c0 INFO GST_PLUGIN_LOADING gstplugin.c:224:gst_plugin_register_static: registered static plugin "staticelements"
0:00:00.000561874 2661 0x561d17aba8c0 INFO GST_PLUGIN_LOADING gstplugin.c:226:gst_plugin_register_static: added static plugin "staticelements", result: 1
0:00:00.000594585 2661 0x561d17aba8c0 INFO GST_REGISTRY gstregistry.c:1723:ensure_current_registry: reading registry cache: /home/coolacid/.cache/gstreamer-1.0/registry.x86_64.bin
0:00:00.006156827 2661 0x561d17aba8c0 INFO GST_REGISTRY gstregistrybinary.c:619:priv_gst_registry_binary_read_cache: loaded /home/coolacid/.cache/gstreamer-1.0/registry.x86_64.bin in 0.005553 seconds
0:00:00.006182073 2661 0x561d17aba8c0 INFO GST_REGISTRY gstregistry.c:1579:scan_and_update_registry: Validating plugins from registry cache: /home/coolacid/.cache/gstreamer-1.0/registry.x86_64.bin
0:00:00.006623763 2661 0x561d17aba8c0 INFO GST_REGISTRY gstregistry.c:1681:scan_and_update_registry: Registry cache has not changed
0:00:00.006628558 2661 0x561d17aba8c0 INFO GST_REGISTRY gstregistry.c:1758:ensure_current_registry: registry reading and updating done, result = 1
0:00:00.006631367 2661 0x561d17aba8c0 INFO GST_INIT gst.c:724:init_post: GLib runtime version: 2.50.0
0:00:00.006634707 2661 0x561d17aba8c0 INFO GST_INIT gst.c:726:init_post: GLib headers version: 2.49.5
0:00:00.006637064 2661 0x561d17aba8c0 INFO GST_INIT gst.c:727:init_post: initialized GStreamer successfully
INIT
0:00:00.007491701 2661 0x561d17aba8c0 INFO GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "pipeline" named "test-pipeline"
0:00:00.009286218 2661 0x561d17aba8c0 INFO GST_PLUGIN_LOADING gstplugin.c:842:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/x86_64-linux-gnu/gstreamer-1.0/libgstpulse.so" loaded
0:00:00.009294242 2661 0x561d17aba8c0 INFO GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "pulsesink" named "sink"
0:00:00.009467233 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstelement.c:659:gst_element_add_pad:<GstBaseSink@0x561d17d111c0> adding pad 'sink'
Playing Tones
0:00:00.009960785 2661 0x561d17aba8c0 INFO GST_PLUGIN_LOADING gstplugin.c:842:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/x86_64-linux-gnu/gstreamer-1.0/libgstaudiotestsrc.so" loaded
0:00:00.009967606 2661 0x561d17aba8c0 INFO GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "audiotestsrc" named "Tone"
0:00:00.010064460 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstelement.c:659:gst_element_add_pad:<GstBaseSrc@0x561d17d1a660> adding pad 'src'
0:00:00.010197295 2661 0x561d17aba8c0 INFO GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "audiotestsrc" named "Audio"
0:00:00.010223882 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstelement.c:659:gst_element_add_pad:<GstBaseSrc@0x561d17d1ddf0> adding pad 'src'
0:00:00.010281377 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstutils.c:1573:gst_element_link_pads_full: trying to link element Tone:(any) to element sink:(any)
0:00:00.010290029 2661 0x561d17aba8c0 INFO GST_PADS gstutils.c:932:gst_pad_check_link: trying to link Tone:src and sink:sink
0:00:00.010319688 2661 0x561d17aba8c0 INFO GST_PADS gstutils.c:1446:prepare_link_maybe_ghosting: Tone and sink in same bin, no need for ghost pads
0:00:00.010327904 2661 0x561d17aba8c0 INFO GST_PADS gstpad.c:2315:gst_pad_link_prepare: trying to link Tone:src and sink:sink
0:00:00.010343668 2661 0x561d17aba8c0 INFO GST_PADS gstpad.c:2521:gst_pad_link_full: linked Tone:src and sink:sink, successful
0:00:00.010356455 2661 0x561d17aba8c0 INFO GST_EVENT gstevent.c:1382:gst_event_new_reconfigure: creating reconfigure event
0:00:00.010361090 2661 0x561d17aba8c0 INFO GST_EVENT gstpad.c:5634:gst_pad_send_event_unchecked:<Tone:src> Received event on flushing pad. Discarding
Tone 1
0:00:00.010500391 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2316:gst_bin_element_set_state:<sink> current NULL pending VOID_PENDING, desired next READY
0:00:00.010508603 2661 0x561d17aba8c0 INFO pulse pulsesink.c:3239:gst_pulsesink_change_state:<sink> new pa main loop thread
0:00:00.010591499 2661 0x561d17aba8c0 INFO pulse pulsesink.c:533:gst_pulseringbuffer_open_device:<sink> new context with name python3, pbuf=0x561d17d24030, pctx=0x561d17d10140
0:00:00.011562137 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<sink> completed state change to READY
0:00:00.011569420 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<sink> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.011582980 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2764:gst_bin_change_state_func:<test-pipeline> child 'sink' changed state to 2(READY) successfully
0:00:00.011591543 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2316:gst_bin_element_set_state:<Tone> current NULL pending VOID_PENDING, desired next READY
0:00:00.011598499 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<Tone> completed state change to READY
0:00:00.011603548 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<Tone> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.011611641 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2764:gst_bin_change_state_func:<test-pipeline> child 'Tone' changed state to 2(READY) successfully
0:00:00.011620622 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2347:gst_element_continue_state:<test-pipeline> committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:00.011627565 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<test-pipeline> notifying about state-changed NULL to READY (PLAYING pending)
0:00:00.011635135 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2354:gst_element_continue_state:<test-pipeline> continue state change READY to PAUSED, final PLAYING
0:00:00.011645386 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2316:gst_bin_element_set_state:<sink> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.011659940 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2770:gst_bin_change_state_func:<test-pipeline> child 'sink' is changing state asynchronously to PAUSED
0:00:00.011667455 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2316:gst_bin_element_set_state:<Tone> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.011677028 2661 0x561d17aba8c0 INFO basesrc gstbasesrc.c:1344:gst_base_src_do_seek:<Tone> 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:00.011695910 2661 0x561d17aba8c0 INFO task gsttask.c:451:gst_task_set_lock: setting stream lock 0x561d17d12310 on task 0x561d17d2c050
0:00:00.011702846 2661 0x561d17aba8c0 INFO GST_PADS gstpad.c:5980:gst_pad_start_task:<Tone:src> created task 0x561d17d2c050
0:00:00.011734569 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<Tone> completed state change to PAUSED
0:00:00.011741423 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<Tone> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.011749500 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2764:gst_bin_change_state_func:<test-pipeline> child 'Tone' changed state to 3(PAUSED) successfully
0:00:00.011760865 2661 0x561d17d22630 INFO GST_ELEMENT_PADS gstelement.c:904:gst_element_get_static_pad: no such pad 'sink' in element "Tone"
0:00:00.011778546 2661 0x561d17d22630 FIXME default gstutils.c:3766:gst_pad_create_stream_id_internal:<Tone:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:00.012288090 2661 0x561d17d22630 INFO structure gststructure.c:3049:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, format=(string)S16LE, layout=(string)interleaved, rate=(int)44100, channels=(int)1;
0:00:00.012307284 2661 0x561d17d22630 INFO GST_EVENT gstevent.c:679:gst_event_new_caps: creating caps event audio/x-raw, format=(string)S16LE, layout=(string)interleaved, rate=(int)44100, channels=(int)1
0:00:00.012331290 2661 0x561d17d22630 INFO structure gststructure.c:3049:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, format=(string)S16LE, layout=(string)interleaved, rate=(int)44100, channels=(int)1;
0:00:00.012438906 2661 0x561d17d22630 INFO structure gststructure.c:3049:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, format=(string)S16LE, layout=(string)interleaved, rate=(int)44100, channels=(int)1;
0:00:00.014259706 2661 0x561d17d22630 INFO pulse pulsesink.c:968:gst_pulseringbuffer_acquire:<sink> tlength: 17640
0:00:00.014270079 2661 0x561d17d22630 INFO pulse pulsesink.c:969:gst_pulseringbuffer_acquire:<sink> maxlength: -1
0:00:00.014276184 2661 0x561d17d22630 INFO pulse pulsesink.c:970:gst_pulseringbuffer_acquire:<sink> prebuf: 0
0:00:00.014280724 2661 0x561d17d22630 INFO pulse pulsesink.c:971:gst_pulseringbuffer_acquire:<sink> minreq: 882
0:00:00.014480333 2661 0x561d17d22630 INFO pulse pulsesink.c:1021:gst_pulseringbuffer_acquire:<sink> negotiated to: pcm, format.sample_format = "\"s16le\"" format.channels = "1" format.rate = "44100" format.channel_map = "\"mono\""
0:00:00.014497255 2661 0x561d17d22630 INFO pulse pulsesink.c:1034:gst_pulseringbuffer_acquire:<sink> tlength: 9702 (wanted: 17640)
0:00:00.014503904 2661 0x561d17d22630 INFO pulse pulsesink.c:1035:gst_pulseringbuffer_acquire:<sink> maxlength: 4194304
0:00:00.014509316 2661 0x561d17d22630 INFO pulse pulsesink.c:1036:gst_pulseringbuffer_acquire:<sink> prebuf: 0
0:00:00.014514276 2661 0x561d17d22630 INFO pulse pulsesink.c:1038:gst_pulseringbuffer_acquire:<sink> minreq: 882 (wanted 882)
0:00:00.014522027 2661 0x561d17d22630 INFO ringbuffer gstaudioringbuffer.c:581:gst_audio_ring_buffer_acquire:<pulseringbuffer0> Allocating an array for 11 timestamps
0:00:00.015264497 2661 0x561d17d22630 INFO GST_EVENT gstevent.c:760:gst_event_new_segment: creating segment event 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:00.015307096 2661 0x561d17d22630 INFO basesrc gstbasesrc.c:2843:gst_base_src_loop:<Tone> marking pending DISCONT
0:00:00.015324664 2661 0x561d17d22630 INFO GST_STATES gstbin.c:3238:bin_handle_async_done:<test-pipeline> committing state from READY to PAUSED, old pending PLAYING
0:00:00.015333679 2661 0x561d17d22630 INFO GST_STATES gstbin.c:3267:bin_handle_async_done:<test-pipeline> continue state change, pending PLAYING
0:00:00.015339722 2661 0x561d17d22630 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<test-pipeline> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:00.015432654 2661 0x7f50fc0025e0 INFO GST_STATES gstbin.c:3061:gst_bin_continue_func:<test-pipeline> continue state change PAUSED to PLAYING, final PLAYING
0:00:00.015473028 2661 0x7f50fc0025e0 INFO GST_EVENT gstevent.c:1253:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:00.015486271 2661 0x7f50fc0025e0 INFO bin gstbin.c:2593:gst_bin_do_latency_func:<test-pipeline> configured latency of 0:00:00.000000000
0:00:00.015493931 2661 0x7f50fc0025e0 INFO GST_STATES gstbin.c:2316:gst_bin_element_set_state:<sink> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.015502687 2661 0x7f50fc0025e0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<sink> completed state change to PLAYING
0:00:00.015508977 2661 0x7f50fc0025e0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<sink> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.015520637 2661 0x7f50fc0025e0 INFO GST_STATES gstbin.c:2764:gst_bin_change_state_func:<test-pipeline> child 'sink' changed state to 4(PLAYING) successfully
0:00:00.015526983 2661 0x7f50fc0025e0 INFO GST_STATES gstbin.c:2316:gst_bin_element_set_state:<Tone> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.015535489 2661 0x7f50fc0025e0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<Tone> completed state change to PLAYING
0:00:00.015541986 2661 0x7f50fc0025e0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<Tone> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.015669439 2661 0x7f50fc0025e0 INFO GST_STATES gstbin.c:2764:gst_bin_change_state_func:<test-pipeline> child 'Tone' changed state to 4(PLAYING) successfully
0:00:00.015676961 2661 0x7f50fc0025e0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<test-pipeline> completed state change to PLAYING
0:00:00.015681199 2661 0x7f50fc0025e0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<test-pipeline> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:02.013885284 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstpad.c:2071:gst_pad_unlink: unlinking Tone:src(0x561d17d122a0) and sink:sink(0x561d17d12060)
0:00:02.013909307 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstpad.c:2125:gst_pad_unlink: unlinked Tone:src and sink:sink
0:00:02.013915590 2661 0x561d17aba8c0 INFO GST_PARENTAGE gstbin.c:1630:gst_bin_remove_func:<test-pipeline> removed child "Tone"
0:00:02.013952920 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstutils.c:1573:gst_element_link_pads_full: trying to link element Audio:(any) to element sink:(any)
0:00:02.013959239 2661 0x561d17aba8c0 INFO GST_PADS gstutils.c:932:gst_pad_check_link: trying to link Audio:src and sink:sink
0:00:02.014366273 2661 0x561d17d22630 INFO basesrc gstbasesrc.c:2856:gst_base_src_loop:<Tone> pausing after gst_pad_push() = not-linked
0:00:02.014389803 2661 0x561d17d22630 WARN basesrc gstbasesrc.c:2948:gst_base_src_loop:<Tone> error: Internal data flow error.
0:00:02.014388488 2661 0x561d17aba8c0 INFO GST_PADS gstutils.c:1446:prepare_link_maybe_ghosting: Audio and sink in same bin, no need for ghost pads
0:00:02.014396174 2661 0x561d17d22630 WARN basesrc gstbasesrc.c:2948:gst_base_src_loop:<Tone> error: streaming task paused, reason not-linked (-1)
0:00:02.014411796 2661 0x561d17aba8c0 INFO GST_PADS gstpad.c:2315:gst_pad_link_prepare: trying to link Audio:src and sink:sink
0:00:02.014422619 2661 0x561d17d22630 INFO GST_ERROR_SYSTEM gstelement.c:1879:gst_element_message_full:<Tone> posting message: Internal data flow error.
0:00:02.014442231 2661 0x561d17d22630 INFO GST_ERROR_SYSTEM gstelement.c:1902:gst_element_message_full:<Tone> posted error message: Internal data flow error.
0:00:02.014463276 2661 0x561d17d22630 INFO task gsttask.c:316:gst_task_func:<Tone:src> Task going to paused
0:00:02.014543459 2661 0x561d17aba8c0 INFO GST_PADS gstpad.c:2521:gst_pad_link_full: linked Audio:src and sink:sink, successful
0:00:02.014553403 2661 0x561d17aba8c0 INFO GST_EVENT gstevent.c:1382:gst_event_new_reconfigure: creating reconfigure event
0:00:02.014557929 2661 0x561d17aba8c0 INFO GST_EVENT gstpad.c:5634:gst_pad_send_event_unchecked:<Audio:src> Received event on flushing pad. Discarding
Tone 2
0:00:02.014605817 2661 0x561d17aba8c0 INFO GST_EVENT gstevent.c:1253:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:02.014616727 2661 0x561d17aba8c0 INFO GST_EVENT gstpad.c:5634:gst_pad_send_event_unchecked:<Audio:src> Received event on flushing pad. Discarding
0:00:02.014623746 2661 0x561d17aba8c0 WARN bin gstbin.c:2597:gst_bin_do_latency_func:<test-pipeline> did not really configure latency of 0:00:00.000000000
0:00:02.014636247 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2316:gst_bin_element_set_state:<sink> current PLAYING pending VOID_PENDING, desired next PLAYING
0:00:02.014641465 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2433:gst_bin_element_set_state:<sink> skipping transition from PLAYING to PLAYING
0:00:02.014646408 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2764:gst_bin_change_state_func:<test-pipeline> child 'sink' changed state to 4(PLAYING) successfully
0:00:02.014652533 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2316:gst_bin_element_set_state:<Audio> current NULL pending VOID_PENDING, desired next PLAYING
0:00:02.014659225 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2347:gst_element_continue_state:<Audio> committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:02.014665472 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<Audio> notifying about state-changed NULL to READY (PLAYING pending)
0:00:02.014673814 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2354:gst_element_continue_state:<Audio> continue state change READY to PAUSED, final PLAYING
0:00:02.014682651 2661 0x561d17aba8c0 INFO basesrc gstbasesrc.c:1344:gst_base_src_do_seek:<Audio> 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:02.014699979 2661 0x561d17aba8c0 INFO task gsttask.c:451:gst_task_set_lock: setting stream lock 0x561d17d12550 on task 0x561d17d2c170
0:00:02.014705435 2661 0x561d17aba8c0 INFO GST_PADS gstpad.c:5980:gst_pad_start_task:<Audio:src> created task 0x561d17d2c170
0:00:02.014718829 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2347:gst_element_continue_state:<Audio> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:02.014726450 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<Audio> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:02.014728699 2661 0x7f50fc0025e0 INFO GST_ELEMENT_PADS gstelement.c:904:gst_element_get_static_pad: no such pad 'sink' in element "Audio"
0:00:02.014741693 2661 0x7f50fc0025e0 FIXME default gstutils.c:3766:gst_pad_create_stream_id_internal:<Audio:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:02.014741688 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2354:gst_element_continue_state:<Audio> continue state change PAUSED to PLAYING, final PLAYING
0:00:02.014753661 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<Audio> completed state change to PLAYING
0:00:02.014759820 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<Audio> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:02.014767993 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2764:gst_bin_change_state_func:<test-pipeline> child 'Audio' changed state to 4(PLAYING) successfully
0:00:02.014776094 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<test-pipeline> completed state change to PLAYING
0:00:02.014881704 2661 0x7f50fc0025e0 INFO structure gststructure.c:3049:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, format=(string)S16LE, layout=(string)interleaved, rate=(int)44100, channels=(int)1;
0:00:02.014893441 2661 0x7f50fc0025e0 INFO GST_EVENT gstevent.c:679:gst_event_new_caps: creating caps event audio/x-raw, format=(string)S16LE, layout=(string)interleaved, rate=(int)44100, channels=(int)1
0:00:02.014907419 2661 0x7f50fc0025e0 INFO structure gststructure.c:3049:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, format=(string)S16LE, layout=(string)interleaved, rate=(int)44100, channels=(int)1;
0:00:02.015027927 2661 0x7f50fc0025e0 INFO GST_EVENT gstevent.c:760:gst_event_new_segment: creating segment event 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:02.015052429 2661 0x7f50fc0025e0 INFO basesrc gstbasesrc.c:2843:gst_base_src_loop:<Audio> marking pending DISCONT
0:00:02.015128550 2661 0x7f510c00cc00 WARN pulse pulsesink.c:702:gst_pulsering_stream_underflow_cb:<sink> Got underflow
0:00:02.017481536 2661 0x7f510c00cc00 WARN pulse pulsesink.c:702:gst_pulsering_stream_underflow_cb:<sink> Got underflow
0:00:02.017574757 2661 0x7f510c00cc00 WARN pulse pulsesink.c:702:gst_pulsering_stream_underflow_cb:<sink> Got underflow
0:00:02.017583848 2661 0x7f510c00cc00 WARN pulse pulsesink.c:702:gst_pulsering_stream_underflow_cb:<sink> Got underflow
0:00:04.016835555 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2316:gst_bin_element_set_state:<sink> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:04.017487360 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<sink> completed state change to PAUSED
0:00:04.017508419 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<sink> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:04.017530395 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2764:gst_bin_change_state_func:<test-pipeline> child 'sink' changed state to 3(PAUSED) successfully
0:00:04.017548650 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2316:gst_bin_element_set_state:<Audio> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:04.017554189 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<Audio> completed state change to PAUSED
0:00:04.017561279 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<Audio> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:04.017567052 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2764:gst_bin_change_state_func:<test-pipeline> child 'Audio' changed state to 3(PAUSED) successfully
0:00:04.017574982 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<test-pipeline> completed state change to PAUSED
0:00:04.017580183 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<test-pipeline> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:04.017611668 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstpad.c:2071:gst_pad_unlink: unlinking Audio:src(0x561d17d124e0) and sink:sink(0x561d17d12060)
0:00:04.017624581 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstpad.c:2125:gst_pad_unlink: unlinked Audio:src and sink:sink
0:00:04.017632075 2661 0x561d17aba8c0 INFO GST_PARENTAGE gstbin.c:1630:gst_bin_remove_func:<test-pipeline> removed child "Audio"
Playing /home/Repos/CoolAcid/NAAD/Pelmorex Test Message mp3 en.mp3
0:00:06.020133321 2661 0x561d17aba8c0 INFO GST_PLUGIN_LOADING gstplugin.c:842:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/x86_64-linux-gnu/gstreamer-1.0/libgstcoreelements.so" loaded
0:00:06.020145970 2661 0x561d17aba8c0 INFO GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "filesrc" named "filesrc"
0:00:06.020214917 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstelement.c:659:gst_element_add_pad:<GstBaseSrc@0x561d17d340e0> adding pad 'src'
0:00:06.020510128 2661 0x561d17aba8c0 INFO filesrc gstfilesrc.c:262:gst_file_src_set_location: filename : /home/Repos/CoolAcid/NAAD/Pelmorex Test Message mp3 en.mp3
0:00:06.020518127 2661 0x561d17aba8c0 INFO filesrc gstfilesrc.c:263:gst_file_src_set_location: uri : file:///home/Repos/CoolAcid/NAAD/Pelmorex%20Test%20Message%20mp3%20en.mp3
0:00:06.020763857 2661 0x561d17aba8c0 INFO GST_PLUGIN_LOADING gstplugin.c:842:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/x86_64-linux-gnu/gstreamer-1.0/libgstmad.so" loaded
0:00:06.020770909 2661 0x561d17aba8c0 INFO GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "mad" named "decode"
0:00:06.020885399 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstelement.c:659:gst_element_add_pad:<GstAudioDecoder@0x561d17d39780> adding pad 'sink'
0:00:06.020904065 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstelement.c:659:gst_element_add_pad:<GstAudioDecoder@0x561d17d39780> adding pad 'src'
0:00:06.021109948 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstutils.c:1573:gst_element_link_pads_full: trying to link element filesrc:(any) to element decode:(any)
0:00:06.021119127 2661 0x561d17aba8c0 INFO GST_PADS gstutils.c:932:gst_pad_check_link: trying to link filesrc:src and decode:sink
0:00:06.021131442 2661 0x561d17aba8c0 INFO GST_PADS gstpad.c:4098:gst_pad_peer_query:<decode:src> pad has no peer
0:00:06.021168762 2661 0x561d17aba8c0 INFO GST_PADS gstutils.c:1446:prepare_link_maybe_ghosting: filesrc and decode in same bin, no need for ghost pads
0:00:06.021188816 2661 0x561d17aba8c0 INFO GST_PADS gstpad.c:2315:gst_pad_link_prepare: trying to link filesrc:src and decode:sink
0:00:06.021193936 2661 0x561d17aba8c0 INFO GST_PADS gstpad.c:4098:gst_pad_peer_query:<decode:src> pad has no peer
0:00:06.021214933 2661 0x561d17aba8c0 INFO GST_PADS gstpad.c:2521:gst_pad_link_full: linked filesrc:src and decode:sink, successful
0:00:06.021229070 2661 0x561d17aba8c0 INFO GST_EVENT gstevent.c:1382:gst_event_new_reconfigure: creating reconfigure event
0:00:06.021232560 2661 0x561d17aba8c0 INFO GST_EVENT gstpad.c:5634:gst_pad_send_event_unchecked:<filesrc:src> Received event on flushing pad. Discarding
0:00:06.021250822 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstutils.c:1573:gst_element_link_pads_full: trying to link element decode:(any) to element sink:(any)
0:00:06.021255662 2661 0x561d17aba8c0 INFO GST_PADS gstutils.c:932:gst_pad_check_link: trying to link decode:src and sink:sink
0:00:06.021536052 2661 0x561d17aba8c0 INFO GST_PADS gstutils.c:1446:prepare_link_maybe_ghosting: decode and sink in same bin, no need for ghost pads
0:00:06.021553532 2661 0x561d17aba8c0 INFO GST_PADS gstpad.c:2315:gst_pad_link_prepare: trying to link decode:src and sink:sink
0:00:06.021747638 2661 0x561d17aba8c0 INFO GST_PADS gstpad.c:2521:gst_pad_link_full: linked decode:src and sink:sink, successful
0:00:06.021757342 2661 0x561d17aba8c0 INFO GST_EVENT gstevent.c:1382:gst_event_new_reconfigure: creating reconfigure event
0:00:06.021761660 2661 0x561d17aba8c0 INFO GST_EVENT gstpad.c:5634:gst_pad_send_event_unchecked:<decode:src> Received event on flushing pad. Discarding
0:00:06.021774419 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstutils.c:1573:gst_element_link_pads_full: trying to link element filesrc:(any) to element decode:(any)
0:00:06.021801420 2661 0x561d17aba8c0 INFO GST_EVENT gstevent.c:1253:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:06.021810784 2661 0x561d17aba8c0 INFO GST_EVENT gstpad.c:5634:gst_pad_send_event_unchecked:<decode:src> Received event on flushing pad. Discarding
0:00:06.021815586 2661 0x561d17aba8c0 WARN bin gstbin.c:2597:gst_bin_do_latency_func:<test-pipeline> did not really configure latency of 0:00:00.000000000
0:00:06.021823810 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2316:gst_bin_element_set_state:<sink> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:06.021832085 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<sink> completed state change to PLAYING
0:00:06.021835562 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<sink> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:06.021841870 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2764:gst_bin_change_state_func:<test-pipeline> child 'sink' changed state to 4(PLAYING) successfully
0:00:06.021846487 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2316:gst_bin_element_set_state:<decode> current NULL pending VOID_PENDING, desired next PLAYING
0:00:06.021850882 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2347:gst_element_continue_state:<decode> committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:06.021854930 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<decode> notifying about state-changed NULL to READY (PLAYING pending)
0:00:06.021858964 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2354:gst_element_continue_state:<decode> continue state change READY to PAUSED, final PLAYING
0:00:06.021867878 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2347:gst_element_continue_state:<decode> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:06.021871939 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<decode> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:06.021881078 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2354:gst_element_continue_state:<decode> continue state change PAUSED to PLAYING, final PLAYING
0:00:06.021885524 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<decode> completed state change to PLAYING
0:00:06.021888646 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<decode> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:06.021892909 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2764:gst_bin_change_state_func:<test-pipeline> child 'decode' changed state to 4(PLAYING) successfully
0:00:06.021897084 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2316:gst_bin_element_set_state:<filesrc> current NULL pending VOID_PENDING, desired next PLAYING
0:00:06.021900566 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2347:gst_element_continue_state:<filesrc> committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:06.021904984 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<filesrc> notifying about state-changed NULL to READY (PLAYING pending)
0:00:06.021904498 2661 0x7f50fc0025e0 INFO basesrc gstbasesrc.c:2856:gst_base_src_loop:<Audio> pausing after gst_pad_push() = not-linked
0:00:06.021917971 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2354:gst_element_continue_state:<filesrc> continue state change READY to PAUSED, final PLAYING
0:00:06.021931422 2661 0x561d17aba8c0 INFO filesrc gstfilesrc.c:465:gst_file_src_start:<filesrc> opening file /home/Repos/CoolAcid/NAAD/Pelmorex Test Message mp3 en.mp3
0:00:06.021933610 2661 0x7f50fc0025e0 WARN basesrc gstbasesrc.c:2948:gst_base_src_loop:<Audio> error: Internal data flow error.
0:00:06.021937926 2661 0x7f50fc0025e0 WARN basesrc gstbasesrc.c:2948:gst_base_src_loop:<Audio> error: streaming task paused, reason not-linked (-1)
0:00:06.021943025 2661 0x7f50fc0025e0 INFO GST_ERROR_SYSTEM gstelement.c:1879:gst_element_message_full:<Audio> posting message: Internal data flow error.
0:00:06.021942724 2661 0x561d17aba8c0 INFO basesrc gstbasesrc.c:1344:gst_base_src_do_seek:<filesrc> 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 120192
0:00:06.021949668 2661 0x7f50fc0025e0 INFO GST_ERROR_SYSTEM gstelement.c:1902:gst_element_message_full:<Audio> posted error message: Internal data flow error.
0:00:06.021954871 2661 0x561d17aba8c0 INFO task gsttask.c:451:gst_task_set_lock: setting stream lock 0x561d17d12790 on task 0x561d17d2c290
0:00:06.021959500 2661 0x561d17aba8c0 INFO GST_PADS gstpad.c:5980:gst_pad_start_task:<filesrc:src> created task 0x561d17d2c290
0:00:06.021955986 2661 0x7f50fc0025e0 INFO task gsttask.c:316:gst_task_func:<Audio:src> Task going to paused
0:00:06.021988031 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2347:gst_element_continue_state:<filesrc> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:06.021993435 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<filesrc> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:06.022003349 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2354:gst_element_continue_state:<filesrc> continue state change PAUSED to PLAYING, final PLAYING
0:00:06.022009595 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<filesrc> completed state change to PLAYING
0:00:06.022016412 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<filesrc> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:06.022017891 2661 0x561d17d336d0 INFO GST_ELEMENT_PADS gstelement.c:904:gst_element_get_static_pad: no such pad 'sink' in element "filesrc"
0:00:06.022036053 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2764:gst_bin_change_state_func:<test-pipeline> child 'filesrc' changed state to 4(PLAYING) successfully
0:00:06.022044905 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<test-pipeline> completed state change to PLAYING
0:00:06.022049590 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<test-pipeline> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:06.022076898 2661 0x561d17d336d0 INFO GST_EVENT gstevent.c:760:gst_event_new_segment: creating segment event bytes segment start=0, offset=0, stop=-1, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0, base=0, position 0, duration 120192
0:00:06.022097518 2661 0x561d17d336d0 INFO GST_EVENT gstevent.c:760:gst_event_new_segment: creating segment event 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 0:00:00.000120192
0:00:06.022112231 2661 0x561d17d336d0 INFO basesrc gstbasesrc.c:2843:gst_base_src_loop:<filesrc> marking pending DISCONT
0:00:06.022216141 2661 0x561d17d336d0 INFO GST_EVENT gstevent.c:679:gst_event_new_caps: creating caps event audio/x-raw, format=(string)S32LE, layout=(string)interleaved, rate=(int)48000, channels=(int)2, channel-mask=(bitmask)0x0000000000000003
0:00:06.023008701 2661 0x561d17d336d0 INFO ringbuffer gstaudioringbuffer.c:675:gst_audio_ring_buffer_release:<pulseringbuffer0> Freeing timestamp buffer, 11 entries
0:00:06.023038265 2661 0x561d17d336d0 INFO pulse pulsesink.c:968:gst_pulseringbuffer_acquire:<sink> tlength: 76800
0:00:06.023042932 2661 0x561d17d336d0 INFO pulse pulsesink.c:969:gst_pulseringbuffer_acquire:<sink> maxlength: -1
0:00:06.023045932 2661 0x561d17d336d0 INFO pulse pulsesink.c:970:gst_pulseringbuffer_acquire:<sink> prebuf: 0
0:00:06.023048416 2661 0x561d17d336d0 INFO pulse pulsesink.c:971:gst_pulseringbuffer_acquire:<sink> minreq: 3840
0:00:06.091446032 2661 0x561d17d336d0 INFO pulse pulsesink.c:1021:gst_pulseringbuffer_acquire:<sink> negotiated to: pcm, format.sample_format = "\"s32le\"" format.channels = "2" format.rate = "48000" format.channel_map = "\"front-left,front-right\""
0:00:06.091471851 2661 0x561d17d336d0 INFO pulse pulsesink.c:1034:gst_pulseringbuffer_acquire:<sink> tlength: 42240 (wanted: 76800)
0:00:06.091475789 2661 0x561d17d336d0 INFO pulse pulsesink.c:1035:gst_pulseringbuffer_acquire:<sink> maxlength: 4194304
0:00:06.091480433 2661 0x561d17d336d0 INFO pulse pulsesink.c:1036:gst_pulseringbuffer_acquire:<sink> prebuf: 0
0:00:06.091499903 2661 0x561d17d336d0 INFO pulse pulsesink.c:1038:gst_pulseringbuffer_acquire:<sink> minreq: 3840 (wanted 3840)
0:00:06.091516778 2661 0x561d17d336d0 INFO ringbuffer gstaudioringbuffer.c:581:gst_audio_ring_buffer_acquire:<pulseringbuffer0> Allocating an array for 11 timestamps
0:00:06.091546787 2661 0x561d17aba8c0 INFO GST_BUS gstbus.c:567:gst_bus_timed_pop_filtered:<bus1> we got woken up, recheck for message
0:00:30.929599860 2661 0x561d17d336d0 WARN basesrc gstbasesrc.c:2396:gst_base_src_update_length:<filesrc> processing at or past EOS
0:00:30.929624463 2661 0x561d17d336d0 INFO basesrc gstbasesrc.c:2739:gst_base_src_loop:<filesrc> pausing after gst_base_src_get_range() = eos
0:00:31.072864387 2661 0x7f510c00cc00 WARN pulse pulsesink.c:702:gst_pulsering_stream_underflow_cb:<sink> Got underflow
0:00:31.091262900 2661 0x561d17d336d0 INFO task gsttask.c:316:gst_task_func:<filesrc:src> Task going to paused
0:00:31.091271896 2661 0x561d17aba8c0 INFO GST_BUS gstbus.c:567:gst_bus_timed_pop_filtered:<bus1> we got woken up, recheck for message
0:00:31.091348933 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstpad.c:2071:gst_pad_unlink: unlinking filesrc:src(0x561d17d12720) and decode:sink(0x561d17d12960)
0:00:31.091383796 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstpad.c:2125:gst_pad_unlink: unlinked filesrc:src and decode:sink
0:00:31.091391094 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstpad.c:2071:gst_pad_unlink: unlinking decode:src(0x561d17d12ba0) and sink:sink(0x561d17d12060)
0:00:31.091398998 2661 0x561d17aba8c0 INFO GST_ELEMENT_PADS gstpad.c:2125:gst_pad_unlink: unlinked decode:src and sink:sink
0:00:31.091404217 2661 0x561d17aba8c0 INFO GST_PARENTAGE gstbin.c:1630:gst_bin_remove_func:<test-pipeline> removed child "decode"
0:00:31.091416992 2661 0x561d17aba8c0 INFO GST_PARENTAGE gstbin.c:1630:gst_bin_remove_func:<test-pipeline> removed child "filesrc"
0:00:31.091447535 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2316:gst_bin_element_set_state:<sink> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:31.091644143 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<sink> completed state change to PAUSED
0:00:31.091649002 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<sink> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:31.091667104 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2764:gst_bin_change_state_func:<test-pipeline> child 'sink' changed state to 3(PAUSED) successfully
0:00:31.091673746 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<test-pipeline> completed state change to PAUSED
0:00:31.091678552 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<test-pipeline> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:31.091680130 2661 0x7f510c00cc00 INFO pulse pulsesink.c:478:gst_pulsering_context_subscribe_cb:<sink> emitting sink-changed
0:00:31.091707686 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2316:gst_bin_element_set_state:<sink> current PAUSED pending VOID_PENDING, desired next READY
0:00:31.091870285 2661 0x561d17aba8c0 INFO ringbuffer gstaudioringbuffer.c:675:gst_audio_ring_buffer_release:<pulseringbuffer0> Freeing timestamp buffer, 11 entries
0:00:31.091892826 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<sink> completed state change to READY
0:00:31.091898657 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<sink> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:31.091905498 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2764:gst_bin_change_state_func:<test-pipeline> child 'sink' changed state to 2(READY) successfully
0:00:31.091914502 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2347:gst_element_continue_state:<test-pipeline> committing state from PAUSED to READY, pending NULL, next NULL
0:00:31.091920879 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<test-pipeline> notifying about state-changed PAUSED to READY (NULL pending)
0:00:31.091927049 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2354:gst_element_continue_state:<test-pipeline> continue state change READY to NULL, final NULL
0:00:31.091935000 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2316:gst_bin_element_set_state:<sink> current READY pending VOID_PENDING, desired next NULL
0:00:31.092046289 2661 0x561d17aba8c0 INFO pulse pulsesink.c:3221:gst_pulsesink_release_mainloop:<sink> terminating pa main loop thread
0:00:31.092082434 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<sink> completed state change to NULL
0:00:31.092089432 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<sink> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:31.092097476 2661 0x561d17aba8c0 INFO GST_STATES gstbin.c:2764:gst_bin_change_state_func:<test-pipeline> child 'sink' changed state to 1(NULL) successfully
0:00:31.092110597 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2372:gst_element_continue_state:<test-pipeline> completed state change to NULL
0:00:31.092116175 2661 0x561d17aba8c0 INFO GST_STATES gstelement.c:2277:_priv_gst_element_state_changed:<test-pipeline> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:31.096235643 2661 0x561d17aba8c0 INFO GST_PARENTAGE gstbin.c:1630:gst_bin_remove_func:<test-pipeline> removed child "sink"
0:00:31.096262292 2661 0x561d17aba8c0 INFO GST_REFCOUNTING gstelement.c:2947:gst_element_dispose:<test-pipeline> dispose
0:00:31.096284521 2661 0x561d17aba8c0 INFO GST_REFCOUNTING gstelement.c:2992:gst_element_dispose:<test-pipeline> parent class dispose
0:00:31.096291945 2661 0x561d17aba8c0 INFO GST_REFCOUNTING gstelement.c:3023:gst_element_finalize:<test-pipeline> finalize
0:00:31.096300723 2661 0x561d17aba8c0 INFO GST_REFCOUNTING gstelement.c:3028:gst_element_finalize:<test-pipeline> finalize parent
Updated again today. It appears if I sent a "Ready" state playing something new doesn't produce audio. Skipping, the tones work, however the filesrc does not.
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment
I have tried: