2016-08-06 08:17:05,697 sound output safe_exec(['which', 'pactl'])=(0, '/usr/bin/pactl\n') 2016-08-06 08:17:05,697 sound output is_pa_installed() pactl_bin=/usr/bin/pactl 2016-08-06 08:17:05,697 sound output get_default_sink() no pulsesink: cannot import name get_pactl_server 2016-08-06 08:17:05,697 sound output SoundSink(..) found matching codecs ['wav'] 2016-08-06 08:17:05,698 sound output pipeline elements=['appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=0 format=4', 'wavparse', 'audioconvert', 'audioresample', 'volume name=volume volume=0', 'queue name=queue min-threshold-time=0 max-size-buffers=0 max-size-bytes=0 max-size-time=450000000 leaky=2', 'autoaudiosink async=True qos=True sync=False'] 2016-08-06 08:17:05,698 sound output pipeline=appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=0 format=4 ! wavparse ! audioconvert ! audioresample ! volume name=volume volume=0 ! queue name=queue min-threshold-time=0 max-size-buffers=0 max-size-bytes=0 max-size-time=450000000 leaky=2 ! autoaudiosink async=True qos=True sync=False 0:00:00.050868183 12228 0x8c55420 INFO GST_PIPELINE gstparse.c:334:gst_parse_launch_full: parsing pipeline description 'appsrc do-timestamp=1 name=src emit-signals=0 block=0 is-live=0 stream-type=0 format=4 ! wavparse ! audioconvert ! audioresample ! volume name=volume volume=0 ! queue name=queue min-threshold-time=0 max-size-buffers=0 max-size-bytes=0 max-size-time=450000000 leaky=2 ! autoaudiosink async=True qos=True sync=False' 0:00:00.051227352 12228 0x8c55420 INFO GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/i386-linux-gnu/gstreamer-0.10/libgstapp.so" loaded 0:00:00.051238896 12228 0x8c55420 INFO GST_ELEMENT_FACTORY gstelementfactory.c:376:gst_element_factory_create: creating element "appsrc" 0:00:00.051410992 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad: adding pad 'src' 0:00:00.052043995 12228 0x8c55420 INFO GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/i386-linux-gnu/gstreamer-0.10/libgstwavparse.so" loaded 0:00:00.052052290 12228 0x8c55420 INFO GST_ELEMENT_FACTORY gstelementfactory.c:376:gst_element_factory_create: creating element "wavparse" 0:00:00.052204528 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad: adding pad 'sink' 0:00:00.065793697 12228 0x8c55420 INFO GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/i386-linux-gnu/gstreamer-0.10/libgstaudioconvert.so" loaded 0:00:00.065864540 12228 0x8c55420 INFO GST_ELEMENT_FACTORY gstelementfactory.c:376:gst_element_factory_create: creating element "audioconvert" 0:00:00.066020217 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad: adding pad 'sink' 0:00:00.066065933 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad: adding pad 'src' 0:00:00.066892582 12228 0x8c55420 INFO audioresample gstaudioresample.c:1557:_benchmark_integer_resampling: Using integer resampler if appropriate: 63565.444444 < 111842.750000 0:00:00.066941992 12228 0x8c55420 INFO GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/i386-linux-gnu/gstreamer-0.10/libgstaudioresample.so" loaded 0:00:00.066974036 12228 0x8c55420 INFO GST_ELEMENT_FACTORY gstelementfactory.c:376:gst_element_factory_create: creating element "audioresample" 0:00:00.067075808 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad: adding pad 'sink' 0:00:00.067118625 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad: adding pad 'src' 0:00:00.069416854 12228 0x8c55420 INFO GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/i386-linux-gnu/gstreamer-0.10/libgstvolume.so" loaded 0:00:00.069462413 12228 0x8c55420 INFO GST_ELEMENT_FACTORY gstelementfactory.c:376:gst_element_factory_create: creating element "volume" 0:00:00.069604765 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad: adding pad 'sink' 0:00:00.069651406 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad: adding pad 'src' 0:00:00.069995207 12228 0x8c55420 INFO GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/i386-linux-gnu/gstreamer-0.10/libgstcoreelements.so" loaded 0:00:00.070187098 12228 0x8c55420 INFO GST_ELEMENT_FACTORY gstelementfactory.c:376:gst_element_factory_create: creating element "queue" 0:00:00.070300888 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad: adding pad 'sink' 0:00:00.070348398 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad: adding pad 'src' 0:00:00.096678281 12228 0x8c55420 INFO GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/i386-linux-gnu/gstreamer-0.10/libgstautodetect.so" loaded 0:00:00.096734641 12228 0x8c55420 INFO GST_ELEMENT_FACTORY gstelementfactory.c:376:gst_element_factory_create: creating element "autoaudiosink" 0:00:00.096852487 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad: adding pad 'sink' 0:00:00.096891468 12228 0x8c55420 INFO GST_ELEMENT_FACTORY gstelementfactory.c:374:gst_element_factory_create: creating element "fakesink" named "tempsink" 0:00:00.115465945 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad: adding pad 'sink' 0:00:00.115508862 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad tempsink:sink 0:00:00.115518549 12228 0x8c55420 INFO GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link sink:proxypad0 and tempsink:sink 0:00:00.115527019 12228 0x8c55420 INFO GST_PADS gstpad.c:2161:gst_pad_link_full: linked sink:proxypad0 and tempsink:sink, successful 0:00:00.115543860 12228 0x8c55420 INFO default gstchildproxy.c:209:gst_child_proxy_lookup: no param spec named async 0:00:00.115555540 12228 0x8c55420 INFO GST_PARENTAGE gstbin.c:3762:gst_bin_get_by_name: [autoaudiosink0]: looking up child element async 0:00:00.115565965 12228 0x8c55420 INFO default gstchildproxy.c:209:gst_child_proxy_lookup: no param spec named qos 0:00:00.115573037 12228 0x8c55420 INFO GST_PARENTAGE gstbin.c:3762:gst_bin_get_by_name: [autoaudiosink0]: looking up child element qos 0:00:00.115580876 12228 0x8c55420 INFO default gstchildproxy.c:209:gst_child_proxy_lookup: no param spec named sync 0:00:00.115587188 12228 0x8c55420 INFO GST_PARENTAGE gstbin.c:3762:gst_bin_get_by_name: [autoaudiosink0]: looking up child element sync 0:00:00.115604242 12228 0x8c55420 INFO GST_ELEMENT_FACTORY gstelementfactory.c:376:gst_element_factory_create: creating element "pipeline" 0:00:00.115658820 12228 0x8c55420 INFO GST_PIPELINE ./grammar.y:568:gst_parse_perform_link: linking src:(any) to wavparse0:(any) (0/0) with caps "(NULL)" 0:00:00.115671266 12228 0x8c55420 INFO GST_ELEMENT_PADS gstutils.c:1698:gst_element_link_pads_full: trying to link element src:(any) to element wavparse0:(any) 0:00:00.115678868 12228 0x8c55420 INFO GST_PADS gstutils.c:1032:gst_pad_check_link: trying to link src:src and wavparse0:sink 0:00:00.115691477 12228 0x8c55420 INFO GST_PADS gstutils.c:1596:prepare_link_maybe_ghosting: src and wavparse0 in same bin, no need for ghost pads 0:00:00.115702801 12228 0x8c55420 INFO GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link src:src and wavparse0:sink 0:00:00.115709972 12228 0x8c55420 INFO GST_PADS gstpad.c:2161:gst_pad_link_full: linked src:src and wavparse0:sink, successful 0:00:00.115721448 12228 0x8c55420 INFO GST_PIPELINE ./grammar.y:568:gst_parse_perform_link: linking wavparse0:(any) to audioconvert0:(any) (0/0) with caps "(NULL)" 0:00:00.115728744 12228 0x8c55420 INFO GST_ELEMENT_PADS gstutils.c:1698:gst_element_link_pads_full: trying to link element wavparse0:(any) to element audioconvert0:(any) 0:00:00.116080528 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:972:gst_element_get_static_pad: no such pad 'wavparse_src' in element "wavparse0" 0:00:00.116096899 12228 0x8c55420 INFO GST_ELEMENT_PADS gstutils.c:1216:gst_element_get_compatible_pad: Could not find a compatible pad to link to audioconvert0:sink 0:00:00.116103948 12228 0x8c55420 INFO default gstutils.c:2037:gst_element_link_pads_filtered: Could not link pads: wavparse0:(null) - audioconvert0:(null) 0:00:00.116111805 12228 0x8c55420 INFO GST_PIPELINE ./grammar.y:568:gst_parse_perform_link: linking audioconvert0:(any) to audioresample0:(any) (0/0) with caps "(NULL)" 0:00:00.116118767 12228 0x8c55420 INFO GST_ELEMENT_PADS gstutils.c:1698:gst_element_link_pads_full: trying to link element audioconvert0:(any) to element audioresample0:(any) 0:00:00.116125690 12228 0x8c55420 INFO GST_PADS gstutils.c:1032:gst_pad_check_link: trying to link audioconvert0:src and audioresample0:sink 0:00:00.120497492 12228 0x8c55420 INFO GST_PADS gstutils.c:1596:prepare_link_maybe_ghosting: audioconvert0 and audioresample0 in same bin, no need for ghost pads 0:00:00.120526658 12228 0x8c55420 INFO GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link audioconvert0:src and audioresample0:sink 0:00:00.120868284 12228 0x8c55420 INFO GST_PADS gstpad.c:2161:gst_pad_link_full: linked audioconvert0:src and audioresample0:sink, successful 0:00:00.120882295 12228 0x8c55420 INFO GST_PIPELINE ./grammar.y:568:gst_parse_perform_link: linking audioresample0:(any) to volume:(any) (0/0) with caps "(NULL)" 0:00:00.120891686 12228 0x8c55420 INFO GST_ELEMENT_PADS gstutils.c:1698:gst_element_link_pads_full: trying to link element audioresample0:(any) to element volume:(any) 0:00:00.120899772 12228 0x8c55420 INFO GST_PADS gstutils.c:1032:gst_pad_check_link: trying to link audioresample0:src and volume:sink 0:00:00.121345510 12228 0x8c55420 INFO GST_PADS gstutils.c:1596:prepare_link_maybe_ghosting: audioresample0 and volume in same bin, no need for ghost pads 0:00:00.121355974 12228 0x8c55420 INFO GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link audioresample0:src and volume:sink 0:00:00.121695124 12228 0x8c55420 INFO GST_PADS gstpad.c:2161:gst_pad_link_full: linked audioresample0:src and volume:sink, successful 0:00:00.121708059 12228 0x8c55420 INFO GST_PIPELINE ./grammar.y:568:gst_parse_perform_link: linking volume:(any) to queue:(any) (0/0) with caps "(NULL)" 0:00:00.121715861 12228 0x8c55420 INFO GST_ELEMENT_PADS gstutils.c:1698:gst_element_link_pads_full: trying to link element volume:(any) to element queue:(any) 0:00:00.121723016 12228 0x8c55420 INFO GST_PADS gstutils.c:1032:gst_pad_check_link: trying to link volume:src and queue:sink 0:00:00.122114592 12228 0x8c55420 INFO GST_PADS gstutils.c:1596:prepare_link_maybe_ghosting: volume and queue in same bin, no need for ghost pads 0:00:00.122126187 12228 0x8c55420 INFO GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link volume:src and queue:sink 0:00:00.122316087 12228 0x8c55420 INFO GST_PADS gstpad.c:2161:gst_pad_link_full: linked volume:src and queue:sink, successful 0:00:00.122596548 12228 0x8c55420 INFO GST_PIPELINE ./grammar.y:568:gst_parse_perform_link: linking queue:(any) to autoaudiosink0:(any) (0/0) with caps "(NULL)" 0:00:00.122699288 12228 0x8c55420 INFO GST_ELEMENT_PADS gstutils.c:1698:gst_element_link_pads_full: trying to link element queue:(any) to element autoaudiosink0:(any) 0:00:00.122741818 12228 0x8c55420 INFO GST_PADS gstutils.c:1032:gst_pad_check_link: trying to link queue:src and autoaudiosink0:sink 0:00:00.123065082 12228 0x8c55420 INFO GST_PADS gstutils.c:1596:prepare_link_maybe_ghosting: queue and autoaudiosink0 in same bin, no need for ghost pads 0:00:00.123076241 12228 0x8c55420 INFO GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link queue:src and autoaudiosink0:sink 0:00:00.123377506 12228 0x8c55420 INFO GST_PADS gstpad.c:2161:gst_pad_link_full: linked queue:src and autoaudiosink0:sink, successful 0:00:00.123468564 12228 0x8c55420 INFO GST_PARENTAGE gstbin.c:3762:gst_bin_get_by_name: [pipeline0]: looking up child element volume 0:00:00.123621278 12228 0x8c55420 INFO GST_PARENTAGE gstbin.c:3762:gst_bin_get_by_name: [pipeline0]: looking up child element src 0:00:00.125561173 12228 0x8c55420 INFO GST_PARENTAGE gstbin.c:3762:gst_bin_get_by_name: [pipeline0]: looking up child element queue 2016-08-06 08:17:05,773 sound output SoundPipeline.start() codec=wav 2016-08-06 08:17:05,773 sound output update_state(active) 0:00:00.126450864 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to NULL 0:00:00.126499249 12228 0x8c55420 INFO GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking sink:proxypad0(0x92fb800) and tempsink:sink(0x92f9008) 0:00:00.126527627 12228 0x8c55420 INFO GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked sink:proxypad0 and tempsink:sink 0:00:00.126548210 12228 0x8c55420 INFO GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func: removed child "tempsink" 0:00:00.126571436 12228 0x8c55420 INFO GST_REFCOUNTING gstelement.c:3064:gst_element_dispose: dispose 0:00:00.126586216 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad: removing pad 'sink' 0:00:00.126607994 12228 0x8c55420 INFO GST_REFCOUNTING gstelement.c:3088:gst_element_dispose: parent class dispose 0:00:00.126625063 12228 0x8c55420 INFO GST_REFCOUNTING gstelement.c:3119:gst_element_finalize: finalize 0:00:00.126633335 12228 0x8c55420 INFO GST_REFCOUNTING gstelement.c:3130:gst_element_finalize: finalize parent 0:00:00.229353789 12228 0x8c55420 INFO GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/i386-linux-gnu/gstreamer-0.10/libgstpulse.so" loaded 0:00:00.229378418 12228 0x8c55420 INFO GST_ELEMENT_FACTORY gstelementfactory.c:374:gst_element_factory_create: creating element "pulsesink" named "autoaudiosink0-actual-sink-pulse" 0:00:00.229647345 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad: adding pad 'sink' 0:00:00.229758447 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad autoaudiosink0-actual-sink-pulse:sink 0:00:00.229803425 12228 0x8c55420 INFO pulse pulsesink.c:2955:gst_pulsesink_change_state: new pa main loop thread 0:00:00.229937997 12228 0x8c55420 INFO pulse pulsesink.c:493:gst_pulseringbuffer_open_device: new context with name xpra, pbuf=0x9314068, pctx=0x930fea8 0:00:00.231651613 12228 0x8c55420 WARN pulse pulsesink.c:570:gst_pulseringbuffer_open_device: error: Failed to connect: Connection refused 0:00:00.231733746 12228 0x8c55420 INFO GST_ERROR_SYSTEM gstelement.c:1964:gst_element_message_full: posting message: Failed to connect: Connection refused 0:00:00.231758605 12228 0x8c55420 INFO GST_ERROR_SYSTEM gstelement.c:1987:gst_element_message_full: posted error message: Failed to connect: Connection refused 0:00:00.231887310 12228 0x8c55420 INFO pulse pulsesink.c:2937:gst_pulsesink_release_mainloop: terminating pa main loop thread 0:00:00.232028027 12228 0x8c55420 INFO GST_STATES gstelement.c:2768:gst_element_change_state: have FAILURE change_state return 0:00:00.232116651 12228 0x8c55420 INFO GST_STATES gstelement.c:2335:gst_element_abort_state: aborting state from NULL to READY 0:00:00.232191788 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to NULL 0:00:00.265614803 12228 0x8c55420 INFO GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/i386-linux-gnu/gstreamer-0.10/libgstjack.so" loaded 0:00:00.265689419 12228 0x8c55420 INFO GST_ELEMENT_FACTORY gstelementfactory.c:374:gst_element_factory_create: creating element "jackaudiosink" named "autoaudiosink0-actual-sink-jackaudio" 0:00:00.265993168 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad: adding pad 'sink' 0:00:00.266120504 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad autoaudiosink0-actual-sink-jackaudio:sink Cannot connect to server socket err = No such file or directory Cannot connect to server request channel jack server is not running or cannot be started 0:00:00.278501667 12228 0x8c55420 WARN jackclient gstjackaudioclient.c:292:gst_jack_audio_get_connection: could not create connection 0:00:00.278835028 12228 0x8c55420 WARN jacksink gstjackaudiosink.c:358:gst_jack_ring_buffer_open_device: error: Jack server not found 0:00:00.278866521 12228 0x8c55420 WARN jacksink gstjackaudiosink.c:358:gst_jack_ring_buffer_open_device: error: Cannot connect to the Jack server (status 17) 0:00:00.278908949 12228 0x8c55420 INFO GST_ERROR_SYSTEM gstelement.c:1964:gst_element_message_full: posting message: Jack server not found 0:00:00.278966809 12228 0x8c55420 INFO GST_ERROR_SYSTEM gstelement.c:1987:gst_element_message_full: posted error message: Jack server not found 0:00:00.278996103 12228 0x8c55420 INFO GST_STATES gstelement.c:2768:gst_element_change_state: have FAILURE change_state return 0:00:00.279018229 12228 0x8c55420 INFO GST_STATES gstelement.c:2335:gst_element_abort_state: aborting state from NULL to READY 0:00:00.279050130 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to NULL 0:00:00.282231764 12228 0x8c55420 INFO GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/i386-linux-gnu/gstreamer-0.10/libgstalsa.so" loaded 0:00:00.282355690 12228 0x8c55420 INFO GST_ELEMENT_FACTORY gstelementfactory.c:374:gst_element_factory_create: creating element "alsasink" named "autoaudiosink0-actual-sink-alsa" 0:00:00.282826532 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad: adding pad 'sink' 0:00:00.283004682 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad autoaudiosink0-actual-sink-alsa:sink 0:00:00.298384097 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to READY 0:00:00.304229492 12228 0x8c55420 INFO GST_STATES gstelement.c:2365:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.304308525 12228 0x8c55420 INFO GST_REFCOUNTING gstelement.c:3064:gst_element_dispose: dispose 0:00:00.304324905 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad: removing pad 'sink' 0:00:00.304340808 12228 0x8c55420 INFO GST_REFCOUNTING gstelement.c:3088:gst_element_dispose: parent class dispose 0:00:00.304355642 12228 0x8c55420 INFO GST_REFCOUNTING gstelement.c:3119:gst_element_finalize: finalize 0:00:00.304366458 12228 0x8c55420 INFO GST_REFCOUNTING gstelement.c:3130:gst_element_finalize: finalize parent 0:00:00.304391799 12228 0x8c55420 INFO GST_REFCOUNTING gstelement.c:3064:gst_element_dispose: dispose 0:00:00.304403046 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad: removing pad 'sink' 0:00:00.304415551 12228 0x8c55420 INFO GST_REFCOUNTING gstelement.c:3088:gst_element_dispose: parent class dispose 0:00:00.304426521 12228 0x8c55420 INFO GST_REFCOUNTING gstelement.c:3119:gst_element_finalize: finalize 0:00:00.304436660 12228 0x8c55420 INFO GST_REFCOUNTING gstelement.c:3130:gst_element_finalize: finalize parent 0:00:00.304474915 12228 0x8c55420 INFO default gstchildproxy.c:209:gst_child_proxy_lookup: no param spec named async 0:00:00.304489540 12228 0x8c55420 INFO default gstchildproxy.c:209:gst_child_proxy_lookup: no param spec named qos 0:00:00.304499811 12228 0x8c55420 INFO default gstchildproxy.c:209:gst_child_proxy_lookup: no param spec named sync 0:00:00.304509996 12228 0x8c55420 INFO GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad autoaudiosink0-actual-sink-alsa:sink 0:00:00.304522699 12228 0x8c55420 INFO GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link sink:proxypad0 and autoaudiosink0-actual-sink-alsa:sink 0:00:00.304543328 12228 0x8c55420 INFO GST_PADS gstpad.c:2161:gst_pad_link_full: linked sink:proxypad0 and autoaudiosink0-actual-sink-alsa:sink, successful 0:00:00.304561816 12228 0x8c55420 INFO GST_STATES gstbin.c:2237:gst_bin_element_set_state: skipping transition from READY to READY, since bin pending is READY : last change state return follows 0:00:00.304580033 12228 0x8c55420 INFO GST_STATES gstbin.c:2526:gst_bin_change_state_func: child 'autoaudiosink0-actual-sink-alsa' changed state to 2(READY) successfully 0:00:00.304595887 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to READY 0:00:00.304606433 12228 0x8c55420 INFO GST_STATES gstelement.c:2365:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.304624431 12228 0x8c55420 INFO GST_STATES gstbin.c:2526:gst_bin_change_state_func: child 'autoaudiosink0' changed state to 2(READY) successfully 0:00:00.309533018 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to READY 0:00:00.309562366 12228 0x8c55420 INFO GST_STATES gstelement.c:2365:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.309588361 12228 0x8c55420 INFO GST_STATES gstbin.c:2526:gst_bin_change_state_func: child 'queue' changed state to 2(READY) successfully 0:00:00.309603214 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to READY 0:00:00.309612363 12228 0x8c55420 INFO GST_STATES gstelement.c:2365:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.309624054 12228 0x8c55420 INFO GST_STATES gstbin.c:2526:gst_bin_change_state_func: child 'volume' changed state to 2(READY) successfully 0:00:00.309635832 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to READY 0:00:00.309644602 12228 0x8c55420 INFO GST_STATES gstelement.c:2365:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.309656038 12228 0x8c55420 INFO GST_STATES gstbin.c:2526:gst_bin_change_state_func: child 'audioresample0' changed state to 2(READY) successfully 0:00:00.309666609 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to READY 0:00:00.309675112 12228 0x8c55420 INFO GST_STATES gstelement.c:2365:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.309686488 12228 0x8c55420 INFO GST_STATES gstbin.c:2526:gst_bin_change_state_func: child 'audioconvert0' changed state to 2(READY) successfully 0:00:00.309699781 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to READY 0:00:00.309708453 12228 0x8c55420 INFO GST_STATES gstelement.c:2365:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.309719527 12228 0x8c55420 INFO GST_STATES gstbin.c:2526:gst_bin_change_state_func: child 'wavparse0' changed state to 2(READY) successfully 0:00:00.309730047 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to READY 0:00:00.309738534 12228 0x8c55420 INFO GST_STATES gstelement.c:2365:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.309749421 12228 0x8c55420 INFO GST_STATES gstbin.c:2526:gst_bin_change_state_func: child 'src' changed state to 2(READY) successfully 0:00:00.309760379 12228 0x8c55420 INFO GST_STATES gstelement.c:2435:gst_element_continue_state: committing state from NULL to READY, pending PLAYING, next PAUSED 0:00:00.309769604 12228 0x8c55420 INFO GST_STATES gstelement.c:2365:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (PLAYING pending) 0:00:00.309780915 12228 0x8c55420 INFO GST_STATES gstelement.c:2442:gst_element_continue_state: continue state change READY to PAUSED, final PLAYING 0:00:00.309836621 12228 0x8c55420 INFO GST_STATES gstbin.c:2532:gst_bin_change_state_func: child 'autoaudiosink0-actual-sink-alsa' is changing state asynchronously to PAUSED 0:00:00.309851341 12228 0x8c55420 INFO GST_STATES gstbin.c:2532:gst_bin_change_state_func: child 'autoaudiosink0' is changing state asynchronously to PAUSED 0:00:00.309933424 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to PAUSED 0:00:00.309945877 12228 0x8c55420 INFO GST_STATES gstelement.c:2365:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.309958154 12228 0x8c55420 INFO GST_STATES gstbin.c:2526:gst_bin_change_state_func: child 'queue' changed state to 3(PAUSED) successfully 0:00:00.309984421 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to PAUSED 0:00:00.309994015 12228 0x8c55420 INFO GST_STATES gstelement.c:2365:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.310005144 12228 0x8c55420 INFO GST_STATES gstbin.c:2526:gst_bin_change_state_func: child 'volume' changed state to 3(PAUSED) successfully 0:00:00.310028267 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to PAUSED 0:00:00.310037351 12228 0x8c55420 INFO GST_STATES gstelement.c:2365:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.310048464 12228 0x8c55420 INFO GST_STATES gstbin.c:2526:gst_bin_change_state_func: child 'audioresample0' changed state to 3(PAUSED) successfully 0:00:00.310071529 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to PAUSED 0:00:00.310080423 12228 0x8c55420 INFO GST_STATES gstelement.c:2365:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.310091637 12228 0x8c55420 INFO GST_STATES gstbin.c:2526:gst_bin_change_state_func: child 'audioconvert0' changed state to 3(PAUSED) successfully 0:00:00.310117145 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to PAUSED 0:00:00.310126937 12228 0x8c55420 INFO GST_STATES gstelement.c:2365:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.310138149 12228 0x8c55420 INFO GST_STATES gstbin.c:2526:gst_bin_change_state_func: child 'wavparse0' changed state to 3(PAUSED) successfully 0:00:00.310164292 12228 0x8c55420 INFO GST_EVENT gstevent.c:606:gst_event_new_new_segment_full: creating newsegment update 0, rate 1.000000, format buffers, start 0, stop -1, position 0 0:00:00.310227371 12228 0x8c55420 INFO GST_STATES gstelement.c:2460:gst_element_continue_state: completed state change to PAUSED 0:00:00.310237450 12228 0x8c55420 INFO GST_STATES gstelement.c:2365:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.310252172 12228 0x8c55420 INFO GST_STATES gstbin.c:2526:gst_bin_change_state_func: child 'src' changed state to 3(PAUSED) successfully 2016-08-06 08:17:05,957 sound output SoundPipeline.start() done 2016-08-06 08:17:05,959 the wav sound sink has stopped 2016-08-06 08:17:05,959 stop_receiving_sound(True) sound sink=sink_subprocess_wrapper(12228) 2016-08-06 08:17:05,960 stop_receiving_sound(True) calling 2016-08-06 08:17:05,960 cleanup() sending cleanup request to sound output 2016-08-06 08:17:05,961 stop_receiving_sound(True) done 2016-08-06 08:17:05,961 sound_sink_exit() not the current sink, ignoring it 2016-08-06 08:17:05,961 sound_process_stopped(sink_subprocess_wrapper(12228), ()) not the current sink, ignoring it 2016-08-06 08:17:08,458 verify_started() process=, info={}, codec=