0:00:03.518667417 10271 0x804b008 INFO      GST_ELEMENT_PADS gstelement.c:887:gst_element_get_static_pad: found pad decoder:src
** (mp3:10271): DEBUG: main (150): block decoder src pad

0:00:03.520788494 10271 0x804b008 INFO    GST_PLUGIN_LOADING gstplugin.c:620:gst_plugin_load_file: plugin "/home/halley/gst/plugin-test//libgstpulse.so" loaded
0:00:03.520825199 10271 0x804b008 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "pulsesink" named "pulse_play_audio"
0:00:03.520883961 10271 0x804b008 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSink@0x8194130> adding pad 'sink'
Time: 0:00:03.312517006 / 0:04:00.428875000
** (mp3:10271): DEBUG: unlink_and_replace: Blocked pad successfully, unlinking and replacing downstream
0:00:03.537835719 10271 0x8135370 INFO      GST_ELEMENT_PADS gstpad.c:1548:gst_pad_unlink: unlinking decoder:src(0x81221b0) and alsa_play_audio:sink(0x8122270)
0:00:03.537860591 10271 0x8135370 INFO      GST_ELEMENT_PADS gstpad.c:1583:gst_pad_unlink: unlinked decoder:src and alsa_play_audio:sink
0:00:03.537908198 10271 0x8135370 INFO      GST_ELEMENT_PADS gstutils.c:1358:gst_element_link_pads: trying to link element decoder:(any) to element pulse_play_audio:(any)
0:00:03.537928810 10271 0x8135370 INFO              GST_PADS gstutils.c:2039:gst_pad_can_link: trying to link decoder:src and pulse_play_audio:sink
0:00:03.537945182 10271 0x8135370 INFO              GST_PADS gstutils.c:1263:prepare_link_maybe_ghosting: decoder and pulse_play_audio in same bin, no need for ghost pads
0:00:03.537959294 10271 0x8135370 INFO              GST_PADS gstpad.c:1769:gst_pad_link_prepare: trying to link decoder:src and pulse_play_audio:sink
0:00:03.538086357 10271 0x8135370 INFO              GST_PADS gstpad.c:1906:gst_pad_link: linked decoder:src and pulse_play_audio:sink, successful
0:00:03.539418213 10271 0x8135370 INFO            GST_STATES gstelement.c:2121:gst_element_continue_state:<pulse_play_audio> committing state from NULL to READY, pending PLAYING
0:00:03.539462700 10271 0x8135370 INFO            GST_STATES gstelement.c:2130:gst_element_continue_state:<pulse_play_audio> continue state change READY to PAUSED, final PLAYING
0:00:03.539528112 10271 0x8135370 INFO         GST_PARENTAGE gstbin.c:1224:gst_bin_remove_func:<pipeline> removed child "alsa_play_audio"
** (mp3:10271): DEBUG: Pad src unblocked successfully
pulsesink->stream_name:(null)
0:00:03.545628194 10271 0x8135370 WARN              basesink gstbasesink.c:2519:gst_base_sink_chain_unlocked:<pulse_play_audio> warning: Internal data flow problem.
0:00:03.545651813 10271 0x8135370 WARN              basesink gstbasesink.c:2519:gst_base_sink_chain_unlocked:<pulse_play_audio> warning: Received buffer without a new-segment. Assuming timestamps start from 0.
0:00:03.545681282 10271 0x8135370 INFO      GST_ERROR_SYSTEM gstelement.c:1674:gst_element_message_full:<pulse_play_audio> posting message: Internal data flow problem.
0:00:03.545729897 10271 0x8135370 INFO      GST_ERROR_SYSTEM gstelement.c:1697:gst_element_message_full:<pulse_play_audio> posted warning message: Internal data flow problem.
0:00:03.545793752 10271 0x8135370 INFO            GST_STATES gstbin.c:2570:bin_handle_async_done:<pipeline> committing state from PAUSED to PAUSED, old pending PLAYING
0:00:03.545841513 10271 0x8135370 INFO            GST_STATES gstbin.c:2594:bin_handle_async_done:<pipeline> continue state change, pending PLAYING
0:00:03.545945577 10271 0x81970e0 INFO            GST_STATES gstbin.c:2392:gst_bin_continue_func:<pipeline> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.546027018 10271 0x81970e0 INFO             GST_EVENT gstevent.c:942:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:03.546053615 10271 0x81970e0 INFO                   bin gstbin.c:2069:do_bin_latency:<pipeline> configured latency of 0:00:00.000000000
0:00:03.546082140 10271 0x81970e0 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<pulse_play_audio> completed state change to PLAYING
0:00:03.546096268 10271 0x81970e0 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline> child 'pulse_play_audio' changed state to 4(PLAYING) successfully
0:00:03.546113530 10271 0x81970e0 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<decoder> completed state change to PLAYING
0:00:03.546126248 10271 0x81970e0 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline> child 'decoder' changed state to 4(PLAYING) successfully
0:00:03.546141063 10271 0x81970e0 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<disk_source> completed state change to PLAYING
0:00:03.546153190 10271 0x81970e0 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline> child 'disk_source' changed state to 4(PLAYING) successfully
0:00:03.546168002 10271 0x81970e0 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<pipeline> completed state change to PLAYING
0:00:03.546179832 10271 0x81970e0 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<pipeline> posting state-changed PAUSED to PLAYING
    q:Quit, p:Pause/Play, t:Test: Time: 0:00:03.761632653 / 0:04:00.428875000