0:30:37.495372000 32612 0xecf960 WARN         baseaudiosink gstbaseaudiosink.c:696:gst_base_audio_sink_render:<autoaudiosink2-actual-sink-alsadmix> warning: Compensating for audio synchronisation problems
0:30:37.495430000 32612 0xecf960 WARN         baseaudiosink gstbaseaudiosink.c:696:gst_base_audio_sink_render:<autoaudiosink2-actual-sink-alsadmix> warning: Unexpected discontinuity in audio timestamps of more than half a second (0:00:00.500000000), resyncing
0:30:37.495544000 32612 0xecf960 INFO      GST_ERROR_SYSTEM gstelement.c:1587:gst_element_message_full:<autoaudiosink2-actual-sink-alsadmix> posting message: Compensating for audio synchronisation problems
0:30:37.495633000 32612 0xecf960 INFO      GST_ERROR_SYSTEM gstelement.c:1602:gst_element_message_full:<autoaudiosink2-actual-sink-alsadmix> posted warning message: Compensating for audio synchronisation problems
0:40:59.446093000 32612 0xecf960 WARN         baseaudiosink gstbaseaudiosink.c:696:gst_base_audio_sink_render:<autoaudiosink2-actual-sink-alsadmix> warning: Compensating for audio synchronisation problems
0:40:59.446143000 32612 0xecf960 WARN         baseaudiosink gstbaseaudiosink.c:696:gst_base_audio_sink_render:<autoaudiosink2-actual-sink-alsadmix> warning: Unexpected discontinuity in audio timestamps of more than half a second (0:00:00.500000000), resyncing
0:40:59.446182000 32612 0xecf960 INFO      GST_ERROR_SYSTEM gstelement.c:1587:gst_element_message_full:<autoaudiosink2-actual-sink-alsadmix> posting message: Compensating for audio synchronisation problems
0:40:59.446229000 32612 0xecf960 INFO      GST_ERROR_SYSTEM gstelement.c:1602:gst_element_message_full:<autoaudiosink2-actual-sink-alsadmix> posted warning message: Compensating for audio synchronisation problems
0:44:05.181228000 32612 0xecf960 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad preroll_audio_src0:sink
0:44:05.181334000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1289:gst_bin_get_state_func:<playbin> getting state
0:44:05.181354000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1434:gst_bin_recalc_state:<playbin> not dirty
0:44:05.191369000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2299:gst_element_change_state:<autoaudiosink2-actual-sink-alsadmix> forcing commit state PLAYING < PAUSED
0:44:05.191389000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<autoaudiosink2-actual-sink-alsadmix> completed state change
0:44:05.191422000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<autoaudiosink2> child 'autoaudiosink2-actual-sink-alsadmix' changed state to 3(PAUSED) successfully
0:44:05.191442000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<autoaudiosink2> completed state change
0:44:05.191467000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<bin3> child 'autoaudiosink2' changed state to 3(PAUSED) successfully
0:44:05.191485000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<bin3> completed state change
0:44:05.191509000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<audiosink> child 'bin3' changed state to 3(PAUSED) successfully
0:44:05.191527000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<audiosink> completed state change
0:44:05.191549000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<abin> child 'audiosink' changed state to 3(PAUSED) successfully
0:44:05.191569000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<volume> completed state change
0:44:05.191593000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<abin> child 'volume' changed state to 3(PAUSED) successfully
0:44:05.191614000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<aresample> completed state change
0:44:05.191637000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<abin> child 'aresample' changed state to 3(PAUSED) successfully
0:44:05.191657000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<aconv> completed state change
0:44:05.191680000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<abin> child 'aconv' changed state to 3(PAUSED) successfully
0:44:05.191730000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<abin> completed state change
0:44:05.191755000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'abin' changed state to 3(PAUSED) successfully
0:44:05.191775000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<preroll_audio_src0> completed state change
0:44:05.191799000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'preroll_audio_src0' changed state to 3(PAUSED) successfully
0:44:05.191819000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<selector_audio_src0> completed state change
0:44:05.191842000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'selector_audio_src0' changed state to 3(PAUSED) successfully
0:44:05.191878000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<vorbisdec3> completed state change
0:44:05.191903000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<decodebin2> child 'vorbisdec3' changed state to 3(PAUSED) successfully
0:44:05.191923000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<queue3> completed state change
0:44:05.191946000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<decodebin2> child 'queue3' changed state to 3(PAUSED) successfully
0:44:05.191967000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<oggdemux1> completed state change
0:44:05.191990000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<decodebin2> child 'oggdemux1' changed state to 3(PAUSED) successfully
0:44:05.192010000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<typefind> completed state change
0:44:05.192034000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<decodebin2> child 'typefind' changed state to 3(PAUSED) successfully
0:44:05.192053000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<decodebin2> completed state change
0:44:05.192074000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'decodebin2' changed state to 3(PAUSED) successfully
0:44:05.192093000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<source> completed state change
0:44:05.192116000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'source' changed state to 3(PAUSED) successfully
0:44:05.192143000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<playbin> completed state change
0:44:06.429465000 32612 0xecf960 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad preroll_audio_src0:sink
0:44:06.430030000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<autoaudiosink2-actual-sink-alsadmix> completed state change
0:44:06.430074000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<autoaudiosink2> child 'autoaudiosink2-actual-sink-alsadmix' changed state to 4(PLAYING) successfully
0:44:06.430097000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<autoaudiosink2> completed state change
0:44:06.430122000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<bin3> child 'autoaudiosink2' changed state to 4(PLAYING) successfully
0:44:06.430141000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<bin3> completed state change
0:44:06.430164000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<audiosink> child 'bin3' changed state to 4(PLAYING) successfully
0:44:06.430207000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<audiosink> completed state change
0:44:06.430233000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<abin> child 'audiosink' changed state to 4(PLAYING) successfully
0:44:06.430254000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<volume> completed state change
0:44:06.430278000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<abin> child 'volume' changed state to 4(PLAYING) successfully
0:44:06.430299000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<aresample> completed state change
0:44:06.430323000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<abin> child 'aresample' changed state to 4(PLAYING) successfully
0:44:06.430342000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<aconv> completed state change
0:44:06.430366000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<abin> child 'aconv' changed state to 4(PLAYING) successfully
0:44:06.430385000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<abin> completed state change
0:44:06.430407000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'abin' changed state to 4(PLAYING) successfully
0:44:06.430428000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<preroll_audio_src0> completed state change
0:44:06.430452000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'preroll_audio_src0' changed state to 4(PLAYING) successfully
0:44:06.430473000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<selector_audio_src0> completed state change
0:44:06.430496000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'selector_audio_src0' changed state to 4(PLAYING) successfully
0:44:06.430533000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<vorbisdec3> completed state change
0:44:06.430558000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<decodebin2> child 'vorbisdec3' changed state to 4(PLAYING) successfully
0:44:06.430578000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<queue3> completed state change
0:44:06.430602000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<decodebin2> child 'queue3' changed state to 4(PLAYING) successfully
0:44:06.430622000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<oggdemux1> completed state change
0:44:06.430646000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<decodebin2> child 'oggdemux1' changed state to 4(PLAYING) successfully
0:44:06.430667000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<typefind> completed state change
0:44:06.430692000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<decodebin2> child 'typefind' changed state to 4(PLAYING) successfully
0:44:06.430711000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<decodebin2> completed state change
0:44:06.430735000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'decodebin2' changed state to 4(PLAYING) successfully
0:44:06.430755000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<source> completed state change
0:44:06.430779000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'source' changed state to 4(PLAYING) successfully
0:44:06.430797000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<playbin> completed state change
0:44:12.652355000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:742:gst_element_remove_pad:<oggdemux1> removing pad 'serial_204bd1bc'
0:44:12.652407000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstpad.c:1512:gst_pad_unlink: unlinking oggdemux1:serial_204bd1bc(0xed4060) and queue3:sink(0xe3a000)
0:44:12.652448000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:742:gst_element_remove_pad:<decodebin2> removing pad 'src0'
0:44:12.652470000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstpad.c:1512:gst_pad_unlink: unlinking decodebin2:src0(0xb17de0) and selector_audio_src0:sink0(0xe13450)
0:44:12.652517000 32612 0x71a090 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:375:gst_element_factory_create: creating element "fakesrc"
0:44:12.652600000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstBaseSrc@0xd8d980> adding pad 'src'
0:44:12.652656000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad fakesrc2:src
0:44:12.652684000 32612 0x71a090 INFO              GST_PADS gstpad.c:1722:gst_pad_link_prepare: trying to link fakesrc2:src and selector_audio_src0:sink0
0:44:12.654890000 32612 0x71a090 INFO              GST_PADS gstpad.c:1859:gst_pad_link: linked fakesrc2:src and selector_audio_src0:sink0, successful
0:44:12.654919000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstpad.c:1547:gst_pad_unlink: unlinked decodebin2:src0 and selector_audio_src0:sink0
0:44:12.654943000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstpad.c:1512:gst_pad_unlink: unlinking vorbisdec3:src(0xd8acf0) and src0:proxypad11(0xb92640)
0:44:12.654960000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstpad.c:1547:gst_pad_unlink: unlinked vorbisdec3:src and src0:proxypad11
0:44:12.654983000 32612 0x71a090 INFO            GST_STATES gstelement.c:2009:gst_element_continue_state:<vorbisdec3> committing state from PLAYING to PAUSED, pending NULL
0:44:12.655021000 32612 0x71a090 INFO            GST_STATES gstelement.c:2018:gst_element_continue_state:<vorbisdec3> continue state change PAUSED to READY, final NULL
0:44:12.672409000 32612 0x71a090 INFO            GST_STATES gstelement.c:2009:gst_element_continue_state:<vorbisdec3> committing state from PAUSED to READY, pending NULL
0:44:12.672481000 32612 0x71a090 INFO            GST_STATES gstelement.c:2018:gst_element_continue_state:<vorbisdec3> continue state change READY to NULL, final NULL
0:44:12.672506000 32612 0x71a090 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<vorbisdec3> completed state change
0:44:12.672537000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstpad.c:1512:gst_pad_unlink: unlinking queue3:src(0xd8a8a0) and vorbisdec3:sink(0xd8ab80)
0:44:12.672554000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstpad.c:1547:gst_pad_unlink: unlinked queue3:src and vorbisdec3:sink
0:44:12.672573000 32612 0x71a090 INFO         GST_PARENTAGE gstbin.c:968:gst_bin_remove_func:<decodebin2> removed child "vorbisdec3"
0:44:12.672606000 32612 0x71a090 INFO            GST_STATES gstelement.c:2009:gst_element_continue_state:<queue3> committing state from PLAYING to PAUSED, pending NULL
0:44:12.672632000 32612 0x71a090 INFO            GST_STATES gstelement.c:2018:gst_element_continue_state:<queue3> continue state change PAUSED to READY, final NULL
0:44:12.672723000 32612 0x6d3040 INFO       GST_REFCOUNTING gstelement.c:2562:gst_element_dispose:<vorbisdec3> dispose
0:44:12.672745000 32612 0x6d3040 INFO      GST_ELEMENT_PADS gstelement.c:742:gst_element_remove_pad:<vorbisdec3> removing pad 'src'
0:44:12.672764000 32612 0x6d3040 INFO      GST_ELEMENT_PADS gstelement.c:742:gst_element_remove_pad:<vorbisdec3> removing pad 'sink'
0:44:12.672792000 32612 0x6d3040 INFO       GST_REFCOUNTING gstelement.c:2587:gst_element_dispose:<vorbisdec3> parent class dispose
0:44:12.672809000 32612 0x6d3040 INFO       GST_REFCOUNTING gstelement.c:2610:gst_element_finalize:<vorbisdec3> finalize
0:44:12.672825000 32612 0x6d3040 INFO       GST_REFCOUNTING gstelement.c:2621:gst_element_finalize:<vorbisdec3> finalize parent
0:44:12.673038000 32612 0x71a090 INFO            GST_STATES gstelement.c:2009:gst_element_continue_state:<queue3> committing state from PAUSED to READY, pending NULL
0:44:12.673095000 32612 0x71a090 INFO            GST_STATES gstelement.c:2018:gst_element_continue_state:<queue3> continue state change READY to NULL, final NULL
0:44:12.673117000 32612 0x71a090 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<queue3> completed state change
0:44:12.673147000 32612 0x71a090 INFO         GST_PARENTAGE gstbin.c:968:gst_bin_remove_func:<decodebin2> removed child "queue3"
0:44:12.673179000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:867:gst_element_get_static_pad: no such pad 'src_%d' in element "oggdemux1"
0:44:12.673215000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstpad.c:1547:gst_pad_unlink: unlinked oggdemux1:serial_204bd1bc and queue3:sink
0:44:12.673334000 32612 0x71a090 INFO            GST_STATES gstelement.c:2009:gst_element_continue_state:<vorbisdec2> committing state from PAUSED to READY, pending NULL
0:44:12.673361000 32612 0x71a090 INFO            GST_STATES gstelement.c:2018:gst_element_continue_state:<vorbisdec2> continue state change READY to NULL, final NULL
0:44:12.673382000 32612 0x71a090 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<vorbisdec2> completed state change
0:44:12.673407000 32612 0x71a090 INFO       GST_REFCOUNTING gstelement.c:2562:gst_element_dispose:<vorbisdec2> dispose
0:44:12.673423000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:742:gst_element_remove_pad:<vorbisdec2> removing pad 'src'
0:44:12.673438000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstpad.c:1512:gst_pad_unlink: unlinking vorbisdec2:src(0xee3170) and '':internal(0xee32e0)
0:44:12.673454000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstpad.c:1547:gst_pad_unlink: unlinked vorbisdec2:src and '':internal
0:44:12.673483000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:742:gst_element_remove_pad:<vorbisdec2> removing pad 'sink'
0:44:12.673509000 32612 0x71a090 INFO       GST_REFCOUNTING gstelement.c:2587:gst_element_dispose:<vorbisdec2> parent class dispose
0:44:12.673527000 32612 0x71a090 INFO       GST_REFCOUNTING gstelement.c:2610:gst_element_finalize:<vorbisdec2> finalize
0:44:12.673542000 32612 0x71a090 INFO       GST_REFCOUNTING gstelement.c:2621:gst_element_finalize:<vorbisdec2> finalize parent
0:44:12.673955000 32612 0x71a090 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:375:gst_element_factory_create: creating element "vorbisdec"
0:44:12.674037000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstVorbisDec@0xeca7b0> adding pad 'sink'
0:44:12.674100000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstVorbisDec@0xeca7b0> adding pad 'src'
0:44:12.674128000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad vorbisdec4:sink
0:44:12.674147000 32612 0x71a090 INFO            GST_STATES gstelement.c:2009:gst_element_continue_state:<vorbisdec4> committing state from NULL to READY, pending PAUSED
0:44:12.674174000 32612 0x71a090 INFO            GST_STATES gstelement.c:2018:gst_element_continue_state:<vorbisdec4> continue state change READY to PAUSED, final PAUSED
0:44:12.674201000 32612 0x71a090 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<vorbisdec4> completed state change
0:44:12.674259000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad vorbisdec4:src
0:44:12.674277000 32612 0x71a090 INFO              GST_PADS gstpad.c:1722:gst_pad_link_prepare: trying to link vorbisdec4:src and '':internal
0:44:12.674312000 32612 0x71a090 INFO              GST_PADS gstpad.c:1859:gst_pad_link: linked vorbisdec4:src and '':internal, successful
0:44:12.676882000 32612 0x71a090 INFO              GST_PADS gstpad.c:2873:gst_pad_event_default_dispatch:<'':internal> Sending event 0x777e80 (tag) to all internally linked pads
0:44:12.679843000 32612 0x71a090 INFO             GST_EVENT gstevent.c:528:gst_event_new_new_segment_full: creating newsegment update 0, rate 1.000000, format GST_FORMAT_TIME, start 5124095:24:48.755356604, stop 99:99:99.999999999, position 0:09:44.954195012
0:44:12.679904000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<oggdemux1> adding pad 'serial_7f62d048'
0:44:12.680039000 32612 0x71a090 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:375:gst_element_factory_create: creating element "queue"
0:44:12.680118000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstQueue@0xe91640> adding pad 'sink'
0:44:12.680176000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstQueue@0xe91640> adding pad 'src'
0:44:12.680259000 32612 0x71a090 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<queue5> completed state change
0:44:12.680295000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad queue5:sink
0:44:12.680312000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad queue5:src
0:44:12.680326000 32612 0x71a090 INFO              GST_PADS gstpad.c:1722:gst_pad_link_prepare: trying to link oggdemux1:serial_7f62d048 and queue5:sink
0:44:12.680348000 32612 0x71a090 INFO              GST_PADS gstpad.c:1859:gst_pad_link: linked oggdemux1:serial_7f62d048 and queue5:sink, successful
0:44:12.680365000 32612 0x71a090 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:375:gst_element_factory_create: creating element "vorbisdec"
0:44:12.680423000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstVorbisDec@0xecab10> adding pad 'sink'
0:44:12.680490000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstVorbisDec@0xecab10> adding pad 'src'
0:44:12.680519000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad vorbisdec5:sink
0:44:12.680553000 32612 0x71a090 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<vorbisdec5> completed state change
0:44:12.680580000 32612 0x71a090 INFO              GST_PADS gstpad.c:1722:gst_pad_link_prepare: trying to link queue5:src and vorbisdec5:sink
0:44:12.680604000 32612 0x71a090 INFO              GST_PADS gstpad.c:1859:gst_pad_link: linked queue5:src and vorbisdec5:sink, successful
0:44:12.680638000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad vorbisdec5:src
0:44:12.680752000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<decodebin2> adding pad 'src1'
0:44:12.680775000 32612 0x71a090 INFO              GST_PADS gstpad.c:1722:gst_pad_link_prepare: trying to link vorbisdec5:src and src1:proxypad14
0:44:12.680806000 32612 0x71a090 INFO              GST_PADS gstpad.c:1859:gst_pad_link: linked vorbisdec5:src and src1:proxypad14, successful
0:44:12.680892000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstStreamSelector@0xe2f380> adding pad 'src'
0:44:12.680938000 32612 0x71a090 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "queue" named "preroll_audio_src1"
0:44:12.680996000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstQueue@0xe91960> adding pad 'sink'
0:44:12.681057000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstQueue@0xe91960> adding pad 'src'
0:44:12.681119000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad preroll_audio_src1:sink
0:44:12.681165000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad preroll_audio_src1:src
0:44:12.681204000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstutils.c:1373:gst_element_link_pads: trying to link element selector_audio_src1:(any) to element preroll_audio_src1:(any)
0:44:12.681223000 32612 0x71a090 INFO              GST_PADS gstutils.c:2046:gst_pad_can_link: trying to link selector_audio_src1:src and preroll_audio_src1:src
0:44:12.681264000 32612 0x71a090 INFO              GST_PADS gstutils.c:2066:gst_pad_can_link: Sink pad preroll_audio_src1:src is not sink pad, failed
0:44:12.681279000 32612 0x71a090 INFO              GST_PADS gstutils.c:2046:gst_pad_can_link: trying to link selector_audio_src1:src and preroll_audio_src1:sink
0:44:12.681293000 32612 0x71a090 INFO              GST_PADS gstutils.c:1278:prepare_link_maybe_ghosting: selector_audio_src1 and preroll_audio_src1 in same bin, no need for ghost pads
0:44:12.681307000 32612 0x71a090 INFO              GST_PADS gstpad.c:1722:gst_pad_link_prepare: trying to link selector_audio_src1:src and preroll_audio_src1:sink
0:44:12.681325000 32612 0x71a090 INFO              GST_PADS gstpad.c:1859:gst_pad_link: linked selector_audio_src1:src and preroll_audio_src1:sink, successful
0:44:12.681345000 32612 0x71a090 INFO            GST_STATES gstelement.c:2009:gst_element_continue_state:<selector_audio_src1> committing state from NULL to READY, pending PLAYING
0:44:12.681374000 32612 0x71a090 INFO            GST_STATES gstelement.c:2018:gst_element_continue_state:<selector_audio_src1> continue state change READY to PAUSED, final PLAYING
0:44:12.681396000 32612 0x71a090 INFO            GST_STATES gstelement.c:2009:gst_element_continue_state:<selector_audio_src1> committing state from READY to PAUSED, pending PLAYING
0:44:12.681419000 32612 0x71a090 INFO            GST_STATES gstelement.c:2018:gst_element_continue_state:<selector_audio_src1> continue state change PAUSED to PLAYING, final PLAYING
0:44:12.681435000 32612 0x71a090 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<selector_audio_src1> completed state change
0:44:12.681458000 32612 0x71a090 INFO            GST_STATES gstelement.c:2009:gst_element_continue_state:<preroll_audio_src1> committing state from NULL to READY, pending PLAYING
0:44:12.681481000 32612 0x71a090 INFO            GST_STATES gstelement.c:2018:gst_element_continue_state:<preroll_audio_src1> continue state change READY to PAUSED, final PLAYING
0:44:12.681504000 32612 0x71a090 INFO            GST_STATES gstelement.c:2009:gst_element_continue_state:<preroll_audio_src1> committing state from READY to PAUSED, pending PLAYING
0:44:12.681527000 32612 0x71a090 INFO            GST_STATES gstelement.c:2018:gst_element_continue_state:<preroll_audio_src1> continue state change PAUSED to PLAYING, final PLAYING
0:44:12.681543000 32612 0x71a090 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<preroll_audio_src1> completed state change
0:44:12.681596000 32612 0x71a090 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<selector_audio_src1> adding pad 'sink0'
0:44:12.681622000 32612 0x71a090 INFO              GST_PADS gstpad.c:1722:gst_pad_link_prepare: trying to link decodebin2:src1 and selector_audio_src1:sink0
0:44:12.681658000 32612 0x71a090 INFO              GST_PADS gstpad.c:1859:gst_pad_link: linked decodebin2:src1 and selector_audio_src1:sink0, successful
0:44:12.681695000 32612 0x71a090 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<vorbisdec5> completed state change
0:44:12.681757000 32612 0x71a090 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<queue5> completed state change
0:44:12.682307000 32612 0x6d3040 INFO       GST_REFCOUNTING gstelement.c:2562:gst_element_dispose:<queue3> dispose
0:44:12.682354000 32612 0x6d3040 INFO      GST_ELEMENT_PADS gstelement.c:742:gst_element_remove_pad:<queue3> removing pad 'src'
0:44:12.682387000 32612 0x6d3040 INFO      GST_ELEMENT_PADS gstelement.c:742:gst_element_remove_pad:<queue3> removing pad 'sink'
0:44:12.682411000 32612 0x6d3040 INFO       GST_REFCOUNTING gstelement.c:2587:gst_element_dispose:<queue3> parent class dispose
0:44:12.682433000 32612 0x6d3040 INFO       GST_REFCOUNTING gstelement.c:2610:gst_element_finalize:<queue3> finalize
0:44:12.682448000 32612 0x6d3040 INFO       GST_REFCOUNTING gstelement.c:2621:gst_element_finalize:<queue3> finalize parent
0:44:12.685322000 32612 0xecf9f0 INFO              GST_PADS gstpad.c:2873:gst_pad_event_default_dispatch:<selector_audio_src1:sink0> Sending event 0x76c4c0 (newsegment) to all internally linked pads
0:44:12.685400000 32612 0xecf9f0 INFO              GST_PADS gstpad.c:2873:gst_pad_event_default_dispatch:<selector_audio_src1:sink0> Sending event 0x772ac0 (tag) to all internally linked pads
0:44:12.690246000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1289:gst_bin_get_state_func:<playbin> getting state
0:44:12.690284000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1328:gst_bin_recalc_state:<playbin> recalc state
0:44:12.690300000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1338:gst_bin_recalc_state:<playbin> checking element states
0:44:12.690319000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1289:gst_bin_get_state_func:<abin> getting state
0:44:12.690333000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1434:gst_bin_recalc_state:<abin> not dirty
0:44:12.690350000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1289:gst_bin_get_state_func:<decodebin2> getting state
0:44:12.690364000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1328:gst_bin_recalc_state:<decodebin2> recalc state
0:44:12.690377000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1338:gst_bin_recalc_state:<decodebin2> checking element states
0:44:12.690396000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2026:gst_element_continue_state:<decodebin2> nothing pending
0:44:12.690411000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1428:gst_bin_recalc_state:<decodebin2> bin RETURN is now SUCCESS
0:44:12.690427000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2026:gst_element_continue_state:<playbin> nothing pending
0:44:12.690440000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1428:gst_bin_recalc_state:<playbin> bin RETURN is now SUCCESS
0:44:12.693190000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<autoaudiosink2-actual-sink-alsadmix> completed state change
0:44:12.693250000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<autoaudiosink2> child 'autoaudiosink2-actual-sink-alsadmix' changed state to 3(PAUSED) successfully
0:44:12.693276000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<autoaudiosink2> completed state change
0:44:12.693302000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<bin3> child 'autoaudiosink2' changed state to 3(PAUSED) successfully
0:44:12.693321000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<bin3> completed state change
0:44:12.693346000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<audiosink> child 'bin3' changed state to 3(PAUSED) successfully
0:44:12.693365000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<audiosink> completed state change
0:44:12.693388000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<abin> child 'audiosink' changed state to 3(PAUSED) successfully
0:44:12.693413000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<volume> completed state change
0:44:12.693437000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<abin> child 'volume' changed state to 3(PAUSED) successfully
0:44:12.693459000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<aresample> completed state change
0:44:12.693483000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<abin> child 'aresample' changed state to 3(PAUSED) successfully
0:44:12.693503000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<aconv> completed state change
0:44:12.693527000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<abin> child 'aconv' changed state to 3(PAUSED) successfully
0:44:12.693571000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<abin> completed state change
0:44:12.693595000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'abin' changed state to 3(PAUSED) successfully
0:44:12.693617000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<preroll_audio_src0> completed state change
0:44:12.693640000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'preroll_audio_src0' changed state to 3(PAUSED) successfully
0:44:12.693661000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<selector_audio_src0> completed state change
0:44:12.693684000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'selector_audio_src0' changed state to 3(PAUSED) successfully
0:44:12.693704000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2009:gst_element_continue_state:<fakesrc2> committing state from NULL to READY, pending PAUSED
0:44:12.693727000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2018:gst_element_continue_state:<fakesrc2> continue state change READY to PAUSED, final PAUSED
0:44:12.693759000 32612 0x6d3040 INFO             GST_EVENT gstevent.c:533:gst_event_new_new_segment_full: creating newsegment update 0, rate 1.000000, format 2, start 0, stop -1, position 0
0:44:12.694006000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<fakesrc2> completed state change
0:44:12.694035000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'fakesrc2' changed state to 3(PAUSED) successfully
0:44:12.694059000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<preroll_audio_src1> completed state change
0:44:12.694083000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'preroll_audio_src1' changed state to 3(PAUSED) successfully
0:44:12.694103000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<selector_audio_src1> completed state change
0:44:12.694126000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'selector_audio_src1' changed state to 3(PAUSED) successfully
0:44:12.694163000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<vorbisdec5> completed state change
0:44:12.694179000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<decodebin2> child 'vorbisdec5' changed state to 3(PAUSED) successfully
0:44:12.694199000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<queue5> completed state change
0:44:12.694214000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<decodebin2> child 'queue5' changed state to 3(PAUSED) successfully
0:44:12.694233000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<oggdemux1> completed state change
0:44:12.694258000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<decodebin2> child 'oggdemux1' changed state to 3(PAUSED) successfully
0:44:12.694279000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<typefind> completed state change
0:44:12.694302000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<decodebin2> child 'typefind' changed state to 3(PAUSED) successfully
0:44:12.694322000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<decodebin2> completed state change
0:44:12.694345000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'decodebin2' changed state to 3(PAUSED) successfully
0:44:12.694364000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<source> completed state change
0:44:12.694407000 32612 0x6d3040 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<playbin> child 'source' changed state to 3(PAUSED) successfully
0:44:12.694441000 32612 0x6d3040 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<playbin> completed state change
0:44:12.713196000 32612 0xe37e50 INFO              GST_PADS gstpad.c:2873:gst_pad_event_default_dispatch:<selector_audio_src0:sink0> Sending event 0xdc9a40 (newsegment) to all internally linked pads
0:44:12.713275000 32612 0xe37e50 INFO               basesrc gstbasesrc.c:1524:gst_base_src_loop:<fakesrc2> pausing after gst_base_src_get_range() = -3
0:44:12.713328000 32612 0xe37e50 INFO              GST_PADS gstpad.c:2873:gst_pad_event_default_dispatch:<selector_audio_src0:sink0> Sending event 0xedb900 (eos) to all internally linked pads